"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/08/22 05:28:17 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/08/22 05:28:17 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/08/22 05:28:17 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/08/22 05:28:17 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/08/22 05:28:17 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/08/22 05:28:17 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/08/22 05:28:17 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:457: Using remote "TestZoho:" 2026/08/22 05:28:17 DEBUG : Creating backend with remote "TestZoho:rclone-test-sepuqof7miza" 2026/08/22 05:28:17 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/22 05:28:17 DEBUG : zoho root 'rclone-test-sepuqof7miza': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/08/22 05:28:17 DEBUG : TestZoho: Token expired 2026/08/22 05:28:17 DEBUG : TestZoho: No updated token found in the config file 2026/08/22 05:28:17 DEBUG : TestZoho: Token refresh successful 2026/08/22 05:28:17 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/08/22 05:28:17 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:498: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:526: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/08/22 05:28:18 DEBUG : pacer: Reducing sleep to 6.824534ms 2026/08/22 05:28:18 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:28:18 DEBUG : pacer: Reducing sleep to 99.303101ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/08/22 05:28:18 DEBUG : pacer: Reducing sleep to 189.356919ms 2026/08/22 05:28:18 DEBUG : pacer: Reducing sleep to 247.073012ms === RUN TestIntegration/FsMkdir 2026/08/22 05:28:19 DEBUG : pacer: Reducing sleep to 153.028694ms 2026/08/22 05:28:19 DEBUG : pacer: Reducing sleep to 64.584544ms 2026/08/22 05:28:19 DEBUG : pacer: Reducing sleep to 159.732441ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/08/22 05:28:19 INFO : dir/subdir: Making directory 2026/08/22 05:28:19 DEBUG : pacer: Reducing sleep to 259.495282ms 2026/08/22 05:28:19 DEBUG : pacer: Reducing sleep to 226.913836ms 2026/08/22 05:28:19 DEBUG : pacer: Reducing sleep to 170.306442ms 2026/08/22 05:28:20 DEBUG : pacer: Reducing sleep to 69.1261ms 2026/08/22 05:28:20 DEBUG : pacer: Reducing sleep to 93.112572ms 2026/08/22 05:28:20 DEBUG : pacer: Reducing sleep to 182.312928ms 2026/08/22 05:28:20 DEBUG : pacer: Reducing sleep to 263.725237ms 2026/08/22 05:28:20 INFO : dir/subdir: Removing directory 2026/08/22 05:28:20 DEBUG : pacer: Reducing sleep to 243.242808ms 2026/08/22 05:28:21 DEBUG : pacer: Reducing sleep to 135.193965ms 2026/08/22 05:28:21 DEBUG : pacer: Reducing sleep to 64.791122ms 2026/08/22 05:28:21 DEBUG : pacer: Reducing sleep to 103.524691ms 2026/08/22 05:28:21 INFO : dir: Removing directory 2026/08/22 05:28:21 DEBUG : pacer: Reducing sleep to 194.401984ms 2026/08/22 05:28:21 DEBUG : pacer: Reducing sleep to 251.112778ms 2026/08/22 05:28:21 DEBUG : pacer: Reducing sleep to 233.496082ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/08/22 05:28:21 DEBUG : pacer: Reducing sleep to 151.055014ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/08/22 05:28:26 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/08/22 05:28:31 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:727: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/08/22 05:28:40 DEBUG : pacer: Reducing sleep to 71.86463ms 2026/08/22 05:28:40 DEBUG : pacer: Reducing sleep to 169.312711ms 2026/08/22 05:28:40 DEBUG : pacer: Reducing sleep to 151.02737ms 2026/08/22 05:28:40 DEBUG : pacer: Reducing sleep to 241.5329ms 2026/08/22 05:28:44 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:28:44 DEBUG : pacer: Reducing sleep to 88.394825ms 2026/08/22 05:28:45 DEBUG : pacer: Reducing sleep to 26.28987ms 2026/08/22 05:28:45 DEBUG : pacer: Reducing sleep to 114.865535ms 2026/08/22 05:28:45 DEBUG : pacer: Reducing sleep to 181.585171ms 2026/08/22 05:28:49 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:727: testing "." 2026/08/22 05:28:54 DEBUG : pacer: Reducing sleep to 72.641516ms 2026/08/22 05:28:54 DEBUG : pacer: Reducing sleep to 172.048267ms 2026/08/22 05:28:54 DEBUG : pacer: Reducing sleep to 46.381255ms 2026/08/22 05:28:54 DEBUG : pacer: Reducing sleep to 137.040916ms 2026/08/22 05:28:58 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:28:58 DEBUG : pacer: Reducing sleep to 87.293154ms 2026/08/22 05:28:58 DEBUG : pacer: Reducing sleep to 168.75134ms 2026/08/22 05:28:58 DEBUG : pacer: Reducing sleep to 260.328193ms 2026/08/22 05:28:59 DEBUG : pacer: Reducing sleep to 246.206595ms 2026/08/22 05:29:03 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:727: testing ".." 2026/08/22 05:29:08 DEBUG : pacer: Reducing sleep to 44.831333ms 2026/08/22 05:29:08 DEBUG : pacer: Reducing sleep to 142.649672ms 2026/08/22 05:29:08 DEBUG : pacer: Reducing sleep to 151.365647ms 2026/08/22 05:29:08 DEBUG : pacer: Reducing sleep to 244.545708ms 2026/08/22 05:29:12 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:29:12 DEBUG : pacer: Reducing sleep to 87.973679ms 2026/08/22 05:29:12 DEBUG : pacer: Reducing sleep to 76.416081ms 2026/08/22 05:29:12 DEBUG : pacer: Reducing sleep to 165.608082ms 2026/08/22 05:29:12 DEBUG : pacer: Reducing sleep to 247.332791ms 2026/08/22 05:29:17 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:727: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/08/22 05:29:17 DEBUG : zoho root 'rclone-test-sepuqof7miza': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/08/22 05:29:21 DEBUG : pacer: Reducing sleep to 71.020315ms 2026/08/22 05:29:21 DEBUG : pacer: Reducing sleep to 166.979865ms 2026/08/22 05:29:22 DEBUG : pacer: Reducing sleep to 86.110746ms 2026/08/22 05:29:22 DEBUG : pacer: Reducing sleep to 175.169667ms 2026/08/22 05:29:22 DEBUG : pacer: Reducing sleep to 255.337406ms 2026/08/22 05:29:22 DEBUG : pacer: Reducing sleep to 249.017011ms 2026/08/22 05:29:22 DEBUG : pacer: Reducing sleep to 146.441086ms 2026/08/22 05:29:23 DEBUG : pacer: Reducing sleep to 78.319283ms 2026/08/22 05:29:23 DEBUG : pacer: Reducing sleep to 87.033237ms 2026/08/22 05:29:23 DEBUG : pacer: Reducing sleep to 187.076935ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:727: testing " leading space" 2026/08/22 05:29:23 DEBUG : pacer: Reducing sleep to 264.216343ms 2026/08/22 05:29:23 DEBUG : pacer: Reducing sleep to 217.451296ms 2026/08/22 05:29:23 DEBUG : pacer: Reducing sleep to 143.62229ms 2026/08/22 05:29:24 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:29:24 DEBUG : pacer: Reducing sleep to 92.26535ms 2026/08/22 05:29:24 DEBUG : pacer: Reducing sleep to 183.770744ms 2026/08/22 05:29:24 DEBUG : pacer: Reducing sleep to 256.390833ms 2026/08/22 05:29:24 DEBUG : pacer: Reducing sleep to 233.775127ms 2026/08/22 05:29:24 DEBUG : pacer: Reducing sleep to 153.438095ms 2026/08/22 05:29:25 DEBUG : pacer: Reducing sleep to 69.786118ms 2026/08/22 05:29:25 DEBUG : pacer: Reducing sleep to 101.671677ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:727: testing "~leading tilde" 2026/08/22 05:29:29 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:29:29 DEBUG : pacer: Reducing sleep to 66.352485ms 2026/08/22 05:29:29 DEBUG : pacer: Reducing sleep to 164.879699ms 2026/08/22 05:29:30 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:29:30 DEBUG : pacer: Reducing sleep to 79.650844ms 2026/08/22 05:29:34 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:29:34 DEBUG : pacer: Reducing sleep to 84.24703ms 2026/08/22 05:29:34 DEBUG : pacer: Reducing sleep to 167.199595ms 2026/08/22 05:29:34 DEBUG : pacer: Reducing sleep to 259.491617ms 2026/08/22 05:29:34 DEBUG : pacer: Reducing sleep to 247.985427ms 2026/08/22 05:29:38 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:727: testing "␍leading CR" 2026/08/22 05:29:43 DEBUG : pacer: Reducing sleep to 66.811997ms 2026/08/22 05:29:43 DEBUG : pacer: Reducing sleep to 166.574757ms 2026/08/22 05:29:43 DEBUG : pacer: Reducing sleep to 128.337512ms 2026/08/22 05:29:44 DEBUG : pacer: Reducing sleep to 207.894733ms 2026/08/22 05:29:48 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:29:48 DEBUG : pacer: Reducing sleep to 82.974598ms 2026/08/22 05:29:48 DEBUG : pacer: Reducing sleep to 114.045123ms 2026/08/22 05:29:48 DEBUG : pacer: Reducing sleep to 207.437646ms 2026/08/22 05:29:48 DEBUG : pacer: Reducing sleep to 252.688705ms 2026/08/22 05:29:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:727: testing "␊leading LF" 2026/08/22 05:29:57 DEBUG : pacer: Reducing sleep to 73.791279ms 2026/08/22 05:29:57 DEBUG : pacer: Reducing sleep to 169.977964ms 2026/08/22 05:29:57 DEBUG : pacer: Reducing sleep to 166.533276ms 2026/08/22 05:29:57 DEBUG : pacer: Reducing sleep to 253.075516ms 2026/08/22 05:30:02 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:30:02 DEBUG : pacer: Reducing sleep to 73.483888ms 2026/08/22 05:30:02 DEBUG : pacer: Reducing sleep to 77.667936ms 2026/08/22 05:30:02 DEBUG : pacer: Reducing sleep to 169.112982ms 2026/08/22 05:30:02 DEBUG : pacer: Reducing sleep to 250.804719ms 2026/08/22 05:30:06 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:727: testing "␉leading HT" 2026/08/22 05:30:11 DEBUG : pacer: Reducing sleep to 65.180369ms 2026/08/22 05:30:11 DEBUG : pacer: Reducing sleep to 164.536534ms 2026/08/22 05:30:11 DEBUG : pacer: Reducing sleep to 110.676623ms 2026/08/22 05:30:11 DEBUG : pacer: Reducing sleep to 200.293667ms 2026/08/22 05:30:15 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:30:16 DEBUG : pacer: Reducing sleep to 85.390328ms 2026/08/22 05:30:16 DEBUG : pacer: Reducing sleep to 116.958401ms 2026/08/22 05:30:16 DEBUG : pacer: Reducing sleep to 207.990169ms 2026/08/22 05:30:16 DEBUG : pacer: Reducing sleep to 250.222179ms 2026/08/22 05:30:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:727: testing "␋leading VT" 2026/08/22 05:30:20 DEBUG : zoho root 'rclone-test-sepuqof7miza': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/08/22 05:30:20 DEBUG : pacer: Reducing sleep to 97.464913ms 2026/08/22 05:30:20 DEBUG : pacer: Reducing sleep to 57.009382ms 2026/08/22 05:30:20 DEBUG : pacer: Reducing sleep to 153.708277ms 2026/08/22 05:30:21 DEBUG : pacer: Reducing sleep to 119.189313ms 2026/08/22 05:30:21 DEBUG : pacer: Reducing sleep to 209.212315ms 2026/08/22 05:30:22 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:30:22 DEBUG : pacer: Reducing sleep to 86.906633ms 2026/08/22 05:30:22 DEBUG : pacer: Reducing sleep to 111.597723ms 2026/08/22 05:30:22 DEBUG : pacer: Reducing sleep to 199.806298ms 2026/08/22 05:30:22 DEBUG : pacer: Reducing sleep to 249.354705ms 2026/08/22 05:30:22 DEBUG : pacer: Reducing sleep to 224.276138ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:727: testing ".leading dot" 2026/08/22 05:30:23 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:30:23 DEBUG : pacer: Reducing sleep to 72.682278ms 2026/08/22 05:30:24 DEBUG : pacer: Reducing sleep to 107.966464ms 2026/08/22 05:30:24 DEBUG : pacer: Reducing sleep to 117.362025ms 2026/08/22 05:30:24 DEBUG : pacer: Reducing sleep to 208.845366ms 2026/08/22 05:30:24 DEBUG : pacer: Reducing sleep to 253.678195ms 2026/08/22 05:30:24 DEBUG : pacer: Reducing sleep to 216.166738ms 2026/08/22 05:30:24 DEBUG : pacer: Reducing sleep to 113.376841ms 2026/08/22 05:30:25 DEBUG : pacer: Reducing sleep to 79.970349ms 2026/08/22 05:30:25 DEBUG : pacer: Reducing sleep to 120.170755ms 2026/08/22 05:30:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:727: testing "trailing space " 2026/08/22 05:30:33 DEBUG : pacer: Reducing sleep to 65.213236ms 2026/08/22 05:30:33 DEBUG : pacer: Reducing sleep to 163.768972ms 2026/08/22 05:30:33 DEBUG : pacer: Reducing sleep to 180.836182ms 2026/08/22 05:30:33 DEBUG : pacer: Reducing sleep to 255.24297ms 2026/08/22 05:30:38 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:30:38 DEBUG : pacer: Reducing sleep to 77.891653ms 2026/08/22 05:30:38 DEBUG : pacer: Reducing sleep to 70.037056ms 2026/08/22 05:30:38 DEBUG : pacer: Reducing sleep to 161.520059ms 2026/08/22 05:30:38 DEBUG : pacer: Reducing sleep to 247.6621ms 2026/08/22 05:30:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:727: testing "trailing CR␍" 2026/08/22 05:30:47 DEBUG : pacer: Reducing sleep to 71.537769ms 2026/08/22 05:30:47 DEBUG : pacer: Reducing sleep to 165.30601ms 2026/08/22 05:30:47 DEBUG : pacer: Reducing sleep to 104.343564ms 2026/08/22 05:30:47 DEBUG : pacer: Reducing sleep to 197.247543ms 2026/08/22 05:30:52 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:30:52 DEBUG : pacer: Reducing sleep to 76.663504ms 2026/08/22 05:30:52 DEBUG : pacer: Reducing sleep to 127.227086ms 2026/08/22 05:30:52 DEBUG : pacer: Reducing sleep to 219.019466ms 2026/08/22 05:30:52 DEBUG : pacer: Reducing sleep to 250.17585ms 2026/08/22 05:30:56 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:727: testing "trailing LF␊" 2026/08/22 05:31:01 DEBUG : pacer: Reducing sleep to 73.212005ms 2026/08/22 05:31:01 DEBUG : pacer: Reducing sleep to 171.384058ms 2026/08/22 05:31:01 DEBUG : pacer: Reducing sleep to 184.588387ms 2026/08/22 05:31:01 DEBUG : pacer: Reducing sleep to 254.808599ms 2026/08/22 05:31:05 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:31:05 DEBUG : pacer: Reducing sleep to 87.072162ms 2026/08/22 05:31:06 DEBUG : pacer: Reducing sleep to 61.551687ms 2026/08/22 05:31:06 DEBUG : pacer: Reducing sleep to 150.563224ms 2026/08/22 05:31:06 DEBUG : pacer: Reducing sleep to 233.06909ms 2026/08/22 05:31:10 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:727: testing "trailing HT␉" 2026/08/22 05:31:15 DEBUG : pacer: Reducing sleep to 94.117475ms 2026/08/22 05:31:15 DEBUG : pacer: Reducing sleep to 31.456331ms 2026/08/22 05:31:15 DEBUG : pacer: Reducing sleep to 119.659642ms 2026/08/22 05:31:19 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:31:19 DEBUG : pacer: Reducing sleep to 88.23407ms 2026/08/22 05:31:19 DEBUG : pacer: Reducing sleep to 172.258757ms 2026/08/22 05:31:19 DEBUG : pacer: Reducing sleep to 259.224239ms 2026/08/22 05:31:20 DEBUG : pacer: Reducing sleep to 241.965297ms 2026/08/22 05:31:20 DEBUG : pacer: Reducing sleep to 163.317491ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:727: testing "trailing VT␋" 2026/08/22 05:31:21 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:31:21 DEBUG : pacer: Reducing sleep to 66.244274ms 2026/08/22 05:31:21 DEBUG : zoho root 'rclone-test-sepuqof7miza': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/08/22 05:31:21 DEBUG : pacer: Reducing sleep to 164.338725ms 2026/08/22 05:31:21 DEBUG : pacer: Reducing sleep to 167.584593ms 2026/08/22 05:31:21 DEBUG : pacer: Reducing sleep to 256.100616ms 2026/08/22 05:31:21 DEBUG : pacer: Reducing sleep to 257.636983ms 2026/08/22 05:31:21 DEBUG : pacer: Reducing sleep to 163.155865ms 2026/08/22 05:31:22 DEBUG : pacer: Reducing sleep to 57.47374ms 2026/08/22 05:31:22 DEBUG : pacer: Reducing sleep to 75.770205ms 2026/08/22 05:31:22 DEBUG : pacer: Reducing sleep to 161.484966ms 2026/08/22 05:31:22 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:727: testing "trailing dot." 2026/08/22 05:31:23 DEBUG : pacer: Reducing sleep to 57.335594ms 2026/08/22 05:31:23 DEBUG : pacer: Reducing sleep to 156.283565ms 2026/08/22 05:31:23 DEBUG : pacer: Reducing sleep to 131.348222ms 2026/08/22 05:31:23 DEBUG : pacer: Reducing sleep to 222.65083ms 2026/08/22 05:31:27 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:31:28 DEBUG : pacer: Reducing sleep to 71.776237ms 2026/08/22 05:31:28 DEBUG : pacer: Reducing sleep to 102.321635ms 2026/08/22 05:31:28 DEBUG : pacer: Reducing sleep to 195.018447ms 2026/08/22 05:31:28 DEBUG : pacer: Reducing sleep to 251.609124ms 2026/08/22 05:31:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:718: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:727: testing "test%46.txt" 2026/08/22 05:31:37 DEBUG : pacer: Reducing sleep to 71.098086ms 2026/08/22 05:31:37 DEBUG : pacer: Reducing sleep to 169.715316ms 2026/08/22 05:31:37 DEBUG : pacer: Reducing sleep to 176.823907ms 2026/08/22 05:31:37 DEBUG : pacer: Reducing sleep to 246.103438ms 2026/08/22 05:31:41 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:31:41 DEBUG : pacer: Reducing sleep to 82.693544ms 2026/08/22 05:31:42 DEBUG : pacer: Reducing sleep to 50.027952ms 2026/08/22 05:31:42 DEBUG : pacer: Reducing sleep to 139.618848ms 2026/08/22 05:31:42 DEBUG : pacer: Reducing sleep to 225.767211ms 2026/08/22 05:31:46 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/08/22 05:32:05 DEBUG : pacer: Reducing sleep to 73.804715ms 2026/08/22 05:32:05 DEBUG : pacer: Reducing sleep to 169.355655ms 2026/08/22 05:32:05 DEBUG : pacer: Reducing sleep to 231.836966ms 2026/08/22 05:32:05 DEBUG : pacer: Reducing sleep to 254.037186ms 2026/08/22 05:32:05 DEBUG : pacer: Reducing sleep to 158.147503ms 2026/08/22 05:32:05 DEBUG : pacer: Reducing sleep to 94.39723ms 2026/08/22 05:32:06 DEBUG : pacer: Reducing sleep to 77.342908ms 2026/08/22 05:32:06 DEBUG : pacer: Reducing sleep to 243.011057ms 2026/08/22 05:32:06 DEBUG : pacer: Reducing sleep to 249.193195ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/08/22 05:32:09 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:32:14 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:32:14 UTC": parsing time "Sat, 22 Aug 2026 05:32:14 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:32:14 UTC" as " " 2026/08/22 05:32:14 DEBUG : pacer: Reducing sleep to 84.641779ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:806: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:839: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:892: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/08/22 05:32:18 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:32:18 DEBUG : pacer: Reducing sleep to 19.832209ms 2026/08/22 05:32:23 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:32:23 DEBUG : zoho root 'rclone-test-sepuqof7miza': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/08/22 05:32:23 DEBUG : pacer: Reducing sleep to 97.982014ms 2026/08/22 05:32:23 DEBUG : pacer: Reducing sleep to 50.270028ms 2026/08/22 05:32:23 DEBUG : pacer: Reducing sleep to 136.972141ms 2026/08/22 05:32:23 DEBUG : pacer: Reducing sleep to 228.679922ms 2026/08/22 05:32:24 DEBUG : pacer: Reducing sleep to 171.61716ms 2026/08/22 05:32:24 DEBUG : pacer: Reducing sleep to 182.623126ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/08/22 05:32:24 DEBUG : pacer: Reducing sleep to 188.690541ms 2026/08/22 05:32:24 DEBUG : pacer: Reducing sleep to 163.31786ms 2026/08/22 05:32:24 DEBUG : pacer: Reducing sleep to 145.253144ms 2026/08/22 05:32:24 DEBUG : pacer: Reducing sleep to 147.354106ms 2026/08/22 05:32:25 DEBUG : pacer: Reducing sleep to 173.056738ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/08/22 05:32:25 DEBUG : pacer: Reducing sleep to 187.426258ms 2026/08/22 05:32:25 DEBUG : pacer: Reducing sleep to 182.896263ms 2026/08/22 05:32:25 DEBUG : pacer: Reducing sleep to 161.512467ms 2026/08/22 05:32:25 DEBUG : pacer: Reducing sleep to 147.847925ms 2026/08/22 05:32:25 DEBUG : pacer: Reducing sleep to 147.588649ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/08/22 05:32:26 DEBUG : pacer: Reducing sleep to 169.163982ms 2026/08/22 05:32:26 DEBUG : pacer: Reducing sleep to 179.136988ms 2026/08/22 05:32:26 DEBUG : pacer: Reducing sleep to 183.369598ms 2026/08/22 05:32:26 DEBUG : pacer: Reducing sleep to 157.908369ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/08/22 05:32:26 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/08/22 05:32:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/08/22 05:32:35 DEBUG : pacer: Reducing sleep to 91.493387ms 2026/08/22 05:32:35 DEBUG : pacer: Reducing sleep to 181.622547ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/08/22 05:32:40 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:32:40 DEBUG : pacer: Reducing sleep to 88.925436ms 2026/08/22 05:32:40 DEBUG : pacer: Reducing sleep to 154.249746ms 2026/08/22 05:32:40 DEBUG : pacer: Reducing sleep to 225.698845ms 2026/08/22 05:32:40 DEBUG : pacer: Reducing sleep to 243.637843ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/08/22 05:32:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1145: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/08/22 05:32:49 DEBUG : pacer: Reducing sleep to 92.251659ms 2026/08/22 05:32:49 DEBUG : pacer: Reducing sleep to 181.661411ms 2026/08/22 05:32:49 DEBUG : pacer: Reducing sleep to 257.330204ms 2026/08/22 05:32:50 DEBUG : pacer: Reducing sleep to 240.1724ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/08/22 05:32:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/08/22 05:32:54 DEBUG : pacer: Reducing sleep to 90.407929ms 2026/08/22 05:32:59 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:32:59 DEBUG : pacer: Reducing sleep to 61.277436ms 2026/08/22 05:32:59 DEBUG : pacer: Reducing sleep to 1.060655ms 2026/08/22 05:32:59 DEBUG : pacer: Reducing sleep to 94.622502ms 2026/08/22 05:33:03 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:03 DEBUG : pacer: Reducing sleep to 88.176594ms 2026/08/22 05:33:03 DEBUG : pacer: Reducing sleep to 252.484224ms 2026/08/22 05:33:03 DEBUG : pacer: Reducing sleep to 348.783643ms 2026/08/22 05:33:04 DEBUG : pacer: Reducing sleep to 421.353143ms 2026/08/22 05:33:04 DEBUG : pacer: Reducing sleep to 241.661506ms 2026/08/22 05:33:04 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:04 DEBUG : pacer: Reducing sleep to 83.843377ms 2026/08/22 05:33:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:08 DEBUG : pacer: Reducing sleep to 93.221555ms 2026/08/22 05:33:08 DEBUG : pacer: Reducing sleep to 182.531601ms 2026/08/22 05:33:08 DEBUG : pacer: Reducing sleep to 258.591895ms 2026/08/22 05:33:08 DEBUG : pacer: Reducing sleep to 237.966654ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/08/22 05:33:12 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:17 DEBUG : pacer: Reducing sleep to 72.252172ms 2026/08/22 05:33:18 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:18 DEBUG : pacer: Reducing sleep to 77.220098ms 2026/08/22 05:33:22 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:22 DEBUG : pacer: Reducing sleep to 90.071486ms 2026/08/22 05:33:22 DEBUG : pacer: Reducing sleep to 255.819106ms 2026/08/22 05:33:22 DEBUG : pacer: Reducing sleep to 350.642429ms 2026/08/22 05:33:22 DEBUG : pacer: Reducing sleep to 419.343432ms 2026/08/22 05:33:22 DEBUG : pacer: Reducing sleep to 239.430055ms 2026/08/22 05:33:22 DEBUG : Creating backend with remote "TestZoho:rclone-test-sepuqof7miza/dirToPurgeFromRoot" 2026/08/22 05:33:23 DEBUG : pacer: Reducing sleep to 118.526942ms 2026/08/22 05:33:23 DEBUG : pacer: Reducing sleep to 238.211241ms 2026/08/22 05:33:23 DEBUG : pacer: Reducing sleep to 276.505341ms 2026/08/22 05:33:23 DEBUG : zoho root 'rclone-test-sepuqof7miza': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/08/22 05:33:23 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:23 DEBUG : pacer: Reducing sleep to 92.822623ms 2026/08/22 05:33:23 DEBUG : pacer: Reducing sleep to 93.769299ms 2026/08/22 05:33:23 DEBUG : pacer: Reducing sleep to 171.788225ms 2026/08/22 05:33:23 DEBUG : pacer: Reducing sleep to 243.482084ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/08/22 05:33:23 DEBUG : Creating backend with remote "TestZoho:rclone-test-sepuqof7miza/hello? sausage/êé" 2026/08/22 05:33:24 DEBUG : pacer: Reducing sleep to 120.741367ms 2026/08/22 05:33:24 DEBUG : pacer: Reducing sleep to 237.423745ms 2026/08/22 05:33:24 DEBUG : pacer: Reducing sleep to 286.93178ms 2026/08/22 05:33:24 DEBUG : pacer: Reducing sleep to 208.550983ms 2026/08/22 05:33:24 DEBUG : pacer: Reducing sleep to 71.485503ms 2026/08/22 05:33:25 DEBUG : pacer: Reducing sleep to 53.570045ms 2026/08/22 05:33:25 DEBUG : pacer: Reducing sleep to 120.399584ms 2026/08/22 05:33:25 DEBUG : pacer: Reducing sleep to 244.521586ms 2026/08/22 05:33:25 DEBUG : pacer: Reducing sleep to 262.165946ms 2026/08/22 05:33:25 DEBUG : pacer: Reducing sleep to 211.341309ms 2026/08/22 05:33:25 DEBUG : pacer: Reducing sleep to 87.138298ms 2026/08/22 05:33:26 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:26 DEBUG : pacer: Reducing sleep to 114.67698ms 2026/08/22 05:33:26 DEBUG : pacer: Reducing sleep to 233.40884ms 2026/08/22 05:33:26 DEBUG : pacer: Reducing sleep to 269.340579ms 2026/08/22 05:33:26 DEBUG : pacer: Reducing sleep to 216.990028ms 2026/08/22 05:33:26 DEBUG : pacer: Reducing sleep to 103.981906ms 2026/08/22 05:33:27 DEBUG : pacer: Reducing sleep to 65.122574ms 2026/08/22 05:33:27 DEBUG : pacer: Reducing sleep to 123.463156ms 2026/08/22 05:33:27 DEBUG : pacer: Reducing sleep to 225.441826ms 2026/08/22 05:33:27 DEBUG : pacer: Reducing sleep to 264.386165ms 2026/08/22 05:33:27 DEBUG : pacer: Reducing sleep to 201.322909ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/08/22 05:33:27 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:28 DEBUG : pacer: Reducing sleep to 67.606948ms 2026/08/22 05:33:28 DEBUG : pacer: Reducing sleep to 155.850439ms 2026/08/22 05:33:28 DEBUG : pacer: Reducing sleep to 249.695735ms 2026/08/22 05:33:30 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:31 DEBUG : pacer: Reducing sleep to 92.0482ms 2026/08/22 05:33:31 DEBUG : pacer: Reducing sleep to 80.825087ms 2026/08/22 05:33:35 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:35 DEBUG : pacer: Reducing sleep to 61.870172ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1318: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/08/22 05:33:40 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:40 DEBUG : pacer: Reducing sleep to 71.733624ms 2026/08/22 05:33:40 DEBUG : pacer: Reducing sleep to 141.811682ms 2026/08/22 05:33:40 DEBUG : pacer: Reducing sleep to 220.01534ms 2026/08/22 05:33:40 DEBUG : pacer: Reducing sleep to 258.18615ms 2026/08/22 05:33:41 DEBUG : pacer: Reducing sleep to 204.270848ms 2026/08/22 05:33:41 DEBUG : pacer: Reducing sleep to 113.446009ms 2026/08/22 05:33:41 DEBUG : pacer: Reducing sleep to 73.307614ms 2026/08/22 05:33:44 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:54 DEBUG : pacer: Reducing sleep to 67.462418ms 2026/08/22 05:33:54 DEBUG : pacer: Reducing sleep to 148.461997ms 2026/08/22 05:33:54 DEBUG : pacer: Reducing sleep to 209.564802ms 2026/08/22 05:33:54 DEBUG : pacer: Reducing sleep to 250.578786ms 2026/08/22 05:33:59 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:33:59 DEBUG : pacer: Reducing sleep to 92.791297ms 2026/08/22 05:33:59 DEBUG : pacer: Reducing sleep to 255.191462ms 2026/08/22 05:33:59 DEBUG : pacer: Reducing sleep to 254.099195ms 2026/08/22 05:33:59 DEBUG : pacer: Reducing sleep to 160.01843ms 2026/08/22 05:33:59 DEBUG : pacer: Reducing sleep to 81.579644ms 2026/08/22 05:34:00 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:34:00 DEBUG : pacer: Reducing sleep to 52.441693ms 2026/08/22 05:34:00 DEBUG : pacer: Reducing sleep to 54.800564ms 2026/08/22 05:34:00 DEBUG : pacer: Reducing sleep to 105.971758ms 2026/08/22 05:34:03 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:34:03 DEBUG : pacer: Reducing sleep to 97.765745ms 2026/08/22 05:34:03 DEBUG : pacer: Reducing sleep to 258.204716ms 2026/08/22 05:34:03 DEBUG : pacer: Reducing sleep to 347.066061ms 2026/08/22 05:34:04 DEBUG : pacer: Reducing sleep to 256.106094ms 2026/08/22 05:34:04 DEBUG : pacer: Reducing sleep to 79.05931ms 2026/08/22 05:34:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:34:08 DEBUG : pacer: Reducing sleep to 78.120313ms 2026/08/22 05:34:08 DEBUG : pacer: Reducing sleep to 135.937533ms 2026/08/22 05:34:08 DEBUG : pacer: Reducing sleep to 213.564915ms 2026/08/22 05:34:13 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:34:13 DEBUG : pacer: Reducing sleep to 94.776339ms 2026/08/22 05:34:13 DEBUG : pacer: Reducing sleep to 260.774102ms 2026/08/22 05:34:13 DEBUG : pacer: Reducing sleep to 283.725375ms 2026/08/22 05:34:13 DEBUG : pacer: Reducing sleep to 192.614628ms 2026/08/22 05:34:13 DEBUG : pacer: Reducing sleep to 70.166128ms 2026/08/22 05:34:13 DEBUG : pacer: Reducing sleep to 52.909485ms 2026/08/22 05:34:14 DEBUG : pacer: Reducing sleep to 111.91476ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1430: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/08/22 05:34:14 DEBUG : Creating backend with remote "TestZoho:rclone-test-muzunoq0gosa" 2026/08/22 05:34:14 DEBUG : pacer: Reducing sleep to 37.407814ms 2026/08/22 05:34:14 DEBUG : pacer: Reducing sleep to 75.619096ms 2026/08/22 05:34:14 DEBUG : pacer: Reducing sleep to 90.397151ms 2026/08/22 05:34:14 DEBUG : pacer: Reducing sleep to 214.678767ms 2026/08/22 05:34:14 DEBUG : pacer: Reducing sleep to 253.302069ms 2026/08/22 05:34:15 DEBUG : pacer: Reducing sleep to 227.234754ms 2026/08/22 05:34:15 DEBUG : pacer: Reducing sleep to 71.711143ms 2026/08/22 05:34:15 DEBUG : pacer: Reducing sleep to 76.650793ms 2026/08/22 05:34:15 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:34:15 DEBUG : pacer: Reducing sleep to 37.772848ms 2026/08/22 05:34:15 DEBUG : pacer: Reducing sleep to 133.305074ms 2026/08/22 05:34:17 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:34:17 DEBUG : pacer: Reducing sleep to 113.375976ms 2026/08/22 05:34:17 DEBUG : pacer: Reducing sleep to 195.538466ms 2026/08/22 05:34:17 DEBUG : pacer: Reducing sleep to 253.872612ms 2026/08/22 05:34:18 DEBUG : pacer: Reducing sleep to 185.654229ms 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 4.290643ms 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 90.637288ms 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 151.695361ms 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 242.598592ms 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 253.134196ms 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 172.578829ms 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 123.79131ms 2026/08/22 05:34:20 DEBUG : zoho root 'rclone-test-muzunoq0gosa': Purge remote 2026/08/22 05:34:20 DEBUG : pacer: Reducing sleep to 147.010974ms 2026/08/22 05:34:21 DEBUG : pacer: Reducing sleep to 177.392926ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/08/22 05:34:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/08/22 05:34:21 DEBUG : pacer: Reducing sleep to 88.690414ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/08/22 05:34:21 DEBUG : pacer: Reducing sleep to 156.065951ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/08/22 05:34:21 DEBUG : pacer: Reducing sleep to 228.894248ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/08/22 05:34:21 DEBUG : pacer: Reducing sleep to 244.69703ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/08/22 05:34:22 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1626: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/08/22 05:34:27 DEBUG : zoho root 'rclone-test-sepuqof7miza': folder listing limiter sweep: evicted 6 of 15 idle limiters fstests.go:1657: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1728: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1785: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/08/22 05:34:50 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:34:50 UTC": parsing time "Sat, 22 Aug 2026 05:34:50 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:34:50 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/08/22 05:34:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:34:55 UTC": parsing time "Sat, 22 Aug 2026 05:34:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:34:55 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/08/22 05:34:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:34:59 UTC": parsing time "Sat, 22 Aug 2026 05:34:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:34:59 UTC" as " " 2026/08/22 05:35:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:00 UTC": parsing time "Sat, 22 Aug 2026 05:35:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:00 UTC" as " " 2026/08/22 05:35:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:00 UTC": parsing time "Sat, 22 Aug 2026 05:35:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:00 UTC" as " " 2026/08/22 05:35:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:00 UTC": parsing time "Sat, 22 Aug 2026 05:35:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:00 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/08/22 05:35:04 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:04 UTC": parsing time "Sat, 22 Aug 2026 05:35:04 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:04 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/08/22 05:35:09 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:09 UTC": parsing time "Sat, 22 Aug 2026 05:35:09 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:09 UTC" as " " 2026/08/22 05:35:09 DEBUG : pacer: Reducing sleep to 17.451034ms 2026/08/22 05:35:13 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:35:13 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:13 UTC": parsing time "Sat, 22 Aug 2026 05:35:13 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:13 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/08/22 05:35:13 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:13 UTC": parsing time "Sat, 22 Aug 2026 05:35:13 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:13 UTC" as " " 2026/08/22 05:35:13 DEBUG : pacer: Reducing sleep to 8.699953ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/08/22 05:35:14 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:14 UTC": parsing time "Sat, 22 Aug 2026 05:35:14 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:14 UTC" as " " 2026/08/22 05:35:14 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/08/22 05:35:14 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:14 UTC": parsing time "Sat, 22 Aug 2026 05:35:14 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:14 UTC" as " " 2026/08/22 05:35:14 DEBUG : pacer: Reducing sleep to 14.944711ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/08/22 05:35:14 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:14 UTC": parsing time "Sat, 22 Aug 2026 05:35:14 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:14 UTC" as " " 2026/08/22 05:35:14 DEBUG : pacer: Reducing sleep to 12.146504ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/08/22 05:35:18 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:35:18 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:18 UTC": parsing time "Sat, 22 Aug 2026 05:35:18 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:18 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/08/22 05:35:22 DEBUG : pacer: Reducing sleep to 24.479854ms 2026/08/22 05:35:22 DEBUG : pacer: Reducing sleep to 118.163533ms 2026/08/22 05:35:23 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 22 Aug 2026 05:35:23 UTC": parsing time "Sat, 22 Aug 2026 05:35:23 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 22 Aug 2026 05:35:23 UTC" as " " 2026/08/22 05:35:23 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/08/22 05:35:23 DEBUG : pacer: Reducing sleep to 85.618334ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/08/22 05:35:23 DEBUG : Creating backend with remote "TestZoho:rclone-test-sepuqof7miza/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/08/22 05:35:23 DEBUG : pacer: Reducing sleep to 117.275776ms 2026/08/22 05:35:23 DEBUG : pacer: Reducing sleep to 231.838085ms 2026/08/22 05:35:24 DEBUG : pacer: Reducing sleep to 280.594758ms 2026/08/22 05:35:24 DEBUG : pacer: Reducing sleep to 214.869133ms 2026/08/22 05:35:24 DEBUG : pacer: Reducing sleep to 96.863901ms 2026/08/22 05:35:24 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:35:24 DEBUG : pacer: Reducing sleep to 117.178763ms 2026/08/22 05:35:24 DEBUG : pacer: Reducing sleep to 233.696888ms 2026/08/22 05:35:25 DEBUG : pacer: Reducing sleep to 282.723159ms 2026/08/22 05:35:25 DEBUG : pacer: Reducing sleep to 212.235088ms 2026/08/22 05:35:25 DEBUG : pacer: Reducing sleep to 98.468984ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/08/22 05:35:25 DEBUG : pacer: Reducing sleep to 54.442469ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/08/22 05:35:25 DEBUG : Creating backend with remote "TestZoho:rclone-test-sepuqof7miza/not found.txt" 2026/08/22 05:35:26 DEBUG : pacer: Reducing sleep to 111.643326ms 2026/08/22 05:35:26 DEBUG : pacer: Reducing sleep to 148.10224ms 2026/08/22 05:35:30 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:35:30 DEBUG : zoho root 'rclone-test-sepuqof7miza/not found.txt': folder listing limiter sweep: evicted 3 of 9 idle limiters 2026/08/22 05:35:35 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1987: Opening root remote "TestZoho:" path "rclone-test-sepuqof7miza" from "TestZoho:rclone-test-sepuqof7miza" 2026/08/22 05:35:35 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/08/22 05:35:39 DEBUG : pacer: Reducing sleep to 118.485671ms 2026/08/22 05:35:40 DEBUG : pacer: Reducing sleep to 229.930944ms 2026/08/22 05:35:40 DEBUG : pacer: Reducing sleep to 280.510262ms 2026/08/22 05:35:40 DEBUG : pacer: Reducing sleep to 219.876389ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/08/22 05:35:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2024: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/08/22 05:35:58 DEBUG : pacer: Reducing sleep to 115.927381ms 2026/08/22 05:35:58 DEBUG : pacer: Reducing sleep to 229.199304ms 2026/08/22 05:35:58 DEBUG : pacer: Reducing sleep to 282.414925ms 2026/08/22 05:35:58 DEBUG : pacer: Reducing sleep to 214.931836ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/08/22 05:35:59 DEBUG : pacer: Reducing sleep to 68.598503ms 2026/08/22 05:36:03 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:36:03 DEBUG : pacer: Reducing sleep to 116.486126ms 2026/08/22 05:36:03 DEBUG : pacer: Reducing sleep to 235.5439ms 2026/08/22 05:36:03 DEBUG : pacer: Reducing sleep to 280.137323ms 2026/08/22 05:36:03 DEBUG : pacer: Reducing sleep to 209.774487ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2083: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:431: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2188: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2204: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/08/22 05:36:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:36:12 DEBUG : pacer: Reducing sleep to 116.34498ms 2026/08/22 05:36:12 DEBUG : pacer: Reducing sleep to 226.896006ms 2026/08/22 05:36:12 DEBUG : pacer: Reducing sleep to 280.913542ms 2026/08/22 05:36:12 DEBUG : pacer: Reducing sleep to 214.17831ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/08/22 05:36:12 DEBUG : pacer: Reducing sleep to 101.965052ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2378: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2424: *zoho.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2445: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/08/22 05:36:16 DEBUG : pacer: Reducing sleep to 0s 2026/08/22 05:36:16 DEBUG : pacer: Reducing sleep to 132.863544ms fstests.go:2596: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n