"./operations.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -fast-list" - Starting (try 1/5)
2024/12/19 02:52:14 DEBUG : Creating backend with remote "TestStorj:rclone-test-hametuw5cuxi"
2024/12/19 02:52:14 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/19 02:52:14 DEBUG : FS sj://rclone-test-hametuw5cuxi: connecting...
2024/12/19 02:52:14 DEBUG : FS sj://rclone-test-hametuw5cuxi: connected: <nil>
2024/12/19 02:52:14 DEBUG : Creating backend with remote "/tmp/rclone3015567944"
=== 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:180: Remote "FS sj://rclone-test-hametuw5cuxi", Local "Local file system at /tmp/rclone3015567944", Modify Window "1ns"
    multithread_test.go:121: multithread writing not supported
2024/12/19 02:52:14 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:52:14 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
--- SKIP: TestMultithreadCopy (0.18s)
=== RUN   TestMultithreadCopyAbort
    run.go:180: Remote "FS sj://rclone-test-hametuw5cuxi", Local "Local file system at /tmp/rclone3015567944", Modify Window "1ns"
    multithread_test.go:121: multithread writing not supported
2024/12/19 02:52:14 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:52:14 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
--- SKIP: TestMultithreadCopyAbort (0.04s)
=== RUN   TestSizeDiffers
--- PASS: TestSizeDiffers (0.00s)
=== RUN   TestReOpen
=== RUN   TestReOpen/Normal
=== RUN   TestReOpen/Normal/Basics
2024/12/19 02:52:14 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/Seek
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2024/12/19 02:52:14 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2024/12/19 02:52:14 DEBUG : potato: Seek from 10 to 0
2024/12/19 02:52:14 DEBUG : potato: Seek from 10 to 0
2024/12/19 02:52:14 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2024/12/19 02:52:14 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/Seek
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2024/12/19 02:52:14 DEBUG : potato: Seek from 5 to 2
2024/12/19 02:52:14 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2024/12/19 02:52:14 DEBUG : potato: Seek from 7 to 0
2024/12/19 02:52:14 DEBUG : potato: Seek from 7 to 0
2024/12/19 02:52:14 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2024/12/19 02:52:14 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/Seek
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2024/12/19 02:52:14 DEBUG : potato: Seek from 5 to 2
2024/12/19 02:52:14 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2024/12/19 02:52:14 DEBUG : potato: Seek from 8 to 0
2024/12/19 02:52:14 DEBUG : potato: Seek from 8 to 0
2024/12/19 02:52:14 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2024/12/19 02:52:14 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2024/12/19 02:52:14 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/Seek
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2024/12/19 02:52:14 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2024/12/19 02:52:14 DEBUG : potato: Seek from 5 to 2
2024/12/19 02:52:14 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2024/12/19 02:52:14 DEBUG : potato: Seek from 9 to 0
2024/12/19 02:52:14 DEBUG : potato: Seek from 9 to 0
2024/12/19 02:52:14 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/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/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/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/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:180: Remote "FS sj://rclone-test-hametuw5cuxi", Local "Local file system at /tmp/rclone3015567944", Modify Window "1ns"
2024/12/19 02:52:14 DEBUG : FS sj://rclone-test-hametuw5cuxi: mkdir -p ./
2024/12/19 02:52:15 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./rutabaga # [] 8
2024/12/19 02:56:58 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:56:58 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2024/12/19 02:56:58 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./empty space # [] 1
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheck/3
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./potato2 # [] 60
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./remotepotato # [] 60
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheck/6
=== RUN   TestCheck/7
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
2024/12/19 02:56:59 DEBUG : rutabaga: rm sj://rclone-test-hametuw5cuxi/rutabaga
2024/12/19 02:56:59 DEBUG : remotepotato: rm sj://rclone-test-hametuw5cuxi/remotepotato
2024/12/19 02:56:59 DEBUG : potato2: rm sj://rclone-test-hametuw5cuxi/potato2
2024/12/19 02:56:59 DEBUG : empty space: rm sj://rclone-test-hametuw5cuxi/empty space
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:56:59 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
--- PASS: TestCheck (285.22s)
    --- PASS: TestCheck/1 (0.04s)
    --- PASS: TestCheck/2 (0.04s)
    --- PASS: TestCheck/3 (0.04s)
    --- PASS: TestCheck/4 (0.04s)
    --- PASS: TestCheck/5 (0.05s)
    --- PASS: TestCheck/6 (0.04s)
    --- PASS: TestCheck/7 (0.04s)
=== RUN   TestCheckFsError
2024/12/19 02:57:00 DEBUG : Creating backend with remote "nonexistent"
2024/12/19 02:57:00 DEBUG : Creating backend with remote "nonexistent"
2024/12/19 02:57:00 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish
2024/12/19 02:57:00 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found
2024/12/19 02:57:00 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 differences found
2024/12/19 02:57:00 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 2 errors while checking
--- PASS: TestCheckFsError (0.00s)
=== RUN   TestCheckDownload
    run.go:180: Remote "FS sj://rclone-test-hametuw5cuxi", Local "Local file system at /tmp/rclone3015567944", Modify Window "1ns"
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./rutabaga # [] 8
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./empty space # [] 1
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckDownload/3
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./potato2 # [] 60
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2024/12/19 02:57:01 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./remotepotato # [] 60
2024/12/19 02:57:01 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:01 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
2024/12/19 02:57:01 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:01 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
2024/12/19 02:57:01 DEBUG : rutabaga: rm sj://rclone-test-hametuw5cuxi/rutabaga
2024/12/19 02:57:01 DEBUG : remotepotato: rm sj://rclone-test-hametuw5cuxi/remotepotato
2024/12/19 02:57:01 DEBUG : potato2: rm sj://rclone-test-hametuw5cuxi/potato2
2024/12/19 02:57:01 DEBUG : empty space: rm sj://rclone-test-hametuw5cuxi/empty space
2024/12/19 02:57:01 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:01 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
--- PASS: TestCheckDownload (1.96s)
    --- PASS: TestCheckDownload/1 (0.10s)
    --- PASS: TestCheckDownload/2 (0.09s)
    --- PASS: TestCheckDownload/3 (0.09s)
    --- PASS: TestCheckDownload/4 (0.18s)
    --- PASS: TestCheckDownload/5 (0.09s)
    --- PASS: TestCheckDownload/6 (0.11s)
    --- PASS: TestCheckDownload/7 (0.11s)
=== RUN   TestCheckSizeOnly
    run.go:180: Remote "FS sj://rclone-test-hametuw5cuxi", Local "Local file system at /tmp/rclone3015567944", Modify Window "1ns"
2024/12/19 02:57:01 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./rutabaga # [] 8
2024/12/19 02:57:02 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:02 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2024/12/19 02:57:02 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./empty space # [] 1
2024/12/19 02:57:02 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:02 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSizeOnly/3
2024/12/19 02:57:02 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./potato2 # [] 60
2024/12/19 02:57:02 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:02 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./remotepotato # [] 60
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
2024/12/19 02:57:03 DEBUG : rutabaga: rm sj://rclone-test-hametuw5cuxi/rutabaga
2024/12/19 02:57:03 DEBUG : remotepotato: rm sj://rclone-test-hametuw5cuxi/remotepotato
2024/12/19 02:57:03 DEBUG : potato2: rm sj://rclone-test-hametuw5cuxi/potato2
2024/12/19 02:57:03 DEBUG : empty space: rm sj://rclone-test-hametuw5cuxi/empty space
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
--- PASS: TestCheckSizeOnly (1.73s)
    --- PASS: TestCheckSizeOnly/1 (0.04s)
    --- PASS: TestCheckSizeOnly/2 (0.06s)
    --- PASS: TestCheckSizeOnly/3 (0.04s)
    --- PASS: TestCheckSizeOnly/4 (0.04s)
    --- PASS: TestCheckSizeOnly/5 (0.04s)
    --- PASS: TestCheckSizeOnly/6 (0.04s)
    --- PASS: TestCheckSizeOnly/7 (0.04s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:180: Remote "FS sj://rclone-test-hametuw5cuxi", Local "Local file system at /tmp/rclone3015567944", Modify Window "1ns"
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 81
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: stat ./test.sum
2024/12/19 02:57:03 DEBUG : test.sum: cat sj://rclone-test-hametuw5cuxi/test.sum # []
2024/12/19 02:57:03 DEBUG : test.sum: range 0 + -1
2024/12/19 02:57:03 NOTICE: test.sum: improperly formatted checksum line 4
2024/12/19 02:57:03 NOTICE: test.sum: improperly formatted checksum line 5
2024/12/19 02:57:03 NOTICE: test.sum: improperly formatted checksum line 6
2024/12/19 02:57:03 NOTICE: test.sum: 2 warning(s) suppressed...
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 90
2024/12/19 02:57:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: uploading too fast - sleeping for 1 second: uplink: too many requests
    run.go:281: Retry Put of "test.sum" to FS sj://rclone-test-hametuw5cuxi: 1/10 (uplink: too many requests)
2024/12/19 02:57:06 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 90
2024/12/19 02:57:45 DEBUG : FS sj://rclone-test-hametuw5cuxi: stat ./test.sum
2024/12/19 02:57:46 DEBUG : test.sum: cat sj://rclone-test-hametuw5cuxi/test.sum # []
2024/12/19 02:57:46 DEBUG : test.sum: range 0 + -1
2024/12/19 02:57:46 NOTICE: test.sum: improperly formatted checksum line 4
2024/12/19 02:57:46 NOTICE: test.sum: improperly formatted checksum line 5
2024/12/19 02:57:46 NOTICE: test.sum: improperly formatted checksum line 6
2024/12/19 02:57:46 NOTICE: test.sum: 2 warning(s) suppressed...
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
2024/12/19 02:57:46 DEBUG : test.sum: rm sj://rclone-test-hametuw5cuxi/test.sum
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
--- PASS: TestParseSumFile (42.51s)
=== RUN   TestCheckSum
    run.go:180: Remote "FS sj://rclone-test-hametuw5cuxi", Local "Local file system at /tmp/rclone3015567944", Modify Window "1ns"
2024/12/19 02:57:46 DEBUG : Creating backend with remote "TestStorj:rclone-test-hametuw5cuxi/data"
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi/data: connecting...
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi/data: connected: <nil>
    check_test.go:354: FS sj://rclone-test-hametuw5cuxi/data lacks md5, skipping
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
--- SKIP: TestCheckSum (0.24s)
=== RUN   TestCheckSumDownload
    run.go:180: Remote "FS sj://rclone-test-hametuw5cuxi", Local "Local file system at /tmp/rclone3015567944", Modify Window "1ns"
2024/12/19 02:57:46 DEBUG : Creating backend with remote "TestStorj:rclone-test-hametuw5cuxi/data"
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi/data: connecting...
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi/data: connected: <nil>
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./data/banana # [] 13
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 41
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:46 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSumDownload/subtest1
2024/12/19 02:57:47 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./data/potato # [] 15
2024/12/19 02:57:47 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 41
2024/12/19 02:57:47 DEBUG : FS sj://rclone-test-hametuw5cuxi: uploading too fast - sleeping for 1 second: uplink: too many requests
=== NAME  TestCheckSumDownload
    run.go:281: Retry Put of "test.sum" to FS sj://rclone-test-hametuw5cuxi: 1/10 (uplink: too many requests)
2024/12/19 02:57:50 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 41
2024/12/19 02:57:50 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:50 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSumDownload/subtest2
2024/12/19 02:57:50 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 82
2024/12/19 02:57:50 DEBUG : FS sj://rclone-test-hametuw5cuxi: uploading too fast - sleeping for 1 second: uplink: too many requests
=== NAME  TestCheckSumDownload
    run.go:281: Retry Put of "test.sum" to FS sj://rclone-test-hametuw5cuxi: 1/10 (uplink: too many requests)
2024/12/19 02:57:53 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 82
2024/12/19 02:57:53 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:53 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSumDownload/subtest3
2024/12/19 02:57:54 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 82
2024/12/19 02:57:54 DEBUG : FS sj://rclone-test-hametuw5cuxi: uploading too fast - sleeping for 1 second: uplink: too many requests
=== NAME  TestCheckSumDownload
    run.go:281: Retry Put of "test.sum" to FS sj://rclone-test-hametuw5cuxi: 1/10 (uplink: too many requests)
2024/12/19 02:57:57 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 82
2024/12/19 02:57:57 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:57:57 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSumDownload/subtest4
2024/12/19 02:57:57 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 123
2024/12/19 02:57:57 DEBUG : FS sj://rclone-test-hametuw5cuxi: uploading too fast - sleeping for 1 second: uplink: too many requests
=== NAME  TestCheckSumDownload
    run.go:281: Retry Put of "test.sum" to FS sj://rclone-test-hametuw5cuxi: 1/10 (uplink: too many requests)
2024/12/19 02:58:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 123
2024/12/19 02:58:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:58:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSumDownload/subtest5
2024/12/19 02:58:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 123
2024/12/19 02:58:00 DEBUG : FS sj://rclone-test-hametuw5cuxi: uploading too fast - sleeping for 1 second: uplink: too many requests
=== NAME  TestCheckSumDownload
    run.go:281: Retry Put of "test.sum" to FS sj://rclone-test-hametuw5cuxi: 1/10 (uplink: too many requests)
2024/12/19 02:58:03 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 123
2024/12/19 02:58:04 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:58:04 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSumDownload/subtest6
2024/12/19 02:58:04 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./data/banana # [] 13
2024/12/19 02:58:04 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./data/potato # [] 15
2024/12/19 02:58:04 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 82
2024/12/19 02:58:04 DEBUG : FS sj://rclone-test-hametuw5cuxi: uploading too fast - sleeping for 1 second: uplink: too many requests
=== NAME  TestCheckSumDownload
    run.go:281: Retry Put of "test.sum" to FS sj://rclone-test-hametuw5cuxi: 1/10 (uplink: too many requests)
2024/12/19 02:58:07 DEBUG : FS sj://rclone-test-hametuw5cuxi: cp input ./test.sum # [] 82
2024/12/19 02:58:07 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:58:07 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
=== RUN   TestCheckSumDownload/subtest7
2024/12/19 02:58:07 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:58:07 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
2024/12/19 02:58:08 DEBUG : data/potato: rm sj://rclone-test-hametuw5cuxi/data/potato
2024/12/19 02:58:08 DEBUG : data/banana: rm sj://rclone-test-hametuw5cuxi/data/banana
2024/12/19 02:58:08 DEBUG : test.sum: rm sj://rclone-test-hametuw5cuxi/test.sum
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-hametuw5cuxi: rmdir ./data
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-hametuw5cuxi: ls -R ./
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-hametuw5cuxi: OBJ ls -R ./ ("rclone-test-hametuw5cuxi", "")
--- PASS: TestCheckSumDownload (21.88s)
    --- PASS: TestCheckSumDownload/subtest1 (0.17s)
    --- PASS: TestCheckSumDownload/subtest2 (0.17s)
    --- PASS: TestCheckSumDownload/subtest3 (0.24s)
    --- PASS: TestCheckSumDownload/subtest4 (0.21s)
    --- PASS: TestCheckSumDownload/subtest5 (0.21s)
    --- PASS: TestCheckSumDownload/subtest6 (0.19s)
    --- PASS: TestCheckSumDownload/subtest7 (0.17s)
=== RUN   TestApplyTransforms
2024/12/19 02:58:08 DEBUG : Creating backend with remote "TestStorj:rclone-test-vujatir4jive"
2024/12/19 02:58:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: connecting...
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: connected: <nil>
2024/12/19 02:58:08 DEBUG : Creating backend with remote "/tmp/rclone2552614935"
    run.go:180: Remote "FS sj://rclone-test-vujatir4jive", Local "Local file system at /tmp/rclone2552614935", Modify Window "1ns"
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: mkdir -p ./
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: cp input ./hello, world! # [] 13
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: ls ./
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: OBJ ls ./ ("rclone-test-vujatir4jive", "")
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
upper checkfile vs. lower remote (without normalization)
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: ls -R ./
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: OBJ ls -R ./ ("rclone-test-vujatir4jive", "")
2024/12/19 02:58:08 ERROR : hello, world!: sum not found
2024/12/19 02:58:08 ERROR : HELLO, WORLD!: file not in FS sj://rclone-test-vujatir4jive
2024/12/19 02:58:08 NOTICE: FS sj://rclone-test-vujatir4jive: 1 files missing
2024/12/19 02:58:08 NOTICE: 1 hashes missing
2024/12/19 02:58:08 NOTICE: FS sj://rclone-test-vujatir4jive: 2 differences found
2024/12/19 02:58:08 NOTICE: FS sj://rclone-test-vujatir4jive: 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: ls -R ./
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-vujatir4jive: OBJ ls -R ./ ("rclone-test-vujatir4jive", "")
2024/12/19 02:58:08 DEBUG : hello, world!: cat sj://rclone-test-vujatir4jive/hello, world! # []
2024/12/19 02:58:08 DEBUG : hello, world!: range 0 + -1
2024/12/19 02:58:08 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2024/12/19 02:58:08 NOTICE: FS sj://rclone-test-vujatir4jive: 0 differences found
2024/12/19 02:58:08 NOTICE: FS sj://rclone-test-vujatir4jive: 1 matching files
2024/12/19 02:58:08 DEBUG : Creating backend with remote "TestStorj:rclone-test-yiyeqaj5hami"
2024/12/19 02:58:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-yiyeqaj5hami: connecting...
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-yiyeqaj5hami: connected: <nil>
2024/12/19 02:58:08 DEBUG : Creating backend with remote "/tmp/rclone2873095154"
    run.go:180: Remote "FS sj://rclone-test-yiyeqaj5hami", Local "Local file system at /tmp/rclone2873095154", Modify Window "1ns"
2024/12/19 02:58:08 DEBUG : FS sj://rclone-test-yiyeqaj5hami: mkdir -p ./
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-yiyeqaj5hami: cp input ./HELLO, WORLD! # [] 13
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-yiyeqaj5hami: ls ./
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-yiyeqaj5hami: OBJ ls ./ ("rclone-test-yiyeqaj5hami", "")
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-yiyeqaj5hami: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
lower checkfile vs. upper remote (without normalization)
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-yiyeqaj5hami: ls -R ./
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-yiyeqaj5hami: OBJ ls -R ./ ("rclone-test-yiyeqaj5hami", "")
2024/12/19 02:58:09 ERROR : HELLO, WORLD!: sum not found
2024/12/19 02:58:09 ERROR : hello, world!: file not in FS sj://rclone-test-yiyeqaj5hami
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-yiyeqaj5hami: 1 files missing
2024/12/19 02:58:09 NOTICE: 1 hashes missing
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-yiyeqaj5hami: 2 differences found
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-yiyeqaj5hami: 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-yiyeqaj5hami: ls -R ./
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-yiyeqaj5hami: OBJ ls -R ./ ("rclone-test-yiyeqaj5hami", "")
2024/12/19 02:58:09 DEBUG : HELLO, WORLD!: cat sj://rclone-test-yiyeqaj5hami/HELLO, WORLD! # []
2024/12/19 02:58:09 DEBUG : HELLO, WORLD!: range 0 + -1
2024/12/19 02:58:09 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-yiyeqaj5hami: 0 differences found
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-yiyeqaj5hami: 1 matching files
2024/12/19 02:58:09 DEBUG : Creating backend with remote "TestStorj:rclone-test-cuwihiv9guno"
2024/12/19 02:58:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: connecting...
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: connected: <nil>
2024/12/19 02:58:09 DEBUG : Creating backend with remote "/tmp/rclone4011987828"
    run.go:180: Remote "FS sj://rclone-test-cuwihiv9guno", Local "Local file system at /tmp/rclone4011987828", Modify Window "1ns"
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: mkdir -p ./
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: cp input ./HeLlO, wOrLd! # [] 13
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: ls ./
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: OBJ ls ./ ("rclone-test-cuwihiv9guno", "")
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
lower checkfile vs. upperlowermixed remote (without normalization)
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: ls -R ./
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: OBJ ls -R ./ ("rclone-test-cuwihiv9guno", "")
2024/12/19 02:58:09 ERROR : HeLlO, wOrLd!: sum not found
2024/12/19 02:58:09 ERROR : hello, world!: file not in FS sj://rclone-test-cuwihiv9guno
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-cuwihiv9guno: 1 files missing
2024/12/19 02:58:09 NOTICE: 1 hashes missing
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-cuwihiv9guno: 2 differences found
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-cuwihiv9guno: 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: ls -R ./
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-cuwihiv9guno: OBJ ls -R ./ ("rclone-test-cuwihiv9guno", "")
2024/12/19 02:58:09 DEBUG : HeLlO, wOrLd!: cat sj://rclone-test-cuwihiv9guno/HeLlO, wOrLd! # []
2024/12/19 02:58:09 DEBUG : HeLlO, wOrLd!: range 0 + -1
2024/12/19 02:58:09 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-cuwihiv9guno: 0 differences found
2024/12/19 02:58:09 NOTICE: FS sj://rclone-test-cuwihiv9guno: 1 matching files
2024/12/19 02:58:09 DEBUG : Creating backend with remote "TestStorj:rclone-test-wedaxax0kuzo"
2024/12/19 02:58:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-wedaxax0kuzo: connecting...
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-wedaxax0kuzo: connected: <nil>
2024/12/19 02:58:09 DEBUG : Creating backend with remote "/tmp/rclone3325733849"
    run.go:180: Remote "FS sj://rclone-test-wedaxax0kuzo", Local "Local file system at /tmp/rclone3325733849", Modify Window "1ns"
2024/12/19 02:58:09 DEBUG : FS sj://rclone-test-wedaxax0kuzo: mkdir -p ./
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-wedaxax0kuzo: cp input ./HELLO, WORLD! # [] 13
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-wedaxax0kuzo: ls ./
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-wedaxax0kuzo: OBJ ls ./ ("rclone-test-wedaxax0kuzo", "")
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-wedaxax0kuzo: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
upperlowermixed checkfile vs. upper remote (without normalization)
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-wedaxax0kuzo: ls -R ./
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-wedaxax0kuzo: OBJ ls -R ./ ("rclone-test-wedaxax0kuzo", "")
2024/12/19 02:58:10 ERROR : HELLO, WORLD!: sum not found
2024/12/19 02:58:10 ERROR : HeLlO, wOrLd!: file not in FS sj://rclone-test-wedaxax0kuzo
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-wedaxax0kuzo: 1 files missing
2024/12/19 02:58:10 NOTICE: 1 hashes missing
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-wedaxax0kuzo: 2 differences found
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-wedaxax0kuzo: 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-wedaxax0kuzo: ls -R ./
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-wedaxax0kuzo: OBJ ls -R ./ ("rclone-test-wedaxax0kuzo", "")
2024/12/19 02:58:10 DEBUG : HELLO, WORLD!: cat sj://rclone-test-wedaxax0kuzo/HELLO, WORLD! # []
2024/12/19 02:58:10 DEBUG : HELLO, WORLD!: range 0 + -1
2024/12/19 02:58:10 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-wedaxax0kuzo: 0 differences found
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-wedaxax0kuzo: 1 matching files
2024/12/19 02:58:10 DEBUG : Creating backend with remote "TestStorj:rclone-test-hupotes2bagu"
2024/12/19 02:58:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: connecting...
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: connected: <nil>
2024/12/19 02:58:10 DEBUG : Creating backend with remote "/tmp/rclone2080789957"
    run.go:180: Remote "FS sj://rclone-test-hupotes2bagu", Local "Local file system at /tmp/rclone2080789957", Modify Window "1ns"
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: mkdir -p ./
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: cp input ./測試_Русский___ě_áñ # [] 13
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: ls ./
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: OBJ ls ./ ("rclone-test-hupotes2bagu", "")
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
NFD checkfile vs. NFC remote (without normalization)
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: ls -R ./
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: OBJ ls -R ./ ("rclone-test-hupotes2bagu", "")
2024/12/19 02:58:10 ERROR : 測試_Русский___ě_áñ: sum not found
2024/12/19 02:58:10 ERROR : 測試_Русский___ě_áñ: file not in FS sj://rclone-test-hupotes2bagu
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-hupotes2bagu: 1 files missing
2024/12/19 02:58:10 NOTICE: 1 hashes missing
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-hupotes2bagu: 2 differences found
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-hupotes2bagu: 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: ls -R ./
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-hupotes2bagu: OBJ ls -R ./ ("rclone-test-hupotes2bagu", "")
2024/12/19 02:58:10 DEBUG : 測試_Русский___ě_áñ: cat sj://rclone-test-hupotes2bagu/測試_Русский___ě_áñ # []
2024/12/19 02:58:10 DEBUG : 測試_Русский___ě_áñ: range 0 + -1
2024/12/19 02:58:10 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-hupotes2bagu: 0 differences found
2024/12/19 02:58:10 NOTICE: FS sj://rclone-test-hupotes2bagu: 1 matching files
2024/12/19 02:58:10 DEBUG : Creating backend with remote "TestStorj:rclone-test-geqereb9qeta"
2024/12/19 02:58:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-geqereb9qeta: connecting...
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-geqereb9qeta: connected: <nil>
2024/12/19 02:58:10 DEBUG : Creating backend with remote "/tmp/rclone3103669266"
    run.go:180: Remote "FS sj://rclone-test-geqereb9qeta", Local "Local file system at /tmp/rclone3103669266", Modify Window "1ns"
2024/12/19 02:58:10 DEBUG : FS sj://rclone-test-geqereb9qeta: mkdir -p ./
2024/12/19 02:58:11 DEBUG : FS sj://rclone-test-geqereb9qeta: cp input ./測試_Русский___ě_áñ # [] 13
2024/12/19 02:58:11 DEBUG : FS sj://rclone-test-geqereb9qeta: ls ./
2024/12/19 02:58:11 DEBUG : FS sj://rclone-test-geqereb9qeta: OBJ ls ./ ("rclone-test-geqereb9qeta", "")
2024/12/19 02:58:11 DEBUG : FS sj://rclone-test-geqereb9qeta: opts &{Prefix: Cursor: Recursive:false System:true Custom:true}
    check_test.go:575: Fs is incapable of running test, skipping: NFC checkfile vs. NFD remote (expected: 測試_Русский___ě_áñ (NFD) actual: 測試_Русский___ě_áñ (NFC))
2024/12/19 02:58:11 DEBUG : FS sj://rclone-test-geqereb9qeta: Purge remote
2024/12/19 03:24:26 DEBUG : FS sj://rclone-test-hupotes2bagu: Purge remote
2024/12/19 03:28:24 DEBUG : FS sj://rclone-test-wedaxax0kuzo: Purge remote
2024/12/19 03:34:47 DEBUG : FS sj://rclone-test-cuwihiv9guno: Purge remote
2024/12/19 03:43:48 DEBUG : FS sj://rclone-test-yiyeqaj5hami: Purge remote
2024/12/19 03:48:22 DEBUG : FS sj://rclone-test-vujatir4jive: Purge remote
panic: test timed out after 1h0m0s
running tests:
	TestApplyTransforms (54m6s)

goroutine 1038 [running]:
testing.(*M).startAlarm.func1()
	/usr/local/go/src/testing/testing.go:2366 +0x385
created by time.goFunc
	/usr/local/go/src/time/sleep.go:177 +0x2d

goroutine 1 [chan receive, 54 minutes]:
testing.(*T).Run(0xc0000fa820, {0x23fa891?, 0x0?}, 0x25168d8)
	/usr/local/go/src/testing/testing.go:1750 +0x3ab
testing.runTests.func1(0xc0000fa820)
	/usr/local/go/src/testing/testing.go:2161 +0x37
testing.tRunner(0xc0000fa820, 0xc00060fc00)
	/usr/local/go/src/testing/testing.go:1689 +0xfb
testing.runTests(0xc000012de0, {0x39a6a60, 0x6c, 0x6c}, {0x1?, 0xedef5815e?, 0x39ba660?})
	/usr/local/go/src/testing/testing.go:2159 +0x445
testing.(*M).Run(0xc0000e92c0)
	/usr/local/go/src/testing/testing.go:2027 +0x68b
github.com/rclone/rclone/fstest.TestMain(0xc0000e92c0)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:69 +0xa5
github.com/rclone/rclone/fs/operations_test.TestMain(...)
	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/operations_test.go:61
main.main()
	_testmain.go:265 +0x196

goroutine 174 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 165
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 11 [syscall, 60 minutes]:
os/signal.signal_recv()
	/usr/local/go/src/runtime/sigqueue.go:152 +0x29
os/signal.loop()
	/usr/local/go/src/os/signal/signal_unix.go:23 +0x13
created by os/signal.Notify.func1.1 in goroutine 1
	/usr/local/go/src/os/signal/signal.go:151 +0x1f

goroutine 12 [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 +0xab

goroutine 816 [sync.Cond.Wait, 4 minutes]:
sync.runtime_notifyListWait(0xc00085cd90, 0x0)
	/usr/local/go/src/runtime/sema.go:569 +0x159
sync.(*Cond).Wait(0xc00006a980?)
	/usr/local/go/src/sync/cond.go:70 +0x85
storj.io/drpc/drpcstream.(*packetBuffer).Get(0xc00085cd78)
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcstream/pktbuf.go:65 +0x8b
storj.io/drpc/drpcstream.(*Stream).MsgRecv(0xc00085cc88, {0x2257d40, 0xc000936450}, {0x27cdd10, 0x3a1d7e0})
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcstream/stream.go:504 +0x105
storj.io/drpc/drpcconn.(*Conn).doInvoke(0x0?, 0xc00085cc88, {0x27cdd10, 0x3a1d7e0}, {0x2426324?, 0x4?}, {0xc0004342a0, 0x6f, 0x70}, {0x0, ...}, ...)
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcconn/conn.go:152 +0x117
storj.io/drpc/drpcconn.(*Conn).Invoke(0xc00053ab40, {0x27d72f8, 0xc000824500}, {0x2426324, 0x1f}, {0x27cdd10, 0x3a1d7e0}, {0x2285f20, 0xc00099c190}, {0x2257d40, ...})
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcconn/conn.go:131 +0x325
storj.io/common/rpc/rpcpool.(*poolConn).Invoke.func1()
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/rpc/rpcpool/conn.go:126 +0x59
storj.io/common/rpc/rpcpool.stackAnnotated(0x416c578d, 0xcc74, 0x229f865b, 0x1e61, 0x7, 0x0?)
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/rpc/rpcpool/debug.go:43 +0x34
storj.io/common/rpc/rpcpool.stackAnnotate({0x7f9fd81f0198, 0xc000778660}, 0xc00006aea8)
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/rpc/rpcpool/debug.go:79 +0xe9
storj.io/common/rpc/rpcpool.(*poolConn).Invoke(0xc0000ae5a0, {0x27d72f8, 0xc000824500}, {0x2426324, 0x1f}, {0x27cdd10, 0x3a1d7e0}, {0x2285f20, 0xc00099c190}, {0x2257d40, ...})
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/rpc/rpcpool/conn.go:125 +0x495
storj.io/common/rpc/rpctracing.(*TracingWrapper).Invoke(0xc0009b01c0, {0x27d72f8?, 0xc000824460?}, {0x2426324, 0x1f}, {0x27cdd10, 0x3a1d7e0}, {0x2285f20, 0xc00099c190}, {0x2257d40, ...})
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/rpc/rpctracing/tracing.go:32 +0xbd
storj.io/common/experiment.(*Wrapper).Invoke(0xc0009b01d0, {0x27d72f8?, 0xc000824460?}, {0x2426324, 0x1f}, {0x27cdd10, 0x3a1d7e0}, {0x2285f20, 0xc00099c190}, {0x2257d40, ...})
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/experiment/export.go:28 +0xbd
storj.io/common/pb.(*drpcMetainfoClient).DeleteBucket(0xc0009b01f0, {0x27d72f8, 0xc000824460}, 0xc00099c190)
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/pb/metainfo_drpc.pb.go:150 +0x98
storj.io/uplink/private/metaclient.(*Client).DeleteBucket.func1({0x27d72f8, 0xc000824460})
	/home/rclone/go/pkg/mod/storj.io/uplink@v1.13.1/private/metaclient/client.go:434 +0x149
storj.io/uplink/private/metaclient.WithRetry({0x27d72f8, 0xc000824460}, 0xc00006b308)
	/home/rclone/go/pkg/mod/storj.io/uplink@v1.13.1/private/metaclient/retry.go:71 +0x6f
storj.io/uplink/private/metaclient.(*Client).DeleteBucket(0xc00099c0f0, {0x27d72f8, 0xc000824460}, {{0xc00014a1f8, 0x18, 0x18}, 0x1})
	/home/rclone/go/pkg/mod/storj.io/uplink@v1.13.1/private/metaclient/client.go:432 +0x245
storj.io/uplink/private/metaclient.(*DB).DeleteBucket(0xc000454198, {0x27d72f8, 0xc0008243c0}, {0xc00080a100, 0x18}, 0x1)
	/home/rclone/go/pkg/mod/storj.io/uplink@v1.13.1/private/metaclient/metainfo.go:61 +0x2ae
storj.io/uplink.(*Project).DeleteBucketWithObjects(0xc0003f9688, {0x27d72f8, 0xc000824000}, {0xc00080a100, 0x18})
	/home/rclone/go/pkg/mod/storj.io/uplink@v1.13.1/bucket.go:148 +0x2db
github.com/rclone/rclone/backend/storj.(*Fs).Purge(0xc000053c00, {0x27d64a0, 0x3a1d7e0}, {0x0?, 0x0?})
	/home/rclone/go/src/github.com/rclone/rclone/backend/storj/fs.go:805 +0x85
github.com/rclone/rclone/fstest.Purge({0x27eba08, 0xc000053c00})
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:488 +0xb1
github.com/rclone/rclone/fstest.RandomRemote.func1()
	/home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:465 +0x48
github.com/rclone/rclone/fstest.(*Run).Finalise(0xc000313420)
	/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:388 +0x1c
testing.(*common).Cleanup.func1()
	/usr/local/go/src/testing/testing.go:1175 +0x10f
testing.(*common).runCleanup(0xc0000fb1e0, 0xc000102fc0?)
	/usr/local/go/src/testing/testing.go:1353 +0xdb
testing.tRunner.func2()
	/usr/local/go/src/testing/testing.go:1683 +0x25
runtime.Goexit()
	/usr/local/go/src/runtime/panic.go:626 +0x5e
testing.(*common).SkipNow(0xc0000fb1e0)
	/usr/local/go/src/testing/testing.go:1120 +0x45
testing.(*common).Skipf(0xc0000fb1e0, {0x248fac0?, 0x0?}, {0xc00006be20?, 0x0?, 0x27d64a0?})
	/usr/local/go/src/testing/testing.go:1103 +0x5e
github.com/rclone/rclone/fs/operations_test.TestApplyTransforms.func1({0xc000707180, 0x1f}, {0xc000617ce0, 0x24}, {0x2418301, 0x1c})
	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:575 +0x45f
github.com/rclone/rclone/fs/operations_test.TestApplyTransforms(0xc0000fb1e0)
	/home/rclone/go/src/github.com/rclone/rclone/fs/operations/check_test.go:604 +0x2ba
testing.tRunner(0xc0000fb1e0, 0x25168d8)
	/usr/local/go/src/testing/testing.go:1689 +0xfb
created by testing.(*T).Run in goroutine 1
	/usr/local/go/src/testing/testing.go:1742 +0x390

goroutine 71 [sleep, 11 minutes]:
time.Sleep(0x8bb2c97000)
	/usr/local/go/src/runtime/time.go:195 +0x115
github.com/spacemonkeygo/monkit/v3.(*ticker).run(0x39b97c0)
	/home/rclone/go/pkg/mod/github.com/spacemonkeygo/monkit/v3@v3.0.22/meter.go:203 +0x26
created by github.com/spacemonkeygo/monkit/v3.(*ticker).register in goroutine 70
	/home/rclone/go/pkg/mod/github.com/spacemonkeygo/monkit/v3@v3.0.22/meter.go:195 +0x89

goroutine 846 [chan receive, 54 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 816
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab

goroutine 140 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 131
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 1052 [IO wait, 4 minutes]:
internal/poll.runtime_pollWait(0x7f9fdb5c0a20, 0x72)
	/usr/local/go/src/runtime/netpoll.go:345 +0x85
internal/poll.(*pollDesc).wait(0xc0008f4680?, 0xc000806800?, 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(0xc0008f4680, {0xc000806800, 0x800, 0x800})
	/usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a
net.(*netFD).Read(0xc0008f4680, {0xc000806800?, 0xc0000927e8?, 0x66bd94?})
	/usr/local/go/src/net/fd_posix.go:55 +0x25
net.(*conn).Read(0xc00050e140, {0xc000806800?, 0xc000092798?, 0xc000092788?})
	/usr/local/go/src/net/net.go:179 +0x45
storj.io/common/rpc.(*timedConn).Read(0xc0007780a8, {0xc000806800?, 0x41cc25?, 0x800?})
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/rpc/common.go:61 +0x85
storj.io/common/rpc/multidial.(*conn).Read(0xc0009365d0, {0xc000806800, 0x800, 0x800})
	/home/rclone/go/pkg/mod/storj.io/common@v0.0.0-20240812101423-26b53789c348/rpc/multidial/conn.go:48 +0x27a
crypto/tls.(*atLeastReader).Read(0xc0007da030, {0xc000806800?, 0x0?, 0xc0007da030?})
	/usr/local/go/src/crypto/tls/conn.go:806 +0x3b
bytes.(*Buffer).ReadFrom(0xc00096cd30, {0x27b2660, 0xc0007da030})
	/usr/local/go/src/bytes/buffer.go:211 +0x98
crypto/tls.(*Conn).readFromUntil(0xc00096ca88, {0x7f9fd9399ec8, 0xc000936600}, 0xc000092970?)
	/usr/local/go/src/crypto/tls/conn.go:828 +0xde
crypto/tls.(*Conn).readRecordOrCCS(0xc00096ca88, 0x0)
	/usr/local/go/src/crypto/tls/conn.go:626 +0x3cf
crypto/tls.(*Conn).readRecord(...)
	/usr/local/go/src/crypto/tls/conn.go:588
crypto/tls.(*Conn).Read(0xc00096ca88, {0xc0003c0000, 0x1000, 0x0?})
	/usr/local/go/src/crypto/tls/conn.go:1370 +0x156
storj.io/drpc/drpcwire.(*Reader).read(0xc000329a40, {0xc0003c0000, 0x1000, 0x1000})
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcwire/reader.go:68 +0x74
storj.io/drpc/drpcwire.(*Reader).ReadPacketUsing(0xc000329a40, {0x0?, 0xa1c265?, 0x27d64a0?})
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcwire/reader.go:113 +0x4ec
storj.io/drpc/drpcmanager.(*Manager).manageReader(0xc000656780)
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcmanager/manager.go:230 +0xe5
created by storj.io/drpc/drpcmanager.NewWithOptions in goroutine 816
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcmanager/manager.go:122 +0x436

goroutine 187 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 178
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 202 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 177
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 920 [chan receive, 54 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 816
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab

goroutine 249 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 240
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 474 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 465
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 606 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 597
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 564 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 555
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 387 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 282
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 316 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 307
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 261 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 252
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 497 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 488
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 702 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 693
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 541 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 532
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 273 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 264
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 1053 [select, 4 minutes]:
storj.io/drpc/drpcmanager.(*Manager).manageStream(0xc000656780, {0x27d72f8, 0xc000824500}, 0xc00085cc88)
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcmanager/manager.go:334 +0xf1
storj.io/drpc/drpcmanager.(*Manager).manageStreams(0xc000656780)
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcmanager/manager.go:323 +0x8d
created by storj.io/drpc/drpcmanager.NewWithOptions in goroutine 816
	/home/rclone/go/pkg/mod/storj.io/drpc@v0.0.35-0.20240709171858-0075ac871661/drpcmanager/manager.go:123 +0x476

goroutine 348 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 339
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 1000 [chan receive, 54 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 816
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab

goroutine 416 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 407
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 333 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 324
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 671 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 662
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 817 [chan receive, 54 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 816
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab

goroutine 617 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 544
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 948 [chan receive, 54 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 816
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab

goroutine 507 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 498
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 682 [chan receive (nil chan), 55 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:211 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 625
	/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:209 +0x378

goroutine 978 [chan receive, 54 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 816
	/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab
"./operations.test -test.v -test.timeout 1h0m0s -remote TestStorj: -verbose -fast-list" - Finished ERROR in 1h0m0.129563944s (try 1/5): exit status 2: Failed []