"./operations.test -test.v -test.timeout 1h0m0s -remote TestSugarSync:Test -verbose" - Starting (try 1/5) 2026/10/20 03:27:43 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu" 2026/10/20 03:27:43 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:27:56 DEBUG : Creating backend with remote "/tmp/rclone727927114" === 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 "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" multithread_test.go:120: multithread writing not supported 2026/10/20 03:27:57 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopy (0.92s) === RUN TestMultithreadCopyAbort run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" multithread_test.go:120: multithread writing not supported 2026/10/20 03:27:58 ERROR : error listing: directory not found --- SKIP: TestMultithreadCopyAbort (0.89s) === RUN TestMultithreadCopyWriterAtErrors === RUN TestMultithreadCopyWriterAtErrors/open/fatal=false 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/open/fatal=true 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt === RUN TestMultithreadCopyWriterAtErrors/write/fatal=false 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/10/20 03:27:58 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 1 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 0 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 2 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/10/20 03:27:58 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/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/10/20 03:27:58 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 1 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 2/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 2 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 3/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 0 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 1/4 failed: multi-thread copy: failed to write chunk: disk full 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/10/20 03:27:58 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/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/10/20 03:27:58 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 1 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 2 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 3 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 0 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/10/20 03:27:58 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/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: disabling buffering because destination uses OpenWriterAt 2026/10/20 03:27:58 DEBUG : file.txt: Starting multi-thread copy with 4 chunks of size 4 with 2 parallel streams 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 1 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 2/4 (4-8) size 4 finished 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 2 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 3/4 (8-12) size 4 finished 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 3 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 4/4 (12-16) size 4 finished 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 starting 2026/10/20 03:27:58 DEBUG : file.txt: writing chunk 0 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: chunk 1/4 (0-4) size 4 finished 2026/10/20 03:27:58 DEBUG : file.txt: multi-thread copy: cancelling transfer on exit 2026/10/20 03:27:58 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/10/20 03:27:58 DEBUG : a: size = 0 OK 2026/10/20 03:27:58 DEBUG : a: size = 1 (memory) 2026/10/20 03:27:58 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/10/20 03:27:58 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/ErrorAtStart === RUN TestReOpen/Normal/WithErrors 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/Normal/TooManyErrors 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/Normal/ReadAt 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/Normal/Seek 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Seek from 5 to 2 === RUN TestReOpen/Normal/AccountRead === RUN TestReOpen/Normal/AccountReadDelay 2026/10/20 03:27:58 DEBUG : potato: Seek from 10 to 0 2026/10/20 03:27:58 DEBUG : potato: Seek from 10 to 0 2026/10/20 03:27:58 DEBUG : potato: Seek from 10 to 0 === RUN TestReOpen/Normal/AccountReadError === RUN TestReOpen/WithRangeOption === RUN TestReOpen/WithRangeOption/Basics 2026/10/20 03:27:58 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/ErrorAtStart === RUN TestReOpen/WithRangeOption/WithErrors 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithRangeOption/TooManyErrors 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithRangeOption/ReadAt 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithRangeOption/Seek 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Seek from 5 to 2 2026/10/20 03:27:58 DEBUG : potato: Seek from 7 to 4 === RUN TestReOpen/WithRangeOption/AccountRead === RUN TestReOpen/WithRangeOption/AccountReadDelay 2026/10/20 03:27:58 DEBUG : potato: Seek from 7 to 0 2026/10/20 03:27:58 DEBUG : potato: Seek from 7 to 0 2026/10/20 03:27:58 DEBUG : potato: Seek from 7 to 0 === RUN TestReOpen/WithRangeOption/AccountReadError === RUN TestReOpen/WithSeekOption === RUN TestReOpen/WithSeekOption/Basics 2026/10/20 03:27:58 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/ErrorAtStart === RUN TestReOpen/WithSeekOption/WithErrors 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/WithSeekOption/TooManyErrors 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/WithSeekOption/ReadAt 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/WithSeekOption/Seek 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Seek from 5 to 2 2026/10/20 03:27:58 DEBUG : potato: Seek from 7 to 5 === RUN TestReOpen/WithSeekOption/AccountRead === RUN TestReOpen/WithSeekOption/AccountReadDelay 2026/10/20 03:27:58 DEBUG : potato: Seek from 8 to 0 2026/10/20 03:27:58 DEBUG : potato: Seek from 8 to 0 2026/10/20 03:27:58 DEBUG : potato: Seek from 8 to 0 === RUN TestReOpen/WithSeekOption/AccountReadError === RUN TestReOpen/UnknownSize === RUN TestReOpen/UnknownSize/Basics 2026/10/20 03:27:58 DEBUG : potato: Seek from 9 to 0 === RUN TestReOpen/UnknownSize/ErrorAtStart === RUN TestReOpen/UnknownSize/WithErrors 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error === RUN TestReOpen/UnknownSize/TooManyErrors 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error 2026/10/20 03:27:58 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries === RUN TestReOpen/UnknownSize/ReadAt 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Seek from 5 to 1 === RUN TestReOpen/UnknownSize/Seek 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error 2026/10/20 03:27:58 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error 2026/10/20 03:27:58 DEBUG : potato: Seek from 5 to 2 2026/10/20 03:27:58 DEBUG : potato: Seek from 7 to 6 === RUN TestReOpen/UnknownSize/AccountRead === RUN TestReOpen/UnknownSize/AccountReadDelay 2026/10/20 03:27:58 DEBUG : potato: Seek from 9 to 0 2026/10/20 03:27:58 DEBUG : potato: Seek from 9 to 0 2026/10/20 03:27:58 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 "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" === RUN TestCheck/1 === RUN TestCheck/2 2026/10/20 03:28:16 DEBUG : sugarsync root 'Test/rclone-test-yapurox0huzu': Renewing token 2026/10/20 03:28:17 DEBUG : Saving config "authorization" in section "TestSugarSync" of the config file 2026/10/20 03:28:17 DEBUG : Saving config "authorization_expiry" in section "TestSugarSync" of the config file 2026/10/20 03:28:17 DEBUG : Saving config "user" in section "TestSugarSync" of the config file === RUN TestCheck/3 === RUN TestCheck/4 === RUN TestCheck/5 === RUN TestCheck/6 === RUN TestCheck/7 --- PASS: TestCheck (57.97s) --- PASS: TestCheck/1 (0.50s) --- PASS: TestCheck/2 (0.57s) --- PASS: TestCheck/3 (0.59s) --- PASS: TestCheck/4 (0.54s) --- PASS: TestCheck/5 (1.77s) --- PASS: TestCheck/6 (0.54s) --- PASS: TestCheck/7 (0.54s) === RUN TestCheckFsError 2026/10/20 03:28:56 DEBUG : Creating backend with remote "nonexistent" 2026/10/20 03:28:56 DEBUG : Creating backend with remote "nonexistent" 2026/10/20 03:28:56 DEBUG : Local file system at nonexistent: Waiting for checks to finish 2026/10/20 03:28:56 ERROR : Local file system at nonexistent: error reading source root directory: directory not found 2026/10/20 03:28:56 NOTICE: Local file system at nonexistent: 0 differences found 2026/10/20 03:28:56 NOTICE: Local file system at nonexistent: 2 errors while checking --- PASS: TestCheckFsError (0.00s) === RUN TestCheckDownload run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" === RUN TestCheckDownload/1 === RUN TestCheckDownload/2 === RUN TestCheckDownload/3 === RUN TestCheckDownload/4 === RUN TestCheckDownload/5 === RUN TestCheckDownload/6 === RUN TestCheckDownload/7 --- PASS: TestCheckDownload (103.54s) --- PASS: TestCheckDownload/1 (1.22s) --- PASS: TestCheckDownload/2 (10.58s) --- PASS: TestCheckDownload/3 (1.23s) --- PASS: TestCheckDownload/4 (1.95s) --- PASS: TestCheckDownload/5 (0.89s) --- PASS: TestCheckDownload/6 (2.22s) --- PASS: TestCheckDownload/7 (0.95s) === RUN TestCheckSizeOnly run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" === RUN TestCheckSizeOnly/1 === RUN TestCheckSizeOnly/2 === RUN TestCheckSizeOnly/3 === RUN TestCheckSizeOnly/4 === RUN TestCheckSizeOnly/5 === RUN TestCheckSizeOnly/6 === RUN TestCheckSizeOnly/7 --- PASS: TestCheckSizeOnly (58.59s) --- PASS: TestCheckSizeOnly/1 (1.26s) --- PASS: TestCheckSizeOnly/2 (0.54s) --- PASS: TestCheckSizeOnly/3 (0.54s) --- PASS: TestCheckSizeOnly/4 (1.47s) --- PASS: TestCheckSizeOnly/5 (0.54s) --- PASS: TestCheckSizeOnly/6 (9.68s) --- PASS: TestCheckSizeOnly/7 (0.55s) === RUN TestCheckEqualReaders --- PASS: TestCheckEqualReaders (0.00s) === RUN TestParseSumFile run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:31:42 NOTICE: test.sum: improperly formatted checksum line 4 2026/10/20 03:31:42 NOTICE: test.sum: improperly formatted checksum line 5 2026/10/20 03:31:42 NOTICE: test.sum: improperly formatted checksum line 6 2026/10/20 03:31:42 NOTICE: test.sum: 2 warning(s) suppressed... 2026/10/20 03:31:44 NOTICE: test.sum: improperly formatted checksum line 4 2026/10/20 03:31:44 NOTICE: test.sum: improperly formatted checksum line 5 2026/10/20 03:31:44 NOTICE: test.sum: improperly formatted checksum line 6 2026/10/20 03:31:44 NOTICE: test.sum: 2 warning(s) suppressed... --- PASS: TestParseSumFile (7.99s) === RUN TestCheckSum run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:31:46 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/data" check_test.go:354: sugarsync root 'Test/rclone-test-yapurox0huzu/data' lacks md5, skipping --- SKIP: TestCheckSum (5.31s) === RUN TestCheckSumDownload run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:31:51 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/data" === RUN TestCheckSumDownload/subtest1 === RUN TestCheckSumDownload/subtest2 === RUN TestCheckSumDownload/subtest3 === RUN TestCheckSumDownload/subtest4 === RUN TestCheckSumDownload/subtest5 === RUN TestCheckSumDownload/subtest6 === RUN TestCheckSumDownload/subtest7 --- PASS: TestCheckSumDownload (159.05s) --- PASS: TestCheckSumDownload/subtest1 (30.36s) --- PASS: TestCheckSumDownload/subtest2 (2.08s) --- PASS: TestCheckSumDownload/subtest3 (11.73s) --- PASS: TestCheckSumDownload/subtest4 (2.07s) --- PASS: TestCheckSumDownload/subtest5 (21.17s) --- PASS: TestCheckSumDownload/subtest6 (12.24s) --- PASS: TestCheckSumDownload/subtest7 (11.23s) === RUN TestCheckSumConcurrency 2026/10/20 03:34:30 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/10/20 03:34:30 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/10/20 03:34:30 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/10/20 03:34:30 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/10/20 03:34:30 NOTICE: Mock file system at checkSum: 0 differences found 2026/10/20 03:34:30 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumConcurrency (0.00s) === RUN TestCheckSumDownloadConcurrency 2026/10/20 03:34:30 DEBUG : file0: md5 = 022eb0c0085c731f335df970fafa7446 OK 2026/10/20 03:34:30 DEBUG : file2: md5 = a58f09ae3db8ae4b943fdbaace096072 OK 2026/10/20 03:34:30 DEBUG : file1: md5 = 17fbabbccbdea0e6be9eb64c8c6fea41 OK 2026/10/20 03:34:30 DEBUG : file3: md5 = e0639b17e0744aca9a3ba29c049e59ee OK 2026/10/20 03:34:30 NOTICE: Mock file system at checkSum: 0 differences found 2026/10/20 03:34:30 NOTICE: Mock file system at checkSum: 4 matching files --- PASS: TestCheckSumDownloadConcurrency (0.00s) === RUN TestApplyTransforms 2026/10/20 03:34:30 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-kebidex3nuso" 2026/10/20 03:34:30 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:34:33 DEBUG : Creating backend with remote "/tmp/rclone2714963455" run.go:198: Remote "sugarsync root 'Test/rclone-test-kebidex3nuso'", Local "Local file system at /tmp/rclone2714963455", Modify Window "876000h0m0s" upper checkfile vs. lower remote (without normalization) 2026/10/20 03:34:38 ERROR : hello, world!: sum not found 2026/10/20 03:34:38 ERROR : HELLO, WORLD!: file not in sugarsync root 'Test/rclone-test-kebidex3nuso' 2026/10/20 03:34:38 NOTICE: sugarsync root 'Test/rclone-test-kebidex3nuso': 1 files missing 2026/10/20 03:34:38 NOTICE: 1 hashes missing 2026/10/20 03:34:38 NOTICE: sugarsync root 'Test/rclone-test-kebidex3nuso': 1 differences found 2026/10/20 03:34:38 NOTICE: sugarsync root 'Test/rclone-test-kebidex3nuso': 2 errors while checking upper checkfile vs. lower remote (with normalization) 2026/10/20 03:34:39 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:34:39 NOTICE: sugarsync root 'Test/rclone-test-kebidex3nuso': 0 differences found 2026/10/20 03:34:39 NOTICE: sugarsync root 'Test/rclone-test-kebidex3nuso': 1 matching files 2026/10/20 03:34:39 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-febigep2peda" 2026/10/20 03:34:39 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:34:52 DEBUG : Creating backend with remote "/tmp/rclone613509413" run.go:198: Remote "sugarsync root 'Test/rclone-test-febigep2peda'", Local "Local file system at /tmp/rclone613509413", Modify Window "876000h0m0s" lower checkfile vs. upper remote (without normalization) 2026/10/20 03:35:16 ERROR : HELLO, WORLD!: sum not found 2026/10/20 03:35:16 ERROR : hello, world!: file not in sugarsync root 'Test/rclone-test-febigep2peda' 2026/10/20 03:35:16 NOTICE: sugarsync root 'Test/rclone-test-febigep2peda': 1 files missing 2026/10/20 03:35:16 NOTICE: 1 hashes missing 2026/10/20 03:35:16 NOTICE: sugarsync root 'Test/rclone-test-febigep2peda': 1 differences found 2026/10/20 03:35:16 NOTICE: sugarsync root 'Test/rclone-test-febigep2peda': 2 errors while checking lower checkfile vs. upper remote (with normalization) 2026/10/20 03:35:17 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:35:17 NOTICE: sugarsync root 'Test/rclone-test-febigep2peda': 0 differences found 2026/10/20 03:35:17 NOTICE: sugarsync root 'Test/rclone-test-febigep2peda': 1 matching files 2026/10/20 03:35:17 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-joporos3qeqa" 2026/10/20 03:35:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:35:39 DEBUG : Creating backend with remote "/tmp/rclone244774119" run.go:198: Remote "sugarsync root 'Test/rclone-test-joporos3qeqa'", Local "Local file system at /tmp/rclone244774119", Modify Window "876000h0m0s" lower checkfile vs. upperlowermixed remote (without normalization) 2026/10/20 03:35:55 ERROR : HeLlO, wOrLd!: sum not found 2026/10/20 03:35:55 ERROR : hello, world!: file not in sugarsync root 'Test/rclone-test-joporos3qeqa' 2026/10/20 03:35:55 NOTICE: sugarsync root 'Test/rclone-test-joporos3qeqa': 1 files missing 2026/10/20 03:35:55 NOTICE: 1 hashes missing 2026/10/20 03:35:55 NOTICE: sugarsync root 'Test/rclone-test-joporos3qeqa': 1 differences found 2026/10/20 03:35:55 NOTICE: sugarsync root 'Test/rclone-test-joporos3qeqa': 2 errors while checking lower checkfile vs. upperlowermixed remote (with normalization) 2026/10/20 03:35:56 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:35:56 NOTICE: sugarsync root 'Test/rclone-test-joporos3qeqa': 0 differences found 2026/10/20 03:35:56 NOTICE: sugarsync root 'Test/rclone-test-joporos3qeqa': 1 matching files 2026/10/20 03:35:56 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-waqunac7cequ" 2026/10/20 03:35:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:36:09 DEBUG : Creating backend with remote "/tmp/rclone2506023143" run.go:198: Remote "sugarsync root 'Test/rclone-test-waqunac7cequ'", Local "Local file system at /tmp/rclone2506023143", Modify Window "876000h0m0s" upperlowermixed checkfile vs. upper remote (without normalization) 2026/10/20 03:36:24 ERROR : HELLO, WORLD!: sum not found 2026/10/20 03:36:24 ERROR : HeLlO, wOrLd!: file not in sugarsync root 'Test/rclone-test-waqunac7cequ' 2026/10/20 03:36:24 NOTICE: sugarsync root 'Test/rclone-test-waqunac7cequ': 1 files missing 2026/10/20 03:36:24 NOTICE: 1 hashes missing 2026/10/20 03:36:24 NOTICE: sugarsync root 'Test/rclone-test-waqunac7cequ': 1 differences found 2026/10/20 03:36:24 NOTICE: sugarsync root 'Test/rclone-test-waqunac7cequ': 2 errors while checking upperlowermixed checkfile vs. upper remote (with normalization) 2026/10/20 03:36:35 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:36:35 NOTICE: sugarsync root 'Test/rclone-test-waqunac7cequ': 0 differences found 2026/10/20 03:36:35 NOTICE: sugarsync root 'Test/rclone-test-waqunac7cequ': 1 matching files 2026/10/20 03:36:35 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-vosomur4layu" 2026/10/20 03:36:35 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:36:47 DEBUG : Creating backend with remote "/tmp/rclone875928269" run.go:198: Remote "sugarsync root 'Test/rclone-test-vosomur4layu'", Local "Local file system at /tmp/rclone875928269", Modify Window "876000h0m0s" NFD checkfile vs. NFC remote (without normalization) 2026/10/20 03:37:11 ERROR : 測試_Русский___ě_áñ: sum not found 2026/10/20 03:37:11 ERROR : 測試_Русский___ě_áñ: file not in sugarsync root 'Test/rclone-test-vosomur4layu' 2026/10/20 03:37:11 NOTICE: sugarsync root 'Test/rclone-test-vosomur4layu': 1 files missing 2026/10/20 03:37:11 NOTICE: 1 hashes missing 2026/10/20 03:37:11 NOTICE: sugarsync root 'Test/rclone-test-vosomur4layu': 1 differences found 2026/10/20 03:37:11 NOTICE: sugarsync root 'Test/rclone-test-vosomur4layu': 2 errors while checking NFD checkfile vs. NFC remote (with normalization) 2026/10/20 03:37:13 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:37:13 NOTICE: sugarsync root 'Test/rclone-test-vosomur4layu': 0 differences found 2026/10/20 03:37:13 NOTICE: sugarsync root 'Test/rclone-test-vosomur4layu': 1 matching files 2026/10/20 03:37:13 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-nicuzij9jogo" 2026/10/20 03:37:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:37:27 DEBUG : Creating backend with remote "/tmp/rclone1021945433" run.go:198: Remote "sugarsync root 'Test/rclone-test-nicuzij9jogo'", Local "Local file system at /tmp/rclone1021945433", Modify Window "876000h0m0s" NFC checkfile vs. NFD remote (without normalization) 2026/10/20 03:37:42 ERROR : 測試_Русский___ě_áñ: sum not found 2026/10/20 03:37:42 ERROR : 測試_Русский___ě_áñ: file not in sugarsync root 'Test/rclone-test-nicuzij9jogo' 2026/10/20 03:37:42 NOTICE: sugarsync root 'Test/rclone-test-nicuzij9jogo': 1 files missing 2026/10/20 03:37:42 NOTICE: 1 hashes missing 2026/10/20 03:37:42 NOTICE: sugarsync root 'Test/rclone-test-nicuzij9jogo': 1 differences found 2026/10/20 03:37:42 NOTICE: sugarsync root 'Test/rclone-test-nicuzij9jogo': 2 errors while checking NFC checkfile vs. NFD remote (with normalization) 2026/10/20 03:37:53 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:37:53 NOTICE: sugarsync root 'Test/rclone-test-nicuzij9jogo': 0 differences found 2026/10/20 03:37:53 NOTICE: sugarsync root 'Test/rclone-test-nicuzij9jogo': 1 matching files 2026/10/20 03:37:53 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-negorur4kafi" 2026/10/20 03:37:53 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:38:06 DEBUG : Creating backend with remote "/tmp/rclone1675811918" run.go:198: Remote "sugarsync root 'Test/rclone-test-negorur4kafi'", Local "Local file system at /tmp/rclone1675811918", Modify Window "876000h0m0s" NFDx2 checkfile vs. both remote (without normalization) 2026/10/20 03:38:31 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/10/20 03:38:31 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in sugarsync root 'Test/rclone-test-negorur4kafi' 2026/10/20 03:38:31 NOTICE: sugarsync root 'Test/rclone-test-negorur4kafi': 1 files missing 2026/10/20 03:38:31 NOTICE: 1 hashes missing 2026/10/20 03:38:31 NOTICE: sugarsync root 'Test/rclone-test-negorur4kafi': 1 differences found 2026/10/20 03:38:31 NOTICE: sugarsync root 'Test/rclone-test-negorur4kafi': 2 errors while checking NFDx2 checkfile vs. both remote (with normalization) 2026/10/20 03:38:32 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:38:32 NOTICE: sugarsync root 'Test/rclone-test-negorur4kafi': 0 differences found 2026/10/20 03:38:32 NOTICE: sugarsync root 'Test/rclone-test-negorur4kafi': 1 matching files 2026/10/20 03:38:32 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-vajivoz3biyo" 2026/10/20 03:38:32 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:38:35 DEBUG : Creating backend with remote "/tmp/rclone3090609344" run.go:198: Remote "sugarsync root 'Test/rclone-test-vajivoz3biyo'", Local "Local file system at /tmp/rclone3090609344", Modify Window "876000h0m0s" NFCx2 checkfile vs. both remote (without normalization) 2026/10/20 03:38:40 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/10/20 03:38:40 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in sugarsync root 'Test/rclone-test-vajivoz3biyo' 2026/10/20 03:38:40 NOTICE: sugarsync root 'Test/rclone-test-vajivoz3biyo': 1 files missing 2026/10/20 03:38:40 NOTICE: 1 hashes missing 2026/10/20 03:38:40 NOTICE: sugarsync root 'Test/rclone-test-vajivoz3biyo': 1 differences found 2026/10/20 03:38:40 NOTICE: sugarsync root 'Test/rclone-test-vajivoz3biyo': 2 errors while checking NFCx2 checkfile vs. both remote (with normalization) 2026/10/20 03:38:51 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:38:51 NOTICE: sugarsync root 'Test/rclone-test-vajivoz3biyo': 0 differences found 2026/10/20 03:38:51 NOTICE: sugarsync root 'Test/rclone-test-vajivoz3biyo': 1 matching files 2026/10/20 03:38:51 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-cifofow1fode" 2026/10/20 03:38:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:39:04 DEBUG : Creating backend with remote "/tmp/rclone3454566123" run.go:198: Remote "sugarsync root 'Test/rclone-test-cifofow1fode'", Local "Local file system at /tmp/rclone3454566123", Modify Window "876000h0m0s" both checkfile vs. NFDx2 remote (without normalization) 2026/10/20 03:39:39 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/10/20 03:39:39 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in sugarsync root 'Test/rclone-test-cifofow1fode' 2026/10/20 03:39:39 NOTICE: sugarsync root 'Test/rclone-test-cifofow1fode': 1 files missing 2026/10/20 03:39:39 NOTICE: 1 hashes missing 2026/10/20 03:39:39 NOTICE: sugarsync root 'Test/rclone-test-cifofow1fode': 1 differences found 2026/10/20 03:39:39 NOTICE: sugarsync root 'Test/rclone-test-cifofow1fode': 2 errors while checking both checkfile vs. NFDx2 remote (with normalization) 2026/10/20 03:39:40 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:39:40 NOTICE: sugarsync root 'Test/rclone-test-cifofow1fode': 0 differences found 2026/10/20 03:39:40 NOTICE: sugarsync root 'Test/rclone-test-cifofow1fode': 1 matching files 2026/10/20 03:39:40 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-xeceqed2kula" 2026/10/20 03:39:40 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/20 03:39:43 DEBUG : Creating backend with remote "/tmp/rclone2140554470" run.go:198: Remote "sugarsync root 'Test/rclone-test-xeceqed2kula'", Local "Local file system at /tmp/rclone2140554470", Modify Window "876000h0m0s" both checkfile vs. NFCx2 remote (without normalization) 2026/10/20 03:40:09 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found 2026/10/20 03:40:09 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in sugarsync root 'Test/rclone-test-xeceqed2kula' 2026/10/20 03:40:09 NOTICE: sugarsync root 'Test/rclone-test-xeceqed2kula': 1 files missing 2026/10/20 03:40:09 NOTICE: 1 hashes missing 2026/10/20 03:40:09 NOTICE: sugarsync root 'Test/rclone-test-xeceqed2kula': 1 differences found 2026/10/20 03:40:09 NOTICE: sugarsync root 'Test/rclone-test-xeceqed2kula': 2 errors while checking both checkfile vs. NFCx2 remote (with normalization) 2026/10/20 03:40:10 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK 2026/10/20 03:40:10 NOTICE: sugarsync root 'Test/rclone-test-xeceqed2kula': 0 differences found 2026/10/20 03:40:10 NOTICE: sugarsync root 'Test/rclone-test-xeceqed2kula': 1 matching files 2026/10/20 03:40:10 DEBUG : sugarsync root 'Test/rclone-test-xeceqed2kula': Purge remote 2026/10/20 03:40:20 DEBUG : sugarsync root 'Test/rclone-test-cifofow1fode': Purge remote 2026/10/20 03:40:21 DEBUG : sugarsync root 'Test/rclone-test-vajivoz3biyo': Purge remote 2026/10/20 03:40:22 DEBUG : sugarsync root 'Test/rclone-test-negorur4kafi': Purge remote 2026/10/20 03:40:23 DEBUG : sugarsync root 'Test/rclone-test-nicuzij9jogo': Purge remote 2026/10/20 03:40:24 DEBUG : sugarsync root 'Test/rclone-test-vosomur4layu': Purge remote 2026/10/20 03:40:24 DEBUG : sugarsync root 'Test/rclone-test-waqunac7cequ': Purge remote 2026/10/20 03:40:25 DEBUG : sugarsync root 'Test/rclone-test-joporos3qeqa': Purge remote 2026/10/20 03:40:26 DEBUG : sugarsync root 'Test/rclone-test-febigep2peda': Purge remote 2026/10/20 03:40:26 DEBUG : sugarsync root 'Test/rclone-test-kebidex3nuso': Purge remote --- PASS: TestApplyTransforms (357.43s) === RUN TestTruncateString --- PASS: TestTruncateString (0.00s) === RUN TestCopyFile run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:40:28 DEBUG : file1: Need to transfer - File not found at Destination 2026/10/20 03:40:41 DEBUG : sub/file2: size = 14 OK 2026/10/20 03:40:41 INFO : file1: Copied (new) to: sub/file2 2026/10/20 03:40:43 DEBUG : sub/file2: size = 14 OK 2026/10/20 03:40:43 DEBUG : file1: Sizes identical 2026/10/20 03:40:43 DEBUG : file1: Unchanged skipping 2026/10/20 03:40:44 DEBUG : sugarsync root 'Test/rclone-test-yapurox0huzu': don't need to copy/move sub/file2, it is already at target location --- PASS: TestCopyFile (21.43s) === RUN TestCopyFileImmutable run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:40:49 DEBUG : existing: Need to transfer - File not found at Destination 2026/10/20 03:41:11 DEBUG : existing: size = 6 OK 2026/10/20 03:41:11 INFO : existing: Copied (new) 2026/10/20 03:41:21 DEBUG : existing: size = 6 OK 2026/10/20 03:41:21 DEBUG : existing: Sizes identical 2026/10/20 03:41:21 DEBUG : existing: Unchanged skipping 2026/10/20 03:41:32 DEBUG : existing: size = 8 (Local file system at /tmp/rclone727927114) 2026/10/20 03:41:32 DEBUG : existing: size = 6 (sugarsync root 'Test/rclone-test-yapurox0huzu') 2026/10/20 03:41:32 DEBUG : existing: Sizes differ 2026/10/20 03:41:32 ERROR : existing: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileImmutable (46.09s) === RUN TestCopyLongFile run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" copy_test.go:188: Test only runs on local --- SKIP: TestCopyLongFile (10.80s) === RUN TestCopyFileBackupDir run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:42:00 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/backup" 2026/10/20 03:42:13 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone727927114) 2026/10/20 03:42:13 DEBUG : dst/file1: size = 18 (sugarsync root 'Test/rclone-test-yapurox0huzu') 2026/10/20 03:42:13 DEBUG : dst/file1: Sizes differ 2026/10/20 03:42:26 INFO : dst/file1: Moved (server-side) 2026/10/20 03:42:28 DEBUG : dst/file1: size = 14 OK 2026/10/20 03:42:28 INFO : dst/file1: Copied (new) --- PASS: TestCopyFileBackupDir (70.50s) === RUN TestCopyFileCompareDest run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:42:56 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/dst" 2026/10/20 03:43:01 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/CompareDest" 2026/10/20 03:43:35 DEBUG : one: Need to transfer - File not found at Destination 2026/10/20 03:44:10 DEBUG : one: size = 3 OK 2026/10/20 03:44:10 INFO : one: Copied (new) 2026/10/20 03:44:23 DEBUG : one: size = 5 (Local file system at /tmp/rclone727927114) 2026/10/20 03:44:23 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-yapurox0huzu/dst') 2026/10/20 03:44:23 DEBUG : one: Sizes differ 2026/10/20 03:44:25 DEBUG : one: size = 5 OK 2026/10/20 03:44:25 INFO : one: Copied (replaced existing) 2026/10/20 03:44:44 DEBUG : one: size = 5 (Local file system at /tmp/rclone727927114) 2026/10/20 03:44:44 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-yapurox0huzu/dst') 2026/10/20 03:44:44 DEBUG : one: Sizes differ 2026/10/20 03:44:55 DEBUG : one: size = 5 OK 2026/10/20 03:44:55 DEBUG : one: Sizes identical 2026/10/20 03:44:55 DEBUG : one: Destination found in --compare-dest, skipping 2026/10/20 03:45:11 DEBUG : two: Need to transfer - File not found at Destination 2026/10/20 03:45:12 DEBUG : two: size = 3 OK 2026/10/20 03:45:12 DEBUG : two: Sizes identical 2026/10/20 03:45:12 DEBUG : two: Destination found in --compare-dest, skipping 2026/10/20 03:45:15 DEBUG : two: Need to transfer - File not found at Destination 2026/10/20 03:45:15 DEBUG : two: size = 3 OK 2026/10/20 03:45:15 DEBUG : two: Sizes identical 2026/10/20 03:45:15 DEBUG : two: Destination found in --compare-dest, skipping 2026/10/20 03:45:49 DEBUG : two: Need to transfer - File not found at Destination 2026/10/20 03:45:50 DEBUG : two: size = 5 (Local file system at /tmp/rclone727927114) 2026/10/20 03:45:50 DEBUG : two: size = 3 (sugarsync root 'Test/rclone-test-yapurox0huzu/CompareDest') 2026/10/20 03:45:50 DEBUG : two: Sizes differ 2026/10/20 03:46:01 DEBUG : two: size = 5 OK 2026/10/20 03:46:01 INFO : two: Copied (new) --- PASS: TestCopyFileCompareDest (203.14s) === RUN TestCopyFileCopyDestImmutable run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:46:19 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/dst" 2026/10/20 03:47:05 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/CopyDest" 2026/10/20 03:47:17 DEBUG : one: size = 5 (Local file system at /tmp/rclone727927114) 2026/10/20 03:47:17 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-yapurox0huzu/dst') 2026/10/20 03:47:17 DEBUG : one: Sizes differ 2026/10/20 03:47:18 DEBUG : one: size = 5 OK 2026/10/20 03:47:18 DEBUG : one: Sizes identical 2026/10/20 03:47:18 DEBUG : one: size = 5 (Local file system at /tmp/rclone727927114) 2026/10/20 03:47:18 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-yapurox0huzu/dst') 2026/10/20 03:47:18 DEBUG : one: Sizes differ 2026/10/20 03:47:18 ERROR : one: Source and destination exist but do not match: immutable file modified --- PASS: TestCopyFileCopyDestImmutable (64.32s) === RUN TestCopyFileCopyDest run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:47:24 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/dst" 2026/10/20 03:47:24 DEBUG : Config file has changed externally - reloading 2026/10/20 03:47:41 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/CopyDest" 2026/10/20 03:47:46 DEBUG : one: Need to transfer - File not found at Destination 2026/10/20 03:48:00 DEBUG : one: size = 3 OK 2026/10/20 03:48:00 INFO : one: Copied (new) 2026/10/20 03:48:04 DEBUG : one: size = 5 (Local file system at /tmp/rclone727927114) 2026/10/20 03:48:04 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-yapurox0huzu/dst') 2026/10/20 03:48:04 DEBUG : one: Sizes differ 2026/10/20 03:48:16 DEBUG : one: size = 5 OK 2026/10/20 03:48:16 INFO : one: Copied (replaced existing) 2026/10/20 03:48:40 DEBUG : Creating backend with remote "TestSugarSync:Test/rclone-test-yapurox0huzu/BackupDir" 2026/10/20 03:48:45 DEBUG : one: size = 5 (Local file system at /tmp/rclone727927114) 2026/10/20 03:48:45 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-yapurox0huzu/dst') 2026/10/20 03:48:45 DEBUG : one: Sizes differ 2026/10/20 03:48:57 DEBUG : one: size = 5 OK 2026/10/20 03:48:57 DEBUG : one: Sizes identical 2026/10/20 03:48:57 DEBUG : one: size = 5 (Local file system at /tmp/rclone727927114) 2026/10/20 03:48:57 DEBUG : one: size = 3 (sugarsync root 'Test/rclone-test-yapurox0huzu/dst') 2026/10/20 03:48:57 DEBUG : one: Sizes differ 2026/10/20 03:49:11 INFO : one: Moved (server-side) 2026/10/20 03:49:33 DEBUG : one: size = 5 OK 2026/10/20 03:49:33 INFO : one: Copied (server-side copy) 2026/10/20 03:49:33 DEBUG : one: Destination found in --copy-dest, using server-side copy 2026/10/20 03:49:51 DEBUG : two: Need to transfer - File not found at Destination 2026/10/20 03:49:51 DEBUG : two: size = 3 OK 2026/10/20 03:49:51 DEBUG : two: Sizes identical 2026/10/20 03:49:53 DEBUG : two: size = 3 OK 2026/10/20 03:49:53 INFO : two: Copied (server-side copy) 2026/10/20 03:49:53 DEBUG : two: Destination found in --copy-dest, using server-side copy 2026/10/20 03:49:55 DEBUG : two: size = 3 OK 2026/10/20 03:49:55 DEBUG : two: Sizes identical 2026/10/20 03:49:55 DEBUG : two: Unchanged skipping 2026/10/20 03:50:22 DEBUG : three: Need to transfer - File not found at Destination 2026/10/20 03:50:22 DEBUG : three: size = 7 (Local file system at /tmp/rclone727927114) 2026/10/20 03:50:22 DEBUG : three: size = 5 (sugarsync root 'Test/rclone-test-yapurox0huzu/CopyDest') 2026/10/20 03:50:22 DEBUG : three: Sizes differ 2026/10/20 03:50:22 DEBUG : three: Destination not found in --copy-dest 2026/10/20 03:50:25 DEBUG : three: size = 7 OK 2026/10/20 03:50:25 INFO : three: Copied (new) --- PASS: TestCopyFileCopyDest (215.19s) === RUN TestCopyInplace run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" copy_test.go:434: Partial uploads not supported --- SKIP: TestCopyInplace (1.09s) === RUN TestCopyLongFileName run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" copy_test.go:467: Partial uploads not supported --- SKIP: TestCopyLongFileName (1.11s) === RUN TestCopyLongFileNameCollision run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" copy_test.go:500: Partial uploads not supported --- SKIP: TestCopyLongFileNameCollision (1.11s) === RUN TestCopyFileMaxTransfer run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:51:12 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination 2026/10/20 03:51:26 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK 2026/10/20 03:51:26 INFO : TestCopyFileMaxTransfer/file1: Copied (new) 2026/10/20 03:51:37 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination 2026/10/20 03:51:39 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload file: Put "https://api.sugarsync.com/file/:sc:9068489:215_7890540024/data": max transfer limit reached as set by --max-transfer 2026/10/20 03:51:41 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination 2026/10/20 03:51:43 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination 2026/10/20 03:51:56 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK 2026/10/20 03:51:56 INFO : TestCopyFileMaxTransfer/file4: Copied (new) --- PASS: TestCopyFileMaxTransfer (79.07s) === RUN TestDeduplicateInteractive run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateInteractive (1.82s) === RUN TestDeduplicateSkip run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSkip (1.09s) === RUN TestDeduplicateSizeOnly run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSizeOnly (1.09s) === RUN TestDeduplicateFirst run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateFirst (1.09s) === RUN TestDeduplicateNewest run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateNewest (12.26s) === RUN TestDeduplicateNewestByHash run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:37: Can't run this test without a hash --- SKIP: TestDeduplicateNewestByHash (12.05s) === RUN TestDeduplicateOldest run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateOldest (4.12s) === RUN TestDeduplicateLargest run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateLargest (1.15s) === RUN TestDeduplicateSmallest run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateSmallest (1.12s) === RUN TestDeduplicateRename run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRename (1.14s) === RUN TestDeduplicateRenameManyExisting run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:25: Can't test deduplicate - no duplicate files possible --- SKIP: TestDeduplicateRenameManyExisting (1.08s) === RUN TestMergeDirs run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" dedupe_test.go:294: Can't merge directories --- SKIP: TestMergeDirs (2.55s) === RUN TestIndex run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:54:39 DEBUG : caddy.json: size = 259 OK 2026/10/20 03:54:39 INFO : caddy.json: Copied (new) 2026/10/20 03:54:41 DEBUG : sub/deep/index.json: size = 119 OK 2026/10/20 03:54:41 INFO : sub/deep/index.json: Copied (new) 2026/10/20 03:54:43 DEBUG : sub/index.html: size = 12306 OK 2026/10/20 03:54:43 INFO : sub/index.html: Copied (new) 2026/10/20 03:54:47 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/10/20 03:54:47 INFO : sub/deep/caddy.json: Copied (new) 2026/10/20 03:54:49 DEBUG : index.json: size = 213 OK 2026/10/20 03:54:49 INFO : index.json: Copied (new) 2026/10/20 03:54:50 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/20 03:54:50 INFO : sub/deep/index.html: Copied (new) 2026/10/20 03:54:51 DEBUG : index.html: size = 12147 OK 2026/10/20 03:54:51 INFO : index.html: Copied (new) 2026/10/20 03:54:52 DEBUG : sub/caddy.json: size = 261 OK 2026/10/20 03:54:52 INFO : sub/caddy.json: Copied (new) 2026/10/20 03:55:01 DEBUG : sub/index.json: size = 215 OK 2026/10/20 03:55:01 INFO : sub/index.json: Copied (new) 2026/10/20 03:55:20 DEBUG : sub/deep/index.html: Unchanged skipping 2026/10/20 03:55:20 DEBUG : index.html: Unchanged skipping 2026/10/20 03:55:20 DEBUG : sub/deep/index.json: Unchanged skipping 2026/10/20 03:55:21 DEBUG : index.json: Unchanged skipping 2026/10/20 03:55:21 DEBUG : sub/index.html: Unchanged skipping 2026/10/20 03:55:22 DEBUG : sub/index.json: Unchanged skipping 2026/10/20 03:55:22 DEBUG : caddy.json: Unchanged skipping 2026/10/20 03:55:22 DEBUG : sub/deep/caddy.json: Unchanged skipping 2026/10/20 03:55:32 DEBUG : sub/caddy.json: Unchanged skipping 2026/10/20 03:55:34 DEBUG : index.json: size = 213 OK 2026/10/20 03:55:34 INFO : index.json: Copied (replaced existing) 2026/10/20 03:55:34 DEBUG : index.html: size = 12147 OK 2026/10/20 03:55:34 INFO : index.html: Copied (replaced existing) 2026/10/20 03:55:35 DEBUG : sub/index.json: size = 215 OK 2026/10/20 03:55:35 INFO : sub/index.json: Copied (replaced existing) 2026/10/20 03:55:35 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/20 03:55:35 INFO : sub/deep/index.html: Copied (replaced existing) 2026/10/20 03:55:35 DEBUG : sub/index.html: size = 12306 OK 2026/10/20 03:55:35 INFO : sub/index.html: Copied (replaced existing) 2026/10/20 03:55:37 DEBUG : sub/deep/index.json: size = 119 OK 2026/10/20 03:55:37 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/20 03:55:44 DEBUG : caddy.json: size = 259 OK 2026/10/20 03:55:44 INFO : caddy.json: Copied (replaced existing) 2026/10/20 03:55:46 DEBUG : sub/caddy.json: size = 261 OK 2026/10/20 03:55:46 INFO : sub/caddy.json: Copied (replaced existing) 2026/10/20 03:55:47 DEBUG : sub/deep/caddy.json: size = 131 OK 2026/10/20 03:55:47 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/10/20 03:55:52 DEBUG : sub/index.html: Unchanged skipping 2026/10/20 03:55:53 DEBUG : sub/index.json: Unchanged skipping 2026/10/20 03:55:53 DEBUG : sub/deep/caddy.json: size = 260 OK 2026/10/20 03:55:53 INFO : sub/deep/caddy.json: Copied (replaced existing) 2026/10/20 03:55:53 DEBUG : sub/deep/index.json: size = 235 OK 2026/10/20 03:55:53 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/20 03:55:53 DEBUG : sub/caddy.json: Unchanged skipping 2026/10/20 03:55:54 DEBUG : sub/deep/index.html: size = 12346 OK 2026/10/20 03:55:54 INFO : sub/deep/index.html: Copied (replaced existing) 2026/10/20 03:56:03 DEBUG : index.html: Unchanged skipping 2026/10/20 03:56:14 DEBUG : index.json: Unchanged skipping 2026/10/20 03:56:25 DEBUG : caddy.json: Unchanged skipping 2026/10/20 03:56:31 NOTICE: sub/deep/index.html: Skipped copy as --dry-run is set (size 12.309Ki) 2026/10/20 03:56:31 NOTICE: sub/deep/index.json: Skipped copy as --dry-run is set (size 351) 2026/10/20 03:56:31 NOTICE: sub/deep/caddy.json: Skipped copy as --dry-run is set (size 389) 2026/10/20 03:56:31 DEBUG : index.html: Unchanged skipping 2026/10/20 03:56:31 DEBUG : sub/index.html: Unchanged skipping 2026/10/20 03:56:32 DEBUG : index.json: Unchanged skipping 2026/10/20 03:56:32 DEBUG : sub/index.json: Unchanged skipping 2026/10/20 03:56:32 DEBUG : sub/caddy.json: Unchanged skipping 2026/10/20 03:56:32 DEBUG : caddy.json: Unchanged skipping --- PASS: TestIndex (268.78s) === RUN TestIndexDelete run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 03:58:30 DEBUG : index.html: size = 12147 OK 2026/10/20 03:58:30 INFO : index.html: Copied (new) 2026/10/20 03:58:32 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/20 03:58:32 INFO : sub/deep/index.html: Copied (new) 2026/10/20 03:58:41 DEBUG : sub/index.html: size = 12306 OK 2026/10/20 03:58:41 INFO : sub/index.html: Copied (new) 2026/10/20 03:58:45 DEBUG : index.html: Unchanged skipping 2026/10/20 03:58:45 DEBUG : sub/index.html: Unchanged skipping 2026/10/20 03:58:47 DEBUG : sub/deep/index.html: size = 11829 OK 2026/10/20 03:58:47 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexDelete (175.78s) === RUN TestIndexFilter run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:01:20 DEBUG : other/notes.md: Excluded (Path Filter) 2026/10/20 04:01:24 DEBUG : sub/index.html: size = 12056 OK 2026/10/20 04:01:24 INFO : sub/index.html: Copied (new) 2026/10/20 04:01:27 DEBUG : other/index.html: size = 11802 OK 2026/10/20 04:01:27 INFO : other/index.html: Copied (new) 2026/10/20 04:01:35 DEBUG : index.html: size = 12399 OK 2026/10/20 04:01:35 INFO : index.html: Copied (new) 2026/10/20 04:02:20 DEBUG : other/notes.md: Excluded (Path Filter) 2026/10/20 04:02:20 DEBUG : index.html: Unchanged skipping 2026/10/20 04:02:20 DEBUG : other/index.html: Unchanged skipping 2026/10/20 04:02:20 DEBUG : sub/index.html: Unchanged skipping --- PASS: TestIndexFilter (155.12s) === RUN TestIndexRules run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:03:49 DEBUG : sub/index.html: size = 12306 OK 2026/10/20 04:03:49 INFO : sub/index.html: Copied (new) 2026/10/20 04:04:20 DEBUG : index.html: size = 12147 OK 2026/10/20 04:04:20 INFO : index.html: Copied (new) 2026/10/20 04:04:49 DEBUG : sub/index.html: Unchanged skipping 2026/10/20 04:05:00 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/20 04:05:00 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexRules (150.20s) === RUN TestIndexMaxDepth run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:05:44 DEBUG : index.html: size = 12147 OK 2026/10/20 04:05:44 INFO : index.html: Copied (new) --- PASS: TestIndexMaxDepth (21.66s) === RUN TestIndexLinkIndex run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:06:28 DEBUG : sub/index.html: size = 12347 OK 2026/10/20 04:06:28 INFO : sub/index.html: Copied (new) 2026/10/20 04:06:28 DEBUG : index.html: size = 12167 OK 2026/10/20 04:06:28 INFO : index.html: Copied (new) 2026/10/20 04:06:28 DEBUG : sub/deep/index.html: size = 12128 OK 2026/10/20 04:06:28 INFO : sub/deep/index.html: Copied (new) --- PASS: TestIndexLinkIndex (78.16s) === RUN TestIndexDirTime run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:07:48 DEBUG : sub/deep/index.json: size = 139 OK 2026/10/20 04:07:48 INFO : sub/deep/index.json: Copied (new) 2026/10/20 04:07:49 DEBUG : sub/index.json: size = 255 OK 2026/10/20 04:07:49 INFO : sub/index.json: Copied (new) 2026/10/20 04:07:59 DEBUG : index.json: size = 253 OK 2026/10/20 04:07:59 INFO : index.json: Copied (new) 2026/10/20 04:08:03 DEBUG : sub/deep/index.json: Unchanged skipping 2026/10/20 04:08:04 DEBUG : sub/index.json: size = 235 OK 2026/10/20 04:08:04 INFO : sub/index.json: Copied (replaced existing) 2026/10/20 04:08:04 DEBUG : index.json: size = 233 OK 2026/10/20 04:08:04 INFO : index.json: Copied (replaced existing) 2026/10/20 04:08:19 DEBUG : index.json: size = 213 OK 2026/10/20 04:08:19 INFO : index.json: Copied (replaced existing) 2026/10/20 04:08:19 DEBUG : sub/index.json: size = 215 OK 2026/10/20 04:08:19 INFO : sub/index.json: Copied (replaced existing) 2026/10/20 04:08:29 DEBUG : sub/deep/index.json: size = 119 OK 2026/10/20 04:08:29 INFO : sub/deep/index.json: Copied (replaced existing) --- PASS: TestIndexDirTime (141.56s) === RUN TestIndexTemplate run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:10:11 DEBUG : sub/deep/list.txt: size = 47 OK 2026/10/20 04:10:11 INFO : sub/deep/list.txt: Copied (new) 2026/10/20 04:10:11 DEBUG : sub/list.txt: size = 64 OK 2026/10/20 04:10:11 INFO : sub/list.txt: Copied (new) 2026/10/20 04:10:11 DEBUG : list.txt: size = 59 OK 2026/10/20 04:10:11 INFO : list.txt: Copied (new) --- PASS: TestIndexTemplate (69.90s) === RUN TestIndexTemplateBuiltin --- PASS: TestIndexTemplateBuiltin (0.00s) === RUN TestRcIndex rc_test.go:31: Skipping test on non local remote --- SKIP: TestRcIndex (0.00s) === RUN TestIndexNoHash index_test.go:385: Skipping test on non local remote --- SKIP: TestIndexNoHash (0.00s) === RUN TestIndexChanged run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:11:53 DEBUG : sub/index.html: size = 12306 OK 2026/10/20 04:11:53 INFO : sub/index.html: Copied (new) 2026/10/20 04:11:53 DEBUG : other/index.html: size = 12058 OK 2026/10/20 04:11:53 INFO : other/index.html: Copied (new) 2026/10/20 04:12:02 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/20 04:12:02 INFO : sub/deep/index.html: Copied (new) 2026/10/20 04:12:02 DEBUG : index.html: size = 12399 OK 2026/10/20 04:12:02 INFO : index.html: Copied (new) 2026/10/20 04:12:18 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 3 directories and walking 0 changed directories 2026/10/20 04:12:31 DEBUG : sub/deep/index.html: size = 12346 OK 2026/10/20 04:12:31 INFO : sub/deep/index.html: Copied (replaced existing) 2026/10/20 04:12:32 DEBUG : sub/index.html: Unchanged skipping 2026/10/20 04:12:32 DEBUG : index.html: Unchanged skipping 2026/10/20 04:12:57 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 3 directories and walking 0 changed directories 2026/10/20 04:13:00 DEBUG : index.html: Unchanged skipping 2026/10/20 04:13:01 DEBUG : sub/index.html: size = 12554 OK 2026/10/20 04:13:01 INFO : sub/index.html: Copied (replaced existing) 2026/10/20 04:13:01 DEBUG : sub/new/index.html: size = 12085 OK 2026/10/20 04:13:01 INFO : sub/new/index.html: Copied (new) 2026/10/20 04:13:16 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 1 directories and walking 1 changed directories 2026/10/20 04:13:17 DEBUG : index.html: Unchanged skipping 2026/10/20 04:13:18 DEBUG : other/index.html: size = 12597 OK 2026/10/20 04:13:18 INFO : other/index.html: Copied (replaced existing) 2026/10/20 04:13:22 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 1 directories and walking 0 changed directories 2026/10/20 04:13:34 DEBUG : index.html: Unchanged skipping 2026/10/20 04:13:45 DEBUG : other/index.html: size = 12867 OK 2026/10/20 04:13:45 INFO : other/index.html: Copied (replaced existing) 2026/10/20 04:13:59 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Nothing changed so nothing to index 2026/10/20 04:14:00 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Root changed so doing a full index 2026/10/20 04:14:12 DEBUG : index.html: Unchanged skipping 2026/10/20 04:14:12 DEBUG : other/index.html: Unchanged skipping 2026/10/20 04:14:12 DEBUG : sub/deep/index.html: Unchanged skipping 2026/10/20 04:14:12 DEBUG : sub/new/index.html: Unchanged skipping 2026/10/20 04:14:23 DEBUG : sub/index.html: size = 12813 OK 2026/10/20 04:14:23 INFO : sub/index.html: Copied (replaced existing) 2026/10/20 04:14:46 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index would list 2 directories, more than 1, so doing a full index 2026/10/20 04:14:59 DEBUG : index.html: Unchanged skipping 2026/10/20 04:14:59 DEBUG : sub/deep/index.html: Unchanged skipping 2026/10/20 04:14:59 DEBUG : sub/new/index.html: Unchanged skipping 2026/10/20 04:14:59 DEBUG : sub/index.html: size = 13071 OK 2026/10/20 04:14:59 INFO : sub/index.html: Copied (replaced existing) 2026/10/20 04:15:09 DEBUG : other/index.html: Unchanged skipping 2026/10/20 04:15:12 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 1 directories and walking 1 changed directories 2026/10/20 04:15:15 DEBUG : sub/new/index.html: Unchanged skipping 2026/10/20 04:15:24 DEBUG : index.html: Unchanged skipping 2026/10/20 04:15:24 DEBUG : sub/index.html: Unchanged skipping 2026/10/20 04:15:26 DEBUG : sub/deep/index.html: size = 12604 OK 2026/10/20 04:15:26 INFO : sub/deep/index.html: Copied (replaced existing) 2026/10/20 04:15:31 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 2 directories and walking 0 changed directories 2026/10/20 04:15:33 DEBUG : index.html: Unchanged skipping 2026/10/20 04:15:44 DEBUG : sub/index.html: size = 13331 OK 2026/10/20 04:15:44 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChanged (367.40s) === RUN TestIndexChangedDelete run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:17:32 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/20 04:17:32 INFO : sub/deep/index.html: Copied (new) 2026/10/20 04:17:42 DEBUG : index.html: size = 12147 OK 2026/10/20 04:17:42 INFO : index.html: Copied (new) 2026/10/20 04:17:43 DEBUG : sub/index.html: size = 12306 OK 2026/10/20 04:17:43 INFO : sub/index.html: Copied (new) 2026/10/20 04:17:45 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 3 directories and walking 0 changed directories 2026/10/20 04:17:47 DEBUG : index.html: Unchanged skipping 2026/10/20 04:17:47 DEBUG : sub/index.html: Unchanged skipping 2026/10/20 04:17:47 DEBUG : sub/deep/index.html: size = 11829 OK 2026/10/20 04:17:47 INFO : sub/deep/index.html: Copied (replaced existing) --- PASS: TestIndexChangedDelete (104.58s) === RUN TestIndexChangedFiles run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:20:15 DEBUG : index.html: size = 12401 OK 2026/10/20 04:20:15 INFO : index.html: Copied (new) 2026/10/20 04:20:16 DEBUG : sub/deep/index.html: size = 12087 OK 2026/10/20 04:20:16 INFO : sub/deep/index.html: Copied (new) 2026/10/20 04:20:24 DEBUG : sub/index.html: size = 12306 OK 2026/10/20 04:20:24 INFO : sub/index.html: Copied (new) 2026/10/20 04:20:25 DEBUG : - odd/index.html: size = 12058 OK 2026/10/20 04:20:25 INFO : - odd/index.html: Copied (new) 2026/10/20 04:20:38 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 2 directories and walking 0 changed directories 2026/10/20 04:20:50 DEBUG : index.html: Unchanged skipping 2026/10/20 04:20:50 DEBUG : - odd/index.html: size = 12316 OK 2026/10/20 04:20:50 INFO : - odd/index.html: Copied (replaced existing) 2026/10/20 04:20:52 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 4 directories and walking 0 changed directories 2026/10/20 04:20:55 DEBUG : index.html: Unchanged skipping 2026/10/20 04:20:55 DEBUG : - odd/index.html: Unchanged skipping 2026/10/20 04:20:55 DEBUG : sub/deep/index.html: Unchanged skipping 2026/10/20 04:20:55 DEBUG : sub/index.html: size = 12565 OK 2026/10/20 04:20:55 INFO : sub/index.html: Copied (replaced existing) --- PASS: TestIndexChangedFiles (183.09s) === RUN TestIndexChangedDirTime run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:22:46 DEBUG : sub/deep/index.json: size = 139 OK 2026/10/20 04:22:46 INFO : sub/deep/index.json: Copied (new) 2026/10/20 04:22:55 DEBUG : index.json: size = 371 OK 2026/10/20 04:22:55 INFO : index.json: Copied (new) 2026/10/20 04:22:55 DEBUG : sub/index.json: size = 255 OK 2026/10/20 04:22:55 INFO : sub/index.json: Copied (new) 2026/10/20 04:23:05 DEBUG : other/index.json: size = 140 OK 2026/10/20 04:23:05 INFO : other/index.json: Copied (new) 2026/10/20 04:23:07 INFO : sugarsync root 'Test/rclone-test-yapurox0huzu': Partial index: listing 3 directories and walking 0 changed directories 2026/10/20 04:23:11 DEBUG : sub/deep/index.json: size = 275 OK 2026/10/20 04:23:11 INFO : sub/deep/index.json: Copied (replaced existing) 2026/10/20 04:23:11 DEBUG : index.json: size = 371 OK 2026/10/20 04:23:11 INFO : index.json: Copied (replaced existing) 2026/10/20 04:23:11 DEBUG : sub/index.json: size = 255 OK 2026/10/20 04:23:11 INFO : sub/index.json: Copied (replaced existing) 2026/10/20 04:23:21 DEBUG : sugarsync root 'Test/rclone-test-yapurox0huzu': Renewing token 2026/10/20 04:23:24 DEBUG : Config file has changed externally - reloading 2026/10/20 04:23:24 DEBUG : Saving config "authorization" in section "TestSugarSync" of the config file 2026/10/20 04:23:24 DEBUG : Saving config "authorization_expiry" in section "TestSugarSync" of the config file 2026/10/20 04:23:24 DEBUG : Saving config "user" in section "TestSugarSync" of the config file --- PASS: TestIndexChangedDirTime (141.56s) === RUN TestListDirSorted run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:24:57 DEBUG : a.txt: Excluded (Size Filter) 2026/10/20 04:24:57 DEBUG : a.txt: Excluded 2026/10/20 04:25:08 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/10/20 04:25:08 DEBUG : sub dir/hello world: Excluded 2026/10/20 04:25:08 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/10/20 04:25:08 DEBUG : sub dir/hello world2: Excluded 2026/10/20 04:25:08 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/10/20 04:25:08 DEBUG : sub dir/hello world: Excluded 2026/10/20 04:25:08 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/10/20 04:25:08 DEBUG : sub dir/hello world2: Excluded 2026/10/20 04:25:09 DEBUG : sub dir/ignore dir: Excluded 2026/10/20 04:25:10 DEBUG : sub dir/ignore dir: Excluded --- PASS: TestListDirSorted (118.80s) === RUN TestListDirSortedFn run.go:198: Remote "sugarsync root 'Test/rclone-test-yapurox0huzu'", Local "Local file system at /tmp/rclone727927114", Modify Window "876000h0m0s" 2026/10/20 04:27:36 DEBUG : a.txt: Excluded (Size Filter) 2026/10/20 04:27:36 DEBUG : a.txt: Excluded 2026/10/20 04:27:37 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/10/20 04:27:37 DEBUG : sub dir/hello world: Excluded 2026/10/20 04:27:37 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/10/20 04:27:37 DEBUG : sub dir/hello world2: Excluded 2026/10/20 04:27:38 DEBUG : sub dir/hello world: Excluded (Size Filter) 2026/10/20 04:27:38 DEBUG : sub dir/hello world: Excluded 2026/10/20 04:27:38 DEBUG : sub dir/hello world2: Excluded (Size Filter) 2026/10/20 04:27:38 DEBUG : sub dir/hello world2: Excluded 2026/10/20 04:27:39 DEBUG : sub dir/ignore dir: Excluded 2026/10/20 04:27:40 DEBUG : sub dir/ignore dir: Excluded panic: test timed out after 1h0m0s running tests: TestListDirSortedFn (1m57s) goroutine 14321 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2959 +0x34a created by time.goFunc /usr/local/go/src/time/sleep.go:182 +0x2d goroutine 1 [chan receive, 2 minutes]: testing.(*T).Run(0x24d9dfe4e248, {0x25e65eb?, 0x24d9dfcc5a58?}, 0x4a57e10) /usr/local/go/src/testing/testing.go:2266 +0x4f2 testing.runTests.func1(0x24d9dfe4e248) /usr/local/go/src/testing/testing.go:2742 +0x37 testing.tRunner(0x24d9dfe4e248, 0x24d9dfcc5b80) /usr/local/go/src/testing/testing.go:2193 +0xea testing.runTests({0x25fb0fd, 0x18}, {0x263acef, 0x26}, 0x24d9e086a588, {0x4d35690, 0x96, 0x96}, {0xc2add9530e50c7b7, 0x3493af2d9a7, ...}) /usr/local/go/src/testing/testing.go:2740 +0x510 testing.(*M).Run(0x24d9e03e2460) /usr/local/go/src/testing/testing.go:2600 +0x6af github.com/rclone/rclone/fstest.TestMain(0x24d9e03e2460) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:75 +0xa6 github.com/rclone/rclone/fs/operations_test.TestMain(...) /home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:64 main.main() _testmain.go:348 +0xa6 goroutine 51 [syscall, 60 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x98 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func2.1 in goroutine 1 /usr/local/go/src/os/signal/signal.go:164 +0x1f goroutine 52 [chan receive, 60 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 1 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 2665 [chan receive, 52 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 14263 [select]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:397 +0x150 sync.(*WaitGroup).Go.func1() /usr/local/go/src/sync/waitgroup.go:258 +0x4a created by sync.(*WaitGroup).Go in goroutine 13967 /usr/local/go/src/sync/waitgroup.go:238 +0x7d goroutine 117 [chan receive, 59 minutes]: github.com/rclone/rclone/lib/atexit.Register.func1.1() /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:45 +0x2e created by github.com/rclone/rclone/lib/atexit.Register.func1 in goroutine 115 /home/rclone/go/src/github.com/rclone/rclone/lib/atexit/atexit.go:44 +0x68 goroutine 14225 [select]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:397 +0x150 sync.(*WaitGroup).Go.func1() /usr/local/go/src/sync/waitgroup.go:258 +0x4a created by sync.(*WaitGroup).Go in goroutine 13967 /usr/local/go/src/sync/waitgroup.go:238 +0x7d goroutine 14258 [select]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:397 +0x150 sync.(*WaitGroup).Go.func1() /usr/local/go/src/sync/waitgroup.go:258 +0x4a created by sync.(*WaitGroup).Go in goroutine 13967 /usr/local/go/src/sync/waitgroup.go:238 +0x7d goroutine 14260 [select]: net/http.(*Transport).getConn(0x24d9e000ca80, 0x24d9dfd48960, {{}, 0x0, {0x24d9e0441d40, 0x5}, {0x24d9e06303d8, 0x15}, 0x0}) /usr/local/go/src/net/http/transport.go:1619 +0x4b0 net/http.(*Transport).roundTrip(0x24d9e000ca80, 0x24d9e012a780) /usr/local/go/src/net/http/transport.go:714 +0xa99 net/http.(*Transport).RoundTrip(...) /usr/local/go/src/net/http/roundtrip.go:33 github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0x24d9dffac420, 0x24d9e012a780) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:709 +0x657 net/http.send(0x24d9e012a780, {0x4a16498, 0x24d9dffac420}, {0x24d9e04bae68?, 0x497406?, 0x0?}) /usr/local/go/src/net/http/client.go:266 +0x654 net/http.(*Client).send(0x24d9e040cb10, 0x24d9e012a780, {0x2770cf8?, 0x1?, 0x0?}) /usr/local/go/src/net/http/client.go:187 +0x250 net/http.(*Client).do(0x24d9e040cb10, 0x24d9e012a780) /usr/local/go/src/net/http/client.go:745 +0x9f7 net/http.(*Client).Do(...) /usr/local/go/src/net/http/client.go:604 github.com/rclone/rclone/lib/rest.(*Client).Call(0x24d9e03b3a40, {0x4a3d4b0, 0x4d736e0}, 0x24d9e04bb578) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:511 +0xe85 github.com/rclone/rclone/lib/rest.(*Client).callCodec(0x24d9e03b3a40, {0x4a3d4b0, 0x4d736e0}, 0x24d9e0820420?, {0x41d3ba8?, 0x24d9e065c140?}, {0x41bae08, 0x24d9dfc92198}, 0x24d9e04bb74f?, 0x4a59410, ...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:757 +0x4e8 github.com/rclone/rclone/lib/rest.(*Client).CallXML(...) /home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:711 github.com/rclone/rclone/backend/sugarsync.(*Fs).moveFile.func1() /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:967 +0x8d github.com/rclone/rclone/fs.pacerInvoker(0x1, 0xa, 0x24d9e076a3d3?) /home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0x24d9dffac480, 0x24d9dfd488c0, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:228 +0xd2 github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0x24d9dffac480, 0x24d9dfd488c0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:247 +0x91 github.com/rclone/rclone/backend/sugarsync.(*Fs).moveFile(0x24d9e041a240, {0x4a3d4b0, 0x4d736e0}, {0x24d9e0441d40, 0x39}, {0x24d9e076a3d3, 0x11}, {0x24d9dff3100d, 0x2e}) /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:966 +0x2dd github.com/rclone/rclone/backend/sugarsync.(*Fs).delete(0x5801d7?, {0x4a3d4b0?, 0x4d736e0?}, 0xcb?, {0x24d9e0441d40?, 0x24d9e04bbb50?}, {0x24d9e076a3c0?, 0x27706e8?}, 0x25?) /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:807 +0x234 github.com/rclone/rclone/backend/sugarsync.(*Object).Remove(0x5801c0?, {0x4a3d4b0?, 0x4d736e0?}) /home/rclone/go/src/github.com/rclone/rclone/backend/sugarsync/sugarsync.go:1307 +0x3d github.com/rclone/rclone/fstest.newRunIndividual.func1.1.1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 +0x24 github.com/rclone/rclone/fstest.Retry(0x24d9e0564008, {0x24d9e0441ec0, 0x34}, 0x24d9e04bbcd0) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:135 +0xf6 github.com/rclone/rclone/fstest.newRunIndividual.func1.1({0x24d9e028f1a0?, 0x490e5a?, 0x42b589?}) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:173 +0x29a github.com/rclone/rclone/fs/walk.listRwalk.func1({0x24d9e01f0180?, 0x435e828?}, {0x24d9e028f1a0, 0x2, 0x2}, {0x0?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:179 +0x197 github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:421 +0x2dc sync.(*WaitGroup).Go.func1() /usr/local/go/src/sync/waitgroup.go:258 +0x4a created by sync.(*WaitGroup).Go in goroutine 13967 /usr/local/go/src/sync/waitgroup.go:238 +0x7d goroutine 3326 [chan receive, 49 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 13967 [sync.WaitGroup.Wait]: sync.runtime_SemacquireWaitGroup(0x24d9dfcc5b60?, 0x1?) /usr/local/go/src/runtime/sema.go:114 +0x2e sync.(*WaitGroup).Wait(0x24d9dff4ab10) /usr/local/go/src/sync/waitgroup.go:206 +0x85 github.com/rclone/rclone/fs/walk.walk({0x4a3d4b0, 0x4d736e0}, {0x4a4d8b8, 0x24d9e041a240}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x24d9e02b03c0, 0x4a58338) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:459 +0x372 github.com/rclone/rclone/fs/walk.walkListDirSorted(...) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:350 github.com/rclone/rclone/fs/walk.Walk({0x4a3d4b0, 0x4d736e0}, {0x4a4d8b8, 0x24d9e041a240}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x24d9e02b03c0) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:76 +0x2bd github.com/rclone/rclone/fs/walk.listRwalk({0x4a3d4b0, 0x4d736e0}, {0x4a4d8b8, 0x24d9e041a240}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0x24d9e02b0210) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:170 +0x125 github.com/rclone/rclone/fs/walk.ListR({0x4a3d4b0, 0x4d736e0}, {0x4a4d8b8, 0x24d9e041a240}, {0x0, 0x0}, 0x1, 0xffffffffffffffff, 0x3, 0x24d9e02b0210) /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:160 +0x23e github.com/rclone/rclone/fstest.newRunIndividual.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:169 +0x125 github.com/rclone/rclone/fstest.(*Run).Finalise(0x24d9e05b5a40) /home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:406 +0x1c testing.(*common).Cleanup.func1() /usr/local/go/src/testing/testing.go:1460 +0x109 testing.(*common).runCleanup(0x24d9e0564008, 0x0?) /usr/local/go/src/testing/testing.go:1820 +0xea testing.tRunner.func2() /usr/local/go/src/testing/testing.go:2187 +0x25 testing.tRunner(0x24d9e0564008, 0x4a57e10) /usr/local/go/src/testing/testing.go:2199 +0x114 created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:2258 +0x4d4 goroutine 14326 [chan receive]: net/http.(*persistConn).addTLS(0x24d9e012aa00, {0x4a3d5c8, 0x24d9dfd489b0}, {0x24d9e06303d8, 0x11}, 0x0) /usr/local/go/src/net/http/transport.go:1809 +0x347 net/http.(*Transport).dialConn(0x24d9e000ca80, {0x4a3d5c8, 0x24d9dfd489b0}, {{}, 0x0, {0x24d9e0441d40, 0x5}, {0x24d9e06303d8, 0x15}, 0x0}, ...) /usr/local/go/src/net/http/transport.go:1930 +0xb7e net/http.(*Transport).dialConnFor(0x24d9e000ca80, 0x24d9e070e0b0) /usr/local/go/src/net/http/transport.go:1715 +0x8e net/http.(*Transport).startDialConnForLocked.func1() /usr/local/go/src/net/http/transport.go:1696 +0x35 created by net/http.(*Transport).startDialConnForLocked in goroutine 14260 /usr/local/go/src/net/http/transport.go:1695 +0x11c goroutine 14262 [select]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:397 +0x150 sync.(*WaitGroup).Go.func1() /usr/local/go/src/sync/waitgroup.go:258 +0x4a created by sync.(*WaitGroup).Go in goroutine 13967 /usr/local/go/src/sync/waitgroup.go:238 +0x7d goroutine 14261 [sync.Mutex.Lock]: internal/sync.runtime_SemacquireMutex(0x24d9e04bfdb8?, 0x5f?, 0x4791db8?) /usr/local/go/src/runtime/sema.go:95 +0x25 internal/sync.(*Mutex).lockSlow(0x24d9dff4aa78) /usr/local/go/src/internal/sync/mutex.go:149 +0x15a internal/sync.(*Mutex).Lock(...) /usr/local/go/src/internal/sync/mutex.go:70 sync.(*Mutex).Lock(...) /usr/local/go/src/sync/mutex.go:46 github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:420 +0x287 sync.(*WaitGroup).Go.func1() /usr/local/go/src/sync/waitgroup.go:258 +0x4a created by sync.(*WaitGroup).Go in goroutine 13967 /usr/local/go/src/sync/waitgroup.go:238 +0x7d goroutine 3129 [chan receive, 49 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 2337 [chan receive, 53 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 14224 [select]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:397 +0x150 sync.(*WaitGroup).Go.func1() /usr/local/go/src/sync/waitgroup.go:258 +0x4a created by sync.(*WaitGroup).Go in goroutine 13967 /usr/local/go/src/sync/waitgroup.go:238 +0x7d goroutine 2518 [chan receive, 52 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 3042 [chan receive, 50 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 14320 [IO wait]: internal/poll.runtime_pollWait(0x7644be08c800, 0x72) /usr/local/go/src/runtime/netpoll.go:351 +0x85 internal/poll.(*pollDesc).wait(0x24d9e00e8800?, 0x24d9e0745d40?, 0x0) /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 internal/poll.(*pollDesc).waitRead(...) /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0x24d9e00e8800, {0x24d9e0745d40, 0x240, 0x240}) /usr/local/go/src/internal/poll/fd_unix.go:170 +0x2a8 net.(*netFD).Read(0x24d9e00e8800, {0x24d9e0745d40?, 0x205?, 0x24d9e0745d40?}) /usr/local/go/src/net/fd_posix.go:68 +0x25 net.(*conn).Read(0x24d9dfc5a478, {0x24d9e0745d40?, 0x0?, 0x0?}) /usr/local/go/src/net/net.go:196 +0x45 github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0x24d9e06fdd40, {0x24d9e0745d40?, 0x0?, 0x0?}) /home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29 crypto/tls.(*Conn).readFromUntil(0x24d9e039c408, {0x7644bc5662c0, 0x24d9e06fdd40}, 0xb3914b?) /usr/local/go/src/crypto/tls/conn.go:820 +0xf4 crypto/tls.(*Conn).readRecordOrCCS(0x24d9e039c408, 0x0) /usr/local/go/src/crypto/tls/conn.go:626 +0x3fb crypto/tls.(*Conn).readRecord(...) /usr/local/go/src/crypto/tls/conn.go:588 crypto/tls.(*Conn).readHandshakeBytes(0x24d9e039c408, 0x4) /usr/local/go/src/crypto/tls/conn.go:1083 +0x51 crypto/tls.(*Conn).readHandshake(0x24d9e039c408, {0x0, 0x0}) /usr/local/go/src/crypto/tls/conn.go:1094 +0x39 crypto/tls.(*Conn).clientHandshake(0x24d9e039c408, {0x4a3d5c8, 0x24d9e06414f0}) /usr/local/go/src/crypto/tls/handshake_client.go:301 +0x507 crypto/tls.(*Conn).handshakeContext(0x24d9e039c408, {0x4a3d5c8, 0x24d9dfd489b0}) /usr/local/go/src/crypto/tls/conn.go:1561 +0x32e crypto/tls.(*Conn).HandshakeContext(...) /usr/local/go/src/crypto/tls/conn.go:1515 net/http.(*persistConn).addTLS.func2() /usr/local/go/src/net/http/transport.go:1803 +0x6e created by net/http.(*persistConn).addTLS in goroutine 14326 /usr/local/go/src/net/http/transport.go:1799 +0x329 goroutine 14259 [select]: github.com/rclone/rclone/fs/walk.walk.func2() /home/rclone/go/src/github.com/rclone/rclone/fs/walk/walk.go:397 +0x150 sync.(*WaitGroup).Go.func1() /usr/local/go/src/sync/waitgroup.go:258 +0x4a created by sync.(*WaitGroup).Go in goroutine 13967 /usr/local/go/src/sync/waitgroup.go:238 +0x7d goroutine 2385 [chan receive, 53 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 2795 [chan receive, 51 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 3282 [chan receive, 49 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae goroutine 2839 [chan receive, 51 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 2336 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xae "./operations.test -test.v -test.timeout 1h0m0s -remote TestSugarSync:Test -verbose" - Finished ERROR in 1h0m13.076377564s (try 1/5): exit status 2: Failed []