"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose" - Starting (try 1/5)
2025/09/22 03:09:37 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vezujeg4tana"
2025/09/22 03:09:37 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/22 03:09:38 DEBUG : Starting OpenDrive session with ID: 3f71c4f7ecf084002632ac52d2bf468cabd56a42494b792bf27bfaeadee5e1f2
2025/09/22 03:09:38 DEBUG : Creating backend with remote "/tmp/rclone97880718"
=== 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 "OpenDrive root 'rclone-test-vezujeg4tana'", Local "Local file system at /tmp/rclone97880718", Modify Window "1s"
    multithread_test.go:121: multithread writing not supported
2025/09/22 03:09:39 ERROR : error listing: directory not found
--- SKIP: TestMultithreadCopy (0.19s)
=== RUN   TestMultithreadCopyAbort
    run.go:180: Remote "OpenDrive root 'rclone-test-vezujeg4tana'", Local "Local file system at /tmp/rclone97880718", Modify Window "1s"
    multithread_test.go:121: multithread writing not supported
2025/09/22 03:09:39 ERROR : error listing: directory not found
--- SKIP: TestMultithreadCopyAbort (0.20s)
=== RUN   TestSizeDiffers
--- PASS: TestSizeDiffers (0.00s)
=== RUN   TestReOpen
=== RUN   TestReOpen/Normal
=== RUN   TestReOpen/Normal/Basics
2025/09/22 03:09:39 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/Seek
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/09/22 03:09:39 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2025/09/22 03:09:39 DEBUG : potato: Seek from 10 to 0
2025/09/22 03:09:39 DEBUG : potato: Seek from 10 to 0
2025/09/22 03:09:39 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2025/09/22 03:09:39 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/Seek
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/09/22 03:09:39 DEBUG : potato: Seek from 5 to 2
2025/09/22 03:09:39 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2025/09/22 03:09:39 DEBUG : potato: Seek from 7 to 0
2025/09/22 03:09:39 DEBUG : potato: Seek from 7 to 0
2025/09/22 03:09:39 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2025/09/22 03:09:39 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/Seek
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/09/22 03:09:39 DEBUG : potato: Seek from 5 to 2
2025/09/22 03:09:39 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2025/09/22 03:09:39 DEBUG : potato: Seek from 8 to 0
2025/09/22 03:09:39 DEBUG : potato: Seek from 8 to 0
2025/09/22 03:09:39 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2025/09/22 03:09:39 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2025/09/22 03:09:39 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/Seek
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2025/09/22 03:09:39 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2025/09/22 03:09:39 DEBUG : potato: Seek from 5 to 2
2025/09/22 03:09:39 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2025/09/22 03:09:39 DEBUG : potato: Seek from 9 to 0
2025/09/22 03:09:39 DEBUG : potato: Seek from 9 to 0
2025/09/22 03:09:39 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 "OpenDrive root 'rclone-test-vezujeg4tana'", Local "Local file system at /tmp/rclone97880718", Modify Window "1s"
2025/09/22 03:09:40 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0
=== RUN   TestCheck/1
=== RUN   TestCheck/2
2025/09/22 03:09:43 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
=== RUN   TestCheck/3
2025/09/22 03:09:45 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
=== RUN   TestCheck/4
=== RUN   TestCheck/5
2025/09/22 03:09:47 DEBUG : remotepotato: Uploading chunk 0, size=60, remain=0
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (11.29s)
    --- PASS: TestCheck/1 (0.19s)
    --- PASS: TestCheck/2 (0.19s)
    --- PASS: TestCheck/3 (0.19s)
    --- PASS: TestCheck/4 (0.20s)
    --- PASS: TestCheck/5 (0.20s)
    --- PASS: TestCheck/6 (0.19s)
    --- PASS: TestCheck/7 (0.19s)
=== RUN   TestCheckFsError
2025/09/22 03:09:50 DEBUG : Creating backend with remote "nonexistent"
2025/09/22 03:09:50 DEBUG : Creating backend with remote "nonexistent"
2025/09/22 03:09:50 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish
2025/09/22 03:09:50 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found
2025/09/22 03:09:50 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found
2025/09/22 03:09:50 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 "OpenDrive root 'rclone-test-vezujeg4tana'", Local "Local file system at /tmp/rclone97880718", Modify Window "1s"
2025/09/22 03:09:51 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0
=== RUN   TestCheckDownload/1
=== RUN   TestCheckDownload/2
2025/09/22 03:09:54 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
=== RUN   TestCheckDownload/3
2025/09/22 03:09:56 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
=== RUN   TestCheckDownload/4
=== RUN   TestCheckDownload/5
2025/09/22 03:10:00 DEBUG : remotepotato: Uploading chunk 0, size=60, remain=0
=== RUN   TestCheckDownload/6
=== RUN   TestCheckDownload/7
--- PASS: TestCheckDownload (13.75s)
    --- PASS: TestCheckDownload/1 (0.42s)
    --- PASS: TestCheckDownload/2 (0.54s)
    --- PASS: TestCheckDownload/3 (0.39s)
    --- PASS: TestCheckDownload/4 (0.77s)
    --- PASS: TestCheckDownload/5 (0.54s)
    --- PASS: TestCheckDownload/6 (0.38s)
    --- PASS: TestCheckDownload/7 (0.95s)
=== RUN   TestCheckSizeOnly
    run.go:180: Remote "OpenDrive root 'rclone-test-vezujeg4tana'", Local "Local file system at /tmp/rclone97880718", Modify Window "1s"
2025/09/22 03:10:05 DEBUG : rutabaga: Uploading chunk 0, size=8, remain=0
=== RUN   TestCheckSizeOnly/1
=== RUN   TestCheckSizeOnly/2
2025/09/22 03:10:07 DEBUG : empty space: Uploading chunk 0, size=1, remain=0
=== RUN   TestCheckSizeOnly/3
2025/09/22 03:10:10 DEBUG : potato2: Uploading chunk 0, size=60, remain=0
=== RUN   TestCheckSizeOnly/4
=== RUN   TestCheckSizeOnly/5
2025/09/22 03:10:12 DEBUG : remotepotato: Uploading chunk 0, size=60, remain=0
=== RUN   TestCheckSizeOnly/6
=== RUN   TestCheckSizeOnly/7
--- PASS: TestCheckSizeOnly (11.00s)
    --- PASS: TestCheckSizeOnly/1 (0.20s)
    --- PASS: TestCheckSizeOnly/2 (0.20s)
    --- PASS: TestCheckSizeOnly/3 (0.20s)
    --- PASS: TestCheckSizeOnly/4 (0.19s)
    --- PASS: TestCheckSizeOnly/5 (0.20s)
    --- PASS: TestCheckSizeOnly/6 (0.21s)
    --- PASS: TestCheckSizeOnly/7 (0.19s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:180: Remote "OpenDrive root 'rclone-test-vezujeg4tana'", Local "Local file system at /tmp/rclone97880718", Modify Window "1s"
2025/09/22 03:10:16 DEBUG : test.sum: Uploading chunk 0, size=81, remain=0
2025/09/22 03:10:17 NOTICE: test.sum: improperly formatted checksum line 4
2025/09/22 03:10:17 NOTICE: test.sum: improperly formatted checksum line 5
2025/09/22 03:10:17 NOTICE: test.sum: improperly formatted checksum line 6
2025/09/22 03:10:17 NOTICE: test.sum: 2 warning(s) suppressed...
2025/09/22 03:10:18 DEBUG : test.sum: Uploading chunk 0, size=90, remain=0
2025/09/22 03:10:19 NOTICE: test.sum: improperly formatted checksum line 4
2025/09/22 03:10:19 NOTICE: test.sum: improperly formatted checksum line 5
2025/09/22 03:10:19 NOTICE: test.sum: improperly formatted checksum line 6
2025/09/22 03:10:19 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (4.99s)
=== RUN   TestCheckSum
    run.go:180: Remote "OpenDrive root 'rclone-test-vezujeg4tana'", Local "Local file system at /tmp/rclone97880718", Modify Window "1s"
2025/09/22 03:10:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vezujeg4tana/data"
2025/09/22 03:10:20 DEBUG : Starting OpenDrive session with ID: 8d88f2a253428ce20be92748e961c762e999dc89a2ac7677b11f55cbd3fab3e1
2025/09/22 03:10:22 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/09/22 03:10:24 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSum/subtest1
2025/09/22 03:10:27 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/09/22 03:10:29 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSum/subtest2
2025/09/22 03:10:31 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSum/subtest3
2025/09/22 03:10:34 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSum/subtest4
2025/09/22 03:10:36 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSum/subtest5
2025/09/22 03:10:38 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSum/subtest6
2025/09/22 03:10:41 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/09/22 03:10:42 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/09/22 03:10:44 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSum/subtest7
--- PASS: TestCheckSum (27.19s)
    --- PASS: TestCheckSum/subtest1 (0.75s)
    --- PASS: TestCheckSum/subtest2 (0.57s)
    --- PASS: TestCheckSum/subtest3 (0.61s)
    --- PASS: TestCheckSum/subtest4 (0.59s)
    --- PASS: TestCheckSum/subtest5 (0.58s)
    --- PASS: TestCheckSum/subtest6 (0.61s)
    --- PASS: TestCheckSum/subtest7 (0.59s)
=== RUN   TestCheckSumDownload
    run.go:180: Remote "OpenDrive root 'rclone-test-vezujeg4tana'", Local "Local file system at /tmp/rclone97880718", Modify Window "1s"
2025/09/22 03:10:47 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-vezujeg4tana/data"
2025/09/22 03:10:48 DEBUG : Starting OpenDrive session with ID: 555dfd1e9a48fab467bd740cdcf481548022cb8361d93072cddb7e1367c47b15
2025/09/22 03:10:50 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/09/22 03:10:51 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSumDownload/subtest1
2025/09/22 03:10:55 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/09/22 03:10:56 DEBUG : test.sum: Uploading chunk 0, size=41, remain=0
=== RUN   TestCheckSumDownload/subtest2
2025/09/22 03:10:59 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest3
2025/09/22 03:11:02 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest4
2025/09/22 03:11:04 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSumDownload/subtest5
2025/09/22 03:11:07 DEBUG : test.sum: Uploading chunk 0, size=123, remain=0
=== RUN   TestCheckSumDownload/subtest6
2025/09/22 03:11:10 DEBUG : data/banana: Uploading chunk 0, size=13, remain=0
2025/09/22 03:11:11 DEBUG : data/potato: Uploading chunk 0, size=15, remain=0
2025/09/22 03:11:13 DEBUG : test.sum: Uploading chunk 0, size=82, remain=0
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (29.31s)
    --- PASS: TestCheckSumDownload/subtest1 (0.96s)
    --- PASS: TestCheckSumDownload/subtest2 (0.74s)
    --- PASS: TestCheckSumDownload/subtest3 (1.16s)
    --- PASS: TestCheckSumDownload/subtest4 (0.78s)
    --- PASS: TestCheckSumDownload/subtest5 (0.78s)
    --- PASS: TestCheckSumDownload/subtest6 (0.78s)
    --- PASS: TestCheckSumDownload/subtest7 (0.78s)
=== RUN   TestApplyTransforms
2025/09/22 03:11:16 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-zuhudas1zeyo"
2025/09/22 03:11:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/22 03:11:17 DEBUG : Starting OpenDrive session with ID: d23756eac41ccb1c44fa01956b14262eb61713398e24c1a4cf098c068dadcf74
2025/09/22 03:11:17 DEBUG : Creating backend with remote "/tmp/rclone708488769"
    run.go:180: Remote "OpenDrive root 'rclone-test-zuhudas1zeyo'", Local "Local file system at /tmp/rclone708488769", Modify Window "1s"
2025/09/22 03:11:19 DEBUG : hello, world!: Uploading chunk 0, size=13, remain=0
upper checkfile vs. lower remote (without normalization)
2025/09/22 03:11:20 ERROR : hello, world!: sum not found
2025/09/22 03:11:20 ERROR : HELLO, WORLD!: file not in OpenDrive root 'rclone-test-zuhudas1zeyo'
2025/09/22 03:11:20 NOTICE: OpenDrive root 'rclone-test-zuhudas1zeyo': 1 files missing
2025/09/22 03:11:20 NOTICE: 1 hashes missing
2025/09/22 03:11:20 NOTICE: OpenDrive root 'rclone-test-zuhudas1zeyo': 1 differences found
2025/09/22 03:11:20 NOTICE: OpenDrive root 'rclone-test-zuhudas1zeyo': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2025/09/22 03:11:20 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/09/22 03:11:20 NOTICE: OpenDrive root 'rclone-test-zuhudas1zeyo': 0 differences found
2025/09/22 03:11:20 NOTICE: OpenDrive root 'rclone-test-zuhudas1zeyo': 1 matching files
2025/09/22 03:11:20 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-xeyecah3sewo"
2025/09/22 03:11:20 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/22 03:11:21 DEBUG : Starting OpenDrive session with ID: b67ff496c1b1fe0d5a4d973ee04e34f9ba91b798f528dd1f048311d4b93fe3db
2025/09/22 03:11:21 DEBUG : Creating backend with remote "/tmp/rclone1858798320"
    run.go:180: Remote "OpenDrive root 'rclone-test-xeyecah3sewo'", Local "Local file system at /tmp/rclone1858798320", Modify Window "1s"
2025/09/22 03:11:23 DEBUG : HELLO, WORLD!: Uploading chunk 0, size=13, remain=0
lower checkfile vs. upper remote (without normalization)
2025/09/22 03:11:24 ERROR : HELLO, WORLD!: sum not found
2025/09/22 03:11:24 ERROR : hello, world!: file not in OpenDrive root 'rclone-test-xeyecah3sewo'
2025/09/22 03:11:24 NOTICE: OpenDrive root 'rclone-test-xeyecah3sewo': 1 files missing
2025/09/22 03:11:24 NOTICE: 1 hashes missing
2025/09/22 03:11:24 NOTICE: OpenDrive root 'rclone-test-xeyecah3sewo': 1 differences found
2025/09/22 03:11:24 NOTICE: OpenDrive root 'rclone-test-xeyecah3sewo': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2025/09/22 03:11:24 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2025/09/22 03:11:24 NOTICE: OpenDrive root 'rclone-test-xeyecah3sewo': 0 differences found
2025/09/22 03:11:24 NOTICE: OpenDrive root 'rclone-test-xeyecah3sewo': 1 matching files
2025/09/22 03:11:24 DEBUG : Creating backend with remote "TestOpenDrive:rclone-test-litayeb7yona"
2025/09/22 03:11:24 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/09/22 03:11:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2025/09/22 03:11:25 NOTICE: Failed to open remote "TestOpenDrive:": failed to create session: Unknown 404 Not Found (Error 404)
"./operations.test -test.v -test.timeout 1h0m0s -remote TestOpenDrive: -verbose" - Finished ERROR in 1m47.371539222s (try 1/5): exit status 1: Failed []