"./operations.test -test.v -test.timeout 1h0m0s -remote TestMega: -verbose" - Starting (try 1/5)
2026/05/08 01:01:19 DEBUG : Creating backend with remote "TestMega:rclone-test-vidilek4rema"
2026/05/08 01:01:19 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/08 01:01:19 DEBUG : mega root 'rclone-test-vidilek4rema': Using previously stored session ID and master key to initialize the Mega API
2026/05/08 01:01:37 DEBUG : Creating backend with remote "/tmp/rclone3552730748"
=== 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 "mega root 'rclone-test-vidilek4rema'", Local "Local file system at /tmp/rclone3552730748", Modify Window "876000h0m0s"
    multithread_test.go:119: multithread writing not supported
2026/05/08 01:01:37 ERROR : error listing: directory not found
--- SKIP: TestMultithreadCopy (0.00s)
=== RUN   TestMultithreadCopyAbort
    run.go:198: Remote "mega root 'rclone-test-vidilek4rema'", Local "Local file system at /tmp/rclone3552730748", Modify Window "876000h0m0s"
    multithread_test.go:119: multithread writing not supported
2026/05/08 01:01:37 ERROR : error listing: directory not found
--- SKIP: TestMultithreadCopyAbort (0.00s)
=== RUN   TestSizeDiffers
2026/05/08 01:01:37 DEBUG : a: size = 0 OK
2026/05/08 01:01:37 DEBUG : a: size = 1 (memory)
2026/05/08 01:01:37 DEBUG : a: size = 2 (memory)
--- PASS: TestSizeDiffers (0.00s)
=== RUN   TestReOpen
=== RUN   TestReOpen/Normal
=== RUN   TestReOpen/Normal/Basics
2026/05/08 01:01:37 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/ErrorAtStart
=== RUN   TestReOpen/Normal/WithErrors
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/Normal/TooManyErrors
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/Normal/ReadAt
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/Normal/Seek
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Seek from 5 to 2
=== RUN   TestReOpen/Normal/AccountRead
=== RUN   TestReOpen/Normal/AccountReadDelay
2026/05/08 01:01:37 DEBUG : potato: Seek from 10 to 0
2026/05/08 01:01:37 DEBUG : potato: Seek from 10 to 0
2026/05/08 01:01:37 DEBUG : potato: Seek from 10 to 0
=== RUN   TestReOpen/Normal/AccountReadError
=== RUN   TestReOpen/WithRangeOption
=== RUN   TestReOpen/WithRangeOption/Basics
2026/05/08 01:01:37 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/ErrorAtStart
=== RUN   TestReOpen/WithRangeOption/WithErrors
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithRangeOption/TooManyErrors
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithRangeOption/ReadAt
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithRangeOption/Seek
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Seek from 5 to 2
2026/05/08 01:01:37 DEBUG : potato: Seek from 7 to 4
=== RUN   TestReOpen/WithRangeOption/AccountRead
=== RUN   TestReOpen/WithRangeOption/AccountReadDelay
2026/05/08 01:01:37 DEBUG : potato: Seek from 7 to 0
2026/05/08 01:01:37 DEBUG : potato: Seek from 7 to 0
2026/05/08 01:01:37 DEBUG : potato: Seek from 7 to 0
=== RUN   TestReOpen/WithRangeOption/AccountReadError
=== RUN   TestReOpen/WithSeekOption
=== RUN   TestReOpen/WithSeekOption/Basics
2026/05/08 01:01:37 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/ErrorAtStart
=== RUN   TestReOpen/WithSeekOption/WithErrors
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/WithSeekOption/TooManyErrors
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/WithSeekOption/ReadAt
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/WithSeekOption/Seek
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Seek from 5 to 2
2026/05/08 01:01:37 DEBUG : potato: Seek from 7 to 5
=== RUN   TestReOpen/WithSeekOption/AccountRead
=== RUN   TestReOpen/WithSeekOption/AccountReadDelay
2026/05/08 01:01:37 DEBUG : potato: Seek from 8 to 0
2026/05/08 01:01:37 DEBUG : potato: Seek from 8 to 0
2026/05/08 01:01:37 DEBUG : potato: Seek from 8 to 0
=== RUN   TestReOpen/WithSeekOption/AccountReadError
=== RUN   TestReOpen/UnknownSize
=== RUN   TestReOpen/UnknownSize/Basics
2026/05/08 01:01:37 DEBUG : potato: Seek from 9 to 0
=== RUN   TestReOpen/UnknownSize/ErrorAtStart
=== RUN   TestReOpen/UnknownSize/WithErrors
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN   TestReOpen/UnknownSize/TooManyErrors
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/05/08 01:01:37 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN   TestReOpen/UnknownSize/ReadAt
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Seek from 5 to 1
=== RUN   TestReOpen/UnknownSize/Seek
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/08 01:01:37 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/08 01:01:37 DEBUG : potato: Seek from 5 to 2
2026/05/08 01:01:37 DEBUG : potato: Seek from 7 to 6
=== RUN   TestReOpen/UnknownSize/AccountRead
=== RUN   TestReOpen/UnknownSize/AccountReadDelay
2026/05/08 01:01:37 DEBUG : potato: Seek from 9 to 0
2026/05/08 01:01:37 DEBUG : potato: Seek from 9 to 0
2026/05/08 01:01:37 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 "mega root 'rclone-test-vidilek4rema'", Local "Local file system at /tmp/rclone3552730748", Modify Window "876000h0m0s"
=== RUN   TestCheck/1
=== RUN   TestCheck/2
=== RUN   TestCheck/3
=== RUN   TestCheck/4
=== RUN   TestCheck/5
=== RUN   TestCheck/6
=== RUN   TestCheck/7
--- PASS: TestCheck (1.00s)
    --- PASS: TestCheck/1 (0.00s)
    --- PASS: TestCheck/2 (0.00s)
    --- PASS: TestCheck/3 (0.00s)
    --- PASS: TestCheck/4 (0.00s)
    --- PASS: TestCheck/5 (0.00s)
    --- PASS: TestCheck/6 (0.00s)
    --- PASS: TestCheck/7 (0.00s)
=== RUN   TestCheckFsError
2026/05/08 01:01:38 DEBUG : Creating backend with remote "nonexistent"
2026/05/08 01:01:38 DEBUG : Creating backend with remote "nonexistent"
2026/05/08 01:01:38 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish
2026/05/08 01:01:38 ERROR : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: error reading source root directory: directory not found
2026/05/08 01:01:38 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found
2026/05/08 01:01:38 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:198: Remote "mega root 'rclone-test-vidilek4rema'", Local "Local file system at /tmp/rclone3552730748", 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 (2.12s)
    --- PASS: TestCheckDownload/1 (0.10s)
    --- PASS: TestCheckDownload/2 (0.11s)
    --- PASS: TestCheckDownload/3 (0.10s)
    --- PASS: TestCheckDownload/4 (0.15s)
    --- PASS: TestCheckDownload/5 (0.15s)
    --- PASS: TestCheckDownload/6 (0.16s)
    --- PASS: TestCheckDownload/7 (0.19s)
=== RUN   TestCheckSizeOnly
    run.go:198: Remote "mega root 'rclone-test-vidilek4rema'", Local "Local file system at /tmp/rclone3552730748", 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 (1.06s)
    --- PASS: TestCheckSizeOnly/1 (0.00s)
    --- PASS: TestCheckSizeOnly/2 (0.00s)
    --- PASS: TestCheckSizeOnly/3 (0.00s)
    --- PASS: TestCheckSizeOnly/4 (0.00s)
    --- PASS: TestCheckSizeOnly/5 (0.00s)
    --- PASS: TestCheckSizeOnly/6 (0.00s)
    --- PASS: TestCheckSizeOnly/7 (0.00s)
=== RUN   TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN   TestParseSumFile
    run.go:198: Remote "mega root 'rclone-test-vidilek4rema'", Local "Local file system at /tmp/rclone3552730748", Modify Window "876000h0m0s"
2026/05/08 01:01:42 NOTICE: test.sum: improperly formatted checksum line 4
2026/05/08 01:01:42 NOTICE: test.sum: improperly formatted checksum line 5
2026/05/08 01:01:42 NOTICE: test.sum: improperly formatted checksum line 6
2026/05/08 01:01:42 NOTICE: test.sum: 2 warning(s) suppressed...
2026/05/08 01:01:42 NOTICE: test.sum: improperly formatted checksum line 4
2026/05/08 01:01:42 NOTICE: test.sum: improperly formatted checksum line 5
2026/05/08 01:01:42 NOTICE: test.sum: improperly formatted checksum line 6
2026/05/08 01:01:42 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (0.84s)
=== RUN   TestCheckSum
    run.go:198: Remote "mega root 'rclone-test-vidilek4rema'", Local "Local file system at /tmp/rclone3552730748", Modify Window "876000h0m0s"
2026/05/08 01:01:42 DEBUG : Creating backend with remote "TestMega:rclone-test-vidilek4rema/data"
2026/05/08 01:01:42 DEBUG : mega root 'rclone-test-vidilek4rema/data': Using previously stored session ID and master key to initialize the Mega API
    check_test.go:350: mega root 'rclone-test-vidilek4rema/data' lacks md5, skipping
--- SKIP: TestCheckSum (17.58s)
=== RUN   TestCheckSumDownload
    run.go:198: Remote "mega root 'rclone-test-vidilek4rema'", Local "Local file system at /tmp/rclone3552730748", Modify Window "876000h0m0s"
2026/05/08 01:02:00 DEBUG : Creating backend with remote "TestMega:rclone-test-vidilek4rema/data"
2026/05/08 01:02:00 DEBUG : mega root 'rclone-test-vidilek4rema/data': Using previously stored session ID and master key to initialize the Mega API
=== RUN   TestCheckSumDownload/subtest1
=== RUN   TestCheckSumDownload/subtest2
=== RUN   TestCheckSumDownload/subtest3
=== RUN   TestCheckSumDownload/subtest4
=== RUN   TestCheckSumDownload/subtest5
=== RUN   TestCheckSumDownload/subtest6
=== RUN   TestCheckSumDownload/subtest7
--- PASS: TestCheckSumDownload (34.78s)
    --- PASS: TestCheckSumDownload/subtest1 (0.31s)
    --- PASS: TestCheckSumDownload/subtest2 (0.22s)
    --- PASS: TestCheckSumDownload/subtest3 (0.31s)
    --- PASS: TestCheckSumDownload/subtest4 (0.27s)
    --- PASS: TestCheckSumDownload/subtest5 (0.39s)
    --- PASS: TestCheckSumDownload/subtest6 (0.28s)
    --- PASS: TestCheckSumDownload/subtest7 (0.31s)
=== RUN   TestApplyTransforms
2026/05/08 01:02:35 DEBUG : Creating backend with remote "TestMega:rclone-test-dibojet4qucu"
2026/05/08 01:02:35 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/08 01:02:35 DEBUG : mega root 'rclone-test-dibojet4qucu': Using previously stored session ID and master key to initialize the Mega API
2026/05/08 01:07:45 NOTICE: Failed to open remote "TestMega:": login with previous auth keys failed: unexpected end of JSON input
"./operations.test -test.v -test.timeout 1h0m0s -remote TestMega: -verbose" - Finished ERROR in 6m25.860806543s (try 1/5): exit status 1: Failed []