"./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Starting (try 1/5) 2026/09/25 03:54:32 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino" 2026/09/25 03:54:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:54:32 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g" 2026/09/25 03:54:33 DEBUG : Creating backend with remote "/tmp/rclone4104455662" === RUN TestDoMultiThreadCopy --- PASS: TestDoMultiThreadCopy (0.00s) === RUN TestMultithreadCalculateNumChunks === RUN TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} === RUN TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} === RUN TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} === RUN TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} --- PASS: TestMultithreadCalculateNumChunks (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1_chunkSize:65536_wantNumChunks:1} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:1_wantNumChunks:1048576} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048576_chunkSize:2_wantNumChunks:524288} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048577_chunkSize:2_wantNumChunks:524289} (0.00s) --- PASS: TestMultithreadCalculateNumChunks/{size:1048575_chunkSize:2_wantNumChunks:524288} (0.00s) === RUN TestMultithreadCopy run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopy (0.25s) === RUN TestMultithreadCopyAbort run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" multithread_test.go:120: multithread writing not supported --- SKIP: TestMultithreadCopyAbort (0.21s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 03:54:34 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 1 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 0 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 2 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/write/fatal=true 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 03:54:34 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 1 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 2 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 0 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=false 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 03:54:34 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 1 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 2 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 3 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 0 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found === RUN TestMultithreadCopyWriterAtErrors/close/fatal=true 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/09/25 03:54:34 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 1 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 2 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 3 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/09/25 03:54:34 DEBUG : file.txt: writing chunk 0 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/09/25 03:54:34 DEBUG : file.txt: multi-thread copy: abort failed: multi-thread copy: failed to find temp file when aborting chunk writer: object not found --- PASS: TestMultithreadCopyWriterAtErrors (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/open/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/write/fatal=true (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=false (0.00s) --- PASS: TestMultithreadCopyWriterAtErrors/close/fatal=true (0.00s) === RUN TestSizeDiffers 2026/09/25 03:54:34 DEBUG : a: size = 0 OK 2026/09/25 03:54:34 DEBUG : a: size = 1 (memory) 2026/09/25 03:54:34 DEBUG : a: size = 2 (memory) --- PASS: TestSizeDiffers (0.00s) === RUN TestDirTransferEntry --- PASS: TestDirTransferEntry (0.00s) === RUN TestReOpen === RUN TestReOpen/Normal === RUN TestReOpen/Normal/Basics 2026/09/25 03:54:34 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/09/25 03:54:34 DEBUG : potato: Seek from 10 to 0 2026/09/25 03:54:34 DEBUG : potato: Seek from 10 to 0 2026/09/25 03:54:34 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/09/25 03:54:34 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Seek from 5 to 2 2026/09/25 03:54:34 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/09/25 03:54:34 DEBUG : potato: Seek from 7 to 0 2026/09/25 03:54:34 DEBUG : potato: Seek from 7 to 0 2026/09/25 03:54:34 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/09/25 03:54:34 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Seek from 5 to 2 2026/09/25 03:54:34 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/09/25 03:54:34 DEBUG : potato: Seek from 8 to 0 2026/09/25 03:54:34 DEBUG : potato: Seek from 8 to 0 2026/09/25 03:54:34 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/09/25 03:54:34 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/09/25 03:54:34 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/09/25 03:54:34 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/09/25 03:54:34 DEBUG : potato: Seek from 5 to 2 2026/09/25 03:54:34 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/09/25 03:54:34 DEBUG : potato: Seek from 9 to 0 2026/09/25 03:54:34 DEBUG : potato: Seek from 9 to 0 2026/09/25 03:54:34 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/AccountReadError --- PASS: TestReOpen (0.00s) --- PASS: TestReOpen/Normal (0.00s) --- PASS: TestReOpen/Normal/Basics (0.00s) --- PASS: TestReOpen/Normal/ErrorAtStart (0.00s) --- PASS: TestReOpen/Normal/WithErrors (0.00s) --- PASS: TestReOpen/Normal/TooManyErrors (0.00s) --- PASS: TestReOpen/Normal/ReadAt (0.00s) --- PASS: TestReOpen/Normal/Seek (0.00s) --- PASS: TestReOpen/Normal/AccountRead (0.00s) --- PASS: TestReOpen/Normal/AccountReadDelay (0.00s) --- PASS: TestReOpen/Normal/AccountReadError (0.00s) --- PASS: TestReOpen/WithRangeOption (0.00s) --- PASS: TestReOpen/WithRangeOption/Basics (0.00s) --- PASS: TestReOpen/WithRangeOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithRangeOption/WithErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithRangeOption/ReadAt (0.00s) --- PASS: TestReOpen/WithRangeOption/Seek (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountRead (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithRangeOption/AccountReadError (0.00s) --- PASS: TestReOpen/WithSeekOption (0.00s) --- PASS: TestReOpen/WithSeekOption/Basics (0.00s) --- PASS: TestReOpen/WithSeekOption/ErrorAtStart (0.00s) --- PASS: TestReOpen/WithSeekOption/WithErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/TooManyErrors (0.00s) --- PASS: TestReOpen/WithSeekOption/ReadAt (0.00s) --- PASS: TestReOpen/WithSeekOption/Seek (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountRead (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadDelay (0.00s) --- PASS: TestReOpen/WithSeekOption/AccountReadError (0.00s) --- PASS: TestReOpen/UnknownSize (0.00s) --- PASS: TestReOpen/UnknownSize/Basics (0.00s) --- PASS: TestReOpen/UnknownSize/ErrorAtStart (0.00s) --- PASS: TestReOpen/UnknownSize/WithErrors (0.00s) --- PASS: TestReOpen/UnknownSize/TooManyErrors (0.00s) --- PASS: TestReOpen/UnknownSize/ReadAt (0.00s) --- PASS: TestReOpen/UnknownSize/Seek (0.00s) --- PASS: TestReOpen/UnknownSize/AccountRead (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadDelay (0.00s) --- PASS: TestReOpen/UnknownSize/AccountReadError (0.00s) === RUN TestCheck run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:54:36 DEBUG : rutabaga: md5 = 251c96b46ff984c3c377760eef9473f3 OK === RUN TestCheck/1 === RUN TestCheck/2 2026/09/25 03:54:39 DEBUG : empty space: md5 = 2e29eaa825771460c66a31276d62efeb OK === RUN TestCheck/3 2026/09/25 03:54:41 DEBUG : potato2: md5 = d8d7341048e7ab43a86c14ba9f5a9426 OK === RUN TestCheck/4 === RUN TestCheck/5 2026/09/25 03:54:43 DEBUG : remotepotato: md5 = 33766673fc4d030c4265bb264591dd1c OK === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (11.95s) --- PASS: TestCheck/1 (0.25s) --- PASS: TestCheck/2 (0.20s) --- PASS: TestCheck/3 (0.33s) --- PASS: TestCheck/4 (0.25s) --- PASS: TestCheck/5 (0.23s) --- PASS: TestCheck/6 (0.22s) --- PASS: TestCheck/7 (0.29s) === RUN TestCheckFsError 2026/09/25 03:54:46 DEBUG : Creating backend with remote "nonexistent" 2026/09/25 03:54:46 DEBUG : Creating backend with remote "nonexistent" 2026/09/25 03:54:46 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/09/25 03:54:46 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/09/25 03:54:46 NOTICE: Local file system at nonexistent: 0 differences found 2026/09/25 03:54:46 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:54:47 DEBUG : rutabaga: md5 = 5f6298f7efd481d8da5fdf63ab0910e8 OK === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 2026/09/25 03:54:51 DEBUG : empty space: md5 = 519b786766ee51eee64a464889b887a1 OK === RUN TestCheckDownload/3 2026/09/25 03:54:53 DEBUG : potato2: md5 = ca0282347ce9e37008c64cd102e8a814 OK === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 2026/09/25 03:54:57 DEBUG : remotepotato: md5 = c0b3c30911ba69857d1722988d4d6425 OK === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (14.67s) --- PASS: TestCheckDownload/1 (0.83s) --- PASS: TestCheckDownload/2 (0.84s) --- PASS: TestCheckDownload/3 (0.77s) --- PASS: TestCheckDownload/4 (0.94s) --- PASS: TestCheckDownload/5 (0.89s) --- PASS: TestCheckDownload/6 (0.81s) --- PASS: TestCheckDownload/7 (0.81s) === RUN TestCheckSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:55:02 DEBUG : rutabaga: md5 = 64e9a65b6b7d5971d2491c6b84ea35ee OK === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 2026/09/25 03:55:04 DEBUG : empty space: md5 = bddd8dacaa5cec5b66aaba44b3b4af7c OK === RUN TestCheckSizeOnly/3 2026/09/25 03:55:06 DEBUG : potato2: md5 = 3cf7d55ac0dda11b2dd52f4dca55dad1 OK === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 2026/09/25 03:55:08 DEBUG : remotepotato: md5 = 0a138258038cac3330521a26785d90ca OK === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (10.58s) --- PASS: TestCheckSizeOnly/1 (0.25s) --- PASS: TestCheckSizeOnly/2 (0.25s) --- PASS: TestCheckSizeOnly/3 (0.22s) --- PASS: TestCheckSizeOnly/4 (0.27s) --- PASS: TestCheckSizeOnly/5 (0.24s) --- PASS: TestCheckSizeOnly/6 (0.30s) --- PASS: TestCheckSizeOnly/7 (0.26s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:55:13 DEBUG : test.sum: md5 = 4a6830498b426f7efe567559e95fc7f6 OK 2026/09/25 03:55:14 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/25 03:55:14 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/25 03:55:14 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/25 03:55:14 NOTICE: test.sum: 2 warning(s) suppressed... 2026/09/25 03:55:15 DEBUG : test.sum: md5 = d51a522063961e6e6d3988fca1f3e864 OK 2026/09/25 03:55:16 NOTICE: test.sum: improperly formatted checksum line 4 2026/09/25 03:55:16 NOTICE: test.sum: improperly formatted checksum line 5 2026/09/25 03:55:16 NOTICE: test.sum: improperly formatted checksum line 6 2026/09/25 03:55:16 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (5.59s) === RUN TestCheckSum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:55:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/data" 2026/09/25 03:55:17 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/vjrnln8ratgmqakfosrqe8espk" check_test.go:354: Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/data' lacks md5, skipping --- SKIP: TestCheckSum (1.99s) === RUN TestCheckSumDownload run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:55:19 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/data" 2026/09/25 03:55:19 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/vjrnln8ratgmqakfosrqe8espk" 2026/09/25 03:55:23 DEBUG : data/banana: md5 = 22c5ef5b8eb63fb58643e1884cc2860e OK 2026/09/25 03:55:24 DEBUG : test.sum: md5 = a35a378202a3278636a39c6cec6fdfa9 OK === RUN TestCheckSumDownload/subtest1 2026/09/25 03:55:28 DEBUG : data/potato: md5 = c2d1d351904fb42ba54822901633a3a1 OK 2026/09/25 03:55:30 DEBUG : test.sum: md5 = 80deb37fe86757b4892c8cfb989fb602 OK === RUN TestCheckSumDownload/subtest2 2026/09/25 03:55:34 DEBUG : test.sum: md5 = 28f8a01a162b40d6388749c8d4059bbc OK === RUN TestCheckSumDownload/subtest3 2026/09/25 03:55:38 DEBUG : test.sum: md5 = 892f0bfcc0647cf1de03ff8e1ac35c3e OK === RUN TestCheckSumDownload/subtest4 2026/09/25 03:55:42 DEBUG : test.sum: md5 = 5ef11151242f6dc5ebf19cbe9f994af4 OK === RUN TestCheckSumDownload/subtest5 2026/09/25 03:55:45 DEBUG : test.sum: md5 = 8507f601fbfc48a6afac74933e9247d7 OK === RUN TestCheckSumDownload/subtest6 2026/09/25 03:55:49 DEBUG : data/banana: md5 = 9c912f4476bbee9ad11e543cb3bb4e74 OK 2026/09/25 03:55:51 DEBUG : data/potato: md5 = 540f4452b4ac45a5f1ba7b398f148a66 OK 2026/09/25 03:55:52 DEBUG : test.sum: md5 = 4f698ec7cd70453e3e594538ce564004 OK === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (38.33s) --- PASS: TestCheckSumDownload/subtest1 (2.21s) --- PASS: TestCheckSumDownload/subtest2 (1.82s) --- PASS: TestCheckSumDownload/subtest3 (1.71s) --- PASS: TestCheckSumDownload/subtest4 (1.78s) --- PASS: TestCheckSumDownload/subtest5 (1.69s) --- PASS: TestCheckSumDownload/subtest6 (1.77s) --- PASS: TestCheckSumDownload/subtest7 (1.72s) === RUN TestCheckSumConcurrency 2026/09/25 03:55:57 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/25 03:55:57 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/25 03:55:57 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/25 03:55:57 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/25 03:55:57 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/25 03:55:57 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/09/25 03:55:57 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/09/25 03:55:57 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/09/25 03:55:57 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/09/25 03:55:57 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/09/25 03:55:57 NOTICE: Mock file system at checkSum: 0 differences found 2026/09/25 03:55:57 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/09/25 03:55:57 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-mimopes3joto" 2026/09/25 03:55:57 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:55:57 DEBUG : Creating backend with remote "TestDrive:crypt/qdkqvc0ji3msp76061p2ishe8h380s7g3qiqujkpuavdfb1n2t10" 2026/09/25 03:55:58 DEBUG : Creating backend with remote "/tmp/rclone2086447238" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-mimopes3joto'", Local "Local file system at /tmp/rclone2086447238", Modify Window "1ms" 2026/09/25 03:56:00 DEBUG : hello, world!: md5 = b1f609deedcbdf335447bdf0d71f89ac OK upper checkfile vs. lower remote (without normalization) 2026/09/25 03:56:01 ERROR : hello, world!: sum not found 2026/09/25 03:56:01 ERROR : HELLO, WORLD!: file not in Encrypted drive 'TestCryptDrive:rclone-test-mimopes3joto' 2026/09/25 03:56:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mimopes3joto': 1 files missing 2026/09/25 03:56:01 NOTICE: 1 hashes missing 2026/09/25 03:56:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mimopes3joto': 1 differences found 2026/09/25 03:56:01 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mimopes3joto': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/09/25 03:56:02 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mimopes3joto': 0 differences found 2026/09/25 03:56:02 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-mimopes3joto': 1 matching files 2026/09/25 03:56:02 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-kasozag7riju" 2026/09/25 03:56:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:02 DEBUG : Creating backend with remote "TestDrive:crypt/ibcj1n63plkr4db7ob5062g0l7fdpvhr228muddig4slhu47g8k0" 2026/09/25 03:56:03 DEBUG : Creating backend with remote "/tmp/rclone1232662535" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-kasozag7riju'", Local "Local file system at /tmp/rclone1232662535", Modify Window "1ms" 2026/09/25 03:56:05 DEBUG : HELLO, WORLD!: md5 = fae22a4167505af1817be07007a8fe60 OK lower checkfile vs. upper remote (without normalization) 2026/09/25 03:56:06 ERROR : HELLO, WORLD!: sum not found 2026/09/25 03:56:06 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-kasozag7riju' 2026/09/25 03:56:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kasozag7riju': 1 files missing 2026/09/25 03:56:06 NOTICE: 1 hashes missing 2026/09/25 03:56:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kasozag7riju': 1 differences found 2026/09/25 03:56:06 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kasozag7riju': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/09/25 03:56:07 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kasozag7riju': 0 differences found 2026/09/25 03:56:07 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-kasozag7riju': 1 matching files 2026/09/25 03:56:07 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-yicesoj2pobo" 2026/09/25 03:56:07 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:07 DEBUG : Creating backend with remote "TestDrive:crypt/000fdosm1jebilmdk6kp7pu17vj8laho9himptm3hd8u97cm0vm0" 2026/09/25 03:56:08 DEBUG : Creating backend with remote "/tmp/rclone3030191659" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-yicesoj2pobo'", Local "Local file system at /tmp/rclone3030191659", Modify Window "1ms" 2026/09/25 03:56:10 DEBUG : HeLlO, wOrLd!: md5 = 8f605daf95a99edf0c907f59d24aa819 OK lower checkfile vs. upperlowermixed remote (without normalization) 2026/09/25 03:56:10 ERROR : HeLlO, wOrLd!: sum not found 2026/09/25 03:56:10 ERROR : hello, world!: file not in Encrypted drive 'TestCryptDrive:rclone-test-yicesoj2pobo' 2026/09/25 03:56:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yicesoj2pobo': 1 files missing 2026/09/25 03:56:10 NOTICE: 1 hashes missing 2026/09/25 03:56:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yicesoj2pobo': 1 differences found 2026/09/25 03:56:10 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yicesoj2pobo': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/09/25 03:56:11 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yicesoj2pobo': 0 differences found 2026/09/25 03:56:11 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-yicesoj2pobo': 1 matching files 2026/09/25 03:56:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-catediz8tofo" 2026/09/25 03:56:11 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:11 DEBUG : Creating backend with remote "TestDrive:crypt/tb5pbjc7gmbdc6g3fue4hifr7no3p2fjceuhk9ebdki9lofjgf30" 2026/09/25 03:56:12 DEBUG : Creating backend with remote "/tmp/rclone79130832" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-catediz8tofo'", Local "Local file system at /tmp/rclone79130832", Modify Window "1ms" 2026/09/25 03:56:14 DEBUG : HELLO, WORLD!: md5 = 302cb1ee905795eb14b0fe2dcd3917a3 OK upperlowermixed checkfile vs. upper remote (without normalization) 2026/09/25 03:56:15 ERROR : HELLO, WORLD!: sum not found 2026/09/25 03:56:15 ERROR : HeLlO, wOrLd!: file not in Encrypted drive 'TestCryptDrive:rclone-test-catediz8tofo' 2026/09/25 03:56:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-catediz8tofo': 1 files missing 2026/09/25 03:56:15 NOTICE: 1 hashes missing 2026/09/25 03:56:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-catediz8tofo': 1 differences found 2026/09/25 03:56:15 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-catediz8tofo': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/09/25 03:56:16 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-catediz8tofo': 0 differences found 2026/09/25 03:56:16 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-catediz8tofo': 1 matching files 2026/09/25 03:56:16 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-vomuniz9cefa" 2026/09/25 03:56:16 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:16 DEBUG : Creating backend with remote "TestDrive:crypt/sdmrp4jp194cj3aea3l0dh6gobgqqkur06h30m8a2gdegra2t5mg" 2026/09/25 03:56:17 DEBUG : Creating backend with remote "/tmp/rclone3696394" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-vomuniz9cefa'", Local "Local file system at /tmp/rclone3696394", Modify Window "1ms" 2026/09/25 03:56:19 DEBUG : 測試_Русский___ě_áñ: md5 = c86728cf6758df4e5c393995d9be37e7 OK NFD checkfile vs. NFC remote (without normalization) 2026/09/25 03:56:20 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/25 03:56:20 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-vomuniz9cefa' 2026/09/25 03:56:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vomuniz9cefa': 1 files missing 2026/09/25 03:56:20 NOTICE: 1 hashes missing 2026/09/25 03:56:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vomuniz9cefa': 1 differences found 2026/09/25 03:56:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vomuniz9cefa': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/09/25 03:56:20 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vomuniz9cefa': 0 differences found 2026/09/25 03:56:20 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-vomuniz9cefa': 1 matching files 2026/09/25 03:56:20 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-majubal6vinu" 2026/09/25 03:56:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:21 DEBUG : Creating backend with remote "TestDrive:crypt/hhnan4p6pfbsqu9515c4ev8e55vlh7469p2en1kpcq35s1ei1gc0" 2026/09/25 03:56:21 DEBUG : Creating backend with remote "/tmp/rclone1789361021" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-majubal6vinu'", Local "Local file system at /tmp/rclone1789361021", Modify Window "1ms" 2026/09/25 03:56:24 DEBUG : 測試_Русский___ě_áñ: md5 = 1737c83b6ffff649c6b6f0e2d9b6fecb OK NFC checkfile vs. NFD remote (without normalization) 2026/09/25 03:56:24 ERROR : 測試_Русский___ě_áñ: sum not found 2026/09/25 03:56:24 ERROR : 測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-majubal6vinu' 2026/09/25 03:56:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-majubal6vinu': 1 files missing 2026/09/25 03:56:24 NOTICE: 1 hashes missing 2026/09/25 03:56:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-majubal6vinu': 1 differences found 2026/09/25 03:56:24 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-majubal6vinu': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/09/25 03:56:25 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-majubal6vinu': 0 differences found 2026/09/25 03:56:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-majubal6vinu': 1 matching files 2026/09/25 03:56:25 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-savocuz7fiqo" 2026/09/25 03:56:25 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:25 DEBUG : Creating backend with remote "TestDrive:crypt/rgci1e3no6mbum7kejlso361ucfogjj2qsm3vlmvu6q33s6tsgd0" 2026/09/25 03:56:26 DEBUG : Creating backend with remote "/tmp/rclone489329181" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-savocuz7fiqo'", Local "Local file system at /tmp/rclone489329181", Modify Window "1ms" 2026/09/25 03:56:28 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 636440e2f2aa859ae2eb9da87e5aee90 OK NFDx2 checkfile vs. both remote (without normalization) 2026/09/25 03:56:29 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/25 03:56:29 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-savocuz7fiqo' 2026/09/25 03:56:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-savocuz7fiqo': 1 files missing 2026/09/25 03:56:29 NOTICE: 1 hashes missing 2026/09/25 03:56:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-savocuz7fiqo': 1 differences found 2026/09/25 03:56:29 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-savocuz7fiqo': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/09/25 03:56:30 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-savocuz7fiqo': 0 differences found 2026/09/25 03:56:30 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-savocuz7fiqo': 1 matching files 2026/09/25 03:56:30 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gepanec8vofi" 2026/09/25 03:56:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:30 DEBUG : Creating backend with remote "TestDrive:crypt/lnpe619trtdb9vbrqoare3ttjs4iaubplvuqvlgelidlf2admrvg" 2026/09/25 03:56:31 DEBUG : Creating backend with remote "/tmp/rclone2634009083" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gepanec8vofi'", Local "Local file system at /tmp/rclone2634009083", Modify Window "1ms" 2026/09/25 03:56:33 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 214f79cb3f54a49f586a04af372d5540 OK NFCx2 checkfile vs. both remote (without normalization) 2026/09/25 03:56:33 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/25 03:56:33 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-gepanec8vofi' 2026/09/25 03:56:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gepanec8vofi': 1 files missing 2026/09/25 03:56:33 NOTICE: 1 hashes missing 2026/09/25 03:56:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gepanec8vofi': 1 differences found 2026/09/25 03:56:33 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gepanec8vofi': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/09/25 03:56:34 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gepanec8vofi': 0 differences found 2026/09/25 03:56:34 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gepanec8vofi': 1 matching files 2026/09/25 03:56:34 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-lijoyer3joca" 2026/09/25 03:56:34 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:34 DEBUG : Creating backend with remote "TestDrive:crypt/sps4iccuicqk7mrf82ksud30f4ngesmsf8uevkhqamatnliqn6tg" 2026/09/25 03:56:35 DEBUG : Creating backend with remote "/tmp/rclone2536962451" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-lijoyer3joca'", Local "Local file system at /tmp/rclone2536962451", Modify Window "1ms" 2026/09/25 03:56:38 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 5db31b83967833a379b42538a4d92dc5 OK both checkfile vs. NFDx2 remote (without normalization) 2026/09/25 03:56:38 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/25 03:56:38 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-lijoyer3joca' 2026/09/25 03:56:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lijoyer3joca': 1 files missing 2026/09/25 03:56:38 NOTICE: 1 hashes missing 2026/09/25 03:56:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lijoyer3joca': 1 differences found 2026/09/25 03:56:38 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lijoyer3joca': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/09/25 03:56:39 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lijoyer3joca': 0 differences found 2026/09/25 03:56:39 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-lijoyer3joca': 1 matching files 2026/09/25 03:56:39 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gekitud7xido" 2026/09/25 03:56:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 03:56:39 DEBUG : Creating backend with remote "TestDrive:crypt/i4rljlbi9mkmvtcdebcl44or8e5ecake2a2g8q9pisajas8eioq0" 2026/09/25 03:56:40 DEBUG : Creating backend with remote "/tmp/rclone1682110890" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gekitud7xido'", Local "Local file system at /tmp/rclone1682110890", Modify Window "1ms" 2026/09/25 03:56:42 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 8383e3373f8cbdbed87de608a865b3b8 OK both checkfile vs. NFCx2 remote (without normalization) 2026/09/25 03:56:43 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/09/25 03:56:43 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in Encrypted drive 'TestCryptDrive:rclone-test-gekitud7xido' 2026/09/25 03:56:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gekitud7xido': 1 files missing 2026/09/25 03:56:43 NOTICE: 1 hashes missing 2026/09/25 03:56:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gekitud7xido': 1 differences found 2026/09/25 03:56:43 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gekitud7xido': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/09/25 03:56:44 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/09/25 03:56:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gekitud7xido': 0 differences found 2026/09/25 03:56:44 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gekitud7xido': 1 matching files 2026/09/25 03:56:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gekitud7xido': Purge remote 2026/09/25 03:56:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-lijoyer3joca': Purge remote 2026/09/25 03:56:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gepanec8vofi': Purge remote 2026/09/25 03:56:45 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-savocuz7fiqo': Purge remote 2026/09/25 03:56:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-majubal6vinu': Purge remote 2026/09/25 03:56:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-vomuniz9cefa': Purge remote 2026/09/25 03:56:46 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-catediz8tofo': Purge remote 2026/09/25 03:56:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-yicesoj2pobo': Purge remote 2026/09/25 03:56:47 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-kasozag7riju': Purge remote 2026/09/25 03:56:48 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-mimopes3joto': Purge remote --- PASS: TestApplyTransforms (50.97s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:56:48 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/25 03:56:50 DEBUG : sub/file2: md5 = 12a7681860548c40f91ce23f0e8ee596 OK 2026/09/25 03:56:50 DEBUG : sub/file2: size = 14 OK 2026/09/25 03:56:50 INFO : file1: Copied (new) to: sub/file2 2026/09/25 03:56:51 DEBUG : sub/file2: size = 14 OK 2026/09/25 03:56:51 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 03:56:51 DEBUG : file1: Unchanged skipping 2026/09/25 03:56:51 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (5.68s) === RUN TestCopyFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:56:54 DEBUG : existing: Need to transfer - File not found at Destination 2026/09/25 03:56:55 DEBUG : existing: md5 = 05fdce98bd0a9195f54ba3b6a6f6e44b OK 2026/09/25 03:56:55 DEBUG : existing: size = 6 OK 2026/09/25 03:56:55 INFO : existing: Copied (new) 2026/09/25 03:56:56 DEBUG : existing: size = 6 OK 2026/09/25 03:56:56 DEBUG : existing: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 03:56:56 DEBUG : existing: Unchanged skipping 2026/09/25 03:56:56 DEBUG : existing: size = 8 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:56:56 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 2026/09/25 03:56:56 DEBUG : existing: Sizes differ 2026/09/25 03:56:56 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (3.98s) === RUN TestCopyLongFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (0.45s) === RUN TestCopyFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:57:00 DEBUG : dst/file1: md5 = 5b1e1a090770c8aaefcc325d3ff8d800 OK 2026/09/25 03:57:01 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/backup" 2026/09/25 03:57:01 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/1nrff024r7pq65ecp72fc28jb0" 2026/09/25 03:57:03 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:57:03 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 2026/09/25 03:57:03 DEBUG : dst/file1: Sizes differ 2026/09/25 03:57:05 INFO : dst/file1: Moved (server-side) 2026/09/25 03:57:06 DEBUG : dst/file1: md5 = 0e47e6dbfe4944a8099cb0f450b1c329 OK 2026/09/25 03:57:06 DEBUG : dst/file1: size = 14 OK 2026/09/25 03:57:06 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (12.90s) === RUN TestCopyFileCompareDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:57:11 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/dst" 2026/09/25 03:57:11 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/25 03:57:13 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/CompareDest" 2026/09/25 03:57:13 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/gveqi14airsml4bgu7krj116o8" 2026/09/25 03:57:15 DEBUG : one: Need to transfer - File not found at Destination 2026/09/25 03:57:17 DEBUG : one: md5 = 9d02d2eed1c6a0120366d0895eaf02e2 OK 2026/09/25 03:57:17 DEBUG : one: size = 3 OK 2026/09/25 03:57:17 INFO : one: Copied (new) 2026/09/25 03:57:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:57:18 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/dst') 2026/09/25 03:57:18 DEBUG : one: Sizes differ 2026/09/25 03:57:20 DEBUG : one: md5 = f19cf31b9716b231637f5ffa24808d36 OK 2026/09/25 03:57:20 DEBUG : one: size = 5 OK 2026/09/25 03:57:20 INFO : one: Copied (replaced existing) 2026/09/25 03:57:22 DEBUG : dst/one: md5 = a6f0f359180a5b3c0f92a8f5f63894ab OK 2026/09/25 03:57:24 DEBUG : CompareDest/one: md5 = 1a12e1d857edfb9b2227b9f32520e3a4 OK 2026/09/25 03:57:25 DEBUG : one: size = 5 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:57:25 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/dst') 2026/09/25 03:57:25 DEBUG : one: Sizes differ 2026/09/25 03:57:26 DEBUG : one: size = 5 OK 2026/09/25 03:57:26 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 03:57:26 DEBUG : one: Destination found in --compare-dest, skipping 2026/09/25 03:57:29 DEBUG : CompareDest/two: md5 = 45477a5638150c339d1b44b6375f4eae OK 2026/09/25 03:57:29 DEBUG : two: Need to transfer - File not found at Destination 2026/09/25 03:57:30 DEBUG : two: size = 3 OK 2026/09/25 03:57:30 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 03:57:30 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/25 03:57:30 DEBUG : two: Need to transfer - File not found at Destination 2026/09/25 03:57:31 DEBUG : two: size = 3 OK 2026/09/25 03:57:31 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 03:57:31 DEBUG : two: Destination found in --compare-dest, skipping 2026/09/25 03:57:32 DEBUG : two: Need to transfer - File not found at Destination 2026/09/25 03:57:32 DEBUG : two: size = 5 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:57:32 DEBUG : two: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/CompareDest') 2026/09/25 03:57:32 DEBUG : two: Sizes differ 2026/09/25 03:57:33 DEBUG : two: md5 = 80c9680b110e7688033a14fbc1a80c89 OK 2026/09/25 03:57:33 DEBUG : two: size = 5 OK 2026/09/25 03:57:33 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (26.84s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:57:38 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/dst" 2026/09/25 03:57:38 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/25 03:57:41 DEBUG : dst/one: md5 = 064100b684e16b00dc13dab72023939a OK 2026/09/25 03:57:44 DEBUG : CopyDest/one: md5 = d76389b7ac50df38462198d4417a70d4 OK 2026/09/25 03:57:45 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/CopyDest" 2026/09/25 03:57:45 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/d09o6po3f7bm6ce32vdgs8h9ls" 2026/09/25 03:57:46 DEBUG : one: size = 5 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:57:46 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/dst') 2026/09/25 03:57:46 DEBUG : one: Sizes differ 2026/09/25 03:57:46 DEBUG : one: size = 5 OK 2026/09/25 03:57:46 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 03:57:46 DEBUG : one: size = 5 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:57:46 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/dst') 2026/09/25 03:57:46 DEBUG : one: Sizes differ 2026/09/25 03:57:46 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (11.60s) === RUN TestCopyFileCopyDest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:57:49 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/dst" 2026/09/25 03:57:50 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/31u3jie661vd5p8j7rtc3hgbh0" 2026/09/25 03:57:51 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/CopyDest" 2026/09/25 03:57:51 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/d09o6po3f7bm6ce32vdgs8h9ls" 2026/09/25 03:57:53 DEBUG : one: Need to transfer - File not found at Destination 2026/09/25 03:57:55 DEBUG : one: md5 = d2dbf4174afc555e6ff48b21568515cb OK 2026/09/25 03:57:55 DEBUG : one: size = 3 OK 2026/09/25 03:57:55 INFO : one: Copied (new) 2026/09/25 03:57:57 DEBUG : one: size = 5 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:57:57 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/dst') 2026/09/25 03:57:57 DEBUG : one: Sizes differ 2026/09/25 03:57:58 DEBUG : one: md5 = 5bb4dd0e556e8a56ef78cf759dd375e1 OK 2026/09/25 03:57:58 DEBUG : one: size = 5 OK 2026/09/25 03:57:58 INFO : one: Copied (replaced existing) 2026/09/25 03:58:00 DEBUG : dst/one: md5 = 9ded3af96dead9f6353aedb69a79c6cc OK 2026/09/25 03:58:03 DEBUG : CopyDest/one: md5 = 08c0faa00d551b27e3920dc5d923d06a OK 2026/09/25 03:58:03 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/BackupDir" 2026/09/25 03:58:04 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/s6dbk3lfi7c9kfvo6j7bla9m0g" 2026/09/25 03:58:05 DEBUG : one: size = 5 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:58:05 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/dst') 2026/09/25 03:58:05 DEBUG : one: Sizes differ 2026/09/25 03:58:06 DEBUG : one: size = 5 OK 2026/09/25 03:58:06 DEBUG : one: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 03:58:06 DEBUG : one: size = 5 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:58:06 DEBUG : one: size = 3 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/dst') 2026/09/25 03:58:06 DEBUG : one: Sizes differ 2026/09/25 03:58:07 INFO : one: Moved (server-side) 2026/09/25 03:58:08 DEBUG : one: size = 5 OK 2026/09/25 03:58:08 INFO : one: Copied (server-side copy) 2026/09/25 03:58:08 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/09/25 03:58:10 DEBUG : CopyDest/two: md5 = 8c4bb013d5e285ea115b84cf7d31a8ae OK 2026/09/25 03:58:11 DEBUG : two: Need to transfer - File not found at Destination 2026/09/25 03:58:11 DEBUG : two: size = 3 OK 2026/09/25 03:58:11 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 03:58:13 DEBUG : two: size = 3 OK 2026/09/25 03:58:13 INFO : two: Copied (server-side copy) 2026/09/25 03:58:13 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/09/25 03:58:14 DEBUG : two: size = 3 OK 2026/09/25 03:58:14 DEBUG : two: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) 2026/09/25 03:58:14 DEBUG : two: Unchanged skipping 2026/09/25 03:58:16 DEBUG : CopyDest/three: md5 = 1635d7564d709ea54a6d73ad14d83ae0 OK 2026/09/25 03:58:17 DEBUG : three: Need to transfer - File not found at Destination 2026/09/25 03:58:17 DEBUG : three: size = 7 (Local file system at /tmp/rclone4104455662) 2026/09/25 03:58:17 DEBUG : three: size = 5 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino/CopyDest') 2026/09/25 03:58:17 DEBUG : three: Sizes differ 2026/09/25 03:58:17 DEBUG : three: Destination not found in --copy-dest 2026/09/25 03:58:18 DEBUG : three: md5 = b474686409d96b65242e1d391d3eba20 OK 2026/09/25 03:58:18 DEBUG : three: size = 7 OK 2026/09/25 03:58:18 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (34.92s) === RUN TestCopyInplace run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (0.43s) === RUN TestCopyLongFileName run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (0.58s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (0.47s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:58:26 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/09/25 03:58:28 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 6080b1afd889274fbaa80920107185e6 OK 2026/09/25 03:58:28 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/09/25 03:58:28 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/09/25 03:58:29 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/09/25 03:58:29 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:563: Expecting error to contain accounting.ErrorMaxTransferLimitReachedFatal 2026/09/25 03:58:30 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/09/25 03:58:31 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/09/25 03:58:32 DEBUG : TestCopyFileMaxTransfer/file4: md5 = 82083bf74a0c3b9510bc9236b321f55b OK 2026/09/25 03:58:32 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/09/25 03:58:32 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (9.14s) === RUN TestDeduplicateInteractive run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateInteractive (0.48s) === RUN TestDeduplicateSkip run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:58:38 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using skip mode. 2026/09/25 03:58:39 NOTICE: one: Found 2 files with duplicate names 2026/09/25 03:58:39 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSkip (4.88s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:58:44 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using skip mode. 2026/09/25 03:58:45 NOTICE: one: Found 3 files with duplicate names 2026/09/25 03:58:45 NOTICE: one: Deleting 1/2 identical duplicates (size 11) 2026/09/25 03:58:45 INFO : one: Deleted 2026/09/25 03:58:45 NOTICE: one: Skipping 2 files with duplicate names --- PASS: TestDeduplicateSizeOnly (7.15s) === RUN TestDeduplicateFirst run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:58:51 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using first mode. 2026/09/25 03:58:52 NOTICE: one: Found 3 files with duplicate names 2026/09/25 03:58:52 INFO : one: Deleted 2026/09/25 03:58:53 INFO : one: Deleted 2026/09/25 03:58:53 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateFirst (6.23s) === RUN TestDeduplicateNewest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:58:58 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using newest mode. 2026/09/25 03:58:58 NOTICE: one: Found 3 files with duplicate names 2026/09/25 03:58:59 INFO : one: Deleted 2026/09/25 03:58:59 INFO : one: Deleted 2026/09/25 03:58:59 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateNewest (6.54s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (0.46s) === RUN TestDeduplicateOldest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:59:06 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using oldest mode. 2026/09/25 03:59:06 NOTICE: one: Found 3 files with duplicate names 2026/09/25 03:59:07 INFO : one: Deleted 2026/09/25 03:59:07 INFO : one: Deleted 2026/09/25 03:59:07 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateOldest (7.58s) === RUN TestDeduplicateLargest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:59:14 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using largest mode. 2026/09/25 03:59:14 NOTICE: one: Found 3 files with duplicate names 2026/09/25 03:59:15 INFO : one: Deleted 2026/09/25 03:59:15 INFO : one: Deleted 2026/09/25 03:59:15 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateLargest (8.09s) === RUN TestDeduplicateSmallest run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:59:20 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using smallest mode. 2026/09/25 03:59:21 NOTICE: one: Found 3 files with duplicate names 2026/09/25 03:59:21 INFO : one: Deleted 2026/09/25 03:59:22 INFO : one: Deleted 2026/09/25 03:59:22 NOTICE: one: Deleted 2 extra copies --- PASS: TestDeduplicateSmallest (6.26s) === RUN TestDeduplicateRename run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:59:28 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using rename mode. 2026/09/25 03:59:28 NOTICE: one.txt: Found 3 files with duplicate names 2026/09/25 03:59:29 INFO : one-2.txt: renamed from: one.txt 2026/09/25 03:59:30 INFO : one-3.txt: renamed from: one.txt 2026/09/25 03:59:31 INFO : one-4.txt: renamed from: one.txt --- PASS: TestDeduplicateRename (10.34s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 03:59:34 DEBUG : one-1.txt: md5 = 0d3610d2f5f99c05720a708bb0aa6135 OK 2026/09/25 03:59:36 DEBUG : one-2.txt: md5 = 32a03d41b224d8c94506cecb5d3d1bd1 OK 2026/09/25 03:59:37 DEBUG : one-3.txt: md5 = 24a2e8970a9effc77a67ae97a7ebb1d2 OK 2026/09/25 03:59:39 DEBUG : one-4.txt: md5 = e89a3f3fba5cb0af4d5df9e32ef5361c OK 2026/09/25 03:59:40 DEBUG : one-5.txt: md5 = add0f481aa5ed764c5339b34e556944a OK 2026/09/25 03:59:42 DEBUG : one-6.txt: md5 = 96e410409d370b39540df459f94c4e3d OK 2026/09/25 03:59:43 DEBUG : one-7.txt: md5 = f3745943d1fba6ee48487e820f1ef142 OK 2026/09/25 03:59:45 DEBUG : one-8.txt: md5 = 0a780eea202dec5fbe8b03a8ffbbd58c OK 2026/09/25 03:59:46 DEBUG : one-9.txt: md5 = b3c46302e916ce14565b5f82f50fd220 OK 2026/09/25 03:59:48 DEBUG : one-10.txt: md5 = 1b43fd127fbdff8dbbe542b59a13fb27 OK 2026/09/25 03:59:49 DEBUG : one-11.txt: md5 = 370b7bf158240b09f6f592fc82b1df93 OK 2026/09/25 03:59:50 DEBUG : one-12.txt: md5 = 9db8486358b4ed798a37a2e41a861255 OK 2026/09/25 03:59:52 DEBUG : one-13.txt: md5 = 1520ce0769465509d5e6aae74540846c OK 2026/09/25 03:59:53 DEBUG : one-14.txt: md5 = 5899d414b0c3642f2fdf73204a48c188 OK 2026/09/25 03:59:55 DEBUG : one-15.txt: md5 = 35c87f39fcc432acc5d5e506a1ae64fb OK 2026/09/25 03:59:56 DEBUG : one-16.txt: md5 = a3358b8351717ec6ffec0d1ef3d747ed OK 2026/09/25 03:59:58 DEBUG : one-17.txt: md5 = 337c452c1b0aa588d7fd493e9233878a OK 2026/09/25 03:59:59 DEBUG : one-18.txt: md5 = 8cfd7c3d4a6a732cc688fd96fe6a8fa9 OK 2026/09/25 04:00:00 DEBUG : one-19.txt: md5 = 9054dc0b69b09de27123345eade9b149 OK 2026/09/25 04:00:02 DEBUG : one-20.txt: md5 = edbfbdfb17365c7d225037ef02a8eb3d OK 2026/09/25 04:00:03 DEBUG : one-21.txt: md5 = f74dcf192861984b17c508be809c5218 OK 2026/09/25 04:00:05 DEBUG : one-22.txt: md5 = 21f4c14083f061cd6c99a0942b6d657e OK 2026/09/25 04:00:06 DEBUG : one-23.txt: md5 = 13302a16d64464b654e9151793bf8516 OK 2026/09/25 04:00:08 DEBUG : one-24.txt: md5 = 732f1e3f3cdbe698d7dfa21d62a09f09 OK 2026/09/25 04:00:09 DEBUG : one-25.txt: md5 = 2df4e30a569cb3884044723c49899be1 OK 2026/09/25 04:00:10 DEBUG : one-26.txt: md5 = e9459d6523227cd1b118f46d1267c034 OK 2026/09/25 04:00:12 DEBUG : one-27.txt: md5 = 59162c983c8a46d3ebd7a30b86ead9b4 OK 2026/09/25 04:00:14 DEBUG : one-28.txt: md5 = 149c2621c99e08693cb8636ad60b8fef OK 2026/09/25 04:00:15 DEBUG : one-29.txt: md5 = 40a1d3a818ac58a858bdc6f923c2b929 OK 2026/09/25 04:00:16 DEBUG : one-30.txt: md5 = 3753cde8256b3e18c79c260ce70c2575 OK 2026/09/25 04:00:18 DEBUG : one-31.txt: md5 = 99b59b81bdb2936f1da929882fa14302 OK 2026/09/25 04:00:19 DEBUG : one-32.txt: md5 = bd85a664f0b60d1fc1e787f9c77a36d2 OK 2026/09/25 04:00:21 DEBUG : one-33.txt: md5 = 7534f10edf55fe1a59180a3d80360920 OK 2026/09/25 04:00:22 DEBUG : one-34.txt: md5 = d23078be5093e5256be16e87ec714ab8 OK 2026/09/25 04:00:24 DEBUG : one-35.txt: md5 = 3b3265cb71d20636db8181172441db2c OK 2026/09/25 04:00:25 DEBUG : one-36.txt: md5 = 1d18385491c546c1bd7f79433d232db9 OK 2026/09/25 04:00:26 DEBUG : one-37.txt: md5 = a46a997972b3fbe1b04f75fad4aa68c5 OK 2026/09/25 04:00:28 DEBUG : one-38.txt: md5 = 905dc72988bc35acb2616f30b72ff0b5 OK 2026/09/25 04:00:29 DEBUG : one-39.txt: md5 = a4a23453b697e1b5402301a70f297759 OK 2026/09/25 04:00:31 DEBUG : one-40.txt: md5 = b09bd2e7aa37583c7c95fd8e777fdeb1 OK 2026/09/25 04:00:33 DEBUG : one-41.txt: md5 = b0d0b2155237e25e1351693c3d3d9a85 OK 2026/09/25 04:00:34 DEBUG : one-42.txt: md5 = 927cab34f44f694840214eeff5ee45e7 OK 2026/09/25 04:00:36 DEBUG : one-43.txt: md5 = 8561f857d637e49fce81e5c6d9de44d0 OK 2026/09/25 04:00:37 DEBUG : one-44.txt: md5 = ff9e94eb48c6f1702dc4beb6e6622ea1 OK 2026/09/25 04:00:39 DEBUG : one-45.txt: md5 = 580000ba18f3fe7bd8188b9ec4a36c92 OK 2026/09/25 04:00:40 DEBUG : one-46.txt: md5 = b3c7539c74b953e6a922607a4ebd049a OK 2026/09/25 04:00:42 DEBUG : one-47.txt: md5 = d48417bf85fca9453dde481ccf32e882 OK 2026/09/25 04:00:43 DEBUG : one-48.txt: md5 = 126fdf5a91570bd17afc9f4b7dc11c23 OK 2026/09/25 04:00:45 DEBUG : one-49.txt: md5 = 240fb0d253a3b91f84d72b1290378c37 OK 2026/09/25 04:00:46 DEBUG : one-50.txt: md5 = 7a1e21a84ab85aa3934e0ce19c111359 OK 2026/09/25 04:00:48 DEBUG : one-51.txt: md5 = d68ac3db2e36cc5357a1c37f4ade28ba OK 2026/09/25 04:00:49 DEBUG : one-52.txt: md5 = 182b6b21db1e7ee7fc9f182f383373bb OK 2026/09/25 04:00:51 DEBUG : one-53.txt: md5 = 7962cbacac3596b8a5fc22706e605599 OK 2026/09/25 04:00:52 DEBUG : one-54.txt: md5 = b18a56b9f7290cba6cd0c6b0c82d7fa3 OK 2026/09/25 04:00:53 DEBUG : one-55.txt: md5 = 3d7faeefb4fddc2d59f7711cce4f3276 OK 2026/09/25 04:00:55 DEBUG : one-56.txt: md5 = 7095a58258331f97dab7d90c2628907e OK 2026/09/25 04:00:56 DEBUG : one-57.txt: md5 = a4763d0a6285168d4dab997d513dc5b0 OK 2026/09/25 04:00:58 DEBUG : one-58.txt: md5 = fe4a3f5d7db973cf9077ec22f1886c6a OK 2026/09/25 04:00:59 DEBUG : one-59.txt: md5 = b135197eb93e574d805713ec77624a92 OK 2026/09/25 04:01:01 DEBUG : one-60.txt: md5 = 1451b4ccf84f0049299b3f0e73120c98 OK 2026/09/25 04:01:03 DEBUG : one-61.txt: md5 = e5f80302a70725722bd2bd12176de618 OK 2026/09/25 04:01:04 DEBUG : one-62.txt: md5 = 74382deb7fba5454b7ba68c5d2037179 OK 2026/09/25 04:01:06 DEBUG : one-63.txt: md5 = 78cc0570391688aa2177e9fa9561f9da OK 2026/09/25 04:01:07 DEBUG : one-64.txt: md5 = 79c430de067ad3b212314f5ea6d22ddf OK 2026/09/25 04:01:08 DEBUG : one-65.txt: md5 = b3d1676a556fa389b8c787a04ba51b1b OK 2026/09/25 04:01:10 DEBUG : one-66.txt: md5 = 74c9e5617dfafa522b2099b930092bcc OK 2026/09/25 04:01:11 DEBUG : one-67.txt: md5 = 68a4f45ecc38a6c8c06608e8dcd85656 OK 2026/09/25 04:01:13 DEBUG : one-68.txt: md5 = 1a48284c78dce5f1c98307e93a2ef820 OK 2026/09/25 04:01:14 DEBUG : one-69.txt: md5 = 2adac1bd6954a6c0327e9d3d6f934f1e OK 2026/09/25 04:01:16 DEBUG : one-70.txt: md5 = c30c4ac3ab92d682938429bc56cbedd7 OK 2026/09/25 04:01:17 DEBUG : one-71.txt: md5 = eef311220dd9b02e25be1f6c68d540b5 OK 2026/09/25 04:01:18 DEBUG : one-72.txt: md5 = fcd4e0c74dbcc92a5a7e0d8d3e52bfdc OK 2026/09/25 04:01:20 DEBUG : one-73.txt: md5 = 88707c7622a1f27928fa6b6f1c14fca8 OK 2026/09/25 04:01:21 DEBUG : one-74.txt: md5 = dd7a3be327ac51bff9c1445d9e0870f0 OK 2026/09/25 04:01:23 DEBUG : one-75.txt: md5 = a24dad1432f887977f73f12daf812c2a OK 2026/09/25 04:01:25 DEBUG : one-76.txt: md5 = 17a334a3cc9cc3d705f02b18bf6f03c0 OK 2026/09/25 04:01:26 DEBUG : one-77.txt: md5 = 9fe39f89dfe9ddec3973316bf286141d OK 2026/09/25 04:01:28 DEBUG : one-78.txt: md5 = 4ce299154d009e811ef9b9b7e773c913 OK 2026/09/25 04:01:29 DEBUG : one-79.txt: md5 = 4b4dd6569b395abebc5d20e52413cda3 OK 2026/09/25 04:01:31 DEBUG : one-80.txt: md5 = e7456ba96a1cfd62a4d89ecf019facab OK 2026/09/25 04:01:32 DEBUG : one-81.txt: md5 = 252aac3d5630aed2e2090e8c6951f7a1 OK 2026/09/25 04:01:34 DEBUG : one-82.txt: md5 = 877944085f6a44d532eabcade64f580f OK 2026/09/25 04:01:35 DEBUG : one-83.txt: md5 = c2dddf5a4f1c2cbc8f95057e7f042f46 OK 2026/09/25 04:01:37 DEBUG : one-84.txt: md5 = 9d116dc6c7b8549234f62ac0c5fc4bfc OK 2026/09/25 04:01:38 DEBUG : one-85.txt: md5 = 180e5f53833245952d6e870adbf4745f OK 2026/09/25 04:01:40 DEBUG : one-86.txt: md5 = 41d521ad6f8f509447737a41955ceb03 OK 2026/09/25 04:01:41 DEBUG : one-87.txt: md5 = a7fde0e9bb2488d4fde35056b9c0c252 OK 2026/09/25 04:01:43 DEBUG : one-88.txt: md5 = 75169c7bfb644855c24e5b9f91776ee1 OK 2026/09/25 04:01:44 DEBUG : one-89.txt: md5 = bc8242e29ec93755346d4f3226121e29 OK 2026/09/25 04:01:46 DEBUG : one-90.txt: md5 = 5122eb57487112abc2cc7496c6d3e2d9 OK 2026/09/25 04:01:47 DEBUG : one-91.txt: md5 = b50d063e23bb331f93e08a4dc4e9d7a4 OK 2026/09/25 04:01:49 DEBUG : one-92.txt: md5 = 0932fe24880b55191d60ab45dafe2341 OK 2026/09/25 04:01:50 DEBUG : one-93.txt: md5 = 7732612e28a7dee50d720decd5fcddfb OK 2026/09/25 04:01:52 DEBUG : one-94.txt: md5 = 216a80ca5f086224b04378fcc9454b96 OK 2026/09/25 04:01:53 DEBUG : one-95.txt: md5 = dfaad083e819ee130176846684e44f30 OK 2026/09/25 04:01:54 DEBUG : one-96.txt: md5 = e17a5e451426be6eb185d1d2e7561b81 OK 2026/09/25 04:01:56 DEBUG : one-97.txt: md5 = 4df3f1d3f0a5f107818c99fa64a8c036 OK 2026/09/25 04:01:58 DEBUG : one-98.txt: md5 = adf0bbdd51208d54d0fae2ef4623abbc OK 2026/09/25 04:02:00 DEBUG : one-99.txt: md5 = 5be42fad49ff5872fba3e88acb4213cc OK 2026/09/25 04:02:01 DEBUG : one-100.txt: md5 = 910fb58543efa7a8cb26df29332fc611 OK 2026/09/25 04:02:02 DEBUG : one-101.txt: md5 = f3e576411a37de2a002479c8bca5638c OK 2026/09/25 04:02:04 DEBUG : one-102.txt: md5 = c373badef6c7ac3691c9336b8de68a53 OK 2026/09/25 04:02:05 DEBUG : one-103.txt: md5 = 9a6a06caed2daf350f1799c547f61e84 OK 2026/09/25 04:02:07 DEBUG : one-104.txt: md5 = ef866af7cd06a40b79d0587dcd33e14a OK 2026/09/25 04:02:08 DEBUG : one-105.txt: md5 = ab184a3e5bd6a4faf5a7618dcb38e46d OK 2026/09/25 04:02:11 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Looking for duplicate names using rename mode. 2026/09/25 04:02:12 NOTICE: one.txt: Found 2 files with duplicate names 2026/09/25 04:02:12 INFO : one-106.txt: renamed from: one.txt 2026/09/25 04:02:13 INFO : one-107.txt: renamed from: one.txt --- PASS: TestDeduplicateRenameManyExisting (209.70s) === RUN TestMergeDirs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:03:05 DEBUG : dupe1/one.txt: md5 = 04294b55fd4b01da16740fe87883ed02 OK 2026/09/25 04:03:07 DEBUG : dupe2/two.txt: md5 = 988e27b80bfd552ca013cca779911060 OK 2026/09/25 04:03:09 DEBUG : dupe3/three.txt: md5 = ba3d60aaf88d91712ff9e7f57b18c8f0 OK 2026/09/25 04:03:10 INFO : urj8gsducqbtvekeq181ntd2u8: merging "sfrom47189m9qr3mt4qonu281c" 2026/09/25 04:03:11 INFO : urj8gsducqbtvekeq181ntd2u8: removing empty directory 2026/09/25 04:03:12 INFO : 1u1r4ei7c628fnjg9blqt3j60o: merging "tr2hj63d80ftlmvm6a952snjcc" 2026/09/25 04:03:12 INFO : 1u1r4ei7c628fnjg9blqt3j60o: removing empty directory --- PASS: TestMergeDirs (13.50s) === RUN TestListDirSorted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:03:18 DEBUG : a.txt: md5 = 868016b22fc097ac6115b0f474d9eb5d OK 2026/09/25 04:03:19 DEBUG : zend.txt: md5 = 7968d6b489dc851fd96b5ea2f726fa34 OK 2026/09/25 04:03:21 DEBUG : sub dir/hello world: md5 = 3b4aae695922fabc758d6cd29c725c58 OK 2026/09/25 04:03:23 DEBUG : sub dir/hello world2: md5 = f16a42caaa1f5edad69d8c17fe406ed0 OK 2026/09/25 04:03:25 DEBUG : sub dir/ignore dir/.ignore: md5 = c3c32fbd0fafe28795f72b5767a5032f OK 2026/09/25 04:03:27 DEBUG : sub dir/ignore dir/should be ignored: md5 = 8873921c8967a99e4c1239bac1096ba2 OK 2026/09/25 04:03:29 DEBUG : sub dir/sub sub dir/hello world3: md5 = 5cc10de4200f60445ef29f60e0909d6b OK 2026/09/25 04:03:30 DEBUG : a.txt: Excluded (Size Filter) 2026/09/25 04:03:30 DEBUG : a.txt: Excluded 2026/09/25 04:03:31 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/25 04:03:31 DEBUG : sub dir/hello world2: Excluded 2026/09/25 04:03:31 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/25 04:03:31 DEBUG : sub dir/hello world: Excluded 2026/09/25 04:03:31 DEBUG : sub dir/ignore dir: Excluded 2026/09/25 04:03:31 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/25 04:03:31 DEBUG : sub dir/hello world2: Excluded 2026/09/25 04:03:31 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/25 04:03:31 DEBUG : sub dir/hello world: Excluded 2026/09/25 04:03:32 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (22.02s) === RUN TestListDirSortedFn run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:03:40 DEBUG : a.txt: md5 = 50e970fd41db4e486dd48f43cff501fd OK 2026/09/25 04:03:41 DEBUG : zend.txt: md5 = d71279998643ef722c30b320bfea6fce OK 2026/09/25 04:03:44 DEBUG : sub dir/hello world: md5 = 3f2d38946118f96a37262f05567bc2e0 OK 2026/09/25 04:03:45 DEBUG : sub dir/hello world2: md5 = 5c7a9b47c4fad5a3cde81a100f2b2a61 OK 2026/09/25 04:03:47 DEBUG : sub dir/ignore dir/.ignore: md5 = 1f7a5b3046a93e663c225ac4eb00c920 OK 2026/09/25 04:03:49 DEBUG : sub dir/ignore dir/should be ignored: md5 = 1869b9812a94772be3ed42fd947df8e2 OK 2026/09/25 04:03:51 DEBUG : sub dir/sub sub dir/hello world3: md5 = 6a7612ce206ba38746dc9a033fc3f52a OK 2026/09/25 04:03:52 DEBUG : a.txt: Excluded (Size Filter) 2026/09/25 04:03:52 DEBUG : a.txt: Excluded 2026/09/25 04:03:53 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/25 04:03:53 DEBUG : sub dir/hello world: Excluded 2026/09/25 04:03:53 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/25 04:03:53 DEBUG : sub dir/hello world2: Excluded 2026/09/25 04:03:53 DEBUG : sub dir/ignore dir: Excluded 2026/09/25 04:03:53 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/09/25 04:03:53 DEBUG : sub dir/hello world: Excluded 2026/09/25 04:03:53 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/09/25 04:03:53 DEBUG : sub dir/hello world2: Excluded 2026/09/25 04:03:53 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSortedFn (21.56s) === RUN TestListJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:01 DEBUG : file1: md5 = 1832264d6605a6e79fa1008db58c3564 OK 2026/09/25 04:04:03 DEBUG : sub/file2: md5 = d52ca6def77d1294dd4ad67f5e385aa8 OK === RUN TestListJSON/Default === RUN TestListJSON/FilesOnly === RUN TestListJSON/DirsOnly === RUN TestListJSON/Recurse === RUN TestListJSON/SubDir === RUN TestListJSON/NoModTime === RUN TestListJSON/NoMimeType === RUN TestListJSON/ShowHash === RUN TestListJSON/HashTypes 2026/09/25 04:04:06 ERROR : file1: Failed to read hash: hash type not supported === RUN TestListJSON/Metadata 2026/09/25 04:04:06 DEBUG : eer8kka55qnghc34cq76ca668g: Fetching metadata 2026/09/25 04:04:07 DEBUG : 150fuo3cn4j1uenq6r4g4qk6t4: Fetching metadata --- PASS: TestListJSON (9.41s) --- PASS: TestListJSON/Default (0.24s) --- PASS: TestListJSON/FilesOnly (0.25s) --- PASS: TestListJSON/DirsOnly (0.24s) --- PASS: TestListJSON/Recurse (0.46s) --- PASS: TestListJSON/SubDir (0.27s) --- PASS: TestListJSON/NoModTime (0.25s) --- PASS: TestListJSON/NoMimeType (0.23s) --- PASS: TestListJSON/ShowHash (0.22s) --- PASS: TestListJSON/HashTypes (0.24s) --- PASS: TestListJSON/Metadata (0.66s) === RUN TestStatJSON run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:11 DEBUG : file1: md5 = 6daf0570cc0a4cc7c658cf11d33ed1e6 OK 2026/09/25 04:04:13 DEBUG : sub/file2: md5 = 5af5d0b93b59022e8f36c473fd08d0c7 OK === RUN TestStatJSON/Root === RUN TestStatJSON/RootFilesOnly === RUN TestStatJSON/RootDirsOnly === RUN TestStatJSON/Dir === RUN TestStatJSON/DirWithTrailingSlash === RUN TestStatJSON/File === RUN TestStatJSON/NotFound === RUN TestStatJSON/DirFilesOnly === RUN TestStatJSON/FileFilesOnly === RUN TestStatJSON/NotFoundFilesOnly === RUN TestStatJSON/DirDirsOnly === RUN TestStatJSON/FileDirsOnly === RUN TestStatJSON/NotFoundDirsOnly === RUN TestStatJSON/RootNotFound 2026/09/25 04:04:17 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/notfound" 2026/09/25 04:04:17 DEBUG : Config file has changed externally - reloading 2026/09/25 04:04:17 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/qe0i31qdkoejk60elps0ni5sqk" --- PASS: TestStatJSON (11.31s) --- PASS: TestStatJSON/Root (0.22s) --- PASS: TestStatJSON/RootFilesOnly (0.00s) --- PASS: TestStatJSON/RootDirsOnly (0.25s) --- PASS: TestStatJSON/Dir (0.55s) --- PASS: TestStatJSON/DirWithTrailingSlash (0.19s) --- PASS: TestStatJSON/File (0.23s) --- PASS: TestStatJSON/NotFound (0.55s) --- PASS: TestStatJSON/DirFilesOnly (0.24s) --- PASS: TestStatJSON/FileFilesOnly (0.23s) --- PASS: TestStatJSON/NotFoundFilesOnly (0.20s) --- PASS: TestStatJSON/DirDirsOnly (0.25s) --- PASS: TestStatJSON/FileDirsOnly (0.23s) --- PASS: TestStatJSON/NotFoundDirsOnly (0.25s) --- PASS: TestStatJSON/RootNotFound (1.63s) === RUN TestStatJSONMemory 2026/09/25 04:04:21 DEBUG : Creating backend with remote ":memory:" 2026/09/25 04:04:21 DEBUG : Memory root '': File to upload is small (5 bytes), uploading instead of streaming 2026/09/25 04:04:21 DEBUG : sub/file1: size = 5 OK 2026/09/25 04:04:21 DEBUG : sub/file1: md5 = 5d41402abc4b2a76b9719d911017c592 OK 2026/09/25 04:04:21 DEBUG : sub/file1: Size and md5 of src and dst objects identical === RUN TestStatJSONMemory/Dir === RUN TestStatJSONMemory/DirWithTrailingSlash === RUN TestStatJSONMemory/File === RUN TestStatJSONMemory/NotFound --- PASS: TestStatJSONMemory (0.00s) --- PASS: TestStatJSONMemory/Dir (0.00s) --- PASS: TestStatJSONMemory/DirWithTrailingSlash (0.00s) --- PASS: TestStatJSONMemory/File (0.00s) --- PASS: TestStatJSONMemory/NotFound (0.00s) === RUN TestStatJSONConfinement --- PASS: TestStatJSONConfinement (0.00s) === RUN TestMkdir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:21 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Making directory 2026/09/25 04:04:21 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Making directory --- PASS: TestMkdir (0.62s) === RUN TestLsd run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:23 DEBUG : sub dir/hello world: md5 = d7d9fef1677fd824fdb6405ca018dc2f OK --- PASS: TestLsd (4.45s) === RUN TestLs run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:28 DEBUG : potato2: md5 = 1335470a8a295df7fb09dd83315c736c OK 2026/09/25 04:04:29 DEBUG : empty space: md5 = 733483dd9df98da6d11b68d538265b13 OK --- PASS: TestLs (5.17s) === RUN TestLsWithFilesFrom run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:33 DEBUG : potato2: md5 = 994644ae72de5f215f2e423bbe39b7ec OK 2026/09/25 04:04:35 DEBUG : empty space: md5 = f93103544e94f1f6fa748f65e36eec3d OK 2026/09/25 04:04:35 DEBUG : empty space: Excluded (FilesFrom Filter) 2026/09/25 04:04:35 DEBUG : empty space: Excluded --- PASS: TestLsWithFilesFrom (6.19s) === RUN TestLsLong run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:38 DEBUG : potato2: md5 = 43d1e9d2bdd0244736f56c0ccc4df9c3 OK 2026/09/25 04:04:40 DEBUG : empty space: md5 = 9f9a7434f9f1ee269cffd307e3434842 OK --- PASS: TestLsLong (4.70s) === RUN TestHashSums run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:43 DEBUG : potato2: md5 = d09ed0e99aef2950d6a6b90023056844 OK 2026/09/25 04:04:44 DEBUG : empty space: md5 = 5fe78ff76ae0e00567e6337871d03e32 OK --- PASS: TestHashSums (4.36s) === RUN TestHashSumsWithErrors 2026/09/25 04:04:46 DEBUG : Creating backend with remote ":memory:" 2026/09/25 04:04:46 DEBUG : Config file has changed externally - reloading 2026/09/25 04:04:46 ERROR : file1: hash unsupported: hash type not supported 2026/09/25 04:04:46 ERROR : sub/file1: hash unsupported: hash type not supported 2026/09/25 04:04:46 ERROR : sub/file1: hash unsupported: hash type not supported --- PASS: TestHashSumsWithErrors (0.00s) === RUN TestHashStream 2026/09/25 04:04:46 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/25 04:04:46 DEBUG : Creating md5 hash of 0 bytes read from input stream 2026/09/25 04:04:46 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/25 04:04:46 DEBUG : Creating sha1 hash of 0 bytes read from input stream 2026/09/25 04:04:46 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/25 04:04:46 DEBUG : Creating md5 hash of 12 bytes read from input stream 2026/09/25 04:04:46 DEBUG : Creating sha1 hash of 12 bytes read from input stream 2026/09/25 04:04:46 DEBUG : Creating sha1 hash of 12 bytes read from input stream --- PASS: TestHashStream (0.00s) === RUN TestSuffixName --- PASS: TestSuffixName (0.00s) === RUN TestCount run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:51 DEBUG : potato2: md5 = c8c793ca1d61cc1b168e1ede32148477 OK 2026/09/25 04:04:52 DEBUG : empty space: md5 = ba7c0f34c1b2f6ae027da3bb198f040d OK 2026/09/25 04:04:55 DEBUG : sub dir/potato3: md5 = 308edd275f5fd5f2ee562ff5f3534d4f OK --- PASS: TestCount (11.93s) === RUN TestDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:04:59 DEBUG : small: md5 = 55af88b73df653799716df6d8e44f2f4 OK 2026/09/25 04:05:01 DEBUG : medium: md5 = 66d1eb7a6c44b4aaf5ee4c6a56d7c321 OK 2026/09/25 04:05:02 DEBUG : large: md5 = 2eae10960608dd92e04b8d625d00c00b OK 2026/09/25 04:05:02 DEBUG : Waiting for deletions to finish 2026/09/25 04:05:03 DEBUG : large: Excluded (Size Filter) 2026/09/25 04:05:03 INFO : small: Deleted 2026/09/25 04:05:03 INFO : medium: Deleted --- PASS: TestDelete (6.15s) === RUN TestDeleteFatalError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:05:06 DEBUG : file0: md5 = f2ff7f372ee850fe6aaa5769bd44d129 OK 2026/09/25 04:05:07 DEBUG : file1: md5 = 4289cf84a8c87d277a3e8466fd92ee78 OK 2026/09/25 04:05:08 DEBUG : file2: md5 = d21fa97741eed0bce40e2022ebd7e356 OK 2026/09/25 04:05:10 DEBUG : file3: md5 = eb6c7cb342216180ebfc24d27468a16a OK 2026/09/25 04:05:11 DEBUG : file4: md5 = 9cb1425bb5b29245a7f69cfa037d3eeb OK 2026/09/25 04:05:13 DEBUG : file5: md5 = 30836787ad6b449ae422498d406eff55 OK 2026/09/25 04:05:14 DEBUG : file6: md5 = 880f88ec6838ad38793f716e992a9fe2 OK 2026/09/25 04:05:16 DEBUG : file7: md5 = 5e490f7a524e830181fc42e76edb03b3 OK 2026/09/25 04:05:17 DEBUG : file8: md5 = 4be95d421c194a79fccf530dd3d80aee OK 2026/09/25 04:05:19 DEBUG : file9: md5 = 19a4d3b99b5d3155d9e7f67ac085a281 OK 2026/09/25 04:05:20 DEBUG : file10: md5 = 9736a3123f10e1ba430760318d7a141c OK 2026/09/25 04:05:21 DEBUG : file11: md5 = 8dbefda74c410d35e7d00748920440a4 OK 2026/09/25 04:05:23 DEBUG : file12: md5 = 966741dbe491a02fcf0da60c5076332c OK 2026/09/25 04:05:24 DEBUG : file13: md5 = 2c60dfd6e4a76881bafca4b65577cb6a OK 2026/09/25 04:05:26 DEBUG : file14: md5 = 7dccfb4e221802f1697ecd271d18a067 OK 2026/09/25 04:05:27 DEBUG : file15: md5 = f326b783b03157bf3335d30e8d8afdd1 OK 2026/09/25 04:05:28 DEBUG : file16: md5 = d1186e6d745bab055d29c1981bedcda8 OK 2026/09/25 04:05:30 DEBUG : file17: md5 = eaf0a8c8f94abdb4a1028e892f45a700 OK 2026/09/25 04:05:31 DEBUG : file18: md5 = 3c7f852b6d9c6cfcd641174145fb5be1 OK 2026/09/25 04:05:32 DEBUG : file19: md5 = 975ddd49ef0741616c6f5d6a9e191c1d OK 2026/09/25 04:05:32 DEBUG : Waiting for deletions to finish 2026/09/25 04:05:33 ERROR : file10: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file12: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file3: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file19: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file17: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file14: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file16: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file7: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file13: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file0: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file6: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file8: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file11: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file1: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file9: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file5: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file18: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file2: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file4: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:33 ERROR : file15: Got fatal error on delete: --max-delete threshold reached --- PASS: TestDeleteFatalError (37.59s) === RUN TestMaxDelete run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:05:43 DEBUG : small: md5 = d9dda11bffc5bfe6a55ab9ca42248c4c OK 2026/09/25 04:05:45 DEBUG : medium: md5 = e851519e44f8bf09e5803a54c68f0a6c OK 2026/09/25 04:05:46 DEBUG : large: md5 = d14738416bef402e4d4cf0a691b5cecc OK 2026/09/25 04:05:46 DEBUG : Waiting for deletions to finish 2026/09/25 04:05:47 ERROR : medium: Got fatal error on delete: --max-delete threshold reached 2026/09/25 04:05:47 INFO : large: Deleted 2026/09/25 04:05:47 INFO : small: Deleted --- PASS: TestMaxDelete (7.49s) === RUN TestMaxDeleteSizeLargeFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:05:51 DEBUG : small: md5 = 13f82775b88802e7140e1f3e6e68d5a3 OK 2026/09/25 04:05:52 DEBUG : medium: md5 = 0c2762dc2be6f8f03cd7a3c57213a2a8 OK 2026/09/25 04:05:53 DEBUG : large: md5 = 6246b755eff6be7bf39ff94137a11e59 OK 2026/09/25 04:05:54 DEBUG : Waiting for deletions to finish 2026/09/25 04:05:54 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/25 04:05:54 INFO : small: Deleted 2026/09/25 04:05:55 INFO : medium: Deleted --- PASS: TestMaxDeleteSizeLargeFile (7.04s) === RUN TestMaxDeleteSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:05:58 DEBUG : small: md5 = 362f4cb41fd1fd1db47dd27154b8ed9c OK 2026/09/25 04:05:59 DEBUG : medium: md5 = 6ffd956e52552e7b3dee704f350452dd OK 2026/09/25 04:06:00 DEBUG : large: md5 = 657c1b991e60e6f3156f93f36a5462cf OK 2026/09/25 04:06:01 DEBUG : Waiting for deletions to finish 2026/09/25 04:06:01 ERROR : large: Got fatal error on delete: --max-delete-size threshold reached 2026/09/25 04:06:01 INFO : medium: Deleted 2026/09/25 04:06:01 INFO : small: Deleted --- PASS: TestMaxDeleteSize (6.84s) === RUN TestReadFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:06:04 DEBUG : ReadFile: md5 = ce09b667d5d59a55c9ac7d0765e3f781 OK --- PASS: TestReadFile (3.78s) === RUN TestRetry 2026/09/25 04:06:07 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 1/5 2026/09/25 04:06:07 DEBUG : Received error: Wrapped EOF is retriable: EOF - low level retry 2/5 2026/09/25 04:06:07 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/25 04:06:07 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/25 04:06:07 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms 2026/09/25 04:06:07 DEBUG : Sleeping for 10ms (as indicated by the server) to obey Retry-After error: BANG: trying again in 10ms --- PASS: TestRetry (0.04s) === RUN TestRetryAfterContextCancel 2026/09/25 04:06:07 DEBUG : Sleeping for 1h0m0s (as indicated by the server) to obey Retry-After error: BANG: trying again in 1h0m0s --- PASS: TestRetryAfterContextCancel (0.00s) === RUN TestRetryAfterLastTry --- PASS: TestRetryAfterLastTry (0.00s) === RUN TestCat run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:06:08 DEBUG : file1: md5 = d708411a8e85182a6a0900d4fab6a602 OK 2026/09/25 04:06:10 DEBUG : file2: md5 = acd797ec94dc76720b6bd78a70908c9b OK --- PASS: TestCat (13.78s) === RUN TestPurge 2026/09/25 04:06:21 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-pogezar7hica" 2026/09/25 04:06:21 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/25 04:06:21 DEBUG : Creating backend with remote "TestDrive:crypt/ctd377u249elkm6i8jrr1nkvalqvrfoqi79802niv4mc4kqo2j20" 2026/09/25 04:06:22 DEBUG : Creating backend with remote "/tmp/rclone4019132697" run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-pogezar7hica'", Local "Local file system at /tmp/rclone4019132697", Modify Window "1ms" 2026/09/25 04:06:26 DEBUG : A1/B1/C1/one: md5 = 844cccc131d5693db43c2eb9b2dd29dc OK 2026/09/25 04:06:26 INFO : A2: Making directory 2026/09/25 04:06:27 INFO : A1/B2: Making directory 2026/09/25 04:06:27 INFO : A1/B2/C2: Making directory 2026/09/25 04:06:28 INFO : A1/B1/C3: Making directory 2026/09/25 04:06:29 INFO : A3: Making directory 2026/09/25 04:06:30 INFO : A3/B3: Making directory 2026/09/25 04:06:30 INFO : A3/B3/C4: Making directory 2026/09/25 04:06:33 DEBUG : A1/two: md5 = b905a32884908e190810ccf5186428c7 OK 2026/09/25 04:06:36 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-pogezar7hica': Purge remote 2026/09/25 04:06:36 NOTICE: purge failed: directory not found --- PASS: TestPurge (15.63s) === RUN TestRmdirsNoLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:06:40 DEBUG : A1/B1/C1/one: md5 = cc85959982f05b39926d4828892d33ff OK 2026/09/25 04:06:40 INFO : A2: Making directory 2026/09/25 04:06:41 INFO : A1/B2: Making directory 2026/09/25 04:06:41 INFO : A1/B2/C2: Making directory 2026/09/25 04:06:42 INFO : A1/B1/C3: Making directory 2026/09/25 04:06:43 INFO : A3: Making directory 2026/09/25 04:06:44 INFO : A3/B3: Making directory 2026/09/25 04:06:44 INFO : A3/B3/C4: Making directory 2026/09/25 04:06:46 DEBUG : A1/two: md5 = 0b785f3dd24c54269b2ad9f3de32ce44 OK 2026/09/25 04:06:48 DEBUG : removing 1 level 3 directories 2026/09/25 04:06:48 INFO : A3/B3/C4: Removing directory 2026/09/25 04:06:50 DEBUG : removing 2 level 3 directories 2026/09/25 04:06:50 INFO : A1/B2/C2: Removing directory 2026/09/25 04:06:50 INFO : A1/B1/C3: Removing directory 2026/09/25 04:06:51 DEBUG : removing 2 level 2 directories 2026/09/25 04:06:51 INFO : A3/B3: Removing directory 2026/09/25 04:06:51 INFO : A1/B2: Removing directory 2026/09/25 04:06:51 DEBUG : removing 2 level 1 directories 2026/09/25 04:06:51 INFO : A3: Removing directory 2026/09/25 04:06:51 INFO : A2: Removing directory 2026/09/25 04:06:55 DEBUG : removing 1 level 3 directories 2026/09/25 04:06:55 INFO : A1/B1/C1: Removing directory 2026/09/25 04:06:56 DEBUG : removing 1 level 2 directories 2026/09/25 04:06:56 INFO : A1/B1: Removing directory 2026/09/25 04:06:57 DEBUG : removing 1 level 1 directories 2026/09/25 04:06:57 INFO : A1: Removing directory 2026/09/25 04:06:57 DEBUG : removing 1 level 0 directories 2026/09/25 04:06:57 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Removing directory --- PASS: TestRmdirsNoLeaveRoot (22.48s) === RUN TestRmdirsLeaveRoot run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:06:59 INFO : A1: Making directory 2026/09/25 04:07:00 INFO : A1/B1: Making directory 2026/09/25 04:07:01 INFO : A1/B1/C1: Making directory 2026/09/25 04:07:04 DEBUG : removing 1 level 3 directories 2026/09/25 04:07:04 INFO : A1/B1/C1: Removing directory 2026/09/25 04:07:04 DEBUG : removing 1 level 2 directories 2026/09/25 04:07:04 INFO : A1/B1: Removing directory --- PASS: TestRmdirsLeaveRoot (8.40s) === RUN TestRmdirsWithFilter run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:07:07 INFO : A1: Making directory 2026/09/25 04:07:08 INFO : A1/B1: Making directory 2026/09/25 04:07:09 INFO : A1/B1/C1: Making directory 2026/09/25 04:07:11 DEBUG : removing 1 level 3 directories 2026/09/25 04:07:11 INFO : A1/B1/C1: Removing directory 2026/09/25 04:07:12 DEBUG : removing 1 level 2 directories 2026/09/25 04:07:12 INFO : A1/B1: Removing directory --- PASS: TestRmdirsWithFilter (7.39s) === RUN TestCopyURL run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:07:16 DEBUG : file1: md5 = 8fd1418479007e4936ee25b8c76e13a0 OK 2026/09/25 04:07:16 DEBUG : file1: size = 14 OK 2026/09/25 04:07:17 DEBUG : filename.txt: File name found in url 2026/09/25 04:07:18 DEBUG : filename.txt: md5 = 6d786bf8df9d1a5996458da36bea0872 OK 2026/09/25 04:07:18 DEBUG : filename.txt: size = 14 OK 2026/09/25 04:07:18 DEBUG : headerfilename.txt: filename found in Content-Disposition header. 2026/09/25 04:07:19 DEBUG : headerfilename.txt: md5 = 90f0cb2d9201cb4c54fbd628a98efcb3 OK 2026/09/25 04:07:19 DEBUG : headerfilename.txt: size = 14 OK 2026/09/25 04:07:21 DEBUG : file2: md5 = a78fb11433282c709f6a115f3d0280cd OK 2026/09/25 04:07:21 DEBUG : file2: size = 14 OK --- PASS: TestCopyURL (8.62s) === RUN TestCopyURLDownloadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:07:25 DEBUG : file1: md5 = 5ac1b10abe4e06f29464bcda9be3d27d OK 2026/09/25 04:07:25 DEBUG : file1: size = 14 OK --- PASS: TestCopyURLDownloadHeaders (2.34s) === RUN TestCopyURLToWriter --- PASS: TestCopyURLToWriter (0.00s) === RUN TestMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:07:26 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/25 04:07:28 DEBUG : sub/file2: md5 = 3fbe428ae0c52ac050801d552a073de2 OK 2026/09/25 04:07:28 DEBUG : sub/file2: size = 14 OK 2026/09/25 04:07:28 INFO : file1: Copied (new) to: sub/file2 2026/09/25 04:07:28 INFO : file1: Deleted 2026/09/25 04:07:29 DEBUG : sub/file2: size = 14 OK 2026/09/25 04:07:29 DEBUG : file1: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 04:07:29 DEBUG : file1: Unchanged skipping 2026/09/25 04:07:29 INFO : file1: Deleted 2026/09/25 04:07:30 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': don't need to copy/move sub/file2, it is already at target location --- PASS: TestMoveFile (6.40s) === RUN TestMoveFileWithIgnoreExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:07:32 DEBUG : file1: Need to transfer - File not found at Destination 2026/09/25 04:07:34 DEBUG : file1: md5 = 95df368c30c65f520a8fd9b79e8a399e OK 2026/09/25 04:07:34 DEBUG : file1: size = 14 OK 2026/09/25 04:07:34 INFO : file1: Copied (new) 2026/09/25 04:07:34 INFO : file1: Deleted 2026/09/25 04:07:35 DEBUG : file1: Destination exists, skipping 2026/09/25 04:07:35 DEBUG : file1: Not removing source file as destination file exists and --ignore-existing is set --- PASS: TestMoveFileWithIgnoreExisting (3.79s) === RUN TestMoveFileImmutable run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:07:37 DEBUG : existing: md5 = 02dd84549c85b8a95845e5d66f8a02c4 OK 2026/09/25 04:07:38 DEBUG : existing: size = 8 (Local file system at /tmp/rclone4104455662) 2026/09/25 04:07:38 DEBUG : existing: size = 6 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 2026/09/25 04:07:38 DEBUG : existing: Sizes differ 2026/09/25 04:07:38 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestMoveFileImmutable (2.98s) === RUN TestCaseInsensitiveMoveFile run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFile (0.46s) === RUN TestCaseInsensitiveMoveFileDryRun run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" --- PASS: TestCaseInsensitiveMoveFileDryRun (0.44s) === RUN TestMoveFileBackupDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:07:42 DEBUG : dst/file1: md5 = 6030d05a91ac06e9d04e941474a69d3f OK 2026/09/25 04:07:42 DEBUG : Creating backend with remote "TestCryptDrive:rclone-test-gotufus7gino/backup" 2026/09/25 04:07:42 DEBUG : Config file has changed externally - reloading 2026/09/25 04:07:42 DEBUG : Creating backend with remote "TestDrive:crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g/1nrff024r7pq65ecp72fc28jb0" 2026/09/25 04:07:44 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone4104455662) 2026/09/25 04:07:44 DEBUG : dst/file1: size = 18 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 2026/09/25 04:07:44 DEBUG : dst/file1: Sizes differ 2026/09/25 04:07:46 INFO : dst/file1: Moved (server-side) 2026/09/25 04:07:48 DEBUG : dst/file1: md5 = b062786900a9c542cd8454227aed8731 OK 2026/09/25 04:07:48 DEBUG : dst/file1: size = 14 OK 2026/09/25 04:07:48 INFO : dst/file1: Copied (new) 2026/09/25 04:07:48 INFO : dst/file1: Deleted --- PASS: TestMoveFileBackupDir (12.55s) === 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 TestDirMoveMoveError run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:07:54 DEBUG : A/file0: md5 = cd795cf2e3aa354344c96083174a747e OK 2026/09/25 04:07:56 DEBUG : A/file1: md5 = 3ebcd2780ac3981bdceac2754eed97f5 OK 2026/09/25 04:07:57 DEBUG : A/file2: md5 = 83b187bbb3aa07592ca8dc7a12468696 OK 2026/09/25 04:07:58 DEBUG : A/file3: md5 = 75d9f557ea84702bed533ca3436a7027 OK 2026/09/25 04:08:00 DEBUG : A/file4: md5 = cf08d1c6864eb5a12005b34ac6e59f29 OK 2026/09/25 04:08:01 DEBUG : A/file5: md5 = 5631f9b2aa3f1eedd770e96a4695052d OK 2026/09/25 04:08:03 DEBUG : A/file6: md5 = a6eaa5a95eef161011f21123612a7029 OK 2026/09/25 04:08:04 DEBUG : A/file7: md5 = 4f90446b24291f1a22611bdceb14f3d2 OK 2026/09/25 04:08:05 DEBUG : A/file8: md5 = f5942ff6ea3bacd43bd6c4eb80b2f45d OK 2026/09/25 04:08:07 DEBUG : A/file9: md5 = 7d9f3ceb2a9fba84e559c79be96f3653 OK 2026/09/25 04:08:09 DEBUG : A/file10: md5 = 2cbfb5c146fdfe0f2694d761a7153a6c OK 2026/09/25 04:08:11 DEBUG : A/file11: md5 = f0f92511660cb59501ebb8143bba9566 OK 2026/09/25 04:08:12 DEBUG : A/file12: md5 = 09dde64a3098e8886d230faef3a66a5e OK 2026/09/25 04:08:13 DEBUG : A/file13: md5 = 7f95d29341b8ce49e064b8da0ecb2588 OK 2026/09/25 04:08:15 DEBUG : A/file14: md5 = e21fac26b032d15b89ff3f2728f5ddd5 OK 2026/09/25 04:08:16 DEBUG : A/file15: md5 = 3d29ca0383ffaccbcf006f44061757f4 OK 2026/09/25 04:08:18 DEBUG : A/file16: md5 = 317d8da5bf7ccf8ce80812e44714c4f8 OK 2026/09/25 04:08:19 DEBUG : A/file17: md5 = d0bb0ce335196740a9de7fef35ac4a55 OK 2026/09/25 04:08:20 DEBUG : A/file18: md5 = 503f192ef7ce25586f194f0263e65c84 OK 2026/09/25 04:08:22 DEBUG : A/file19: md5 = b10c2cc455dd8ee57edb98e8a14e93c0 OK operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveMoveError (40.25s) === RUN TestDirMoveContext run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:08:34 DEBUG : A/one: md5 = 9ddc2ab43128983716dcf131608a5d63 OK 2026/09/25 04:08:36 DEBUG : A/two: md5 = 20ef34abb9f7220bbadd6d2686228a62 OK operations_test.go:1490: Skipping test on non local remote --- SKIP: TestDirMoveContext (6.21s) === RUN TestDirMove run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:08:41 DEBUG : A1/one: md5 = 9b76c2298edd33be172bd59cd88856dc OK 2026/09/25 04:08:42 DEBUG : A1/two: md5 = bea1f0137005c0742a8dce1ba064b682 OK 2026/09/25 04:08:44 DEBUG : A1/B1/three: md5 = 11f294ef15221aec9e61525d974331b2 OK 2026/09/25 04:08:46 DEBUG : A1/B1/C1/four: md5 = a2de668f753d3e581e0d77d4a81ec7e5 OK 2026/09/25 04:08:48 DEBUG : A1/B1/C2/five: md5 = b2073f9ef0dbf7b442f478d023d07572 OK 2026/09/25 04:08:48 INFO : A1/B2: Making directory 2026/09/25 04:08:49 INFO : A1/B1/C3: Making directory 2026/09/25 04:08:58 INFO : A2/B1/C2/five: Moved (server-side) to: A3/B1/C2/five 2026/09/25 04:08:58 INFO : A2/one: Moved (server-side) to: A3/one 2026/09/25 04:08:58 INFO : A2/B1/C1/four: Moved (server-side) to: A3/B1/C1/four 2026/09/25 04:08:59 INFO : A2/two: Moved (server-side) to: A3/two 2026/09/25 04:08:59 INFO : A2/B1/three: Moved (server-side) to: A3/B1/three 2026/09/25 04:09:04 INFO : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Can't DirMove - falling back to file moves: can't move directory - incompatible remotes 2026/09/25 04:09:09 INFO : A3/B1/C1/four: Moved (server-side) to: A4/B1/C1/four 2026/09/25 04:09:10 INFO : A3/B1/three: Moved (server-side) to: A4/B1/three 2026/09/25 04:09:10 INFO : A3/one: Moved (server-side) to: A4/one 2026/09/25 04:09:10 INFO : A3/two: Moved (server-side) to: A4/two 2026/09/25 04:09:10 INFO : A3/B1/C2/five: Moved (server-side) to: A4/B1/C2/five --- PASS: TestDirMove (44.26s) === RUN TestGetFsInfo run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:09:23 DEBUG : Config file has changed externally - reloading --- PASS: TestGetFsInfo (0.48s) === RUN TestRcat === RUN TestRcat/withChecksum=false,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:09:24 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': File to upload is small (34 bytes), uploading instead of streaming 2026/09/25 04:09:25 DEBUG : no_checksum_small_file_from_pipe: md5 = dffaeee1e7368ebaae95d777d4baf0d7 OK 2026/09/25 04:09:25 DEBUG : no_checksum_small_file_from_pipe: size = 34 OK 2026/09/25 04:09:25 NOTICE: Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': --checksum is in use but the source and destination have no hashes in common; falling back to --size-only 2026/09/25 04:09:25 DEBUG : no_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/25 04:09:26 DEBUG : qc9k4uauqa9ls6a2r6lo8jf4b1m7r0510pkurois1cc70egbs4h0: Sending chunk 0 length 102465 2026/09/25 04:09:27 DEBUG : no_checksum_big_file_from_pipe: md5 = 27683847fbccbf3e0f963eafcc3330fb OK 2026/09/25 04:09:27 DEBUG : no_checksum_big_file_from_pipe: size = 102401 OK 2026/09/25 04:09:27 DEBUG : no_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=true,ignoreChecksum=false run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:09:29 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': File to upload is small (34 bytes), uploading instead of streaming 2026/09/25 04:09:30 DEBUG : with_checksum_small_file_from_pipe: md5 = d0a276603196ae24cbd5b8fb643f0a3e OK 2026/09/25 04:09:30 DEBUG : with_checksum_small_file_from_pipe: size = 34 OK 2026/09/25 04:09:30 DEBUG : with_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/25 04:09:31 DEBUG : e74jo8a7lr1aimg2u78fct67gnuetc1qujiofaci0c8fnq7rjt1d4i296pcklqhgdihs03etvgdi4: Sending chunk 0 length 102465 2026/09/25 04:09:32 DEBUG : with_checksum_big_file_from_pipe: md5 = a511155af407916b7714e165057bf65f OK 2026/09/25 04:09:32 DEBUG : with_checksum_big_file_from_pipe: size = 102401 OK 2026/09/25 04:09:32 DEBUG : with_checksum_big_file_from_pipe: Size of src and dst objects identical === RUN TestRcat/withChecksum=false,ignoreChecksum=true run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:09:34 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': File to upload is small (34 bytes), uploading instead of streaming 2026/09/25 04:09:35 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/25 04:09:35 DEBUG : ignore_checksum_small_file_from_pipe: Size and modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 04:09:36 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/09/25 04:09:37 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/25 04:09:37 DEBUG : ignore_checksum_big_file_from_pipe: Size and modification time the same (differ by -456.789µs, within tolerance 1ms) === RUN TestRcat/withChecksum=true,ignoreChecksum=true run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:09:39 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': File to upload is small (34 bytes), uploading instead of streaming 2026/09/25 04:09:40 DEBUG : ignore_checksum_small_file_from_pipe: size = 34 OK 2026/09/25 04:09:40 DEBUG : ignore_checksum_small_file_from_pipe: Size of src and dst objects identical 2026/09/25 04:09:41 DEBUG : 1963rn6olajfbjn4nbqi9nacu7bk879vpf0d2bg8r0ndcqqsfntob0urt056p0qujf574rqnb5f62: Sending chunk 0 length 102465 2026/09/25 04:09:42 DEBUG : ignore_checksum_big_file_from_pipe: size = 102401 OK 2026/09/25 04:09:42 DEBUG : ignore_checksum_big_file_from_pipe: Size of src and dst objects identical --- PASS: TestRcat (20.23s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=false (5.17s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=false (5.25s) --- PASS: TestRcat/withChecksum=false,ignoreChecksum=true (5.07s) --- PASS: TestRcat/withChecksum=true,ignoreChecksum=true (4.74s) === RUN TestRcatMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" === RUN TestRcatMetadata/Normal 2026/09/25 04:09:44 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': File to upload is small (48 bytes), uploading instead of streaming 2026/09/25 04:09:45 DEBUG : rcat_metadata: md5 = 873c7ac5a90de71ce91336c5bf8b8b1c OK 2026/09/25 04:09:45 DEBUG : rcat_metadata: size = 48 OK 2026/09/25 04:09:45 DEBUG : rcat_metadata: Size of src and dst objects identical === RUN TestRcatMetadata/ViaDisk 2026/09/25 04:09:47 DEBUG : rijssoobhbn85c5p1imhg55m6frank1nlplla6hkdjlqmqb2471g: Sending chunk 0 length 111 2026/09/25 04:09:48 DEBUG : rcat_metadata_uploadcutoff0: md5 = 3cd6249539477205bf86823b1fb553c6 OK 2026/09/25 04:09:48 DEBUG : rcat_metadata_uploadcutoff0: size = 63 OK 2026/09/25 04:09:48 DEBUG : rcat_metadata_uploadcutoff0: Size of src and dst objects identical --- PASS: TestRcatMetadata (6.60s) --- PASS: TestRcatMetadata/Normal (2.60s) --- PASS: TestRcatMetadata/ViaDisk (3.50s) === RUN TestRcatSize run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:09:52 DEBUG : potato1: md5 = 3346e4407726838ec4fc872a153f9ba8 OK 2026/09/25 04:09:52 DEBUG : potato1: size = 60 OK 2026/09/25 04:09:52 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': File to upload is small (60 bytes), uploading instead of streaming 2026/09/25 04:09:53 DEBUG : potato2: md5 = 500f3562cf79cd3d434863fe5ae6f1b2 OK 2026/09/25 04:09:53 DEBUG : potato2: size = 60 OK 2026/09/25 04:09:53 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSize (4.68s) === RUN TestRcatSizeShortEOF run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:09:56 DEBUG : potato1: md5 = 9558ebb0dd621d1968b245a01247164b OK 2026/09/25 04:09:56 DEBUG : potato1: size = 120 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 2026/09/25 04:09:56 DEBUG : potato1: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 2026/09/25 04:09:56 ERROR : potato1: corrupted on transfer: sizes differ src 120 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 60 2026/09/25 04:09:56 INFO : potato1: Removing failed copy --- PASS: TestRcatSizeShortEOF (2.29s) === RUN TestRcatSizeMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:09:58 DEBUG : potato1: md5 = bc1971a6f371e1e6c015e2eacb18a3db OK 2026/09/25 04:09:58 DEBUG : potato1: size = 60 OK 2026/09/25 04:09:58 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': File to upload is small (60 bytes), uploading instead of streaming 2026/09/25 04:10:00 DEBUG : potato2: md5 = d1b914bce375fa6fd42c3f41d439ab19 OK 2026/09/25 04:10:00 DEBUG : potato2: size = 60 OK 2026/09/25 04:10:00 DEBUG : potato2: Size of src and dst objects identical --- PASS: TestRcatSizeMetadata (6.13s) === RUN TestRcatSizeUploadHeaders run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:05 DEBUG : potato1: md5 = f12013e92e8b13db9f311d6f7a2a1c74 OK 2026/09/25 04:10:05 DEBUG : potato1: size = 60 OK --- PASS: TestRcatSizeUploadHeaders (2.41s) === RUN TestRcatSizeChecksum === RUN TestRcatSizeChecksum/Corrupted run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" operations_test.go:1975: Skipping as destination doesn't support hashes === RUN TestRcatSizeChecksum/SizeDiffers run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:08 DEBUG : potato4: md5 = 2316cf05120b7e0044a8f761d032b607 OK 2026/09/25 04:10:08 DEBUG : potato4: size = 60 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 2026/09/25 04:10:08 DEBUG : potato4: size = 59 (Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 2026/09/25 04:10:08 ERROR : potato4: corrupted on transfer: sizes differ src 60 vs dst(Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino') 59 2026/09/25 04:10:08 INFO : potato4: Removing failed copy === RUN TestRcatSizeChecksum/IgnoreChecksum run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:10 DEBUG : potato2: size = 60 OK === RUN TestRcatSizeChecksum/NoHashes run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:13 DEBUG : potato3: md5 = b785814c6d0482f4e2f10591678bf458 OK 2026/09/25 04:10:13 DEBUG : potato3: size = 60 OK --- PASS: TestRcatSizeChecksum (8.00s) --- SKIP: TestRcatSizeChecksum/Corrupted (0.48s) --- PASS: TestRcatSizeChecksum/SizeDiffers (2.52s) --- PASS: TestRcatSizeChecksum/IgnoreChecksum (2.58s) --- PASS: TestRcatSizeChecksum/NoHashes (2.42s) === RUN TestTouchDir run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:15 DEBUG : potato2: md5 = f967db0a07417193fe90f7e5386446bf OK 2026/09/25 04:10:16 DEBUG : empty space: md5 = 7c50a6a53d5e07ce88a945c8c7f122c5 OK 2026/09/25 04:10:19 DEBUG : sub dir/potato3: md5 = a54e3277ed242a4523da33b260fa5c30 OK 2026/09/25 04:10:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Touching "sub dir/potato3" 2026/09/25 04:10:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Touching "empty space" 2026/09/25 04:10:20 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Touching "potato2" --- PASS: TestTouchDir (9.87s) === RUN TestMkdirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:24 DEBUG : dir with metadata: Making directory with metadata 2026/09/25 04:10:24 INFO : dir with metadata: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) --- PASS: TestMkdirMetadata (2.55s) === RUN TestMkdirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:26 DEBUG : directory with modtime: Making directory with metadata 2026/09/25 04:10:27 INFO : directory with modtime: Made directory with metadata (mtime=2011-12-25T12:59:59.123456789Z) 2026/09/25 04:10:27 NOTICE: dry run directory with modtime: Skipped make directory as --dry-run is set --- PASS: TestMkdirModTime (2.31s) === RUN TestCopyDirMetadata run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:28 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/09/25 04:10:28 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/25 04:10:29 DEBUG : Google drive root 'crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g': Skipping btime metadata as can't update it on an existing file: 2026-09-25T04:10:28.876175724Z 2026/09/25 04:10:29 INFO : non existent directory: Updated directory metadata 2026/09/25 04:10:31 DEBUG : Google drive root 'crypt/j5rlkukqajpru9hoot48l1l9qis5oadvlp5saa3bqmtuajiaq33g': Skipping btime metadata as can't update it on an existing file: 2026-09-25T04:10:28.876175724Z 2026/09/25 04:10:31 INFO : existing directory: Updated directory metadata --- PASS: TestCopyDirMetadata (4.69s) === RUN TestSetDirModTime run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:33 DEBUG : set modtime on non existent directory: Skipping set directory modification time as --no-update-dir-modtime is set 2026/09/25 04:10:35 INFO : set modtime on existing directory: Set directory modification time (using SetModTime) 2026/09/25 04:10:35 INFO : set modtime on existing directory: Set directory modification time (using DirSetModTime) --- PASS: TestSetDirModTime (4.17s) === RUN TestDirsEqual run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:37 DEBUG : dir with metadata to be copied: Making directory with metadata 2026/09/25 04:10:37 INFO : dir with metadata to be copied: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/25 04:10:37 DEBUG : dst: Making directory with metadata 2026/09/25 04:10:38 INFO : dst: Made directory with metadata (mtime=2001-02-03T04:05:06.499999999Z) 2026/09/25 04:10:38 DEBUG : dst: Directory modification time the same (differ by -999.999µs, within tolerance 1ms) 2026/09/25 04:10:38 INFO : dst: Set directory modification time (using SetModTime) 2026/09/25 04:10:39 INFO : dst: Set directory modification time (using SetModTime) 2026/09/25 04:10:39 DEBUG : dst: Directory modification time the same (differ by 1ns, within tolerance 1ms) 2026/09/25 04:10:39 INFO : dst: Set directory modification time (using SetModTime) 2026/09/25 04:10:39 DEBUG : dst: Destination directory is newer than source, skipping --- PASS: TestDirsEqual (3.44s) === RUN TestRemoveExisting run.go:198: Remote "Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino'", Local "Local file system at /tmp/rclone4104455662", Modify Window "1ms" 2026/09/25 04:10:43 DEBUG : sub dir/test remove existing: md5 = 3fdd2bffc073781d5bcef3777ae2ad5c OK 2026/09/25 04:10:44 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: md5 = 80f7844c309061179cf94254390a4b96 OK 2026/09/25 04:10:46 DEBUG : sub dir/test remove existing: TEST: renaming existing object to "sub dir/test remove existing.fimiyov0" before starting 2026/09/25 04:10:47 DEBUG : sub dir/test remove existing.fimiyov0: TEST: removing renamed existing file after operation 2026/09/25 04:10:48 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.yohadod2" before starting 2026/09/25 04:10:50 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.yohadod2: TEST: renaming existing back after failed operation 2026/09/25 04:10:51 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890123456789: TEST: renaming existing object to "sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cicuciz0" before starting 2026/09/25 04:10:52 DEBUG : sub dir/test remove existing with long name 123456789012345678901234567890123456789012345678901234567890.cicuciz0: TEST: removing renamed existing file after operation --- PASS: TestRemoveExisting (13.40s) === RUN TestRcatInputFailurePreservesDestination 2026/09/25 04:10:54 DEBUG : Creating backend with remote "/tmp/TestRcatInputFailurePreservesDestination3139220202/001" 2026/09/25 04:10:54 DEBUG : Config file has changed externally - reloading --- PASS: TestRcatInputFailurePreservesDestination (0.00s) === RUN TestRcAbout rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcAbout (0.00s) === RUN TestRcCleanup rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCleanup (0.00s) === RUN TestRcCopyfile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCopyfile (0.00s) === RUN TestRcCopyurl rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCopyurl (0.00s) === RUN TestRcDelete rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDelete (0.00s) === RUN TestRcDeletefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDeletefile (0.00s) === RUN TestRcList rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcList (0.00s) === RUN TestRcStat rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcStat (0.00s) === RUN TestRcSetTier rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTier (0.00s) === RUN TestRcSetTierFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSetTierFile (0.00s) === RUN TestRcMkdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMkdir (0.00s) === RUN TestRcMovefile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcMovefile (0.00s) === RUN TestRcPurge rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPurge (0.00s) === RUN TestRcRmdir rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdir (0.00s) === RUN TestRcRmdirs rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcRmdirs (0.00s) === RUN TestRcSize rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcSize (0.00s) === RUN TestRcPublicLink rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcPublicLink (0.00s) === RUN TestRcFsInfo rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcFsInfo (0.00s) === RUN TestUploadFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestUploadFile (0.00s) === RUN TestRcCommand rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCommand (0.00s) === RUN TestRcDu rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcDu (0.00s) === RUN TestRcCheck rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcCheck (0.00s) === RUN TestRcHashsum rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsum (0.00s) === RUN TestRcHashsumSingleFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumSingleFile (0.00s) === RUN TestRcHashsumFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcHashsumFile (0.00s) === RUN TestRcGetFile rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcGetFile (0.00s) PASS 2026/09/25 04:10:54 DEBUG : Encrypted drive 'TestCryptDrive:rclone-test-gotufus7gino': Purge remote "./operations.test -test.v -test.timeout 1h0m0s -remote TestCryptDrive: -verbose" - Finished OK in 16m22.253075546s (try 1/5)