"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/09/03 16:07:15 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/09/03 16:07:15 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/09/03 16:07:15 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/03 16:07:15 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/09/03 16:07:15 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/09/03 16:07:15 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/09/03 16:07:15 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/09/03 16:07:15 DEBUG : Creating backend with remote "TestZoho:rclone-test-kagirij4bude" 2026/09/03 16:07:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/03 16:07:15 DEBUG : zoho root 'rclone-test-kagirij4bude': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/03 16:07:15 DEBUG : TestZoho: Token expired 2026/09/03 16:07:15 DEBUG : TestZoho: No updated token found in the config file 2026/09/03 16:07:15 DEBUG : TestZoho: Token refresh successful 2026/09/03 16:07:15 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/03 16:07:15 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/09/03 16:07:17 DEBUG : pacer: Reducing sleep to 30.426395ms 2026/09/03 16:07:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:07:17 DEBUG : pacer: Reducing sleep to 123.772149ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/03 16:07:17 DEBUG : pacer: Reducing sleep to 215.505144ms 2026/09/03 16:07:17 DEBUG : pacer: Reducing sleep to 276.174806ms === RUN TestIntegration/FsMkdir 2026/09/03 16:07:17 DEBUG : pacer: Reducing sleep to 117.311324ms 2026/09/03 16:07:18 DEBUG : pacer: Reducing sleep to 16.068515ms 2026/09/03 16:07:18 DEBUG : pacer: Reducing sleep to 50.037014ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/03 16:07:18 INFO : dir/subdir: Making directory 2026/09/03 16:07:18 DEBUG : pacer: Reducing sleep to 175.337106ms 2026/09/03 16:07:18 DEBUG : pacer: Reducing sleep to 211.287165ms 2026/09/03 16:07:18 DEBUG : pacer: Reducing sleep to 282.921906ms 2026/09/03 16:07:18 DEBUG : pacer: Reducing sleep to 144.854033ms 2026/09/03 16:07:18 DEBUG : pacer: Reducing sleep to 108.94431ms 2026/09/03 16:07:19 DEBUG : pacer: Reducing sleep to 122.736909ms 2026/09/03 16:07:19 DEBUG : pacer: Reducing sleep to 195.127647ms 2026/09/03 16:07:19 INFO : dir/subdir: Removing directory 2026/09/03 16:07:19 DEBUG : pacer: Reducing sleep to 223.346799ms 2026/09/03 16:07:19 DEBUG : pacer: Reducing sleep to 194.380136ms 2026/09/03 16:07:19 DEBUG : pacer: Reducing sleep to 124.139925ms 2026/09/03 16:07:19 DEBUG : pacer: Reducing sleep to 114.182049ms 2026/09/03 16:07:19 INFO : dir: Removing directory 2026/09/03 16:07:20 DEBUG : pacer: Reducing sleep to 150.88641ms 2026/09/03 16:07:20 DEBUG : pacer: Reducing sleep to 140.345805ms 2026/09/03 16:07:20 DEBUG : pacer: Reducing sleep to 233.502996ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/03 16:07:20 DEBUG : pacer: Reducing sleep to 234.932087ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/03 16:07:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/03 16:07:29 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/09/03 16:07:38 DEBUG : pacer: Reducing sleep to 67.23237ms 2026/09/03 16:07:39 DEBUG : pacer: Reducing sleep to 190.628688ms 2026/09/03 16:07:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:07:39 DEBUG : pacer: Reducing sleep to 113.416376ms 2026/09/03 16:07:43 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:07:43 DEBUG : pacer: Reducing sleep to 113.075351ms 2026/09/03 16:07:43 DEBUG : pacer: Reducing sleep to 207.445649ms 2026/09/03 16:07:43 DEBUG : pacer: Reducing sleep to 285.105185ms 2026/09/03 16:07:43 DEBUG : pacer: Reducing sleep to 202.210393ms 2026/09/03 16:07:48 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/03 16:07:52 DEBUG : pacer: Reducing sleep to 68.368652ms 2026/09/03 16:07:52 DEBUG : pacer: Reducing sleep to 193.850254ms 2026/09/03 16:07:53 DEBUG : pacer: Reducing sleep to 177.133283ms 2026/09/03 16:07:53 DEBUG : pacer: Reducing sleep to 234.949323ms 2026/09/03 16:07:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:07:57 DEBUG : pacer: Reducing sleep to 86.442856ms 2026/09/03 16:07:57 DEBUG : pacer: Reducing sleep to 90.229635ms 2026/09/03 16:07:57 DEBUG : pacer: Reducing sleep to 116.744911ms 2026/09/03 16:07:57 DEBUG : pacer: Reducing sleep to 230.275932ms 2026/09/03 16:08:01 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/03 16:08:06 DEBUG : pacer: Reducing sleep to 95.499045ms 2026/09/03 16:08:06 DEBUG : pacer: Reducing sleep to 213.770581ms 2026/09/03 16:08:06 DEBUG : pacer: Reducing sleep to 149.241645ms 2026/09/03 16:08:06 DEBUG : pacer: Reducing sleep to 234.562003ms 2026/09/03 16:08:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:08:11 DEBUG : pacer: Reducing sleep to 112.875224ms 2026/09/03 16:08:11 DEBUG : pacer: Reducing sleep to 59.91348ms 2026/09/03 16:08:11 DEBUG : pacer: Reducing sleep to 168.544906ms 2026/09/03 16:08:11 DEBUG : pacer: Reducing sleep to 246.105981ms 2026/09/03 16:08:15 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/03 16:08:15 DEBUG : zoho root 'rclone-test-kagirij4bude': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/03 16:08:20 DEBUG : pacer: Reducing sleep to 93.312135ms 2026/09/03 16:08:20 DEBUG : pacer: Reducing sleep to 201.91747ms 2026/09/03 16:08:20 DEBUG : pacer: Reducing sleep to 201.944767ms 2026/09/03 16:08:20 DEBUG : pacer: Reducing sleep to 234.958519ms 2026/09/03 16:08:21 DEBUG : pacer: Reducing sleep to 183.610572ms 2026/09/03 16:08:21 DEBUG : pacer: Reducing sleep to 141.035148ms 2026/09/03 16:08:21 DEBUG : pacer: Reducing sleep to 105.758805ms 2026/09/03 16:08:21 DEBUG : pacer: Reducing sleep to 155.683049ms 2026/09/03 16:08:21 DEBUG : pacer: Reducing sleep to 169.184691ms 2026/09/03 16:08:21 DEBUG : pacer: Reducing sleep to 228.366372ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/03 16:08:22 DEBUG : pacer: Reducing sleep to 177.703437ms 2026/09/03 16:08:22 DEBUG : pacer: Reducing sleep to 141.104039ms 2026/09/03 16:08:22 DEBUG : pacer: Reducing sleep to 132.646605ms 2026/09/03 16:08:22 DEBUG : pacer: Reducing sleep to 43.838968ms 2026/09/03 16:08:22 DEBUG : pacer: Reducing sleep to 163.275853ms 2026/09/03 16:08:22 DEBUG : pacer: Reducing sleep to 263.699377ms 2026/09/03 16:08:22 DEBUG : pacer: Reducing sleep to 284.860914ms 2026/09/03 16:08:23 DEBUG : pacer: Reducing sleep to 158.474276ms 2026/09/03 16:08:23 DEBUG : pacer: Reducing sleep to 74.571767ms 2026/09/03 16:08:23 DEBUG : pacer: Reducing sleep to 58.42448ms 2026/09/03 16:08:23 DEBUG : pacer: Reducing sleep to 174.730096ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/03 16:08:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:08:28 DEBUG : pacer: Reducing sleep to 97.526297ms 2026/09/03 16:08:28 DEBUG : pacer: Reducing sleep to 152.595507ms 2026/09/03 16:08:28 DEBUG : pacer: Reducing sleep to 149.591261ms 2026/09/03 16:08:28 DEBUG : pacer: Reducing sleep to 239.3184ms 2026/09/03 16:08:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:08:33 DEBUG : pacer: Reducing sleep to 113.885016ms 2026/09/03 16:08:33 DEBUG : pacer: Reducing sleep to 69.840704ms 2026/09/03 16:08:33 DEBUG : pacer: Reducing sleep to 190.856876ms 2026/09/03 16:08:33 DEBUG : pacer: Reducing sleep to 258.679151ms 2026/09/03 16:08:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/03 16:08:42 DEBUG : pacer: Reducing sleep to 89.981027ms 2026/09/03 16:08:42 DEBUG : pacer: Reducing sleep to 207.589618ms 2026/09/03 16:08:42 DEBUG : pacer: Reducing sleep to 134.25887ms 2026/09/03 16:08:42 DEBUG : pacer: Reducing sleep to 241.541285ms 2026/09/03 16:08:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:08:46 DEBUG : pacer: Reducing sleep to 116.545174ms 2026/09/03 16:08:47 DEBUG : pacer: Reducing sleep to 74.577226ms 2026/09/03 16:08:47 DEBUG : pacer: Reducing sleep to 154.528766ms 2026/09/03 16:08:47 DEBUG : pacer: Reducing sleep to 216.913768ms 2026/09/03 16:08:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/03 16:08:56 DEBUG : pacer: Reducing sleep to 89.509826ms 2026/09/03 16:08:56 DEBUG : pacer: Reducing sleep to 207.820923ms 2026/09/03 16:08:56 DEBUG : pacer: Reducing sleep to 174.121766ms 2026/09/03 16:08:56 DEBUG : pacer: Reducing sleep to 238.669508ms 2026/09/03 16:09:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:09:00 DEBUG : pacer: Reducing sleep to 41.877469ms 2026/09/03 16:09:00 DEBUG : pacer: Reducing sleep to 90.929348ms 2026/09/03 16:09:01 DEBUG : pacer: Reducing sleep to 173.789037ms 2026/09/03 16:09:01 DEBUG : pacer: Reducing sleep to 278.0776ms 2026/09/03 16:09:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/03 16:09:09 DEBUG : pacer: Reducing sleep to 90.806061ms 2026/09/03 16:09:10 DEBUG : pacer: Reducing sleep to 175.226631ms 2026/09/03 16:09:10 DEBUG : pacer: Reducing sleep to 129.100128ms 2026/09/03 16:09:10 DEBUG : pacer: Reducing sleep to 244.939579ms 2026/09/03 16:09:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:09:14 DEBUG : pacer: Reducing sleep to 114.600884ms 2026/09/03 16:09:14 DEBUG : pacer: Reducing sleep to 94.55818ms 2026/09/03 16:09:14 DEBUG : pacer: Reducing sleep to 149.034302ms 2026/09/03 16:09:14 DEBUG : pacer: Reducing sleep to 224.881884ms 2026/09/03 16:09:19 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/03 16:09:19 DEBUG : zoho root 'rclone-test-kagirij4bude': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/03 16:09:19 DEBUG : pacer: Reducing sleep to 125.875739ms 2026/09/03 16:09:19 DEBUG : pacer: Reducing sleep to 87.533613ms 2026/09/03 16:09:19 DEBUG : pacer: Reducing sleep to 169.541169ms 2026/09/03 16:09:19 DEBUG : pacer: Reducing sleep to 165.788176ms 2026/09/03 16:09:19 DEBUG : pacer: Reducing sleep to 232.864045ms 2026/09/03 16:09:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:09:20 DEBUG : pacer: Reducing sleep to 100.729345ms 2026/09/03 16:09:21 DEBUG : pacer: Reducing sleep to 89.36623ms 2026/09/03 16:09:21 DEBUG : pacer: Reducing sleep to 151.831849ms 2026/09/03 16:09:21 DEBUG : pacer: Reducing sleep to 236.658413ms 2026/09/03 16:09:21 DEBUG : pacer: Reducing sleep to 36.246235ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/03 16:09:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:09:22 DEBUG : pacer: Reducing sleep to 95.90474ms 2026/09/03 16:09:22 DEBUG : pacer: Reducing sleep to 211.69635ms 2026/09/03 16:09:22 DEBUG : pacer: Reducing sleep to 156.578907ms 2026/09/03 16:09:22 DEBUG : pacer: Reducing sleep to 238.83405ms 2026/09/03 16:09:22 DEBUG : pacer: Reducing sleep to 233.686765ms 2026/09/03 16:09:23 DEBUG : pacer: Reducing sleep to 176.852972ms 2026/09/03 16:09:23 DEBUG : pacer: Reducing sleep to 74.445726ms 2026/09/03 16:09:23 DEBUG : pacer: Reducing sleep to 99.681147ms 2026/09/03 16:09:23 DEBUG : pacer: Reducing sleep to 167.187778ms 2026/09/03 16:09:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/03 16:09:32 DEBUG : pacer: Reducing sleep to 126.595697ms 2026/09/03 16:09:32 DEBUG : pacer: Reducing sleep to 162.653027ms 2026/09/03 16:09:32 DEBUG : pacer: Reducing sleep to 268.132297ms 2026/09/03 16:09:36 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:09:36 DEBUG : pacer: Reducing sleep to 109.625241ms 2026/09/03 16:09:36 DEBUG : pacer: Reducing sleep to 53.40453ms 2026/09/03 16:09:37 DEBUG : pacer: Reducing sleep to 107.065731ms 2026/09/03 16:09:37 DEBUG : pacer: Reducing sleep to 218.058423ms 2026/09/03 16:09:41 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/03 16:09:45 DEBUG : pacer: Reducing sleep to 35.5026ms 2026/09/03 16:09:46 DEBUG : pacer: Reducing sleep to 156.325073ms 2026/09/03 16:09:46 DEBUG : pacer: Reducing sleep to 104.39301ms 2026/09/03 16:09:46 DEBUG : pacer: Reducing sleep to 149.481753ms 2026/09/03 16:09:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:09:50 DEBUG : pacer: Reducing sleep to 97.806585ms 2026/09/03 16:09:50 DEBUG : pacer: Reducing sleep to 172.174439ms 2026/09/03 16:09:50 DEBUG : pacer: Reducing sleep to 242.309717ms 2026/09/03 16:09:50 DEBUG : pacer: Reducing sleep to 237.171633ms 2026/09/03 16:09:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/03 16:09:59 DEBUG : pacer: Reducing sleep to 40.839549ms 2026/09/03 16:09:59 DEBUG : pacer: Reducing sleep to 166.250671ms 2026/09/03 16:09:59 DEBUG : pacer: Reducing sleep to 201.831325ms 2026/09/03 16:10:00 DEBUG : pacer: Reducing sleep to 72.577522ms 2026/09/03 16:10:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:10:04 DEBUG : pacer: Reducing sleep to 111.751413ms 2026/09/03 16:10:04 DEBUG : pacer: Reducing sleep to 207.886393ms 2026/09/03 16:10:04 DEBUG : pacer: Reducing sleep to 287.293367ms 2026/09/03 16:10:04 DEBUG : pacer: Reducing sleep to 221.777334ms 2026/09/03 16:10:08 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/03 16:10:13 DEBUG : pacer: Reducing sleep to 94.221368ms 2026/09/03 16:10:13 DEBUG : pacer: Reducing sleep to 206.043943ms 2026/09/03 16:10:13 DEBUG : pacer: Reducing sleep to 147.221611ms 2026/09/03 16:10:13 DEBUG : pacer: Reducing sleep to 243.365358ms 2026/09/03 16:10:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:10:18 DEBUG : pacer: Reducing sleep to 78.503625ms 2026/09/03 16:10:18 DEBUG : pacer: Reducing sleep to 82.451369ms 2026/09/03 16:10:18 DEBUG : pacer: Reducing sleep to 159.010215ms 2026/09/03 16:10:18 DEBUG : pacer: Reducing sleep to 251.845377ms 2026/09/03 16:10:18 DEBUG : pacer: Reducing sleep to 260.82017ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/03 16:10:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:10:19 DEBUG : pacer: Reducing sleep to 53.83522ms 2026/09/03 16:10:19 DEBUG : zoho root 'rclone-test-kagirij4bude': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/03 16:10:19 DEBUG : pacer: Reducing sleep to 74.425553ms 2026/09/03 16:10:20 DEBUG : pacer: Reducing sleep to 103.361015ms 2026/09/03 16:10:20 DEBUG : pacer: Reducing sleep to 171.853448ms 2026/09/03 16:10:20 DEBUG : pacer: Reducing sleep to 286.404472ms 2026/09/03 16:10:20 DEBUG : pacer: Reducing sleep to 250.296153ms 2026/09/03 16:10:20 DEBUG : pacer: Reducing sleep to 148.851694ms 2026/09/03 16:10:20 DEBUG : pacer: Reducing sleep to 59.512477ms 2026/09/03 16:10:21 DEBUG : pacer: Reducing sleep to 86.510612ms 2026/09/03 16:10:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/03 16:10:21 DEBUG : pacer: Reducing sleep to 102.707563ms 2026/09/03 16:10:22 DEBUG : pacer: Reducing sleep to 138.957849ms 2026/09/03 16:10:22 DEBUG : pacer: Reducing sleep to 123.539957ms 2026/09/03 16:10:22 DEBUG : pacer: Reducing sleep to 243.142323ms 2026/09/03 16:10:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:10:26 DEBUG : pacer: Reducing sleep to 99.40963ms 2026/09/03 16:10:26 DEBUG : pacer: Reducing sleep to 79.080695ms 2026/09/03 16:10:26 DEBUG : pacer: Reducing sleep to 152.749277ms 2026/09/03 16:10:26 DEBUG : pacer: Reducing sleep to 241.645826ms 2026/09/03 16:10:31 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/09/03 16:10:35 DEBUG : pacer: Reducing sleep to 95.20336ms 2026/09/03 16:10:35 DEBUG : pacer: Reducing sleep to 200.047073ms 2026/09/03 16:10:36 DEBUG : pacer: Reducing sleep to 176.749266ms 2026/09/03 16:10:36 DEBUG : pacer: Reducing sleep to 189.043308ms 2026/09/03 16:10:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:10:40 DEBUG : pacer: Reducing sleep to 70.019544ms 2026/09/03 16:10:40 DEBUG : pacer: Reducing sleep to 124.129811ms 2026/09/03 16:10:40 DEBUG : pacer: Reducing sleep to 240.561202ms 2026/09/03 16:10:40 DEBUG : pacer: Reducing sleep to 275.987479ms 2026/09/03 16:10:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/03 16:11:03 DEBUG : pacer: Reducing sleep to 93.074409ms 2026/09/03 16:11:03 DEBUG : pacer: Reducing sleep to 213.259534ms 2026/09/03 16:11:03 DEBUG : pacer: Reducing sleep to 260.256544ms 2026/09/03 16:11:03 DEBUG : pacer: Reducing sleep to 233.287182ms 2026/09/03 16:11:04 DEBUG : pacer: Reducing sleep to 64.023655ms 2026/09/03 16:11:04 DEBUG : pacer: Reducing sleep to 81.369655ms 2026/09/03 16:11:04 DEBUG : pacer: Reducing sleep to 130.033561ms 2026/09/03 16:11:04 DEBUG : pacer: Reducing sleep to 295.566898ms 2026/09/03 16:11:04 DEBUG : pacer: Reducing sleep to 290.251421ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/03 16:11:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:11:08 DEBUG : pacer: Reducing sleep to 40.570621ms 2026/09/03 16:11:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:11:13 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:11:13 UTC": parsing time "Thu, 03 Sep 2026 16:11:13 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:11:13 UTC" as " " 2026/09/03 16:11:13 DEBUG : pacer: Reducing sleep to 88.308402ms === 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/09/03 16:11:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:11:21 DEBUG : zoho root 'rclone-test-kagirij4bude': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/03 16:11:21 DEBUG : pacer: Reducing sleep to 120.080075ms 2026/09/03 16:11:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:11:22 DEBUG : pacer: Reducing sleep to 116.400609ms 2026/09/03 16:11:22 DEBUG : pacer: Reducing sleep to 233.510529ms 2026/09/03 16:11:23 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:11:23 DEBUG : pacer: Reducing sleep to 105.34221ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/03 16:11:23 DEBUG : pacer: Reducing sleep to 222.108952ms 2026/09/03 16:11:23 DEBUG : pacer: Reducing sleep to 268.319293ms 2026/09/03 16:11:23 DEBUG : pacer: Reducing sleep to 186.539363ms 2026/09/03 16:11:23 DEBUG : pacer: Reducing sleep to 125.950007ms 2026/09/03 16:11:23 DEBUG : pacer: Reducing sleep to 75.743984ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/03 16:11:24 DEBUG : pacer: Reducing sleep to 140.792299ms 2026/09/03 16:11:24 DEBUG : pacer: Reducing sleep to 149.576118ms 2026/09/03 16:11:24 DEBUG : pacer: Reducing sleep to 260.27679ms 2026/09/03 16:11:24 DEBUG : pacer: Reducing sleep to 247.630855ms 2026/09/03 16:11:24 DEBUG : pacer: Reducing sleep to 183.656358ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/03 16:11:24 DEBUG : pacer: Reducing sleep to 94.857632ms 2026/09/03 16:11:25 DEBUG : pacer: Reducing sleep to 74.416394ms 2026/09/03 16:11:25 DEBUG : pacer: Reducing sleep to 153.057141ms 2026/09/03 16:11:25 DEBUG : pacer: Reducing sleep to 206.566498ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/03 16:11:25 DEBUG : Creating backend with remote "TestZoho:" 2026/09/03 16:11:25 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/09/03 16:11:29 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/09/03 16:11:34 DEBUG : pacer: Reducing sleep to 85.648902ms 2026/09/03 16:11:34 DEBUG : pacer: Reducing sleep to 204.435725ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/03 16:11:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:11:39 DEBUG : pacer: Reducing sleep to 119.235478ms 2026/09/03 16:11:39 DEBUG : pacer: Reducing sleep to 128.005988ms 2026/09/03 16:11:39 DEBUG : pacer: Reducing sleep to 189.077537ms 2026/09/03 16:11:39 DEBUG : pacer: Reducing sleep to 199.662361ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/03 16:11:43 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/03 16:11:48 DEBUG : pacer: Reducing sleep to 97.841451ms 2026/09/03 16:11:48 DEBUG : pacer: Reducing sleep to 218.196615ms 2026/09/03 16:11:48 DEBUG : pacer: Reducing sleep to 270.919615ms 2026/09/03 16:11:48 DEBUG : pacer: Reducing sleep to 232.029484ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/03 16:11:53 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/03 16:11:53 DEBUG : pacer: Reducing sleep to 101.869032ms 2026/09/03 16:11:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:11:57 DEBUG : pacer: Reducing sleep to 58.143548ms 2026/09/03 16:11:58 DEBUG : pacer: Reducing sleep to 82.519016ms 2026/09/03 16:11:58 DEBUG : pacer: Reducing sleep to 198.890049ms 2026/09/03 16:12:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:02 DEBUG : pacer: Reducing sleep to 119.166067ms 2026/09/03 16:12:02 DEBUG : pacer: Reducing sleep to 271.520422ms 2026/09/03 16:12:02 DEBUG : pacer: Reducing sleep to 279.684343ms 2026/09/03 16:12:02 DEBUG : pacer: Reducing sleep to 176.7305ms 2026/09/03 16:12:03 DEBUG : pacer: Reducing sleep to 67.318688ms 2026/09/03 16:12:03 DEBUG : pacer: Reducing sleep to 72.223689ms 2026/09/03 16:12:03 DEBUG : pacer: Reducing sleep to 159.532321ms 2026/09/03 16:12:07 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:07 DEBUG : pacer: Reducing sleep to 118.507614ms 2026/09/03 16:12:07 DEBUG : pacer: Reducing sleep to 184.303643ms 2026/09/03 16:12:07 DEBUG : pacer: Reducing sleep to 221.815886ms 2026/09/03 16:12:07 DEBUG : pacer: Reducing sleep to 221.108722ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/03 16:12:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:16 DEBUG : pacer: Reducing sleep to 98.249961ms 2026/09/03 16:12:16 DEBUG : pacer: Reducing sleep to 85.802389ms 2026/09/03 16:12:16 DEBUG : pacer: Reducing sleep to 206.515225ms 2026/09/03 16:12:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:21 DEBUG : pacer: Reducing sleep to 118.179856ms 2026/09/03 16:12:21 DEBUG : pacer: Reducing sleep to 237.184757ms 2026/09/03 16:12:21 DEBUG : pacer: Reducing sleep to 359.640071ms 2026/09/03 16:12:21 DEBUG : pacer: Reducing sleep to 411.004683ms 2026/09/03 16:12:21 DEBUG : pacer: Reducing sleep to 212.366104ms 2026/09/03 16:12:21 DEBUG : Creating backend with remote "TestZoho:rclone-test-kagirij4bude/dirToPurgeFromRoot" 2026/09/03 16:12:21 DEBUG : Config file has changed externally - reloading 2026/09/03 16:12:22 DEBUG : zoho root 'rclone-test-kagirij4bude/dirToPurgeFromRoot': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/03 16:12:22 DEBUG : pacer: Reducing sleep to 108.264923ms 2026/09/03 16:12:23 DEBUG : pacer: Reducing sleep to 195.319384ms 2026/09/03 16:12:23 DEBUG : pacer: Reducing sleep to 258.097276ms 2026/09/03 16:12:23 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:23 DEBUG : pacer: Reducing sleep to 116.488393ms 2026/09/03 16:12:23 DEBUG : pacer: Reducing sleep to 144.448082ms 2026/09/03 16:12:23 DEBUG : pacer: Reducing sleep to 193.935422ms 2026/09/03 16:12:23 DEBUG : pacer: Reducing sleep to 202.409493ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/03 16:12:23 DEBUG : Creating backend with remote "TestZoho:rclone-test-kagirij4bude/hello? sausage/êé" 2026/09/03 16:12:23 DEBUG : pacer: Reducing sleep to 64.1968ms 2026/09/03 16:12:24 DEBUG : pacer: Reducing sleep to 179.033085ms 2026/09/03 16:12:24 DEBUG : pacer: Reducing sleep to 234.639453ms 2026/09/03 16:12:24 DEBUG : pacer: Reducing sleep to 256.426321ms 2026/09/03 16:12:24 DEBUG : pacer: Reducing sleep to 176.434388ms 2026/09/03 16:12:24 DEBUG : pacer: Reducing sleep to 91.433842ms 2026/09/03 16:12:24 DEBUG : pacer: Reducing sleep to 77.112282ms 2026/09/03 16:12:25 DEBUG : pacer: Reducing sleep to 152.882716ms 2026/09/03 16:12:25 DEBUG : pacer: Reducing sleep to 242.337566ms 2026/09/03 16:12:25 DEBUG : pacer: Reducing sleep to 265.957754ms 2026/09/03 16:12:25 DEBUG : pacer: Reducing sleep to 177.544929ms 2026/09/03 16:12:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:25 DEBUG : pacer: Reducing sleep to 106.543363ms 2026/09/03 16:12:26 DEBUG : pacer: Reducing sleep to 171.236169ms 2026/09/03 16:12:26 DEBUG : pacer: Reducing sleep to 261.972167ms 2026/09/03 16:12:26 DEBUG : pacer: Reducing sleep to 268.178862ms 2026/09/03 16:12:26 DEBUG : pacer: Reducing sleep to 173.947464ms 2026/09/03 16:12:26 DEBUG : pacer: Reducing sleep to 79.516629ms 2026/09/03 16:12:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:27 DEBUG : pacer: Reducing sleep to 113.942267ms 2026/09/03 16:12:27 DEBUG : pacer: Reducing sleep to 197.228746ms 2026/09/03 16:12:27 DEBUG : pacer: Reducing sleep to 273.108666ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/03 16:12:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:27 DEBUG : pacer: Reducing sleep to 101.902654ms 2026/09/03 16:12:27 DEBUG : pacer: Reducing sleep to 203.048871ms 2026/09/03 16:12:27 DEBUG : pacer: Reducing sleep to 286.810086ms 2026/09/03 16:12:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:30 DEBUG : pacer: Reducing sleep to 30.824827ms 2026/09/03 16:12:30 DEBUG : pacer: Reducing sleep to 10.915687ms 2026/09/03 16:12:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:35 DEBUG : pacer: Reducing sleep to 94.070802ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/03 16:12:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:40 DEBUG : pacer: Reducing sleep to 101.381119ms 2026/09/03 16:12:40 DEBUG : pacer: Reducing sleep to 173.462059ms 2026/09/03 16:12:40 DEBUG : pacer: Reducing sleep to 251.752707ms 2026/09/03 16:12:40 DEBUG : pacer: Reducing sleep to 275.046668ms 2026/09/03 16:12:40 DEBUG : pacer: Reducing sleep to 162.227474ms 2026/09/03 16:12:41 DEBUG : pacer: Reducing sleep to 80.160261ms 2026/09/03 16:12:41 DEBUG : pacer: Reducing sleep to 75.33503ms 2026/09/03 16:12:44 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:54 DEBUG : pacer: Reducing sleep to 101.750053ms 2026/09/03 16:12:54 DEBUG : pacer: Reducing sleep to 188.587853ms 2026/09/03 16:12:54 DEBUG : pacer: Reducing sleep to 259.491148ms 2026/09/03 16:12:54 DEBUG : pacer: Reducing sleep to 198.047625ms 2026/09/03 16:12:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:12:58 DEBUG : pacer: Reducing sleep to 120.187272ms 2026/09/03 16:12:58 DEBUG : pacer: Reducing sleep to 278.219772ms 2026/09/03 16:12:59 DEBUG : pacer: Reducing sleep to 321.532463ms 2026/09/03 16:12:59 DEBUG : pacer: Reducing sleep to 187.608962ms 2026/09/03 16:12:59 DEBUG : pacer: Reducing sleep to 34.024284ms 2026/09/03 16:12:59 DEBUG : pacer: Reducing sleep to 29.184813ms 2026/09/03 16:12:59 DEBUG : pacer: Reducing sleep to 106.493546ms 2026/09/03 16:13:00 DEBUG : pacer: Reducing sleep to 180.324116ms 2026/09/03 16:13:00 DEBUG : pacer: Reducing sleep to 2.037855ms 2026/09/03 16:13:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:13:03 DEBUG : pacer: Reducing sleep to 125.047694ms 2026/09/03 16:13:03 DEBUG : pacer: Reducing sleep to 246.856346ms 2026/09/03 16:13:03 DEBUG : pacer: Reducing sleep to 366.31921ms 2026/09/03 16:13:03 DEBUG : pacer: Reducing sleep to 277.04618ms 2026/09/03 16:13:04 DEBUG : pacer: Reducing sleep to 90.293127ms 2026/09/03 16:13:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:13:08 DEBUG : pacer: Reducing sleep to 69.838413ms 2026/09/03 16:13:08 DEBUG : pacer: Reducing sleep to 163.158266ms 2026/09/03 16:13:08 DEBUG : pacer: Reducing sleep to 256.653278ms 2026/09/03 16:13:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:13:12 DEBUG : pacer: Reducing sleep to 118.335104ms 2026/09/03 16:13:12 DEBUG : pacer: Reducing sleep to 278.302285ms 2026/09/03 16:13:12 DEBUG : pacer: Reducing sleep to 272.387633ms 2026/09/03 16:13:13 DEBUG : pacer: Reducing sleep to 149.740411ms 2026/09/03 16:13:13 DEBUG : pacer: Reducing sleep to 51.487329ms 2026/09/03 16:13:13 DEBUG : pacer: Reducing sleep to 50.024421ms 2026/09/03 16:13:13 DEBUG : pacer: Reducing sleep to 159.194789ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/03 16:13:13 DEBUG : Creating backend with remote "TestZoho:rclone-test-revutob3gake" 2026/09/03 16:13:13 DEBUG : Config file has changed externally - reloading 2026/09/03 16:13:14 DEBUG : pacer: Reducing sleep to 36.825153ms 2026/09/03 16:13:14 DEBUG : pacer: Reducing sleep to 32.300736ms 2026/09/03 16:13:14 DEBUG : pacer: Reducing sleep to 139.470577ms 2026/09/03 16:13:14 DEBUG : pacer: Reducing sleep to 225.742347ms 2026/09/03 16:13:14 DEBUG : pacer: Reducing sleep to 281.051889ms 2026/09/03 16:13:15 DEBUG : pacer: Reducing sleep to 117.534417ms 2026/09/03 16:13:15 DEBUG : pacer: Reducing sleep to 36.143611ms 2026/09/03 16:13:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:13:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:13:15 DEBUG : pacer: Reducing sleep to 86.873575ms 2026/09/03 16:13:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:13:17 DEBUG : pacer: Reducing sleep to 117.388048ms 2026/09/03 16:13:17 DEBUG : pacer: Reducing sleep to 215.492581ms 2026/09/03 16:13:17 DEBUG : pacer: Reducing sleep to 281.977534ms 2026/09/03 16:13:17 DEBUG : pacer: Reducing sleep to 226.845709ms 2026/09/03 16:13:19 DEBUG : pacer: Reducing sleep to 13.639745ms 2026/09/03 16:13:19 DEBUG : pacer: Reducing sleep to 131.672709ms 2026/09/03 16:13:19 DEBUG : pacer: Reducing sleep to 220.59489ms 2026/09/03 16:13:19 DEBUG : pacer: Reducing sleep to 283.318945ms 2026/09/03 16:13:20 DEBUG : pacer: Reducing sleep to 154.227708ms 2026/09/03 16:13:20 DEBUG : pacer: Reducing sleep to 108.594629ms 2026/09/03 16:13:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:13:20 DEBUG : pacer: Reducing sleep to 124.095757ms 2026/09/03 16:13:20 DEBUG : zoho root 'rclone-test-revutob3gake': Purge remote 2026/09/03 16:13:20 DEBUG : pacer: Reducing sleep to 107.972605ms 2026/09/03 16:13:20 DEBUG : pacer: Reducing sleep to 156.28481ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/03 16:13:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/03 16:13:20 DEBUG : pacer: Reducing sleep to 116.260228ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/03 16:13:21 DEBUG : pacer: Reducing sleep to 214.455011ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/03 16:13:21 DEBUG : pacer: Reducing sleep to 276.701884ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/03 16:13:21 DEBUG : pacer: Reducing sleep to 201.926342ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/03 16:13:21 DEBUG : pacer: Reducing sleep to 114.331326ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType 2026/09/03 16:13:26 DEBUG : pacer: Reducing sleep to 0s fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/09/03 16:13:26 DEBUG : zoho root 'rclone-test-kagirij4bude': folder listing limiter sweep: evicted 2 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/09/03 16:13:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:13:49 UTC": parsing time "Thu, 03 Sep 2026 16:13:49 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:13:49 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/03 16:13:53 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:13:53 UTC": parsing time "Thu, 03 Sep 2026 16:13:53 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:13:53 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/03 16:13:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:13:58 UTC": parsing time "Thu, 03 Sep 2026 16:13:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:13:58 UTC" as " " 2026/09/03 16:13:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:13:58 UTC": parsing time "Thu, 03 Sep 2026 16:13:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:13:58 UTC" as " " 2026/09/03 16:13:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:13:58 UTC": parsing time "Thu, 03 Sep 2026 16:13:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:13:58 UTC" as " " 2026/09/03 16:13:58 DEBUG : pacer: Reducing sleep to 2.630237ms 2026/09/03 16:13:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:13:59 UTC": parsing time "Thu, 03 Sep 2026 16:13:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:13:59 UTC" as " " 2026/09/03 16:13:59 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/03 16:14:03 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:03 UTC": parsing time "Thu, 03 Sep 2026 16:14:03 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:03 UTC" as " " 2026/09/03 16:14:03 DEBUG : pacer: Reducing sleep to 5.412989ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/03 16:14:07 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:14:08 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:08 UTC": parsing time "Thu, 03 Sep 2026 16:14:08 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:08 UTC" as " " 2026/09/03 16:14:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:12 UTC": parsing time "Thu, 03 Sep 2026 16:14:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/03 16:14:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:12 UTC": parsing time "Thu, 03 Sep 2026 16:14:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/03 16:14:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:12 UTC": parsing time "Thu, 03 Sep 2026 16:14:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/03 16:14:13 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:13 UTC": parsing time "Thu, 03 Sep 2026 16:14:13 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:13 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/03 16:14:13 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:13 UTC": parsing time "Thu, 03 Sep 2026 16:14:13 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:13 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/03 16:14:16 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:16 UTC": parsing time "Thu, 03 Sep 2026 16:14:16 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:16 UTC" as " " 2026/09/03 16:14:16 DEBUG : pacer: Reducing sleep to 10.61708ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/03 16:14:21 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:14:21 DEBUG : pacer: Reducing sleep to 34.610026ms 2026/09/03 16:14:21 DEBUG : pacer: Reducing sleep to 157.639378ms 2026/09/03 16:14:21 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 03 Sep 2026 16:14:21 UTC": parsing time "Thu, 03 Sep 2026 16:14:21 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Sep 2026 16:14:21 UTC" as " " 2026/09/03 16:14:21 DEBUG : pacer: Reducing sleep to 145.0732ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/03 16:14:21 DEBUG : pacer: Reducing sleep to 246.241691ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/03 16:14:21 DEBUG : Creating backend with remote "TestZoho:rclone-test-kagirij4bude/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/03 16:14:22 DEBUG : pacer: Reducing sleep to 68.146043ms 2026/09/03 16:14:22 DEBUG : pacer: Reducing sleep to 184.433901ms 2026/09/03 16:14:22 DEBUG : pacer: Reducing sleep to 264.191158ms 2026/09/03 16:14:22 DEBUG : pacer: Reducing sleep to 263.8107ms 2026/09/03 16:14:22 DEBUG : pacer: Reducing sleep to 141.388233ms 2026/09/03 16:14:23 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:14:23 DEBUG : pacer: Reducing sleep to 108.605067ms 2026/09/03 16:14:23 DEBUG : pacer: Reducing sleep to 225.310745ms 2026/09/03 16:14:23 DEBUG : pacer: Reducing sleep to 271.07904ms 2026/09/03 16:14:23 DEBUG : pacer: Reducing sleep to 224.670427ms 2026/09/03 16:14:23 DEBUG : pacer: Reducing sleep to 73.665961ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/03 16:14:24 DEBUG : pacer: Reducing sleep to 54.252269ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/03 16:14:24 DEBUG : Creating backend with remote "TestZoho:rclone-test-kagirij4bude/not found.txt" 2026/09/03 16:14:24 DEBUG : pacer: Reducing sleep to 118.664664ms 2026/09/03 16:14:24 DEBUG : pacer: Reducing sleep to 121.643076ms 2026/09/03 16:14:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:14:28 DEBUG : zoho root 'rclone-test-kagirij4bude/not found.txt': folder listing limiter sweep: evicted 7 of 13 idle limiters 2026/09/03 16:14:33 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-kagirij4bude" from "TestZoho:rclone-test-kagirij4bude" 2026/09/03 16:14:33 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/03 16:14:38 DEBUG : pacer: Reducing sleep to 86.715851ms 2026/09/03 16:14:38 DEBUG : pacer: Reducing sleep to 207.015942ms 2026/09/03 16:14:38 DEBUG : pacer: Reducing sleep to 260.032357ms 2026/09/03 16:14:38 DEBUG : pacer: Reducing sleep to 215.66882ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/03 16:14:42 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/09/03 16:14:56 DEBUG : pacer: Reducing sleep to 109.565869ms 2026/09/03 16:14:56 DEBUG : pacer: Reducing sleep to 226.903916ms 2026/09/03 16:14:56 DEBUG : pacer: Reducing sleep to 266.932256ms 2026/09/03 16:14:57 DEBUG : pacer: Reducing sleep to 209.692532ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/03 16:14:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:15:01 DEBUG : pacer: Reducing sleep to 100.693542ms 2026/09/03 16:15:01 DEBUG : pacer: Reducing sleep to 217.930208ms 2026/09/03 16:15:01 DEBUG : pacer: Reducing sleep to 266.997617ms 2026/09/03 16:15:01 DEBUG : pacer: Reducing sleep to 230.13252ms === 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/09/03 16:15:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:15:10 DEBUG : pacer: Reducing sleep to 113.939925ms 2026/09/03 16:15:10 DEBUG : pacer: Reducing sleep to 219.069891ms 2026/09/03 16:15:10 DEBUG : pacer: Reducing sleep to 283.211151ms 2026/09/03 16:15:10 DEBUG : pacer: Reducing sleep to 207.472929ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/03 16:15:11 DEBUG : pacer: Reducing sleep to 111.076124ms === 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/09/03 16:15:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:15:15 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/03 16:15:15 DEBUG : pacer: Rate limited, increasing sleep to 1.046003274s fstests.go:2660: Put returned an error after the injected failure (acceptable): upload error: HTTP error 500 (500 Internal Server Error) returned body: "" 2026/09/03 16:15:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:15:19 DEBUG : pacer: Reducing sleep to 67.106054ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/03 16:15:21 DEBUG : pacer: Reducing sleep to 0s 2026/09/03 16:15:21 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=d7vld6cfa72bfec3840b39b519e53019d7b87": write tcp: unexpected EOF) 2026/09/03 16:15:21 DEBUG : pacer: Rate limited, increasing sleep to 1.415345451s 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=d7vld6cfa72bfec3840b39b519e53019d7b87": write tcp: unexpected EOF 2026/09/03 16:15:22 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/09/03 16:15:22 DEBUG : pacer: Reducing sleep to 135.304239ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n