"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose" - Starting (try 1/5)
2026/05/22 01:00:58 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo"
2026/05/22 01:00:58 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:00:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:00:59 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:00:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:00:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:00:59 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:00:59 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:00:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:00:59 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:00:59 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:00:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:00:59 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:00:59 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:00:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:00:59 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:00:59 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:00:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:00:59 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:00:59 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:01:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:01:00 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:01:00 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:01:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:01:00 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:01:00 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:01:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:01:02 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:01:02 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:01:04 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:01:04 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:01:04 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/05/22 01:01:09 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:01:09 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:01:09 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:01:20 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:01:20 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:01:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:01:53 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:02:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:02:00 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:02:27 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:02:27 DEBUG : Creating backend with remote "/tmp/rclone908286492"
=== 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 "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
multithread_test.go:119: multithread writing not supported
2026/05/22 01:02:40 DEBUG : pacer: Reducing sleep to 5s
--- SKIP: TestMultithreadCopy (12.78s)
=== RUN TestMultithreadCopyAbort
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
multithread_test.go:119: multithread writing not supported
2026/05/22 01:02:50 DEBUG : pacer: Reducing sleep to 2.5s
--- SKIP: TestMultithreadCopyAbort (9.99s)
=== RUN TestSizeDiffers
2026/05/22 01:02:50 DEBUG : a: size = 0 OK
2026/05/22 01:02:50 DEBUG : a: size = 1 (memory)
2026/05/22 01:02:50 DEBUG : a: size = 2 (memory)
--- PASS: TestSizeDiffers (0.00s)
=== RUN TestReOpen
=== RUN TestReOpen/Normal
=== RUN TestReOpen/Normal/Basics
2026/05/22 01:02:50 DEBUG : potato: Seek from 10 to 0
=== RUN TestReOpen/Normal/ErrorAtStart
=== RUN TestReOpen/Normal/WithErrors
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/Normal/TooManyErrors
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/Normal/ReadAt
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/Normal/Seek
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Seek from 5 to 2
=== RUN TestReOpen/Normal/AccountRead
=== RUN TestReOpen/Normal/AccountReadDelay
2026/05/22 01:02:50 DEBUG : potato: Seek from 10 to 0
2026/05/22 01:02:50 DEBUG : potato: Seek from 10 to 0
2026/05/22 01:02:50 DEBUG : potato: Seek from 10 to 0
=== RUN TestReOpen/Normal/AccountReadError
=== RUN TestReOpen/WithRangeOption
=== RUN TestReOpen/WithRangeOption/Basics
2026/05/22 01:02:50 DEBUG : potato: Seek from 7 to 0
=== RUN TestReOpen/WithRangeOption/ErrorAtStart
=== RUN TestReOpen/WithRangeOption/WithErrors
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/WithRangeOption/TooManyErrors
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/WithRangeOption/ReadAt
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/WithRangeOption/Seek
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Seek from 5 to 2
2026/05/22 01:02:50 DEBUG : potato: Seek from 7 to 4
=== RUN TestReOpen/WithRangeOption/AccountRead
=== RUN TestReOpen/WithRangeOption/AccountReadDelay
2026/05/22 01:02:50 DEBUG : potato: Seek from 7 to 0
2026/05/22 01:02:50 DEBUG : potato: Seek from 7 to 0
2026/05/22 01:02:50 DEBUG : potato: Seek from 7 to 0
=== RUN TestReOpen/WithRangeOption/AccountReadError
=== RUN TestReOpen/WithSeekOption
=== RUN TestReOpen/WithSeekOption/Basics
2026/05/22 01:02:50 DEBUG : potato: Seek from 8 to 0
=== RUN TestReOpen/WithSeekOption/ErrorAtStart
=== RUN TestReOpen/WithSeekOption/WithErrors
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/WithSeekOption/TooManyErrors
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/WithSeekOption/ReadAt
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/WithSeekOption/Seek
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Seek from 5 to 2
2026/05/22 01:02:50 DEBUG : potato: Seek from 7 to 5
=== RUN TestReOpen/WithSeekOption/AccountRead
=== RUN TestReOpen/WithSeekOption/AccountReadDelay
2026/05/22 01:02:50 DEBUG : potato: Seek from 8 to 0
2026/05/22 01:02:50 DEBUG : potato: Seek from 8 to 0
2026/05/22 01:02:50 DEBUG : potato: Seek from 8 to 0
=== RUN TestReOpen/WithSeekOption/AccountReadError
=== RUN TestReOpen/UnknownSize
=== RUN TestReOpen/UnknownSize/Basics
2026/05/22 01:02:50 DEBUG : potato: Seek from 9 to 0
=== RUN TestReOpen/UnknownSize/ErrorAtStart
=== RUN TestReOpen/UnknownSize/WithErrors
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/10: test error
=== RUN TestReOpen/UnknownSize/TooManyErrors
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 1/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 2/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 6 bytes: retry 3/3: test error
2026/05/22 01:02:50 DEBUG : potato: Reopen failed after offset 6 bytes read: failed to reopen: too many retries
=== RUN TestReOpen/UnknownSize/ReadAt
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Seek from 5 to 1
=== RUN TestReOpen/UnknownSize/Seek
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 2 bytes: retry 0/10: test error
2026/05/22 01:02:50 DEBUG : potato: Reopening on read failure after offset 3 bytes: retry 1/10: test error
2026/05/22 01:02:50 DEBUG : potato: Seek from 5 to 2
2026/05/22 01:02:50 DEBUG : potato: Seek from 7 to 6
=== RUN TestReOpen/UnknownSize/AccountRead
=== RUN TestReOpen/UnknownSize/AccountReadDelay
2026/05/22 01:02:50 DEBUG : potato: Seek from 9 to 0
2026/05/22 01:02:50 DEBUG : potato: Seek from 9 to 0
2026/05/22 01:02:50 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 "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:02:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:02:55 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:02:55 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:02:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:02:57 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:02:57 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:03:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:03:02 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:03:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:03:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:03:12 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:03:32 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:03:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:03:55 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:03:55 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:04:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:04:02 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:04:27 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:04:42 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:04:54 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:04:57 DEBUG : pacer: Reducing sleep to 1.25s
=== RUN TestCheck/1
=== RUN TestCheck/2
2026/05/22 01:05:03 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:05:03 DEBUG : pacer: Reducing sleep to 78.125ms
2026/05/22 01:05:03 DEBUG : pacer: Reducing sleep to 39.0625ms
=== RUN TestCheck/3
2026/05/22 01:05:04 DEBUG : pacer: Reducing sleep to 10ms
=== RUN TestCheck/4
=== RUN TestCheck/5
=== RUN TestCheck/6
=== RUN TestCheck/7
--- PASS: TestCheck (141.98s)
--- PASS: TestCheck/1 (2.83s)
--- PASS: TestCheck/2 (1.96s)
--- PASS: TestCheck/3 (0.20s)
--- PASS: TestCheck/4 (1.29s)
--- PASS: TestCheck/5 (0.29s)
--- PASS: TestCheck/6 (0.67s)
--- PASS: TestCheck/7 (0.57s)
=== RUN TestCheckFsError
2026/05/22 01:05:12 DEBUG : Creating backend with remote "nonexistent"
2026/05/22 01:05:12 DEBUG : Creating backend with remote "nonexistent"
2026/05/22 01:05:12 DEBUG : Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: Waiting for checks to finish
2026/05/22 01:05:12 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/22 01:05:12 NOTICE: Local file system at /home/rclone/go/src/github.com/rclone/rclone/fs/operations/nonexistent: 0 differences found
2026/05/22 01:05:12 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 "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
=== RUN TestCheckDownload/1
=== RUN TestCheckDownload/2
=== RUN TestCheckDownload/3
=== RUN TestCheckDownload/4
=== RUN TestCheckDownload/5
=== RUN TestCheckDownload/6
=== RUN TestCheckDownload/7
--- PASS: TestCheckDownload (45.66s)
--- PASS: TestCheckDownload/1 (8.04s)
--- PASS: TestCheckDownload/2 (2.46s)
--- PASS: TestCheckDownload/3 (5.66s)
--- PASS: TestCheckDownload/4 (3.12s)
--- PASS: TestCheckDownload/5 (10.03s)
--- PASS: TestCheckDownload/6 (2.59s)
--- PASS: TestCheckDownload/7 (1.83s)
=== RUN TestCheckSizeOnly
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
=== RUN TestCheckSizeOnly/1
=== RUN TestCheckSizeOnly/2
2026/05/22 01:06:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:06 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:06 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:06:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:06 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:06 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:06:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:06 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:06 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:06:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:07 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:07 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:06:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:07 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:06:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:07 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:07 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:06:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:07 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:07 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:06:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:08 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:08 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:06:09 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:09 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:09 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:06:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:12 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:12 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestCheckSizeOnly
run.go:299: Retry Put of "empty space" to gofile root 'rclone-test-remumet3vazo': 1/10 (couldn't list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:17 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:17 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:17 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:06:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:06:27 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:06:47 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:07:09 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:07:17 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:07:17 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:07:17 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:07:22 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:07:22 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:07:22 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:07:32 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:07:32 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:07:53 DEBUG : pacer: Reducing sleep to 10s
=== RUN TestCheckSizeOnly/3
2026/05/22 01:09:02 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:09:12 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:09:17 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:09:17 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:09:17 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:09:20 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:09:20 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:09:20 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:09:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:09:25 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:09:25 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:09:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:09:35 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:09:56 DEBUG : pacer: Reducing sleep to 10s
=== RUN TestCheckSizeOnly/4
=== RUN TestCheckSizeOnly/5
2026/05/22 01:10:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:10:30 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:10:30 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:10:32 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:10:32 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:10:32 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:10:37 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:10:37 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:10:37 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:10:47 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:10:47 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:11:22 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:11:28 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:11:38 DEBUG : pacer: Reducing sleep to 2.5s
=== RUN TestCheckSizeOnly/6
=== RUN TestCheckSizeOnly/7
2026/05/22 01:11:48 DEBUG : pacer: Reducing sleep to 312.5ms
2026/05/22 01:11:48 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:11:49 DEBUG : pacer: Reducing sleep to 78.125ms
2026/05/22 01:11:50 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/05/22 01:11:51 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/05/22 01:11:52 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestCheckSizeOnly (354.29s)
--- PASS: TestCheckSizeOnly/1 (0.20s)
--- PASS: TestCheckSizeOnly/2 (1.94s)
--- PASS: TestCheckSizeOnly/3 (49.76s)
--- PASS: TestCheckSizeOnly/4 (18.35s)
--- PASS: TestCheckSizeOnly/5 (10.39s)
--- PASS: TestCheckSizeOnly/6 (5.52s)
--- PASS: TestCheckSizeOnly/7 (2.93s)
=== RUN TestCheckEqualReaders
--- PASS: TestCheckEqualReaders (0.00s)
=== RUN TestParseSumFile
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:11:53 NOTICE: test.sum: improperly formatted checksum line 4
2026/05/22 01:11:53 NOTICE: test.sum: improperly formatted checksum line 5
2026/05/22 01:11:53 NOTICE: test.sum: improperly formatted checksum line 6
2026/05/22 01:11:53 NOTICE: test.sum: 2 warning(s) suppressed...
2026/05/22 01:11:54 DEBUG : test.sum: Removing old object on successful upload
2026/05/22 01:11:56 NOTICE: test.sum: improperly formatted checksum line 4
2026/05/22 01:11:56 NOTICE: test.sum: improperly formatted checksum line 5
2026/05/22 01:11:56 NOTICE: test.sum: improperly formatted checksum line 6
2026/05/22 01:11:56 NOTICE: test.sum: 2 warning(s) suppressed...
--- PASS: TestParseSumFile (5.37s)
=== RUN TestCheckSum
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:11:57 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo/data"
=== RUN TestCheckSum/subtest1
2026/05/22 01:12:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:12 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:12 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:12:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:13 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:13 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:12:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:13 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:13 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:12:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:13 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:13 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:12:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:13 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:13 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:12:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:13 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:13 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:12:14 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:14 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:14 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:12:14 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:14 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:14 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:12:15 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:15 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:15 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:12:18 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:18 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:18 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestCheckSum
run.go:299: Retry Put of "test.sum" to gofile root 'rclone-test-remumet3vazo': 1/10 (couldn't list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:23 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:23 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:23 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:12:33 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:12:33 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:12:54 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:13:15 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:13:15 DEBUG : test.sum: Removing old object on successful upload
2026/05/22 01:13:23 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:13:23 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:13:23 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:13:28 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:13:28 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:13:28 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:13:38 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:13:38 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:14:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:14:08 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:14:19 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:14:39 DEBUG : pacer: Reducing sleep to 5s
=== RUN TestCheckSum/subtest2
2026/05/22 01:14:56 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:15:03 DEBUG : pacer: Reducing sleep to 312.5ms
2026/05/22 01:15:03 DEBUG : test.sum: Removing old object on successful upload
2026/05/22 01:15:03 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:15:04 DEBUG : pacer: Reducing sleep to 78.125ms
=== RUN TestCheckSum/subtest3
2026/05/22 01:15:09 DEBUG : pacer: Reducing sleep to 10ms
2026/05/22 01:15:10 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSum/subtest4
2026/05/22 01:15:15 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSum/subtest5
2026/05/22 01:15:20 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSum/subtest6
2026/05/22 01:15:25 DEBUG : data/banana: Removing old object on successful upload
2026/05/22 01:15:26 DEBUG : data/potato: Removing old object on successful upload
2026/05/22 01:15:29 DEBUG : test.sum: Removing old object on successful upload
2026/05/22 01:15:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:34 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:34 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:15:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:34 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:34 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:15:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:34 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:34 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:15:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:34 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:34 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:15:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:34 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:34 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:15:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:35 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:35 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:15:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:35 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:35 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:15:36 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:36 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:36 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:15:37 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:37 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:37 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:15:39 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:39 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:39 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestCheckSum
fstest.go:282: Error listing: couldn't recursively list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n"
2026/05/22 01:15:45 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:45 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:15:45 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:15:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:15:55 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:16:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:16:35 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:16:35 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:16:35 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:16:56 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:17:26 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:17:26 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:17:26 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:17:26 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:17:26 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:17:46 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:17:46 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:09 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:18:26 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:18:36 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:18:41 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:18:43 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:18:45 DEBUG : pacer: Reducing sleep to 312.5ms
--- FAIL: TestCheckSum (407.63s)
--- PASS: TestCheckSum/subtest1 (1.20s)
--- PASS: TestCheckSum/subtest2 (15.21s)
--- PASS: TestCheckSum/subtest3 (3.60s)
--- PASS: TestCheckSum/subtest4 (1.71s)
--- PASS: TestCheckSum/subtest5 (2.67s)
--- PASS: TestCheckSum/subtest6 (0.83s)
=== RUN TestCheckSumDownload
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:18:45 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo/data"
2026/05/22 01:18:46 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:18:46 DEBUG : pacer: Reducing sleep to 78.125ms
2026/05/22 01:18:47 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/05/22 01:18:47 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/05/22 01:18:48 DEBUG : pacer: Reducing sleep to 10ms
=== RUN TestCheckSumDownload/subtest1
2026/05/22 01:18:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:18:57 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:57 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:18:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:18:57 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:57 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:18:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:18:57 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:57 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:18:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:18:57 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:57 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:18:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:18:57 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:57 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:18:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:18:58 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:58 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:18:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:18:58 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:58 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:18:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:18:59 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:18:59 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:19:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:19:00 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:19:00 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:19:03 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:19:03 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:19:03 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestCheckSumDownload
run.go:299: Retry Put of "data/potato" to gofile root 'rclone-test-remumet3vazo': 1/10 (couldn't list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:19:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:19:08 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:19:08 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:19:18 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:19:18 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:19:44 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:19:44 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:19:58 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:20:18 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:20:28 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:20:33 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:20:33 DEBUG : test.sum: Removing old object on successful upload
2026/05/22 01:20:35 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:20:37 DEBUG : pacer: Reducing sleep to 312.5ms
=== RUN TestCheckSumDownload/subtest2
2026/05/22 01:20:40 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/05/22 01:20:40 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/05/22 01:20:40 DEBUG : test.sum: Removing old object on successful upload
2026/05/22 01:20:40 DEBUG : pacer: Reducing sleep to 10ms
=== RUN TestCheckSumDownload/subtest3
2026/05/22 01:20:43 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSumDownload/subtest4
2026/05/22 01:20:46 DEBUG : test.sum: Removing old object on successful upload
=== RUN TestCheckSumDownload/subtest5
2026/05/22 01:20:51 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:51 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:51 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:20:51 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:51 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:51 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:20:51 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:51 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:51 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:20:51 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:51 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:51 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:20:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:52 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:52 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:20:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:52 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:52 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:20:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:52 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:52 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:20:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:53 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:53 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:20:54 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:54 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:54 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:20:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:20:57 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:20:57 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestCheckSumDownload
run.go:299: Retry Put of "test.sum" to gofile root 'rclone-test-remumet3vazo': 1/10 (couldn't list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:21:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:21:02 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:21:02 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:21:12 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:21:32 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:21:32 DEBUG : test.sum: Removing old object on successful upload
2026/05/22 01:21:42 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:21:47 DEBUG : pacer: Reducing sleep to 1.25s
=== RUN TestCheckSumDownload/subtest6
2026/05/22 01:22:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:22:59 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:22:59 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:23:04 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:23:04 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:23:04 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:23:16 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:23:34 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:23:34 DEBUG : data/banana: Removing old object on successful upload
2026/05/22 01:23:44 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:23:49 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:23:51 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:23:51 DEBUG : data/potato: Removing old object on successful upload
2026/05/22 01:23:52 DEBUG : pacer: Reducing sleep to 312.5ms
2026/05/22 01:23:53 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:23:53 DEBUG : pacer: Reducing sleep to 78.125ms
2026/05/22 01:23:53 DEBUG : test.sum: Removing old object on successful upload
2026/05/22 01:23:54 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/05/22 01:23:54 DEBUG : pacer: Reducing sleep to 19.53125ms
=== RUN TestCheckSumDownload/subtest7
2026/05/22 01:23:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:23:59 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:23:59 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:23:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:23:59 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:23:59 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:23:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:23:59 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:23:59 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:24:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:00 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:00 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:24:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:00 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:00 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:24:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:00 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:00 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:24:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:00 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:00 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:24:01 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:01 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:01 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:24:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:02 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:02 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:24:05 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:05 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:05 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
=== NAME TestCheckSumDownload
fstest.go:282: Error listing: couldn't recursively list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n"
--- FAIL: TestCheckSumDownload (320.03s)
--- PASS: TestCheckSumDownload/subtest1 (5.58s)
--- PASS: TestCheckSumDownload/subtest2 (2.69s)
--- PASS: TestCheckSumDownload/subtest3 (1.75s)
--- PASS: TestCheckSumDownload/subtest4 (1.07s)
--- PASS: TestCheckSumDownload/subtest5 (1.09s)
--- PASS: TestCheckSumDownload/subtest6 (62.99s)
--- PASS: TestCheckSumDownload/subtest7 (1.40s)
=== RUN TestApplyTransforms
2026/05/22 01:24:05 DEBUG : Creating backend with remote "TestGoFile:rclone-test-bequpeq6zumu"
2026/05/22 01:24:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:24:05 DEBUG : Creating backend with remote "/tmp/rclone395565743"
run.go:198: Remote "gofile root 'rclone-test-bequpeq6zumu'", Local "Local file system at /tmp/rclone395565743", Modify Window "1s"
upper checkfile vs. lower remote (without normalization)
2026/05/22 01:24:07 ERROR : hello, world!: sum not found
2026/05/22 01:24:07 ERROR : HELLO, WORLD!: file not in gofile root 'rclone-test-bequpeq6zumu'
2026/05/22 01:24:07 NOTICE: gofile root 'rclone-test-bequpeq6zumu': 1 files missing
2026/05/22 01:24:07 NOTICE: 1 hashes missing
2026/05/22 01:24:07 NOTICE: gofile root 'rclone-test-bequpeq6zumu': 1 differences found
2026/05/22 01:24:07 NOTICE: gofile root 'rclone-test-bequpeq6zumu': 2 errors while checking
upper checkfile vs. lower remote (with normalization)
2026/05/22 01:24:08 DEBUG : hello, world!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:24:08 NOTICE: gofile root 'rclone-test-bequpeq6zumu': 0 differences found
2026/05/22 01:24:08 NOTICE: gofile root 'rclone-test-bequpeq6zumu': 1 matching files
2026/05/22 01:24:08 DEBUG : Creating backend with remote "TestGoFile:rclone-test-kelumih3guma"
2026/05/22 01:24:08 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:24:08 DEBUG : Creating backend with remote "/tmp/rclone2884903255"
run.go:198: Remote "gofile root 'rclone-test-kelumih3guma'", Local "Local file system at /tmp/rclone2884903255", Modify Window "1s"
lower checkfile vs. upper remote (without normalization)
2026/05/22 01:24:08 ERROR : HELLO, WORLD!: sum not found
2026/05/22 01:24:08 ERROR : hello, world!: file not in gofile root 'rclone-test-kelumih3guma'
2026/05/22 01:24:08 NOTICE: gofile root 'rclone-test-kelumih3guma': 1 files missing
2026/05/22 01:24:08 NOTICE: 1 hashes missing
2026/05/22 01:24:08 NOTICE: gofile root 'rclone-test-kelumih3guma': 1 differences found
2026/05/22 01:24:08 NOTICE: gofile root 'rclone-test-kelumih3guma': 2 errors while checking
lower checkfile vs. upper remote (with normalization)
2026/05/22 01:24:09 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:24:09 NOTICE: gofile root 'rclone-test-kelumih3guma': 0 differences found
2026/05/22 01:24:09 NOTICE: gofile root 'rclone-test-kelumih3guma': 1 matching files
2026/05/22 01:24:09 DEBUG : Creating backend with remote "TestGoFile:rclone-test-guyuvum0xeju"
2026/05/22 01:24:09 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:24:09 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:09 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:09 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:24:09 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:09 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:09 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:24:09 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:09 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:09 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:24:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:10 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:10 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:24:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:10 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:10 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:24:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:10 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:10 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:24:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:10 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:10 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:24:11 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:11 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:11 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:24:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:12 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:12 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:24:15 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:15 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:15 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/05/22 01:24:20 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:20 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:20 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:24:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:24:30 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:24:50 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:24:50 DEBUG : Creating backend with remote "/tmp/rclone2528781135"
run.go:198: Remote "gofile root 'rclone-test-guyuvum0xeju'", Local "Local file system at /tmp/rclone2528781135", Modify Window "1s"
2026/05/22 01:25:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:25:10 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:25:10 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:25:20 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:25:20 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:25:40 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:26:01 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:26:11 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:26:16 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:26:18 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:26:18 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:26:18 DEBUG : pacer: Rate limited, increasing sleep to 2.5s
2026/05/22 01:26:19 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:26:19 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:26:19 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:26:22 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:26:22 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:26:22 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:26:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:26:27 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:26:27 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:26:37 DEBUG : pacer: Reducing sleep to 10s
lower checkfile vs. upperlowermixed remote (without normalization)
2026/05/22 01:26:57 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:26:57 ERROR : HeLlO, wOrLd!: sum not found
2026/05/22 01:26:57 ERROR : hello, world!: file not in gofile root 'rclone-test-guyuvum0xeju'
2026/05/22 01:26:57 NOTICE: gofile root 'rclone-test-guyuvum0xeju': 1 files missing
2026/05/22 01:26:57 NOTICE: 1 hashes missing
2026/05/22 01:26:57 NOTICE: gofile root 'rclone-test-guyuvum0xeju': 1 differences found
2026/05/22 01:26:57 NOTICE: gofile root 'rclone-test-guyuvum0xeju': 2 errors while checking
lower checkfile vs. upperlowermixed remote (with normalization)
2026/05/22 01:27:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:27:07 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:27:07 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:27:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:27:12 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:27:12 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:27:22 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:27:22 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:27:42 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:28:02 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:28:02 DEBUG : HeLlO, wOrLd!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:28:02 NOTICE: gofile root 'rclone-test-guyuvum0xeju': 0 differences found
2026/05/22 01:28:02 NOTICE: gofile root 'rclone-test-guyuvum0xeju': 1 matching files
2026/05/22 01:28:02 DEBUG : Creating backend with remote "TestGoFile:rclone-test-jayukif2zaxu"
2026/05/22 01:28:02 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:28:02 DEBUG : Creating backend with remote "/tmp/rclone3900443963"
run.go:198: Remote "gofile root 'rclone-test-jayukif2zaxu'", Local "Local file system at /tmp/rclone3900443963", Modify Window "1s"
upperlowermixed checkfile vs. upper remote (without normalization)
2026/05/22 01:28:04 ERROR : HELLO, WORLD!: sum not found
2026/05/22 01:28:04 ERROR : HeLlO, wOrLd!: file not in gofile root 'rclone-test-jayukif2zaxu'
2026/05/22 01:28:04 NOTICE: gofile root 'rclone-test-jayukif2zaxu': 1 files missing
2026/05/22 01:28:04 NOTICE: 1 hashes missing
2026/05/22 01:28:04 NOTICE: gofile root 'rclone-test-jayukif2zaxu': 1 differences found
2026/05/22 01:28:04 NOTICE: gofile root 'rclone-test-jayukif2zaxu': 2 errors while checking
upperlowermixed checkfile vs. upper remote (with normalization)
2026/05/22 01:28:05 DEBUG : HELLO, WORLD!: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:28:05 NOTICE: gofile root 'rclone-test-jayukif2zaxu': 0 differences found
2026/05/22 01:28:05 NOTICE: gofile root 'rclone-test-jayukif2zaxu': 1 matching files
2026/05/22 01:28:05 DEBUG : Creating backend with remote "TestGoFile:rclone-test-tejazal5kequ"
2026/05/22 01:28:05 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:28:05 DEBUG : Creating backend with remote "/tmp/rclone83165846"
run.go:198: Remote "gofile root 'rclone-test-tejazal5kequ'", Local "Local file system at /tmp/rclone83165846", Modify Window "1s"
NFD checkfile vs. NFC remote (without normalization)
2026/05/22 01:28:06 ERROR : 測試_Русский___ě_áñ: sum not found
2026/05/22 01:28:06 ERROR : 測試_Русский___ě_áñ: file not in gofile root 'rclone-test-tejazal5kequ'
2026/05/22 01:28:06 NOTICE: gofile root 'rclone-test-tejazal5kequ': 1 files missing
2026/05/22 01:28:06 NOTICE: 1 hashes missing
2026/05/22 01:28:06 NOTICE: gofile root 'rclone-test-tejazal5kequ': 1 differences found
2026/05/22 01:28:06 NOTICE: gofile root 'rclone-test-tejazal5kequ': 2 errors while checking
NFD checkfile vs. NFC remote (with normalization)
2026/05/22 01:28:07 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:28:07 NOTICE: gofile root 'rclone-test-tejazal5kequ': 0 differences found
2026/05/22 01:28:07 NOTICE: gofile root 'rclone-test-tejazal5kequ': 1 matching files
2026/05/22 01:28:07 DEBUG : Creating backend with remote "TestGoFile:rclone-test-loyuhey5tesa"
2026/05/22 01:28:07 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:28:08 DEBUG : Creating backend with remote "/tmp/rclone2687317151"
run.go:198: Remote "gofile root 'rclone-test-loyuhey5tesa'", Local "Local file system at /tmp/rclone2687317151", Modify Window "1s"
NFC checkfile vs. NFD remote (without normalization)
2026/05/22 01:28:09 ERROR : 測試_Русский___ě_áñ: sum not found
2026/05/22 01:28:09 ERROR : 測試_Русский___ě_áñ: file not in gofile root 'rclone-test-loyuhey5tesa'
2026/05/22 01:28:09 NOTICE: gofile root 'rclone-test-loyuhey5tesa': 1 files missing
2026/05/22 01:28:09 NOTICE: 1 hashes missing
2026/05/22 01:28:09 NOTICE: gofile root 'rclone-test-loyuhey5tesa': 1 differences found
2026/05/22 01:28:09 NOTICE: gofile root 'rclone-test-loyuhey5tesa': 2 errors while checking
NFC checkfile vs. NFD remote (with normalization)
2026/05/22 01:28:10 DEBUG : 測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:28:10 NOTICE: gofile root 'rclone-test-loyuhey5tesa': 0 differences found
2026/05/22 01:28:10 NOTICE: gofile root 'rclone-test-loyuhey5tesa': 1 matching files
2026/05/22 01:28:10 DEBUG : Creating backend with remote "TestGoFile:rclone-test-cesileb4jimo"
2026/05/22 01:28:10 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:28:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:10 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:10 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:28:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:10 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:10 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:28:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:10 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:10 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:28:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:10 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:10 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:28:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:10 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:10 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:28:11 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:11 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:11 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:28:11 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:11 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:11 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:28:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:12 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:12 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:28:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:13 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:13 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:28:15 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:15 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:15 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/05/22 01:28:21 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:28:21 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:28:21 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:28:31 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:28:31 DEBUG : Creating backend with remote "/tmp/rclone186937611"
run.go:198: Remote "gofile root 'rclone-test-cesileb4jimo'", Local "Local file system at /tmp/rclone186937611", Modify Window "1s"
2026/05/22 01:28:51 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:29:01 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:29:06 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:29:09 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:29:10 DEBUG : pacer: Reducing sleep to 312.5ms
NFDx2 checkfile vs. both remote (without normalization)
2026/05/22 01:29:10 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:29:10 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/05/22 01:29:10 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in gofile root 'rclone-test-cesileb4jimo'
2026/05/22 01:29:10 NOTICE: gofile root 'rclone-test-cesileb4jimo': 1 files missing
2026/05/22 01:29:10 NOTICE: 1 hashes missing
2026/05/22 01:29:10 NOTICE: gofile root 'rclone-test-cesileb4jimo': 1 differences found
2026/05/22 01:29:10 NOTICE: gofile root 'rclone-test-cesileb4jimo': 2 errors while checking
NFDx2 checkfile vs. both remote (with normalization)
2026/05/22 01:29:11 DEBUG : pacer: Reducing sleep to 78.125ms
2026/05/22 01:29:11 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/05/22 01:29:11 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:29:11 NOTICE: gofile root 'rclone-test-cesileb4jimo': 0 differences found
2026/05/22 01:29:11 NOTICE: gofile root 'rclone-test-cesileb4jimo': 1 matching files
2026/05/22 01:29:11 DEBUG : Creating backend with remote "TestGoFile:rclone-test-yefalis5fufu"
2026/05/22 01:29:11 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:29:11 DEBUG : Creating backend with remote "/tmp/rclone824335424"
run.go:198: Remote "gofile root 'rclone-test-yefalis5fufu'", Local "Local file system at /tmp/rclone824335424", Modify Window "1s"
NFCx2 checkfile vs. both remote (without normalization)
2026/05/22 01:29:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/05/22 01:29:13 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in gofile root 'rclone-test-yefalis5fufu'
2026/05/22 01:29:13 NOTICE: gofile root 'rclone-test-yefalis5fufu': 1 files missing
2026/05/22 01:29:13 NOTICE: 1 hashes missing
2026/05/22 01:29:13 NOTICE: gofile root 'rclone-test-yefalis5fufu': 1 differences found
2026/05/22 01:29:13 NOTICE: gofile root 'rclone-test-yefalis5fufu': 2 errors while checking
NFCx2 checkfile vs. both remote (with normalization)
2026/05/22 01:29:13 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:29:13 NOTICE: gofile root 'rclone-test-yefalis5fufu': 0 differences found
2026/05/22 01:29:13 NOTICE: gofile root 'rclone-test-yefalis5fufu': 1 matching files
2026/05/22 01:29:13 DEBUG : Creating backend with remote "TestGoFile:rclone-test-gasicaz5duru"
2026/05/22 01:29:13 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:29:14 DEBUG : Creating backend with remote "/tmp/rclone547077325"
run.go:198: Remote "gofile root 'rclone-test-gasicaz5duru'", Local "Local file system at /tmp/rclone547077325", Modify Window "1s"
both checkfile vs. NFDx2 remote (without normalization)
2026/05/22 01:29:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/05/22 01:29:16 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in gofile root 'rclone-test-gasicaz5duru'
2026/05/22 01:29:16 NOTICE: gofile root 'rclone-test-gasicaz5duru': 1 files missing
2026/05/22 01:29:16 NOTICE: 1 hashes missing
2026/05/22 01:29:16 NOTICE: gofile root 'rclone-test-gasicaz5duru': 1 differences found
2026/05/22 01:29:16 NOTICE: gofile root 'rclone-test-gasicaz5duru': 2 errors while checking
both checkfile vs. NFDx2 remote (with normalization)
2026/05/22 01:29:16 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:29:16 NOTICE: gofile root 'rclone-test-gasicaz5duru': 0 differences found
2026/05/22 01:29:16 NOTICE: gofile root 'rclone-test-gasicaz5duru': 1 matching files
2026/05/22 01:29:16 DEBUG : Creating backend with remote "TestGoFile:rclone-test-qosipuv1yaru"
2026/05/22 01:29:16 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2026/05/22 01:29:16 DEBUG : Creating backend with remote "/tmp/rclone3068620082"
run.go:198: Remote "gofile root 'rclone-test-qosipuv1yaru'", Local "Local file system at /tmp/rclone3068620082", Modify Window "1s"
both checkfile vs. NFCx2 remote (without normalization)
2026/05/22 01:29:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: sum not found
2026/05/22 01:29:17 ERROR : 測試_Русский___ě_áñ測試_Русский___ě_áñ: file not in gofile root 'rclone-test-qosipuv1yaru'
2026/05/22 01:29:17 NOTICE: gofile root 'rclone-test-qosipuv1yaru': 1 files missing
2026/05/22 01:29:17 NOTICE: 1 hashes missing
2026/05/22 01:29:17 NOTICE: gofile root 'rclone-test-qosipuv1yaru': 1 differences found
2026/05/22 01:29:17 NOTICE: gofile root 'rclone-test-qosipuv1yaru': 2 errors while checking
both checkfile vs. NFCx2 remote (with normalization)
2026/05/22 01:29:17 DEBUG : 測試_Русский___ě_áñ測試_Русский___ě_áñ: md5 = 65a8e27d8879283831b664bd8b7f0ad4 OK
2026/05/22 01:29:17 NOTICE: gofile root 'rclone-test-qosipuv1yaru': 0 differences found
2026/05/22 01:29:17 NOTICE: gofile root 'rclone-test-qosipuv1yaru': 1 matching files
2026/05/22 01:29:17 DEBUG : gofile root 'rclone-test-qosipuv1yaru': Purge remote
2026/05/22 01:29:18 DEBUG : gofile root 'rclone-test-gasicaz5duru': Purge remote
2026/05/22 01:29:18 DEBUG : gofile root 'rclone-test-yefalis5fufu': Purge remote
2026/05/22 01:29:19 DEBUG : gofile root 'rclone-test-cesileb4jimo': Purge remote
2026/05/22 01:29:19 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/05/22 01:29:19 DEBUG : gofile root 'rclone-test-loyuhey5tesa': Purge remote
2026/05/22 01:29:20 DEBUG : gofile root 'rclone-test-tejazal5kequ': Purge remote
2026/05/22 01:29:20 DEBUG : gofile root 'rclone-test-jayukif2zaxu': Purge remote
2026/05/22 01:29:21 DEBUG : gofile root 'rclone-test-guyuvum0xeju': Purge remote
2026/05/22 01:29:22 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:29:22 DEBUG : gofile root 'rclone-test-kelumih3guma': Purge remote
2026/05/22 01:29:23 DEBUG : gofile root 'rclone-test-bequpeq6zumu': Purge remote
--- PASS: TestApplyTransforms (319.19s)
=== RUN TestTruncateString
--- PASS: TestTruncateString (0.00s)
=== RUN TestCopyFile
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:29:25 DEBUG : pacer: Reducing sleep to 5.12s
2026/05/22 01:29:25 DEBUG : file1: Need to transfer - File not found at Destination
2026/05/22 01:29:34 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:29:34 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:29:34 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
2026/05/22 01:29:39 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:29:39 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:29:39 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:29:50 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:29:50 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:30:10 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:30:30 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:30:40 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:30:40 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:30:40 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:30:45 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:30:55 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:30:55 DEBUG : sub/file2: size = 14 OK
2026/05/22 01:30:55 DEBUG : file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/05/22 01:30:55 INFO : file1: Copied (new) to: sub/file2
2026/05/22 01:31:00 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:31:02 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:31:02 DEBUG : sub/file2: size = 14 OK
2026/05/22 01:31:02 DEBUG : file1: Size and modification time the same (differ by -499.999999ms, within tolerance 1s)
2026/05/22 01:31:02 DEBUG : file1: Unchanged skipping
2026/05/22 01:31:04 DEBUG : pacer: Reducing sleep to 312.5ms
2026/05/22 01:31:04 DEBUG : gofile root 'rclone-test-remumet3vazo': don't need to copy/move sub/file2, it is already at target location
2026/05/22 01:31:05 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:31:05 DEBUG : pacer: Reducing sleep to 78.125ms
2026/05/22 01:31:05 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/05/22 01:31:05 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/05/22 01:31:06 DEBUG : pacer: Reducing sleep to 10ms
--- PASS: TestCopyFile (101.92s)
=== RUN TestCopyLongFile
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
copy_test.go:154: Test only runs on local
--- SKIP: TestCopyLongFile (0.55s)
=== RUN TestCopyFileBackupDir
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:31:08 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo/backup"
2026/05/22 01:31:09 DEBUG : dst/file1: size = 14 (Local file system at /tmp/rclone908286492)
2026/05/22 01:31:09 DEBUG : dst/file1: size = 18 (gofile root 'rclone-test-remumet3vazo')
2026/05/22 01:31:09 DEBUG : dst/file1: Sizes differ
2026/05/22 01:31:10 INFO : dst/file1: Moved (server-side)
2026/05/22 01:31:11 DEBUG : dst/file1: size = 14 OK
2026/05/22 01:31:11 DEBUG : dst/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/05/22 01:31:11 INFO : dst/file1: Copied (new)
--- PASS: TestCopyFileBackupDir (7.07s)
=== RUN TestCopyFileCompareDest
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:31:13 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo/dst"
2026/05/22 01:31:16 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo/CompareDest"
2026/05/22 01:31:17 DEBUG : one: Need to transfer - File not found at Destination
2026/05/22 01:31:18 DEBUG : one: size = 3 OK
2026/05/22 01:31:18 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2026/05/22 01:31:18 INFO : one: Copied (new)
2026/05/22 01:31:19 DEBUG : one: size = 5 (Local file system at /tmp/rclone908286492)
2026/05/22 01:31:19 DEBUG : one: size = 3 (gofile root 'rclone-test-remumet3vazo/dst')
2026/05/22 01:31:19 DEBUG : one: Sizes differ
2026/05/22 01:31:20 DEBUG : one: Removing old object on successful upload
2026/05/22 01:31:20 DEBUG : one: size = 5 OK
2026/05/22 01:31:20 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2026/05/22 01:31:20 INFO : one: Copied (replaced existing)
2026/05/22 01:31:21 DEBUG : dst/one: Removing old object on successful upload
2026/05/22 01:31:22 DEBUG : one: size = 5 (Local file system at /tmp/rclone908286492)
2026/05/22 01:31:22 DEBUG : one: size = 3 (gofile root 'rclone-test-remumet3vazo/dst')
2026/05/22 01:31:22 DEBUG : one: Sizes differ
2026/05/22 01:31:23 DEBUG : one: size = 5 OK
2026/05/22 01:31:23 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/22 01:31:23 DEBUG : one: Destination found in --compare-dest, skipping
2026/05/22 01:31:24 DEBUG : two: Need to transfer - File not found at Destination
2026/05/22 01:31:24 DEBUG : two: size = 3 OK
2026/05/22 01:31:24 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/22 01:31:24 DEBUG : two: Destination found in --compare-dest, skipping
2026/05/22 01:31:24 DEBUG : two: Need to transfer - File not found at Destination
2026/05/22 01:31:24 DEBUG : two: size = 3 OK
2026/05/22 01:31:24 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/22 01:31:24 DEBUG : two: Destination found in --compare-dest, skipping
2026/05/22 01:31:26 DEBUG : two: Need to transfer - File not found at Destination
2026/05/22 01:31:26 DEBUG : two: size = 5 (Local file system at /tmp/rclone908286492)
2026/05/22 01:31:26 DEBUG : two: size = 3 (gofile root 'rclone-test-remumet3vazo/CompareDest')
2026/05/22 01:31:26 DEBUG : two: Sizes differ
2026/05/22 01:31:26 DEBUG : two: size = 5 OK
2026/05/22 01:31:26 DEBUG : two: md5 = 2379e4ce8c3380e996ab0509f17069ad OK
2026/05/22 01:31:26 INFO : two: Copied (new)
--- PASS: TestCopyFileCompareDest (15.37s)
=== RUN TestCopyFileCopyDest
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:31:29 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo/dst"
2026/05/22 01:31:30 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo/CopyDest"
2026/05/22 01:31:31 DEBUG : one: Need to transfer - File not found at Destination
2026/05/22 01:31:32 DEBUG : one: size = 3 OK
2026/05/22 01:31:32 DEBUG : one: md5 = f97c5d29941bfb1b2fdab0874906ab82 OK
2026/05/22 01:31:32 INFO : one: Copied (new)
2026/05/22 01:31:33 DEBUG : one: size = 5 (Local file system at /tmp/rclone908286492)
2026/05/22 01:31:33 DEBUG : one: size = 3 (gofile root 'rclone-test-remumet3vazo/dst')
2026/05/22 01:31:33 DEBUG : one: Sizes differ
2026/05/22 01:31:34 DEBUG : one: Removing old object on successful upload
2026/05/22 01:31:34 DEBUG : one: size = 5 OK
2026/05/22 01:31:34 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2026/05/22 01:31:34 INFO : one: Copied (replaced existing)
2026/05/22 01:31:35 DEBUG : dst/one: Removing old object on successful upload
2026/05/22 01:31:37 DEBUG : Creating backend with remote "TestGoFile:rclone-test-remumet3vazo/BackupDir"
2026/05/22 01:31:38 DEBUG : one: size = 5 (Local file system at /tmp/rclone908286492)
2026/05/22 01:31:38 DEBUG : one: size = 3 (gofile root 'rclone-test-remumet3vazo/dst')
2026/05/22 01:31:38 DEBUG : one: Sizes differ
2026/05/22 01:31:39 DEBUG : one: size = 5 OK
2026/05/22 01:31:39 DEBUG : one: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/22 01:31:39 DEBUG : one: size = 5 (Local file system at /tmp/rclone908286492)
2026/05/22 01:31:39 DEBUG : one: size = 3 (gofile root 'rclone-test-remumet3vazo/dst')
2026/05/22 01:31:39 DEBUG : one: Sizes differ
2026/05/22 01:31:40 INFO : one: Moved (server-side)
2026/05/22 01:31:41 DEBUG : one: size = 5 OK
2026/05/22 01:31:41 DEBUG : one: md5 = 07912d142f5d63ee918b34796b5a2432 OK
2026/05/22 01:31:41 INFO : one: Copied (server-side copy)
2026/05/22 01:31:41 DEBUG : one: Destination found in --copy-dest, using server-side copy
2026/05/22 01:31:42 DEBUG : two: Need to transfer - File not found at Destination
2026/05/22 01:31:42 DEBUG : two: size = 3 OK
2026/05/22 01:31:42 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/22 01:31:43 DEBUG : two: size = 3 OK
2026/05/22 01:31:43 DEBUG : two: md5 = b8a9f715dbb64fd5c56e7783c6820a61 OK
2026/05/22 01:31:43 INFO : two: Copied (server-side copy)
2026/05/22 01:31:43 DEBUG : two: Destination found in --copy-dest, using server-side copy
2026/05/22 01:31:44 DEBUG : two: size = 3 OK
2026/05/22 01:31:44 DEBUG : two: Size and modification time the same (differ by -123.456789ms, within tolerance 1s)
2026/05/22 01:31:44 DEBUG : two: Unchanged skipping
2026/05/22 01:31:46 DEBUG : three: Need to transfer - File not found at Destination
2026/05/22 01:31:46 DEBUG : three: size = 7 (Local file system at /tmp/rclone908286492)
2026/05/22 01:31:46 DEBUG : three: size = 5 (gofile root 'rclone-test-remumet3vazo/CopyDest')
2026/05/22 01:31:46 DEBUG : three: Sizes differ
2026/05/22 01:31:46 DEBUG : three: Destination not found in --copy-dest
2026/05/22 01:31:47 DEBUG : three: size = 7 OK
2026/05/22 01:31:47 DEBUG : three: md5 = 1bccb9dccb3e9f6a3f9d2a8bdb54b7f5 OK
2026/05/22 01:31:47 INFO : three: Copied (new)
--- PASS: TestCopyFileCopyDest (21.55s)
=== RUN TestCopyInplace
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
copy_test.go:371: Partial uploads not supported
--- SKIP: TestCopyInplace (0.46s)
=== RUN TestCopyLongFileName
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
copy_test.go:404: Partial uploads not supported
--- SKIP: TestCopyLongFileName (0.31s)
=== RUN TestCopyLongFileNameCollision
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
copy_test.go:437: Partial uploads not supported
--- SKIP: TestCopyLongFileNameCollision (0.39s)
=== RUN TestCopyFileMaxTransfer
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:31:52 DEBUG : TestCopyFileMaxTransfer/file1: Need to transfer - File not found at Destination
2026/05/22 01:31:53 DEBUG : TestCopyFileMaxTransfer/file1: size = 14 OK
2026/05/22 01:31:53 DEBUG : TestCopyFileMaxTransfer/file1: md5 = 0ef726ce9b1a7692357ff70dd321d595 OK
2026/05/22 01:31:53 INFO : TestCopyFileMaxTransfer/file1: Copied (new)
2026/05/22 01:31:53 DEBUG : TestCopyFileMaxTransfer/file2: Need to transfer - File not found at Destination
2026/05/22 01:31:53 ERROR : TestCopyFileMaxTransfer/file2: Failed to copy: failed to upload file: Post "https://upload.gofile.io/uploadfile": failed to copy data: max transfer limit reached as set by --max-transfer
2026/05/22 01:31:53 DEBUG : TestCopyFileMaxTransfer/file3: Need to transfer - File not found at Destination
2026/05/22 01:31:54 DEBUG : TestCopyFileMaxTransfer/file4: Need to transfer - File not found at Destination
2026/05/22 01:31:54 DEBUG : TestCopyFileMaxTransfer/file4: size = 2062 OK
2026/05/22 01:31:54 DEBUG : TestCopyFileMaxTransfer/file4: md5 = d9329af8668929f15e81f4e000676f64 OK
2026/05/22 01:31:54 INFO : TestCopyFileMaxTransfer/file4: Copied (new)
--- PASS: TestCopyFileMaxTransfer (4.16s)
=== RUN TestDeduplicateInteractive
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:31:57 INFO : gofile root 'rclone-test-remumet3vazo': Looking for duplicate names using interactive mode.
2026/05/22 01:31:57 NOTICE: one: Found 3 files with duplicate names
2026/05/22 01:31:57 NOTICE: one: Deleting 2/3 identical duplicates (md5 0f6d3e59c45e54d0f2cb30fb594620a3)
2026/05/22 01:31:57 INFO : one: Deleted
2026/05/22 01:31:58 INFO : one: Deleted
2026/05/22 01:31:58 NOTICE: one: All duplicates removed
--- PASS: TestDeduplicateInteractive (2.75s)
=== RUN TestDeduplicateSkip
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:31:59 INFO : gofile root 'rclone-test-remumet3vazo': Looking for duplicate names using skip mode.
2026/05/22 01:32:00 NOTICE: one: Found 3 files with duplicate names
2026/05/22 01:32:00 NOTICE: one: Deleting 1/2 identical duplicates (md5 0f6d3e59c45e54d0f2cb30fb594620a3)
2026/05/22 01:32:00 INFO : one: Deleted
2026/05/22 01:32:00 NOTICE: one: Skipping 2 files with duplicate names
--- PASS: TestDeduplicateSkip (3.43s)
=== RUN TestDeduplicateSizeOnly
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:32:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:07 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:07 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:32:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:07 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:07 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:32:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:07 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:07 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:32:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:07 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:07 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:32:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:07 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:07 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:32:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:07 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:07 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:32:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:08 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:08 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:32:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:08 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:08 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:32:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:10 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:10 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:32:12 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:12 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:12 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:337: Error listing: couldn't recursively list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n"
2026/05/22 01:32:17 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:17 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:17 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:32:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:32:27 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:32:47 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:33:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:33:07 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:33:07 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:33:17 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:33:17 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:33:37 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:33:37 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:33:58 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:34:18 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:34:18 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:34:18 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:34:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:34:27 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:35:05 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:35:05 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:35:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:35:07 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:35:27 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:35:27 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:35:48 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:36:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:36:08 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:36:08 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:36:18 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:36:18 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:36:38 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:36:59 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:36:59 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:36:59 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:37:07 DEBUG : pacer: Reducing sleep to 10s
--- FAIL: TestDeduplicateSizeOnly (305.72s)
=== RUN TestDeduplicateFirst
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:37:28 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:37:38 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:37:43 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:37:45 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:37:45 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:37:45 DEBUG : pacer: Rate limited, increasing sleep to 2.5s
2026/05/22 01:37:46 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:37:46 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:37:46 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:37:49 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:37:49 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:37:49 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:37:54 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:37:54 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:37:54 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:38:04 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:38:04 INFO : gofile root 'rclone-test-remumet3vazo': Looking for duplicate names using first mode.
2026/05/22 01:38:24 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:38:34 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:38:34 NOTICE: one: Found 3 files with duplicate names
2026/05/22 01:38:39 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:38:39 INFO : one: Deleted
2026/05/22 01:38:41 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:38:41 INFO : one: Deleted
2026/05/22 01:38:41 NOTICE: one: Deleted 2 extra copies
2026/05/22 01:38:42 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:38:42 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:38:42 DEBUG : pacer: Rate limited, increasing sleep to 1.25s
2026/05/22 01:38:43 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:38:43 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:38:43 DEBUG : pacer: Rate limited, increasing sleep to 2.5s
2026/05/22 01:38:44 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:38:44 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:38:44 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:38:47 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:38:47 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:38:47 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:38:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:38:52 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:38:52 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:39:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:39:02 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:39:22 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:39:42 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:39:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:39:52 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:39:52 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:39:57 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:39:57 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:39:57 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:40:07 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:40:07 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:40:27 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:40:47 DEBUG : pacer: Reducing sleep to 5s
--- PASS: TestDeduplicateFirst (219.40s)
=== RUN TestDeduplicateNewest
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:40:57 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:41:02 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:41:06 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:41:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:41:06 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:41:06 DEBUG : pacer: Rate limited, increasing sleep to 1.25s
2026/05/22 01:41:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:41:06 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:41:06 DEBUG : pacer: Rate limited, increasing sleep to 2.5s
2026/05/22 01:41:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:41:08 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:41:08 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:41:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:41:10 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:41:10 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:41:15 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:41:15 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:41:15 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:41:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:41:25 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:41:49 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:41:49 INFO : gofile root 'rclone-test-remumet3vazo': Looking for duplicate names using newest mode.
2026/05/22 01:42:05 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:42:05 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:42:05 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:42:15 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:42:15 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:42:35 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:42:55 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:42:55 NOTICE: one: Found 3 files with duplicate names
2026/05/22 01:43:05 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:43:05 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:43:05 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:43:11 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:43:11 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:43:11 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:43:20 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:43:20 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:43:41 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:43:41 INFO : one: Deleted
2026/05/22 01:44:00 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:44:00 INFO : one: Deleted
2026/05/22 01:44:00 NOTICE: one: Deleted 2 extra copies
2026/05/22 01:44:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:44:10 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:44:10 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:44:15 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:44:15 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:44:15 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:44:25 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:44:45 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:44:56 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:44:56 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:44:56 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:45:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:45:00 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:45:00 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:45:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:45:10 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:45:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:45:30 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:45:50 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:46:10 DEBUG : pacer: Reducing sleep to 5s
--- PASS: TestDeduplicateNewest (323.29s)
=== RUN TestDeduplicateNewestByHash
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:46:20 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:46:20 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:46:20 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:46:25 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:46:25 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:46:25 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:46:35 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:46:55 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:47:05 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:05 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:05 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:47:11 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:47:21 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:47:33 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:47:33 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:47:34 DEBUG : pacer: Reducing sleep to 312.5ms
2026/05/22 01:47:35 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:47:35 DEBUG : pacer: Reducing sleep to 78.125ms
2026/05/22 01:47:36 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/05/22 01:47:36 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/05/22 01:47:36 INFO : gofile root 'rclone-test-remumet3vazo': Looking for duplicate md5 hashes using newest mode.
2026/05/22 01:47:36 DEBUG : pacer: Reducing sleep to 10ms
2026/05/22 01:47:36 NOTICE: dec2ceb0c97aa5073d2b2d6d6152dd6c: Found 3 files with duplicate md5 hashes
2026/05/22 01:47:36 INFO : one: Deleted
2026/05/22 01:47:37 INFO : also/one: Deleted
2026/05/22 01:47:37 NOTICE: dec2ceb0c97aa5073d2b2d6d6152dd6c: Deleted 2 extra copies
--- PASS: TestDeduplicateNewestByHash (88.07s)
=== RUN TestDeduplicateOldest
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:47:40 INFO : gofile root 'rclone-test-remumet3vazo': Looking for duplicate names using oldest mode.
2026/05/22 01:47:40 NOTICE: one: Found 3 files with duplicate names
2026/05/22 01:47:40 INFO : one: Deleted
2026/05/22 01:47:40 INFO : one: Deleted
2026/05/22 01:47:40 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateOldest (3.42s)
=== RUN TestDeduplicateLargest
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:47:43 INFO : gofile root 'rclone-test-remumet3vazo': Looking for duplicate names using largest mode.
2026/05/22 01:47:43 NOTICE: one: Found 3 files with duplicate names
2026/05/22 01:47:43 INFO : one: Deleted
2026/05/22 01:47:43 INFO : one: Deleted
2026/05/22 01:47:43 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateLargest (2.43s)
=== RUN TestDeduplicateSmallest
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:47:45 INFO : gofile root 'rclone-test-remumet3vazo': Looking for duplicate names using smallest mode.
2026/05/22 01:47:45 NOTICE: one: Found 3 files with duplicate names
2026/05/22 01:47:45 INFO : one: Deleted
2026/05/22 01:47:45 INFO : one: Deleted
2026/05/22 01:47:45 NOTICE: one: Deleted 2 extra copies
--- PASS: TestDeduplicateSmallest (2.50s)
=== RUN TestDeduplicateRename
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:47:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:53 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:53 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:47:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:53 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:53 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:47:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:53 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:53 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:47:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:53 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:53 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:47:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:53 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:53 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:47:53 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:53 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:53 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:47:54 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:54 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:54 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:47:54 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:54 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:54 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:47:56 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:56 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:56 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:47:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:47:58 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:47:58 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:337: Error listing: couldn't recursively list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n"
2026/05/22 01:48:03 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:48:03 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:48:03 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:48:14 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:48:14 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:48:34 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:48:54 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:49:04 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:49:09 DEBUG : pacer: Reducing sleep to 1.25s
2026/05/22 01:49:11 DEBUG : pacer: Reducing sleep to 625ms
2026/05/22 01:49:12 DEBUG : pacer: Reducing sleep to 312.5ms
--- FAIL: TestDeduplicateRename (85.76s)
=== RUN TestMergeDirs
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:49:13 DEBUG : pacer: Reducing sleep to 156.25ms
2026/05/22 01:49:13 DEBUG : pacer: Reducing sleep to 78.125ms
2026/05/22 01:49:14 DEBUG : pacer: Reducing sleep to 39.0625ms
2026/05/22 01:49:14 DEBUG : pacer: Reducing sleep to 19.53125ms
2026/05/22 01:49:14 DEBUG : pacer: Reducing sleep to 10ms
2026/05/22 01:49:16 INFO : dupe2: merging "two.txt"
2026/05/22 01:49:16 INFO : dupe2: removing empty directory
2026/05/22 01:49:16 INFO : dupe3: merging "three.txt"
2026/05/22 01:49:17 INFO : dupe3: removing empty directory
--- PASS: TestMergeDirs (5.81s)
=== RUN TestListDirSorted
run.go:198: Remote "gofile root 'rclone-test-remumet3vazo'", Local "Local file system at /tmp/rclone908286492", Modify Window "1s"
2026/05/22 01:49:29 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:29 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:29 DEBUG : pacer: Rate limited, increasing sleep to 20ms
2026/05/22 01:49:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:30 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:30 DEBUG : pacer: Rate limited, increasing sleep to 40ms
2026/05/22 01:49:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:30 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:30 DEBUG : pacer: Rate limited, increasing sleep to 80ms
2026/05/22 01:49:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:30 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:30 DEBUG : pacer: Rate limited, increasing sleep to 160ms
2026/05/22 01:49:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:30 DEBUG : pacer: low level retry 5/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:30 DEBUG : pacer: Rate limited, increasing sleep to 320ms
2026/05/22 01:49:31 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:31 DEBUG : pacer: low level retry 6/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:31 DEBUG : pacer: Rate limited, increasing sleep to 640ms
2026/05/22 01:49:32 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:32 DEBUG : pacer: low level retry 7/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:32 DEBUG : pacer: Rate limited, increasing sleep to 1.28s
2026/05/22 01:49:32 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:32 DEBUG : pacer: low level retry 8/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:32 DEBUG : pacer: Rate limited, increasing sleep to 2.56s
2026/05/22 01:49:33 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:33 DEBUG : pacer: low level retry 9/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:33 DEBUG : pacer: Rate limited, increasing sleep to 5.12s
2026/05/22 01:49:36 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:36 DEBUG : pacer: low level retry 10/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:36 DEBUG : pacer: Rate limited, increasing sleep to 10.24s
run.go:299: Retry Put of "a.txt" to gofile root 'rclone-test-remumet3vazo': 1/10 (couldn't list files: Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:41 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:41 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:49:41 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:49:51 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:49:51 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:50:11 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:50:11 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:50:31 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:50:51 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:51:01 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:51:01 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:51:01 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:51:06 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:51:06 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:51:06 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:51:16 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:51:16 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:51:36 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:51:56 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:52:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:52:08 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:52:08 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:52:11 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:52:11 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:52:11 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:52:21 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:52:21 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:52:41 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:53:01 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:53:01 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:53:01 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:53:11 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:53:31 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:53:41 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:53:50 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:53:50 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:53:50 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:53:50 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:53:50 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:53:50 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:53:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:53:55 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:53:55 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:54:05 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:54:05 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:54:29 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:54:46 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:54:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:54:55 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:54:55 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:55:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:55:00 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:55:00 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:55:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:55:10 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:55:30 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:55:30 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:55:50 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:56:10 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:56:10 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:56:10 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:56:20 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:56:20 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:56:47 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:56:47 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:57:00 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:57:00 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:57:20 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:57:41 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:57:50 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 01:57:55 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:57:55 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:57:55 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 01:57:58 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:57:58 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:57:58 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:58:03 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:58:03 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:58:03 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:58:13 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:58:13 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:58:33 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:58:53 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 01:59:03 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:59:03 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:59:03 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 01:59:08 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:59:08 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:59:08 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:59:18 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 01:59:40 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:59:40 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 01:59:40 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 01:59:48 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 01:59:48 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 02:00:08 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 02:00:28 DEBUG : pacer: Reducing sleep to 5s
2026/05/22 02:00:38 DEBUG : pacer: Reducing sleep to 2.5s
2026/05/22 02:00:46 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 02:00:46 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 02:00:46 DEBUG : pacer: Rate limited, increasing sleep to 5s
2026/05/22 02:00:47 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 02:00:47 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 02:00:47 DEBUG : pacer: Rate limited, increasing sleep to 10s
2026/05/22 02:00:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 02:00:52 DEBUG : pacer: low level retry 3/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 02:00:52 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 02:01:02 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 02:01:02 DEBUG : pacer: low level retry 4/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 02:01:30 DEBUG : pacer: Reducing sleep to 10s
2026/05/22 02:01:42 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 02:01:42 DEBUG : pacer: low level retry 1/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
2026/05/22 02:01:42 DEBUG : pacer: Rate limited, increasing sleep to 20s
2026/05/22 02:01:52 DEBUG : Couldn't decode error response: invalid character '<' looking for beginning of value
2026/05/22 02:01:52 DEBUG : pacer: low level retry 2/10 (error Error "502 Bad Gateway (502): \r\n502 Bad Gateway\r\n\r\n502 Bad Gateway
\r\n
nginx\r\n\r\n\r\n")
panic: test timed out after 1h0m0s
running tests:
TestListDirSorted (13m9s)
goroutine 3898 [running]:
testing.(*M).startAlarm.func1()
/usr/local/go/src/testing/testing.go:2802 +0x34b
created by time.goFunc
/usr/local/go/src/time/sleep.go:215 +0x2d
goroutine 1 [chan receive, 14 minutes]:
testing.(*T).Run(0x18a35d4f0248, {0x2a6d8b0?, 0x18a35df4fae8?}, 0x2bd60d0)
/usr/local/go/src/testing/testing.go:2109 +0x4e5
testing.runTests.func1(0x18a35d4f0248)
/usr/local/go/src/testing/testing.go:2585 +0x37
testing.tRunner(0x18a35d4f0248, 0x18a35df4fc10)
/usr/local/go/src/testing/testing.go:2036 +0xea
testing.runTests({0x2a87b97, 0x18}, {0x2ac2495, 0x26}, 0x18a35dd4e240, {0x46811a0, 0x71, 0x71}, {0xc27c0c2cd793bec6, 0x35ae2521110, ...})
/usr/local/go/src/testing/testing.go:2583 +0x505
testing.(*M).Run(0x18a35da27cc0)
/usr/local/go/src/testing/testing.go:2443 +0x6ac
github.com/rclone/rclone/fstest.TestMain(0x18a35da27cc0)
/home/rclone/go/src/github.com/rclone/rclone/fstest/run.go:74 +0xa6
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:274 +0xa6
goroutine 3674 [select]:
net/http.(*http2ClientConn).roundTrip(0x18a35e014000, 0x18a35d9c2000, 0x0)
/usr/local/go/src/net/http/h2_bundle.go:8688 +0x525
net/http.(*http2ClientConn).RoundTrip(...)
/usr/local/go/src/net/http/h2_bundle.go:8603
net/http.(*http2Transport).RoundTripOpt(0x18a35da27d60, 0x18a35d9c2000, {0x80?, 0x41?})
/usr/local/go/src/net/http/h2_bundle.go:7901 +0x1ed
net/http.(*http2Transport).RoundTrip(...)
/usr/local/go/src/net/http/h2_bundle.go:7852
net/http.http2noDialH2RoundTripper.RoundTrip({0x18a35db2f040?}, 0x18a35d9c2000?)
/usr/local/go/src/net/http/h2_bundle.go:10560 +0x1c
net/http.(*Transport).roundTrip(0x18a35db2f040, 0x18a35d9c2000)
/usr/local/go/src/net/http/transport.go:623 +0x4ff
net/http.(*Transport).RoundTrip(...)
/usr/local/go/src/net/http/roundtrip.go:33
github.com/rclone/rclone/fs/fshttp.(*Transport).RoundTrip(0x18a35d941500, 0x18a35d9c2000)
/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/http.go:531 +0x3dc
net/http.send(0x18a35d9c2000, {0x2bf1d40, 0x18a35d941500}, {0x18a35dbbebe0?, 0x4936a6?, 0x0?})
/usr/local/go/src/net/http/client.go:264 +0x64b
net/http.(*Client).send(0x18a35d675980, 0x18a35d9c2000, {0x431836?, 0x46c0a40?, 0x0?})
/usr/local/go/src/net/http/client.go:185 +0x258
net/http.(*Client).do(0x18a35d675980, 0x18a35d9c2000)
/usr/local/go/src/net/http/client.go:733 +0x9d7
net/http.(*Client).Do(...)
/usr/local/go/src/net/http/client.go:592
github.com/rclone/rclone/lib/rest.(*Client).Call(0x18a35dad34a0, {0x2c1c9c8, 0x46bdc80}, 0x18a35db70840)
/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:371 +0xd85
github.com/rclone/rclone/lib/rest.(*Client).callCodec(0x18a35dad34a0, {0x2c1c9c8, 0x46bdc80}, 0x18a35dbbf4e8?, {0x0?, 0x0?}, {0x2585180, 0x18a35d7e8a50}, 0x18a35d925110?, 0x2bd75d0, ...)
/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:579 +0x447
github.com/rclone/rclone/lib/rest.(*Client).CallJSON(...)
/home/rclone/go/src/github.com/rclone/rclone/lib/rest/rest.go:522
github.com/rclone/rclone/backend/gofile.(*Fs).listAll.func1()
/home/rclone/go/src/github.com/rclone/rclone/backend/gofile/gofile.go:542 +0x85
github.com/rclone/rclone/fs.pacerInvoker(0x3, 0xa, 0x2bf1040?)
/home/rclone/go/src/github.com/rclone/rclone/fs/pacer.go:86 +0x32
github.com/rclone/rclone/lib/pacer.(*Pacer).call(0x18a35d941560, 0x18a35de4a540, 0xa)
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:228 +0xd2
github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0x18a35d941560, 0x18a35de4a540)
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:247 +0x92
github.com/rclone/rclone/backend/gofile.(*Fs).listAll(0x18a35d9b1480, {0x2c1c9c8, 0x46bdc80}, {0x18a35df16f30, 0x24}, 0x0, 0x0, {0x0, 0x0}, 0x18a35d521968)
/home/rclone/go/src/github.com/rclone/rclone/backend/gofile/gofile.go:541 +0x576
github.com/rclone/rclone/backend/gofile.(*Fs).List(0x18a35d9b1480, {0x2c1c9c8, 0x46bdc80}, {0x0, 0x0})
/home/rclone/go/src/github.com/rclone/rclone/backend/gofile/gofile.go:617 +0x108
github.com/rclone/rclone/fs/list.DirSorted({0x2c1c9c8, 0x46bdc80}, {0x2c38ce0, 0x18a35d9b1480}, 0x1, {0x0, 0x0})
/home/rclone/go/src/github.com/rclone/rclone/fs/list/list.go:26 +0x67
github.com/rclone/rclone/fs/operations_test.testListDirSorted(0x18a35d4f0b48, 0x2bd6550)
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:55 +0x9c3
github.com/rclone/rclone/fs/operations_test.TestListDirSorted(0x18a35d4f0b48?)
/home/rclone/go/src/github.com/rclone/rclone/fs/operations/listdirsorted_test.go:111 +0x1a
testing.tRunner(0x18a35d4f0b48, 0x2bd60d0)
/usr/local/go/src/testing/testing.go:2036 +0xea
created by testing.(*T).Run in goroutine 1
/usr/local/go/src/testing/testing.go:2101 +0x4c5
goroutine 28 [syscall, 61 minutes]:
os/signal.signal_recv()
/usr/local/go/src/runtime/sigqueue.go:152 +0x98
os/signal.loop()
/usr/local/go/src/os/signal/signal_unix.go:23 +0x13
created by os/signal.Notify.func1.1 in goroutine 1
/usr/local/go/src/os/signal/signal.go:152 +0x1f
goroutine 29 [chan receive, 61 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 +0xa7
goroutine 348 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 338
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1687 [chan receive, 38 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 845 [chan receive (nil chan), 52 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 835
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 296 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 286
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1830 [chan receive, 36 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 206 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 196
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 178 [chan receive (nil chan), 59 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 168
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 2205 [chan receive, 33 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1745 [chan receive, 38 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 225 [chan receive (nil chan), 59 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 215
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 267 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 161
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 3896 [sleep]:
time.Sleep(0x4a817c800)
/usr/local/go/src/runtime/time.go:363 +0x165
github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0x0?)
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:178 +0x1c
created by github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall in goroutine 3674
/home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:177 +0x17f
goroutine 1634 [chan receive, 38 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 2937 [select]:
github.com/rclone/rclone/fs/accounting.(*StatsInfo).averageLoop(0x18a35dbae000, {0x2c1cb18, 0x18a35dd0c0a0})
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:352 +0x145
created by github.com/rclone/rclone/fs/accounting.(*StatsInfo)._startAverageLoop in goroutine 2936
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/stats.go:389 +0x11d
goroutine 314 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 272
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 382 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 373
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 2165 [chan receive, 33 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 407 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 189
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 434 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 424
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 456 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 446
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 481 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 471
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 688 [chan receive (nil chan), 54 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 678
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 632 [chan receive (nil chan), 56 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 622
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 656 [chan receive (nil chan), 56 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 646
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 526 [chan receive (nil chan), 57 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 516
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 568 [chan receive (nil chan), 56 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 542
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 3897 [select]:
net/http.(*http2clientStream).writeRequest(0x18a35d94cd80, 0x18a35d9c2000, 0x0)
/usr/local/go/src/net/http/h2_bundle.go:8857 +0xc68
net/http.(*http2clientStream).doRequest(0x18a35d94cd80, 0x657d5b?, 0x0?)
/usr/local/go/src/net/http/h2_bundle.go:8718 +0x18
created by net/http.(*http2ClientConn).roundTrip in goroutine 3674
/usr/local/go/src/net/http/h2_bundle.go:8624 +0x470
goroutine 588 [chan receive (nil chan), 56 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 578
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 854 [chan receive (nil chan), 52 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 828
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 1907 [chan receive, 36 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 737 [chan receive (nil chan), 52 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 727
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 793 [chan receive (nil chan), 52 minutes]:
github.com/rclone/rclone/fs/march.(*March).Run.func2()
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:259 +0x3c
created by github.com/rclone/rclone/fs/march.(*March).Run in goroutine 783
/home/rclone/go/src/github.com/rclone/rclone/fs/march/march.go:257 +0x359
goroutine 2365 [IO wait, 2 minutes]:
internal/poll.runtime_pollWait(0x784f1266e400, 0x72)
/usr/local/go/src/runtime/netpoll.go:351 +0x85
internal/poll.(*pollDesc).wait(0x18a35d9b0380?, 0x18a35de6d000?, 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(0x18a35d9b0380, {0x18a35de6d000, 0x1000, 0x1000})
/usr/local/go/src/internal/poll/fd_unix.go:165 +0x2ae
net.(*netFD).Read(0x18a35d9b0380, {0x18a35de6d000?, 0x18a35dbcf020?, 0x5?})
/usr/local/go/src/net/fd_posix.go:68 +0x25
net.(*conn).Read(0x18a35d4b8888, {0x18a35de6d000?, 0x18a35dfdb950?, 0x48df6c?})
/usr/local/go/src/net/net.go:196 +0x45
github.com/rclone/rclone/fs/fshttp.(*timeoutConn).Read(0x18a35de9f4e8, {0x18a35de6d000?, 0x784f10d7b580?, 0x784f5b513108?})
/home/rclone/go/src/github.com/rclone/rclone/fs/fshttp/dialer.go:111 +0x29
crypto/tls.(*atLeastReader).Read(0x18a35dd90558, {0x18a35de6d000?, 0x18a35db2c1e0?, 0x18a35dfdba48?})
/usr/local/go/src/crypto/tls/conn.go:815 +0x3b
bytes.(*Buffer).ReadFrom(0x18a35de6e2a8, {0x2bf26c0, 0x18a35dd90558})
/usr/local/go/src/bytes/buffer.go:229 +0x98
crypto/tls.(*Conn).readFromUntil(0x18a35de6e008, {0x784f10d2ba00, 0x18a35de9f4e8}, 0x18a35dfdbc08?)
/usr/local/go/src/crypto/tls/conn.go:837 +0xde
crypto/tls.(*Conn).readRecordOrCCS(0x18a35de6e008, 0x0)
/usr/local/go/src/crypto/tls/conn.go:626 +0x3db
crypto/tls.(*Conn).readRecord(...)
/usr/local/go/src/crypto/tls/conn.go:588
crypto/tls.(*Conn).Read(0x18a35de6e008, {0x18a35e01c000, 0x1000, 0x7e5120?})
/usr/local/go/src/crypto/tls/conn.go:1393 +0x145
bufio.(*Reader).Read(0x18a35df9d4a0, {0x18a35e01e044, 0x9, 0x801c2e?})
/usr/local/go/src/bufio/bufio.go:245 +0x197
io.ReadAtLeast({0x2bf0900, 0x18a35df9d4a0}, {0x18a35e01e044, 0x9, 0x9}, 0x9)
/usr/local/go/src/io/io.go:335 +0x8e
io.ReadFull(...)
/usr/local/go/src/io/io.go:354
net/http.http2readFrameHeader({0x18a35e01e044, 0x9, 0x18a3000002d5?}, {0x2bf0900?, 0x18a35df9d4a0?})
/usr/local/go/src/net/http/h2_bundle.go:1805 +0x65
net/http.(*http2Framer).ReadFrameHeader(0x18a35e01e000)
/usr/local/go/src/net/http/h2_bundle.go:2071 +0x6b
net/http.(*http2Framer).ReadFrame(0x18a35e01e000)
/usr/local/go/src/net/http/h2_bundle.go:2130 +0x18
net/http.(*http2clientConnReadLoop).run(0x18a35dfdbfa8)
/usr/local/go/src/net/http/h2_bundle.go:9550 +0xca
net/http.(*http2ClientConn).readLoop(0x18a35e014000)
/usr/local/go/src/net/http/h2_bundle.go:9419 +0x52
created by net/http.(*http2Transport).newClientConn in goroutine 2364
/usr/local/go/src/net/http/h2_bundle.go:8171 +0xda5
goroutine 1981 [chan receive, 33 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 2005 [chan receive, 34 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
goroutine 1958 [chan receive, 34 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 1585
/home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xa7
"./operations.test -test.v -test.timeout 1h0m0s -remote TestGoFile: -verbose" - Finished ERROR in 1h1m28.894432613s (try 1/5): exit status 2: Failed [TestCheckSum TestCheckSumDownload TestDeduplicateSizeOnly TestDeduplicateRename]