"go test -v -timeout 2h0m0s -remote TestZoho: -verbose" - Starting (try 1/5) === RUN TestFolderListLimiter --- PASS: TestFolderListLimiter (0.00s) === RUN TestFolderWindowLimiterShape --- PASS: TestFolderWindowLimiterShape (0.00s) === RUN TestFolderWindowLimiterIdleResume --- PASS: TestFolderWindowLimiterIdleResume (0.00s) === RUN TestFolderWindowLimiterClamps --- PASS: TestFolderWindowLimiterClamps (0.00s) === RUN TestFolderListLimiterConcurrent --- PASS: TestFolderListLimiterConcurrent (0.00s) === RUN TestTPSMinSleep --- PASS: TestTPSMinSleep (0.00s) === RUN TestShouldRetry === RUN TestShouldRetry/429_honours_Retry-After 2026/10/04 01:43:00 NOTICE: zoho root '': Too many requests: Trying again in 6 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_without_Retry-After_falls_back_to_60s 2026/10/04 01:43:00 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_with_unparseable_Retry-After_falls_back 2026/10/04 01:43:00 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/10/04 01:43:00 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/10/04 01:43:00 ERROR : zoho: missing OAuth Scope. Run rclone config reconnect to fix this issue. === RUN TestShouldRetry/401_expired_token_retries === RUN TestShouldRetry/401_R008_deleted_folder_is_not_retried === RUN TestShouldRetry/cancelled_context_aborts --- PASS: TestShouldRetry (0.00s) --- PASS: TestShouldRetry/429_honours_Retry-After (0.00s) --- PASS: TestShouldRetry/429_without_Retry-After_falls_back_to_60s (0.00s) --- PASS: TestShouldRetry/429_with_unparseable_Retry-After_falls_back (0.00s) --- PASS: TestShouldRetry/401_missing_scope_aborts (0.00s) --- PASS: TestShouldRetry/401_expired_token_retries (0.00s) --- PASS: TestShouldRetry/401_R008_deleted_folder_is_not_retried (0.00s) --- PASS: TestShouldRetry/cancelled_context_aborts (0.00s) === RUN TestIsMissingResourceErr --- PASS: TestIsMissingResourceErr (0.00s) === RUN TestThrottleEpisode 2026/10/04 01:43:00 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/10/04 01:43:00 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleStateConcurrent (0.00s) === RUN TestConfigRootFolderID === RUN TestConfigRootFolderID/SetRootAsksBeforeChanging === RUN TestConfigRootFolderID/EmptyRootGoesToEdition === RUN TestConfigRootFolderID/KeepRootOnNo === RUN TestConfigRootFolderID/ChangeRootOnYes --- PASS: TestConfigRootFolderID (0.00s) --- PASS: TestConfigRootFolderID/SetRootAsksBeforeChanging (0.00s) --- PASS: TestConfigRootFolderID/EmptyRootGoesToEdition (0.00s) --- PASS: TestConfigRootFolderID/KeepRootOnNo (0.00s) --- PASS: TestConfigRootFolderID/ChangeRootOnYes (0.00s) === RUN TestIntegration fstests.go:464: Using remote "TestZoho:" 2026/10/04 01:43:00 DEBUG : Creating backend with remote "TestZoho:rclone-test-zabagoz7jajo" 2026/10/04 01:43:00 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/04 01:43:00 DEBUG : zoho root 'rclone-test-zabagoz7jajo': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/10/04 01:43:00 DEBUG : TestZoho: Token expired 2026/10/04 01:43:00 DEBUG : TestZoho: No updated token found in the config file 2026/10/04 01:43:00 DEBUG : TestZoho: Token refresh successful 2026/10/04 01:43:00 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/10/04 01:43:00 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:505: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:533: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/10/04 01:43:03 DEBUG : pacer: Reducing sleep to 86.995246ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/10/04 01:43:03 DEBUG : pacer: Reducing sleep to 163.422088ms 2026/10/04 01:43:03 DEBUG : pacer: Reducing sleep to 223.422914ms === RUN TestIntegration/FsMkdir 2026/10/04 01:43:03 DEBUG : pacer: Reducing sleep to 146.988544ms 2026/10/04 01:43:03 DEBUG : pacer: Reducing sleep to 102.189685ms 2026/10/04 01:43:03 DEBUG : pacer: Reducing sleep to 190.215428ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/10/04 01:43:03 INFO : dir/subdir: Making directory 2026/10/04 01:43:03 DEBUG : pacer: Reducing sleep to 258.057489ms 2026/10/04 01:43:04 DEBUG : pacer: Reducing sleep to 188.304926ms 2026/10/04 01:43:04 DEBUG : pacer: Reducing sleep to 141.38418ms 2026/10/04 01:43:04 DEBUG : pacer: Reducing sleep to 78.565718ms 2026/10/04 01:43:04 DEBUG : pacer: Reducing sleep to 132.938599ms 2026/10/04 01:43:04 DEBUG : pacer: Reducing sleep to 212.242232ms 2026/10/04 01:43:04 DEBUG : pacer: Reducing sleep to 256.374167ms 2026/10/04 01:43:04 INFO : dir/subdir: Removing directory 2026/10/04 01:43:05 DEBUG : pacer: Reducing sleep to 201.375579ms 2026/10/04 01:43:05 DEBUG : pacer: Reducing sleep to 105.218209ms 2026/10/04 01:43:05 DEBUG : pacer: Reducing sleep to 71.824866ms 2026/10/04 01:43:05 DEBUG : pacer: Reducing sleep to 145.019454ms 2026/10/04 01:43:05 INFO : dir: Removing directory 2026/10/04 01:43:05 DEBUG : pacer: Reducing sleep to 227.827615ms 2026/10/04 01:43:05 DEBUG : pacer: Reducing sleep to 239.086691ms 2026/10/04 01:43:06 DEBUG : pacer: Reducing sleep to 188.590245ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/10/04 01:43:06 DEBUG : pacer: Reducing sleep to 120.686942ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/10/04 01:43:10 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/10/04 01:43:15 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:734: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/10/04 01:43:24 DEBUG : pacer: Reducing sleep to 52.475749ms 2026/10/04 01:43:24 DEBUG : pacer: Reducing sleep to 143.493599ms 2026/10/04 01:43:25 DEBUG : pacer: Reducing sleep to 120.406276ms 2026/10/04 01:43:25 DEBUG : pacer: Reducing sleep to 198.857829ms 2026/10/04 01:43:29 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:43:29 DEBUG : pacer: Reducing sleep to 69.508418ms 2026/10/04 01:43:29 DEBUG : pacer: Reducing sleep to 126.086563ms 2026/10/04 01:43:29 DEBUG : pacer: Reducing sleep to 202.814272ms 2026/10/04 01:43:29 DEBUG : pacer: Reducing sleep to 243.621068ms 2026/10/04 01:43:33 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/10/04 01:43:38 DEBUG : pacer: Reducing sleep to 56.375841ms 2026/10/04 01:43:38 DEBUG : pacer: Reducing sleep to 146.782629ms 2026/10/04 01:43:39 DEBUG : pacer: Reducing sleep to 57.093704ms 2026/10/04 01:43:39 DEBUG : pacer: Reducing sleep to 133.65532ms 2026/10/04 01:43:43 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:43:43 DEBUG : pacer: Reducing sleep to 75.21278ms 2026/10/04 01:43:43 DEBUG : pacer: Reducing sleep to 151.809121ms 2026/10/04 01:43:43 DEBUG : pacer: Reducing sleep to 235.166168ms 2026/10/04 01:43:43 DEBUG : pacer: Reducing sleep to 232.804196ms 2026/10/04 01:43:47 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/10/04 01:43:52 DEBUG : pacer: Reducing sleep to 56.43293ms 2026/10/04 01:43:52 DEBUG : pacer: Reducing sleep to 146.325967ms 2026/10/04 01:43:52 DEBUG : pacer: Reducing sleep to 116.508407ms 2026/10/04 01:43:52 DEBUG : pacer: Reducing sleep to 195.470625ms 2026/10/04 01:43:57 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:43:57 DEBUG : pacer: Reducing sleep to 77.418385ms 2026/10/04 01:43:57 DEBUG : pacer: Reducing sleep to 127.888347ms 2026/10/04 01:43:57 DEBUG : pacer: Reducing sleep to 211.505685ms 2026/10/04 01:43:57 DEBUG : pacer: Reducing sleep to 243.330322ms 2026/10/04 01:44:01 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/10/04 01:44:01 DEBUG : zoho root 'rclone-test-zabagoz7jajo': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/10/04 01:44:06 DEBUG : pacer: Reducing sleep to 64.012079ms 2026/10/04 01:44:06 DEBUG : pacer: Reducing sleep to 151.746502ms 2026/10/04 01:44:06 DEBUG : pacer: Reducing sleep to 11.185333ms 2026/10/04 01:44:06 DEBUG : pacer: Reducing sleep to 94.862495ms 2026/10/04 01:44:06 DEBUG : pacer: Reducing sleep to 170.325898ms 2026/10/04 01:44:07 DEBUG : pacer: Reducing sleep to 249.002238ms 2026/10/04 01:44:07 DEBUG : pacer: Reducing sleep to 227.237442ms 2026/10/04 01:44:07 DEBUG : pacer: Reducing sleep to 164.788078ms 2026/10/04 01:44:07 DEBUG : pacer: Reducing sleep to 85.298409ms 2026/10/04 01:44:07 DEBUG : pacer: Reducing sleep to 107.03064ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/10/04 01:44:07 DEBUG : pacer: Reducing sleep to 184.72235ms 2026/10/04 01:44:08 DEBUG : pacer: Reducing sleep to 197.45458ms 2026/10/04 01:44:08 DEBUG : pacer: Reducing sleep to 221.162993ms 2026/10/04 01:44:08 DEBUG : pacer: Reducing sleep to 114.437992ms 2026/10/04 01:44:08 DEBUG : pacer: Reducing sleep to 135.624533ms 2026/10/04 01:44:08 DEBUG : pacer: Reducing sleep to 183.834968ms 2026/10/04 01:44:08 DEBUG : pacer: Reducing sleep to 213.075286ms 2026/10/04 01:44:09 DEBUG : pacer: Reducing sleep to 185.605428ms 2026/10/04 01:44:09 DEBUG : pacer: Reducing sleep to 152.83358ms 2026/10/04 01:44:09 DEBUG : pacer: Reducing sleep to 124.188032ms 2026/10/04 01:44:09 DEBUG : pacer: Reducing sleep to 154.150714ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/10/04 01:44:14 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:44:14 DEBUG : pacer: Reducing sleep to 61.7821ms 2026/10/04 01:44:14 DEBUG : pacer: Reducing sleep to 154.662288ms 2026/10/04 01:44:14 DEBUG : pacer: Reducing sleep to 19.252086ms 2026/10/04 01:44:14 DEBUG : pacer: Reducing sleep to 99.749494ms 2026/10/04 01:44:18 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:44:18 DEBUG : pacer: Reducing sleep to 80.068657ms 2026/10/04 01:44:18 DEBUG : pacer: Reducing sleep to 155.170298ms 2026/10/04 01:44:19 DEBUG : pacer: Reducing sleep to 241.808541ms 2026/10/04 01:44:19 DEBUG : pacer: Reducing sleep to 237.37648ms 2026/10/04 01:44:23 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/10/04 01:44:28 DEBUG : pacer: Reducing sleep to 52.775907ms 2026/10/04 01:44:28 DEBUG : pacer: Reducing sleep to 139.59974ms 2026/10/04 01:44:28 DEBUG : pacer: Reducing sleep to 138.856133ms 2026/10/04 01:44:28 DEBUG : pacer: Reducing sleep to 224.275765ms 2026/10/04 01:44:32 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:44:32 DEBUG : pacer: Reducing sleep to 81.924862ms 2026/10/04 01:44:32 DEBUG : pacer: Reducing sleep to 94.797236ms 2026/10/04 01:44:32 DEBUG : pacer: Reducing sleep to 181.511771ms 2026/10/04 01:44:33 DEBUG : pacer: Reducing sleep to 240.503875ms 2026/10/04 01:44:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/10/04 01:44:41 DEBUG : pacer: Reducing sleep to 57.630512ms 2026/10/04 01:44:42 DEBUG : pacer: Reducing sleep to 119.536487ms 2026/10/04 01:44:42 DEBUG : pacer: Reducing sleep to 27.328567ms 2026/10/04 01:44:42 DEBUG : pacer: Reducing sleep to 106.264806ms 2026/10/04 01:44:46 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:44:46 DEBUG : pacer: Reducing sleep to 78.388045ms 2026/10/04 01:44:46 DEBUG : pacer: Reducing sleep to 153.177746ms 2026/10/04 01:44:46 DEBUG : pacer: Reducing sleep to 239.487159ms 2026/10/04 01:44:46 DEBUG : pacer: Reducing sleep to 240.4048ms 2026/10/04 01:44:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/10/04 01:44:55 DEBUG : pacer: Reducing sleep to 60.320151ms 2026/10/04 01:44:55 DEBUG : pacer: Reducing sleep to 148.247106ms 2026/10/04 01:44:56 DEBUG : pacer: Reducing sleep to 127.103364ms 2026/10/04 01:44:56 DEBUG : pacer: Reducing sleep to 207.533685ms 2026/10/04 01:45:00 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:45:00 DEBUG : pacer: Reducing sleep to 55.472607ms 2026/10/04 01:45:00 DEBUG : pacer: Reducing sleep to 111.959159ms 2026/10/04 01:45:00 DEBUG : pacer: Reducing sleep to 195.357445ms 2026/10/04 01:45:00 DEBUG : pacer: Reducing sleep to 232.993224ms 2026/10/04 01:45:04 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/10/04 01:45:04 DEBUG : zoho root 'rclone-test-zabagoz7jajo': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/10/04 01:45:05 DEBUG : pacer: Reducing sleep to 88.899352ms 2026/10/04 01:45:05 DEBUG : pacer: Reducing sleep to 70.851254ms 2026/10/04 01:45:05 DEBUG : pacer: Reducing sleep to 160.067694ms 2026/10/04 01:45:05 DEBUG : pacer: Reducing sleep to 55.3267ms 2026/10/04 01:45:05 DEBUG : pacer: Reducing sleep to 139.743789ms 2026/10/04 01:45:06 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:45:06 DEBUG : pacer: Reducing sleep to 84.487342ms 2026/10/04 01:45:06 DEBUG : pacer: Reducing sleep to 159.930508ms 2026/10/04 01:45:07 DEBUG : pacer: Reducing sleep to 246.414819ms 2026/10/04 01:45:07 DEBUG : pacer: Reducing sleep to 237.409856ms 2026/10/04 01:45:07 DEBUG : pacer: Reducing sleep to 176.530367ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/10/04 01:45:08 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:45:08 DEBUG : pacer: Reducing sleep to 60.776352ms 2026/10/04 01:45:08 DEBUG : pacer: Reducing sleep to 148.239011ms 2026/10/04 01:45:08 DEBUG : pacer: Reducing sleep to 149.454655ms 2026/10/04 01:45:08 DEBUG : pacer: Reducing sleep to 231.784824ms 2026/10/04 01:45:08 DEBUG : pacer: Reducing sleep to 246.592983ms 2026/10/04 01:45:09 DEBUG : pacer: Reducing sleep to 182.208806ms 2026/10/04 01:45:09 DEBUG : pacer: Reducing sleep to 89.972351ms 2026/10/04 01:45:09 DEBUG : pacer: Reducing sleep to 87.745697ms 2026/10/04 01:45:09 DEBUG : pacer: Reducing sleep to 153.364552ms 2026/10/04 01:45:13 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/10/04 01:45:18 DEBUG : pacer: Reducing sleep to 33.772055ms 2026/10/04 01:45:18 DEBUG : pacer: Reducing sleep to 119.121614ms 2026/10/04 01:45:18 DEBUG : pacer: Reducing sleep to 18.820663ms 2026/10/04 01:45:18 DEBUG : pacer: Reducing sleep to 104.536714ms 2026/10/04 01:45:22 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:45:22 DEBUG : pacer: Reducing sleep to 81.264836ms 2026/10/04 01:45:22 DEBUG : pacer: Reducing sleep to 145.672695ms 2026/10/04 01:45:22 DEBUG : pacer: Reducing sleep to 229.630335ms 2026/10/04 01:45:23 DEBUG : pacer: Reducing sleep to 231.2003ms 2026/10/04 01:45:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/10/04 01:45:31 DEBUG : pacer: Reducing sleep to 62.286693ms 2026/10/04 01:45:32 DEBUG : pacer: Reducing sleep to 124.226602ms 2026/10/04 01:45:32 DEBUG : pacer: Reducing sleep to 107.168003ms 2026/10/04 01:45:32 DEBUG : pacer: Reducing sleep to 191.121057ms 2026/10/04 01:45:36 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:45:36 DEBUG : pacer: Reducing sleep to 74.13767ms 2026/10/04 01:45:36 DEBUG : pacer: Reducing sleep to 113.474711ms 2026/10/04 01:45:36 DEBUG : pacer: Reducing sleep to 198.859858ms 2026/10/04 01:45:36 DEBUG : pacer: Reducing sleep to 235.241651ms 2026/10/04 01:45:41 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/10/04 01:45:45 DEBUG : pacer: Reducing sleep to 60.333676ms 2026/10/04 01:45:45 DEBUG : pacer: Reducing sleep to 144.658462ms 2026/10/04 01:45:46 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:45:46 DEBUG : pacer: Reducing sleep to 82.446257ms 2026/10/04 01:45:50 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:45:50 DEBUG : pacer: Reducing sleep to 80.256975ms 2026/10/04 01:45:50 DEBUG : pacer: Reducing sleep to 148.022264ms 2026/10/04 01:45:50 DEBUG : pacer: Reducing sleep to 232.286584ms 2026/10/04 01:45:50 DEBUG : pacer: Reducing sleep to 239.415845ms 2026/10/04 01:45:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/10/04 01:45:59 DEBUG : pacer: Reducing sleep to 61.233455ms 2026/10/04 01:45:59 DEBUG : pacer: Reducing sleep to 147.732516ms 2026/10/04 01:45:59 DEBUG : pacer: Reducing sleep to 153.586669ms 2026/10/04 01:46:00 DEBUG : pacer: Reducing sleep to 238.877557ms 2026/10/04 01:46:04 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:46:04 DEBUG : pacer: Reducing sleep to 80.013604ms 2026/10/04 01:46:04 DEBUG : pacer: Reducing sleep to 86.414279ms 2026/10/04 01:46:04 DEBUG : pacer: Reducing sleep to 169.676287ms 2026/10/04 01:46:04 DEBUG : pacer: Reducing sleep to 232.127662ms 2026/10/04 01:46:04 DEBUG : pacer: Reducing sleep to 252.605326ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/10/04 01:46:05 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:46:05 DEBUG : pacer: Reducing sleep to 59.712834ms 2026/10/04 01:46:05 DEBUG : zoho root 'rclone-test-zabagoz7jajo': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/10/04 01:46:05 DEBUG : pacer: Reducing sleep to 83.500739ms 2026/10/04 01:46:05 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:46:06 DEBUG : pacer: Reducing sleep to 77.05036ms 2026/10/04 01:46:06 DEBUG : pacer: Reducing sleep to 116.979361ms 2026/10/04 01:46:06 DEBUG : pacer: Reducing sleep to 203.922939ms 2026/10/04 01:46:06 DEBUG : pacer: Reducing sleep to 217.171865ms 2026/10/04 01:46:06 DEBUG : pacer: Reducing sleep to 209.824552ms 2026/10/04 01:46:06 DEBUG : pacer: Reducing sleep to 144.596664ms 2026/10/04 01:46:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/10/04 01:46:08 DEBUG : pacer: Reducing sleep to 62.6497ms 2026/10/04 01:46:08 DEBUG : pacer: Reducing sleep to 113.219512ms 2026/10/04 01:46:08 DEBUG : pacer: Reducing sleep to 132.91538ms 2026/10/04 01:46:08 DEBUG : pacer: Reducing sleep to 218.299204ms 2026/10/04 01:46:12 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:46:12 DEBUG : pacer: Reducing sleep to 81.696161ms 2026/10/04 01:46:12 DEBUG : pacer: Reducing sleep to 106.393552ms 2026/10/04 01:46:12 DEBUG : pacer: Reducing sleep to 192.984246ms 2026/10/04 01:46:13 DEBUG : pacer: Reducing sleep to 226.890365ms 2026/10/04 01:46:17 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:725: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:734: testing "test%46.txt" 2026/10/04 01:46:21 DEBUG : pacer: Reducing sleep to 59.142969ms 2026/10/04 01:46:21 DEBUG : pacer: Reducing sleep to 145.035254ms 2026/10/04 01:46:22 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:46:22 DEBUG : pacer: Reducing sleep to 82.45866ms 2026/10/04 01:46:26 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:46:26 DEBUG : pacer: Reducing sleep to 84.476811ms 2026/10/04 01:46:26 DEBUG : pacer: Reducing sleep to 140.959527ms 2026/10/04 01:46:26 DEBUG : pacer: Reducing sleep to 226.718519ms 2026/10/04 01:46:26 DEBUG : pacer: Reducing sleep to 237.605095ms 2026/10/04 01:46:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/10/04 01:46:49 DEBUG : pacer: Reducing sleep to 50.435137ms 2026/10/04 01:46:49 DEBUG : pacer: Reducing sleep to 141.065718ms 2026/10/04 01:46:49 DEBUG : pacer: Reducing sleep to 191.850484ms 2026/10/04 01:46:49 DEBUG : pacer: Reducing sleep to 256.645626ms 2026/10/04 01:46:50 DEBUG : pacer: Reducing sleep to 131.347519ms 2026/10/04 01:46:50 DEBUG : pacer: Reducing sleep to 139.552922ms 2026/10/04 01:46:50 DEBUG : pacer: Reducing sleep to 133.092825ms 2026/10/04 01:46:50 DEBUG : pacer: Reducing sleep to 276.501477ms 2026/10/04 01:46:50 DEBUG : pacer: Reducing sleep to 232.387415ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/10/04 01:46:54 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:46:54 DEBUG : pacer: Reducing sleep to 23.087201ms 2026/10/04 01:46:58 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:46:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:46:58 UTC": parsing time "Sun, 04 Oct 2026 01:46:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:46:58 UTC" as " " 2026/10/04 01:46:59 DEBUG : pacer: Reducing sleep to 63.65575ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:813: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:846: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:899: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/10/04 01:47:03 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:47:07 DEBUG : zoho root 'rclone-test-zabagoz7jajo': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/10/04 01:47:08 DEBUG : pacer: Reducing sleep to 90.686757ms 2026/10/04 01:47:08 DEBUG : pacer: Reducing sleep to 81.050342ms 2026/10/04 01:47:08 DEBUG : pacer: Reducing sleep to 160.951296ms 2026/10/04 01:47:08 DEBUG : pacer: Reducing sleep to 244.127261ms 2026/10/04 01:47:08 DEBUG : pacer: Reducing sleep to 98.611491ms 2026/10/04 01:47:08 DEBUG : pacer: Reducing sleep to 167.389082ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/10/04 01:47:08 DEBUG : pacer: Reducing sleep to 238.924628ms 2026/10/04 01:47:09 DEBUG : pacer: Reducing sleep to 228.806576ms 2026/10/04 01:47:09 DEBUG : pacer: Reducing sleep to 160.00958ms 2026/10/04 01:47:09 DEBUG : pacer: Reducing sleep to 97.427398ms 2026/10/04 01:47:09 DEBUG : pacer: Reducing sleep to 107.380752ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/10/04 01:47:09 DEBUG : pacer: Reducing sleep to 172.885441ms 2026/10/04 01:47:09 DEBUG : pacer: Reducing sleep to 235.471973ms 2026/10/04 01:47:10 DEBUG : pacer: Reducing sleep to 157.674103ms 2026/10/04 01:47:10 DEBUG : pacer: Reducing sleep to 158.675554ms 2026/10/04 01:47:10 DEBUG : pacer: Reducing sleep to 161.608752ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/10/04 01:47:10 DEBUG : pacer: Reducing sleep to 173.157377ms 2026/10/04 01:47:10 DEBUG : pacer: Reducing sleep to 174.308341ms 2026/10/04 01:47:10 DEBUG : pacer: Reducing sleep to 168.854564ms 2026/10/04 01:47:11 DEBUG : pacer: Reducing sleep to 149.051614ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/10/04 01:47:11 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/10/04 01:47:15 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/10/04 01:47:20 DEBUG : pacer: Reducing sleep to 81.577839ms 2026/10/04 01:47:20 DEBUG : pacer: Reducing sleep to 163.625295ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/10/04 01:47:24 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:47:25 DEBUG : pacer: Reducing sleep to 82.154278ms 2026/10/04 01:47:25 DEBUG : pacer: Reducing sleep to 166.103433ms 2026/10/04 01:47:25 DEBUG : pacer: Reducing sleep to 246.338477ms 2026/10/04 01:47:25 DEBUG : pacer: Reducing sleep to 248.633368ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/10/04 01:47:29 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/10/04 01:47:34 DEBUG : pacer: Reducing sleep to 84.83538ms 2026/10/04 01:47:34 DEBUG : pacer: Reducing sleep to 167.06024ms 2026/10/04 01:47:34 DEBUG : pacer: Reducing sleep to 247.57965ms 2026/10/04 01:47:34 DEBUG : pacer: Reducing sleep to 245.273056ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/10/04 01:47:39 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/10/04 01:47:39 DEBUG : pacer: Reducing sleep to 81.151915ms 2026/10/04 01:47:43 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:47:43 DEBUG : pacer: Reducing sleep to 65.042588ms 2026/10/04 01:47:44 DEBUG : pacer: Reducing sleep to 83.388468ms 2026/10/04 01:47:44 DEBUG : pacer: Reducing sleep to 127.213015ms 2026/10/04 01:47:48 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:47:48 DEBUG : pacer: Reducing sleep to 82.386064ms 2026/10/04 01:47:48 DEBUG : pacer: Reducing sleep to 245.76305ms 2026/10/04 01:47:48 DEBUG : pacer: Reducing sleep to 329.31774ms 2026/10/04 01:47:48 DEBUG : pacer: Reducing sleep to 410.775553ms 2026/10/04 01:47:48 DEBUG : pacer: Reducing sleep to 245.638797ms 2026/10/04 01:47:49 DEBUG : pacer: Reducing sleep to 3.084377ms 2026/10/04 01:47:49 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:47:53 DEBUG : pacer: Reducing sleep to 76.942725ms 2026/10/04 01:47:53 DEBUG : pacer: Reducing sleep to 160.883635ms 2026/10/04 01:47:53 DEBUG : pacer: Reducing sleep to 238.822093ms 2026/10/04 01:47:53 DEBUG : pacer: Reducing sleep to 248.693242ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/10/04 01:47:57 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:02 DEBUG : pacer: Reducing sleep to 47.60846ms 2026/10/04 01:48:02 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:02 DEBUG : pacer: Reducing sleep to 86.071424ms 2026/10/04 01:48:06 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:06 DEBUG : pacer: Reducing sleep to 82.675391ms 2026/10/04 01:48:06 DEBUG : pacer: Reducing sleep to 242.134469ms 2026/10/04 01:48:07 DEBUG : pacer: Reducing sleep to 331.476508ms 2026/10/04 01:48:07 DEBUG : pacer: Reducing sleep to 408.630384ms 2026/10/04 01:48:07 DEBUG : pacer: Reducing sleep to 245.141329ms 2026/10/04 01:48:07 DEBUG : Creating backend with remote "TestZoho:rclone-test-zabagoz7jajo/dirToPurgeFromRoot" 2026/10/04 01:48:07 DEBUG : pacer: Reducing sleep to 99.975776ms 2026/10/04 01:48:08 DEBUG : pacer: Reducing sleep to 209.256622ms 2026/10/04 01:48:08 DEBUG : pacer: Reducing sleep to 241.058317ms 2026/10/04 01:48:08 DEBUG : zoho root 'rclone-test-zabagoz7jajo': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/10/04 01:48:08 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:08 DEBUG : pacer: Reducing sleep to 83.98886ms 2026/10/04 01:48:08 DEBUG : pacer: Reducing sleep to 84.505457ms 2026/10/04 01:48:08 DEBUG : pacer: Reducing sleep to 168.190043ms 2026/10/04 01:48:08 DEBUG : pacer: Reducing sleep to 245.172986ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/10/04 01:48:08 DEBUG : Creating backend with remote "TestZoho:rclone-test-zabagoz7jajo/hello? sausage/êé" 2026/10/04 01:48:08 DEBUG : pacer: Reducing sleep to 110.82426ms 2026/10/04 01:48:09 DEBUG : pacer: Reducing sleep to 218.625191ms 2026/10/04 01:48:09 DEBUG : pacer: Reducing sleep to 277.903476ms 2026/10/04 01:48:09 DEBUG : pacer: Reducing sleep to 217.690137ms 2026/10/04 01:48:09 DEBUG : pacer: Reducing sleep to 90.865879ms 2026/10/04 01:48:09 DEBUG : pacer: Reducing sleep to 62.609039ms 2026/10/04 01:48:09 DEBUG : pacer: Reducing sleep to 111.757958ms 2026/10/04 01:48:10 DEBUG : pacer: Reducing sleep to 200.377002ms 2026/10/04 01:48:10 DEBUG : pacer: Reducing sleep to 253.870385ms 2026/10/04 01:48:10 DEBUG : pacer: Reducing sleep to 243.870575ms 2026/10/04 01:48:10 DEBUG : pacer: Reducing sleep to 131.094151ms 2026/10/04 01:48:10 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:10 DEBUG : pacer: Reducing sleep to 97.930374ms 2026/10/04 01:48:11 DEBUG : pacer: Reducing sleep to 208.739635ms 2026/10/04 01:48:11 DEBUG : pacer: Reducing sleep to 251.735808ms 2026/10/04 01:48:11 DEBUG : pacer: Reducing sleep to 232.77005ms 2026/10/04 01:48:11 DEBUG : pacer: Reducing sleep to 129.552855ms 2026/10/04 01:48:11 DEBUG : pacer: Reducing sleep to 75.759669ms 2026/10/04 01:48:11 DEBUG : pacer: Reducing sleep to 105.358213ms 2026/10/04 01:48:12 DEBUG : pacer: Reducing sleep to 195.636139ms 2026/10/04 01:48:12 DEBUG : pacer: Reducing sleep to 244.295234ms 2026/10/04 01:48:12 DEBUG : pacer: Reducing sleep to 217.430972ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/10/04 01:48:12 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:12 DEBUG : pacer: Reducing sleep to 63.806574ms 2026/10/04 01:48:12 DEBUG : pacer: Reducing sleep to 143.157299ms 2026/10/04 01:48:13 DEBUG : pacer: Reducing sleep to 223.910751ms 2026/10/04 01:48:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:15 DEBUG : pacer: Reducing sleep to 82.652959ms 2026/10/04 01:48:15 DEBUG : pacer: Reducing sleep to 105.642887ms 2026/10/04 01:48:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/10/04 01:48:25 DEBUG : pacer: Reducing sleep to 53.821752ms 2026/10/04 01:48:25 DEBUG : pacer: Reducing sleep to 96.024658ms 2026/10/04 01:48:25 DEBUG : pacer: Reducing sleep to 157.216475ms 2026/10/04 01:48:25 DEBUG : pacer: Reducing sleep to 240.535281ms 2026/10/04 01:48:25 DEBUG : pacer: Reducing sleep to 229.1681ms 2026/10/04 01:48:25 DEBUG : pacer: Reducing sleep to 172.863251ms 2026/10/04 01:48:26 DEBUG : pacer: Reducing sleep to 79.377471ms 2026/10/04 01:48:29 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:39 DEBUG : pacer: Reducing sleep to 63.911ms 2026/10/04 01:48:39 DEBUG : pacer: Reducing sleep to 129.026017ms 2026/10/04 01:48:39 DEBUG : pacer: Reducing sleep to 175.721264ms 2026/10/04 01:48:39 DEBUG : pacer: Reducing sleep to 222.481644ms 2026/10/04 01:48:43 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:44 DEBUG : pacer: Reducing sleep to 79.806273ms 2026/10/04 01:48:44 DEBUG : pacer: Reducing sleep to 193.604829ms 2026/10/04 01:48:44 DEBUG : pacer: Reducing sleep to 269.92935ms 2026/10/04 01:48:44 DEBUG : pacer: Reducing sleep to 247.895869ms 2026/10/04 01:48:44 DEBUG : pacer: Reducing sleep to 151.59764ms 2026/10/04 01:48:44 DEBUG : pacer: Reducing sleep to 64.162613ms 2026/10/04 01:48:45 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:45 DEBUG : pacer: Reducing sleep to 40.49571ms 2026/10/04 01:48:45 DEBUG : pacer: Reducing sleep to 86.723985ms 2026/10/04 01:48:48 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:48 DEBUG : pacer: Reducing sleep to 87.642009ms 2026/10/04 01:48:48 DEBUG : pacer: Reducing sleep to 252.504888ms 2026/10/04 01:48:48 DEBUG : pacer: Reducing sleep to 324.261124ms 2026/10/04 01:48:49 DEBUG : pacer: Reducing sleep to 248.739772ms 2026/10/04 01:48:49 DEBUG : pacer: Reducing sleep to 88.162316ms 2026/10/04 01:48:53 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:53 DEBUG : pacer: Reducing sleep to 65.003713ms 2026/10/04 01:48:53 DEBUG : pacer: Reducing sleep to 121.813017ms 2026/10/04 01:48:53 DEBUG : pacer: Reducing sleep to 186.5079ms 2026/10/04 01:48:57 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:48:57 DEBUG : pacer: Reducing sleep to 87.794326ms 2026/10/04 01:48:57 DEBUG : pacer: Reducing sleep to 249.885673ms 2026/10/04 01:48:58 DEBUG : pacer: Reducing sleep to 310.754121ms 2026/10/04 01:48:58 DEBUG : pacer: Reducing sleep to 229.069894ms 2026/10/04 01:48:58 DEBUG : pacer: Reducing sleep to 77.504603ms 2026/10/04 01:48:58 DEBUG : pacer: Reducing sleep to 20.941699ms 2026/10/04 01:48:58 DEBUG : pacer: Reducing sleep to 91.999314ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/10/04 01:48:58 DEBUG : Creating backend with remote "TestZoho:rclone-test-tefemah3duco" 2026/10/04 01:48:59 DEBUG : pacer: Reducing sleep to 34.68448ms 2026/10/04 01:48:59 DEBUG : pacer: Reducing sleep to 74.481201ms 2026/10/04 01:48:59 DEBUG : pacer: Reducing sleep to 91.801586ms 2026/10/04 01:48:59 DEBUG : pacer: Reducing sleep to 211.424006ms 2026/10/04 01:48:59 DEBUG : pacer: Reducing sleep to 248.357824ms 2026/10/04 01:48:59 DEBUG : pacer: Reducing sleep to 240.073794ms 2026/10/04 01:49:00 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:49:00 DEBUG : pacer: Reducing sleep to 84.120198ms 2026/10/04 01:49:00 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:49:00 DEBUG : pacer: Reducing sleep to 19.492806ms 2026/10/04 01:49:00 DEBUG : pacer: Reducing sleep to 112.796785ms 2026/10/04 01:49:02 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:49:02 DEBUG : pacer: Reducing sleep to 111.659947ms 2026/10/04 01:49:02 DEBUG : pacer: Reducing sleep to 221.880425ms 2026/10/04 01:49:02 DEBUG : pacer: Reducing sleep to 279.374091ms 2026/10/04 01:49:02 DEBUG : pacer: Reducing sleep to 214.866215ms 2026/10/04 01:49:04 DEBUG : pacer: Reducing sleep to 9.14112ms 2026/10/04 01:49:04 DEBUG : pacer: Reducing sleep to 84.5064ms 2026/10/04 01:49:05 DEBUG : pacer: Reducing sleep to 169.452047ms 2026/10/04 01:49:05 DEBUG : pacer: Reducing sleep to 240.373728ms 2026/10/04 01:49:05 DEBUG : pacer: Reducing sleep to 243.132136ms 2026/10/04 01:49:05 DEBUG : pacer: Reducing sleep to 162.25882ms 2026/10/04 01:49:05 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:49:05 DEBUG : pacer: Reducing sleep to 114.634252ms 2026/10/04 01:49:05 DEBUG : zoho root 'rclone-test-tefemah3duco': Purge remote 2026/10/04 01:49:05 DEBUG : pacer: Reducing sleep to 115.198619ms 2026/10/04 01:49:05 DEBUG : pacer: Reducing sleep to 147.60622ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/10/04 01:49:06 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/10/04 01:49:06 DEBUG : pacer: Reducing sleep to 48.300277ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/10/04 01:49:06 DEBUG : pacer: Reducing sleep to 131.512322ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/10/04 01:49:06 DEBUG : pacer: Reducing sleep to 204.187032ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/10/04 01:49:06 DEBUG : pacer: Reducing sleep to 246.661098ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/10/04 01:49:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/10/04 01:49:11 DEBUG : zoho root 'rclone-test-zabagoz7jajo': folder listing limiter sweep: evicted 5 of 15 idle limiters fstests.go:1664: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1735: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1792: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/10/04 01:49:35 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:35 UTC": parsing time "Sun, 04 Oct 2026 01:49:35 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:35 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/10/04 01:49:40 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:39 UTC": parsing time "Sun, 04 Oct 2026 01:49:39 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:39 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/10/04 01:49:44 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:44 UTC": parsing time "Sun, 04 Oct 2026 01:49:44 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:44 UTC" as " " 2026/10/04 01:49:44 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:44 UTC": parsing time "Sun, 04 Oct 2026 01:49:44 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:44 UTC" as " " 2026/10/04 01:49:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:45 UTC": parsing time "Sun, 04 Oct 2026 01:49:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:45 UTC" as " " 2026/10/04 01:49:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:45 UTC": parsing time "Sun, 04 Oct 2026 01:49:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:45 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/10/04 01:49:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:49 UTC": parsing time "Sun, 04 Oct 2026 01:49:49 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:49 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/10/04 01:49:53 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:53 UTC": parsing time "Sun, 04 Oct 2026 01:49:53 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:53 UTC" as " " 2026/10/04 01:49:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:58 UTC": parsing time "Sun, 04 Oct 2026 01:49:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:58 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/10/04 01:49:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:58 UTC": parsing time "Sun, 04 Oct 2026 01:49:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:58 UTC" as " " 2026/10/04 01:49:58 DEBUG : pacer: Reducing sleep to 466.362µs === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/10/04 01:49:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:58 UTC": parsing time "Sun, 04 Oct 2026 01:49:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:58 UTC" as " " 2026/10/04 01:49:58 DEBUG : pacer: Reducing sleep to 2.393763ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/10/04 01:49:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:59 UTC": parsing time "Sun, 04 Oct 2026 01:49:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:59 UTC" as " " 2026/10/04 01:49:59 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/10/04 01:49:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:49:59 UTC": parsing time "Sun, 04 Oct 2026 01:49:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:49:59 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/10/04 01:50:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:50:02 UTC": parsing time "Sun, 04 Oct 2026 01:50:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:50:02 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/10/04 01:50:07 DEBUG : pacer: Reducing sleep to 79.320257ms 2026/10/04 01:50:07 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 04 Oct 2026 01:50:07 UTC": parsing time "Sun, 04 Oct 2026 01:50:07 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 04 Oct 2026 01:50:07 UTC" as " " 2026/10/04 01:50:07 DEBUG : pacer: Reducing sleep to 53.60222ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/10/04 01:50:07 DEBUG : pacer: Reducing sleep to 135.286399ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/10/04 01:50:07 DEBUG : Creating backend with remote "TestZoho:rclone-test-zabagoz7jajo/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/10/04 01:50:08 DEBUG : pacer: Reducing sleep to 107.505303ms 2026/10/04 01:50:08 DEBUG : pacer: Reducing sleep to 210.795817ms 2026/10/04 01:50:08 DEBUG : pacer: Reducing sleep to 273.655644ms 2026/10/04 01:50:08 DEBUG : pacer: Reducing sleep to 224.807868ms 2026/10/04 01:50:08 DEBUG : pacer: Reducing sleep to 112.283332ms 2026/10/04 01:50:09 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:50:09 DEBUG : pacer: Reducing sleep to 105.912426ms 2026/10/04 01:50:09 DEBUG : pacer: Reducing sleep to 208.586768ms 2026/10/04 01:50:09 DEBUG : pacer: Reducing sleep to 271.394658ms 2026/10/04 01:50:09 DEBUG : pacer: Reducing sleep to 231.709158ms 2026/10/04 01:50:09 DEBUG : pacer: Reducing sleep to 122.044983ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/10/04 01:50:10 DEBUG : pacer: Reducing sleep to 50.533092ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/10/04 01:50:10 DEBUG : Creating backend with remote "TestZoho:rclone-test-zabagoz7jajo/not found.txt" 2026/10/04 01:50:10 DEBUG : pacer: Reducing sleep to 80.09551ms 2026/10/04 01:50:10 DEBUG : pacer: Reducing sleep to 86.302251ms 2026/10/04 01:50:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:50:15 DEBUG : zoho root 'rclone-test-zabagoz7jajo/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/10/04 01:50:19 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-zabagoz7jajo" from "TestZoho:rclone-test-zabagoz7jajo" 2026/10/04 01:50:19 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/10/04 01:50:24 DEBUG : pacer: Reducing sleep to 111.313974ms 2026/10/04 01:50:24 DEBUG : pacer: Reducing sleep to 214.037227ms 2026/10/04 01:50:24 DEBUG : pacer: Reducing sleep to 274.378861ms 2026/10/04 01:50:24 DEBUG : pacer: Reducing sleep to 214.594233ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/10/04 01:50:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2031: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/10/04 01:50:33 DEBUG : pacer: Reducing sleep to 24.946241ms 2026/10/04 01:50:38 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:50:42 DEBUG : pacer: Reducing sleep to 111.356434ms 2026/10/04 01:50:42 DEBUG : pacer: Reducing sleep to 220.28261ms 2026/10/04 01:50:43 DEBUG : pacer: Reducing sleep to 278.391297ms 2026/10/04 01:50:43 DEBUG : pacer: Reducing sleep to 215.94303ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/10/04 01:50:43 DEBUG : pacer: Reducing sleep to 76.637849ms 2026/10/04 01:50:47 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:50:47 DEBUG : pacer: Reducing sleep to 111.036039ms 2026/10/04 01:50:47 DEBUG : pacer: Reducing sleep to 219.56183ms 2026/10/04 01:50:47 DEBUG : pacer: Reducing sleep to 275.815525ms 2026/10/04 01:50:47 DEBUG : pacer: Reducing sleep to 217.726779ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2090: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:438: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2195: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2211: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/10/04 01:50:52 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:50:56 DEBUG : pacer: Reducing sleep to 111.876436ms 2026/10/04 01:50:56 DEBUG : pacer: Reducing sleep to 216.821967ms 2026/10/04 01:50:56 DEBUG : pacer: Reducing sleep to 277.207523ms 2026/10/04 01:50:57 DEBUG : pacer: Reducing sleep to 219.421689ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/10/04 01:50:57 DEBUG : pacer: Reducing sleep to 112.296016ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2385: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2431: *zoho.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2452: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutRetry === RUN TestIntegration/FsMkdir/FsPutRetry/Simple === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/Status500 2026/10/04 01:51:01 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:51:01 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/10/04 01:51:01 DEBUG : pacer: Rate limited, increasing sleep to 1.244480402s fstests.go:2660: Put returned an error after the injected failure (acceptable): upload error: HTTP error 500 (500 Internal Server Error) returned body: "" 2026/10/04 01:51:05 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:51:05 DEBUG : pacer: Reducing sleep to 113.581707ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/10/04 01:51:07 DEBUG : pacer: Reducing sleep to 0s 2026/10/04 01:51:07 DEBUG : pacer: low level retry 1/1 (error Post "https://workdrive.zoho.eu/api/v1/upload?filename=retry-NetError.bin&override-name-exist=true&parent_id=g9uuu6af413c3ade840379ec23fc5d05eeb1a": write tcp: unexpected EOF) 2026/10/04 01:51:07 DEBUG : pacer: Rate limited, increasing sleep to 1.374866862s fstests.go:2660: Put returned an error after the injected failure (acceptable): upload error: Post "https://workdrive.zoho.eu/api/v1/upload?filename=retry-NetError.bin&override-name-exist=true&parent_id=g9uuu6af413c3ade840379ec23fc5d05eeb1a": write tcp: unexpected EOF 2026/10/04 01:51:08 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutRetry/Chunked fstests.go:2697: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/10/04 01:51:08 DEBUG : pacer: Reducing sleep to 131.318113ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n