"go test -v -timeout 2h0m0s -remote TestZoho: -verbose" - Starting (try 1/5) === RUN TestIntegration fstests.go:438: Using remote "TestZoho:" 2025/11/02 02:48:13 NOTICE: TestZoho: Stopped server 2025/11/02 02:48:14 NOTICE: TestZoho: Starting server 2025/11/02 02:48:14 DEBUG : Creating backend with remote "TestZoho:rclone-test-piwujer4zina" 2025/11/02 02:48:14 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2025/11/02 02:48:14 DEBUG : TestZoho: Token expired 2025/11/02 02:48:14 DEBUG : TestZoho: No updated token found in the config file 2025/11/02 02:48:14 DEBUG : TestZoho: Token refresh successful 2025/11/02 02:48:14 DEBUG : Saving config "token" in section "TestZoho" of the config file 2025/11/02 02:48:14 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:479: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:507: No commands in this remote === RUN TestIntegration/FsRmdirNotFound === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty === RUN TestIntegration/FsMkdir === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2025/11/02 02:48:16 INFO : dir/subdir: Making directory 2025/11/02 02:48:17 INFO : dir/subdir: Removing directory 2025/11/02 02:48:17 INFO : dir: Removing directory === RUN TestIntegration/FsMkdir/FsListEmpty === RUN TestIntegration/FsMkdir/FsListDirEmpty === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2025/11/02 02:48:18 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:399: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:707: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:707: testing "." === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:707: testing ".." === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:707: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2025/11/02 02:48:23 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:48:23 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:48:23 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2025/11/02 02:48:23 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:48:23 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:49:23 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:49:23 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:50:23 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:50:23 DEBUG : pacer: low level retry 4/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:51:23 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:51:23 DEBUG : pacer: low level retry 5/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:52:23 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:52:23 DEBUG : pacer: low level retry 6/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:53:23 DEBUG : pacer: Reducing sleep to 45s === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:707: testing " leading space" 2025/11/02 02:54:23 DEBUG : pacer: Reducing sleep to 33.75s 2025/11/02 02:55:08 DEBUG : pacer: Reducing sleep to 25.3125s 2025/11/02 02:55:41 DEBUG : pacer: Reducing sleep to 18.984375s 2025/11/02 02:56:07 DEBUG : pacer: Reducing sleep to 14.23828125s 2025/11/02 02:56:26 DEBUG : pacer: Reducing sleep to 10.678710937s 2025/11/02 02:56:40 DEBUG : pacer: Reducing sleep to 8.009033202s 2025/11/02 02:56:51 DEBUG : pacer: Reducing sleep to 6.006774901s 2025/11/02 02:56:59 DEBUG : pacer: Reducing sleep to 4.505081175s 2025/11/02 02:57:05 DEBUG : pacer: Reducing sleep to 3.378810881s 2025/11/02 02:57:09 DEBUG : pacer: Reducing sleep to 2.53410816s 2025/11/02 02:57:12 DEBUG : pacer: Reducing sleep to 1.90058112s === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:707: testing "~leading tilde" 2025/11/02 02:57:15 DEBUG : pacer: Reducing sleep to 1.42543584s 2025/11/02 02:57:17 DEBUG : pacer: Reducing sleep to 1.06907688s 2025/11/02 02:57:18 DEBUG : pacer: Reducing sleep to 801.80766ms 2025/11/02 02:57:19 DEBUG : pacer: Reducing sleep to 601.355745ms 2025/11/02 02:57:20 DEBUG : pacer: Reducing sleep to 451.016808ms 2025/11/02 02:57:21 DEBUG : pacer: Reducing sleep to 338.262606ms 2025/11/02 02:57:21 DEBUG : pacer: Reducing sleep to 253.696954ms 2025/11/02 02:57:22 DEBUG : pacer: Reducing sleep to 190.272715ms 2025/11/02 02:57:22 DEBUG : pacer: Reducing sleep to 142.704536ms 2025/11/02 02:57:22 DEBUG : pacer: Reducing sleep to 107.028402ms 2025/11/02 02:57:22 DEBUG : pacer: Reducing sleep to 80.271301ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:707: testing "␍leading CR" 2025/11/02 02:57:22 DEBUG : pacer: Reducing sleep to 60.203475ms 2025/11/02 02:57:22 DEBUG : pacer: Reducing sleep to 45.152606ms 2025/11/02 02:57:23 DEBUG : pacer: Reducing sleep to 33.864454ms 2025/11/02 02:57:23 DEBUG : pacer: Reducing sleep to 25.39834ms 2025/11/02 02:57:23 DEBUG : pacer: Reducing sleep to 19.048755ms 2025/11/02 02:57:23 DEBUG : pacer: Reducing sleep to 14.286566ms 2025/11/02 02:57:23 DEBUG : pacer: Reducing sleep to 10.714924ms 2025/11/02 02:57:23 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:707: testing "␊leading LF" === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:707: testing "␉leading HT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:707: testing "␋leading VT" === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:707: testing ".leading dot" === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:707: testing "trailing space " 2025/11/02 02:57:28 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:57:28 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:57:28 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2025/11/02 02:57:28 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:57:28 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:58:28 DEBUG : Too many requests. Trying again in 60 seconds. 2025/11/02 02:58:28 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Request rate limit exceeded. Please retry again later.\"}]}\n": trying again in 1m0s) 2025/11/02 02:59:28 DEBUG : pacer: low level retry 4/10 (error HTTP error 502 (502 Bad Gateway) returned body: "\n\n
\n\t\n\t\n\t\n\t