"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/06 02:48:28 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/06 02:48:28 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/06 02:48:28 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/06 02:48:28 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/06 02:48:28 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/06 02:48:28 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/06 02:48:28 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/06 02:48:28 DEBUG : Creating backend with remote "TestZoho:rclone-test-dosihib4gusa" 2026/09/06 02:48:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/06 02:48:28 DEBUG : zoho root 'rclone-test-dosihib4gusa': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/06 02:48:28 DEBUG : TestZoho: Token expired 2026/09/06 02:48:28 DEBUG : TestZoho: No updated token found in the config file 2026/09/06 02:48:28 DEBUG : TestZoho: Token refresh successful 2026/09/06 02:48:28 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/06 02:48:28 DEBUG : TestZoho: Saved new token in config file 2026/09/06 02:48:29 DEBUG : pacer: Reducing sleep to 6.230245ms === 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/06 02:48:29 DEBUG : pacer: Reducing sleep to 34.06198ms 2026/09/06 02:48:29 DEBUG : pacer: Reducing sleep to 73.085368ms 2026/09/06 02:48:29 DEBUG : pacer: Reducing sleep to 50.863677ms 2026/09/06 02:48:30 DEBUG : pacer: Reducing sleep to 159.691848ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/06 02:48:30 DEBUG : pacer: Reducing sleep to 81.625887ms 2026/09/06 02:48:30 DEBUG : pacer: Reducing sleep to 159.151549ms === RUN TestIntegration/FsMkdir 2026/09/06 02:48:30 DEBUG : pacer: Reducing sleep to 121.532203ms 2026/09/06 02:48:30 DEBUG : pacer: Reducing sleep to 158.062894ms 2026/09/06 02:48:30 DEBUG : pacer: Reducing sleep to 263.47838ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/06 02:48:30 INFO : dir/subdir: Making directory 2026/09/06 02:48:30 DEBUG : pacer: Reducing sleep to 292.418689ms 2026/09/06 02:48:31 DEBUG : pacer: Reducing sleep to 154.441878ms 2026/09/06 02:48:31 DEBUG : pacer: Reducing sleep to 69.05833ms 2026/09/06 02:48:31 DEBUG : pacer: Reducing sleep to 27.145587ms 2026/09/06 02:48:31 DEBUG : pacer: Reducing sleep to 145.540352ms 2026/09/06 02:48:31 DEBUG : pacer: Reducing sleep to 256.742324ms 2026/09/06 02:48:31 DEBUG : pacer: Reducing sleep to 294.156545ms 2026/09/06 02:48:31 INFO : dir/subdir: Removing directory 2026/09/06 02:48:32 DEBUG : pacer: Reducing sleep to 173.401334ms 2026/09/06 02:48:32 DEBUG : pacer: Reducing sleep to 60.173006ms 2026/09/06 02:48:32 DEBUG : pacer: Reducing sleep to 50.727321ms 2026/09/06 02:48:32 DEBUG : pacer: Reducing sleep to 175.310661ms 2026/09/06 02:48:32 INFO : dir: Removing directory 2026/09/06 02:48:32 DEBUG : pacer: Reducing sleep to 264.890325ms 2026/09/06 02:48:32 DEBUG : pacer: Reducing sleep to 264.54375ms 2026/09/06 02:48:33 DEBUG : pacer: Reducing sleep to 173.558936ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/06 02:48:33 DEBUG : pacer: Reducing sleep to 81.056683ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/06 02:48:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/06 02:48:42 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/06 02:48:51 DEBUG : pacer: Reducing sleep to 86.480611ms 2026/09/06 02:48:51 DEBUG : pacer: Reducing sleep to 213.243116ms 2026/09/06 02:48:52 DEBUG : pacer: Reducing sleep to 76.120789ms 2026/09/06 02:48:52 DEBUG : pacer: Reducing sleep to 178.465647ms 2026/09/06 02:48:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:48:56 DEBUG : pacer: Reducing sleep to 110.810013ms 2026/09/06 02:48:56 DEBUG : pacer: Reducing sleep to 142.92219ms 2026/09/06 02:48:56 DEBUG : pacer: Reducing sleep to 202.199544ms 2026/09/06 02:48:56 DEBUG : pacer: Reducing sleep to 224.316808ms 2026/09/06 02:49:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/06 02:49:05 DEBUG : pacer: Reducing sleep to 87.736472ms 2026/09/06 02:49:05 DEBUG : pacer: Reducing sleep to 216.083149ms 2026/09/06 02:49:05 DEBUG : pacer: Reducing sleep to 191.217255ms 2026/09/06 02:49:05 DEBUG : pacer: Reducing sleep to 228.864715ms 2026/09/06 02:49:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:49:10 DEBUG : pacer: Reducing sleep to 100.775403ms 2026/09/06 02:49:10 DEBUG : pacer: Reducing sleep to 92.632296ms 2026/09/06 02:49:10 DEBUG : pacer: Reducing sleep to 159.543154ms 2026/09/06 02:49:10 DEBUG : pacer: Reducing sleep to 233.137604ms 2026/09/06 02:49:14 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/06 02:49:19 DEBUG : pacer: Reducing sleep to 90.486486ms 2026/09/06 02:49:19 DEBUG : pacer: Reducing sleep to 216.11014ms 2026/09/06 02:49:19 DEBUG : pacer: Reducing sleep to 117.70971ms 2026/09/06 02:49:19 DEBUG : pacer: Reducing sleep to 218.729655ms 2026/09/06 02:49:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:49:24 DEBUG : pacer: Reducing sleep to 72.400603ms 2026/09/06 02:49:24 DEBUG : pacer: Reducing sleep to 104.812075ms 2026/09/06 02:49:24 DEBUG : pacer: Reducing sleep to 201.302217ms 2026/09/06 02:49:24 DEBUG : pacer: Reducing sleep to 258.247397ms 2026/09/06 02:49:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/06 02:49:28 DEBUG : zoho root 'rclone-test-dosihib4gusa': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/06 02:49:33 DEBUG : pacer: Reducing sleep to 84.56951ms 2026/09/06 02:49:33 DEBUG : pacer: Reducing sleep to 212.685682ms 2026/09/06 02:49:33 DEBUG : pacer: Reducing sleep to 193.187563ms 2026/09/06 02:49:33 DEBUG : pacer: Reducing sleep to 220.156699ms 2026/09/06 02:49:33 DEBUG : pacer: Reducing sleep to 215.4985ms 2026/09/06 02:49:34 DEBUG : pacer: Reducing sleep to 118.412584ms 2026/09/06 02:49:34 DEBUG : pacer: Reducing sleep to 101.214398ms 2026/09/06 02:49:34 DEBUG : pacer: Reducing sleep to 150.147188ms 2026/09/06 02:49:34 DEBUG : pacer: Reducing sleep to 214.472343ms 2026/09/06 02:49:34 DEBUG : pacer: Reducing sleep to 238.003616ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/06 02:49:34 DEBUG : pacer: Reducing sleep to 194.524915ms 2026/09/06 02:49:35 DEBUG : pacer: Reducing sleep to 71.800776ms 2026/09/06 02:49:35 DEBUG : pacer: Reducing sleep to 95.788731ms 2026/09/06 02:49:35 DEBUG : pacer: Reducing sleep to 41.159555ms 2026/09/06 02:49:35 DEBUG : pacer: Reducing sleep to 120.184688ms 2026/09/06 02:49:35 DEBUG : pacer: Reducing sleep to 240.28815ms 2026/09/06 02:49:35 DEBUG : pacer: Reducing sleep to 277.435003ms 2026/09/06 02:49:36 DEBUG : pacer: Reducing sleep to 197.117441ms 2026/09/06 02:49:36 DEBUG : pacer: Reducing sleep to 86.00734ms 2026/09/06 02:49:36 DEBUG : pacer: Reducing sleep to 63.975327ms 2026/09/06 02:49:36 DEBUG : pacer: Reducing sleep to 147.764125ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/06 02:49:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:49:41 DEBUG : pacer: Reducing sleep to 62.771449ms 2026/09/06 02:49:41 DEBUG : pacer: Reducing sleep to 185.687291ms 2026/09/06 02:49:41 DEBUG : pacer: Reducing sleep to 203.531887ms 2026/09/06 02:49:41 DEBUG : pacer: Reducing sleep to 254.872972ms 2026/09/06 02:49:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:49:45 DEBUG : pacer: Reducing sleep to 95.40897ms 2026/09/06 02:49:46 DEBUG : pacer: Reducing sleep to 59.202119ms 2026/09/06 02:49:46 DEBUG : pacer: Reducing sleep to 112.414119ms 2026/09/06 02:49:46 DEBUG : pacer: Reducing sleep to 225.542379ms 2026/09/06 02:49:50 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/06 02:49:55 DEBUG : pacer: Reducing sleep to 88.171463ms 2026/09/06 02:49:55 DEBUG : pacer: Reducing sleep to 214.10402ms 2026/09/06 02:49:55 DEBUG : pacer: Reducing sleep to 138.841281ms 2026/09/06 02:49:55 DEBUG : pacer: Reducing sleep to 231.355307ms 2026/09/06 02:49:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:49:59 DEBUG : pacer: Reducing sleep to 109.688805ms 2026/09/06 02:49:59 DEBUG : pacer: Reducing sleep to 92.43795ms 2026/09/06 02:49:59 DEBUG : pacer: Reducing sleep to 155.737006ms 2026/09/06 02:50:00 DEBUG : pacer: Reducing sleep to 36.867244ms 2026/09/06 02:50:04 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/06 02:50:08 DEBUG : pacer: Reducing sleep to 93.59913ms 2026/09/06 02:50:08 DEBUG : pacer: Reducing sleep to 222.404163ms 2026/09/06 02:50:09 DEBUG : pacer: Reducing sleep to 205.597586ms 2026/09/06 02:50:09 DEBUG : pacer: Reducing sleep to 227.977306ms 2026/09/06 02:50:13 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:50:13 DEBUG : pacer: Reducing sleep to 103.815794ms 2026/09/06 02:50:13 DEBUG : pacer: Reducing sleep to 93.713638ms 2026/09/06 02:50:13 DEBUG : pacer: Reducing sleep to 164.592106ms 2026/09/06 02:50:13 DEBUG : pacer: Reducing sleep to 230.016229ms 2026/09/06 02:50:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/06 02:50:22 DEBUG : pacer: Reducing sleep to 90.956793ms 2026/09/06 02:50:22 DEBUG : pacer: Reducing sleep to 218.317498ms 2026/09/06 02:50:23 DEBUG : pacer: Reducing sleep to 171.81245ms 2026/09/06 02:50:23 DEBUG : pacer: Reducing sleep to 227.764894ms 2026/09/06 02:50:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:50:27 DEBUG : pacer: Reducing sleep to 109.23588ms 2026/09/06 02:50:27 DEBUG : pacer: Reducing sleep to 97.498795ms 2026/09/06 02:50:27 DEBUG : pacer: Reducing sleep to 151.869393ms 2026/09/06 02:50:27 DEBUG : pacer: Reducing sleep to 224.719826ms 2026/09/06 02:50:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/06 02:50:31 DEBUG : zoho root 'rclone-test-dosihib4gusa': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/06 02:50:31 DEBUG : pacer: Reducing sleep to 126.888702ms 2026/09/06 02:50:32 DEBUG : pacer: Reducing sleep to 57.104208ms 2026/09/06 02:50:32 DEBUG : pacer: Reducing sleep to 149.067431ms 2026/09/06 02:50:32 DEBUG : pacer: Reducing sleep to 178.131032ms 2026/09/06 02:50:32 DEBUG : pacer: Reducing sleep to 262.375158ms 2026/09/06 02:50:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:50:33 DEBUG : pacer: Reducing sleep to 106.433767ms 2026/09/06 02:50:34 DEBUG : pacer: Reducing sleep to 56.621999ms 2026/09/06 02:50:34 DEBUG : pacer: Reducing sleep to 80.796105ms 2026/09/06 02:50:34 DEBUG : pacer: Reducing sleep to 195.503265ms 2026/09/06 02:50:34 DEBUG : pacer: Reducing sleep to 275.916338ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/06 02:50:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:50:35 DEBUG : pacer: Reducing sleep to 81.775245ms 2026/09/06 02:50:35 DEBUG : pacer: Reducing sleep to 56.576079ms 2026/09/06 02:50:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:50:35 DEBUG : pacer: Reducing sleep to 113.246152ms 2026/09/06 02:50:35 DEBUG : pacer: Reducing sleep to 235.446933ms 2026/09/06 02:50:35 DEBUG : pacer: Reducing sleep to 275.818005ms 2026/09/06 02:50:36 DEBUG : pacer: Reducing sleep to 200.623503ms 2026/09/06 02:50:36 DEBUG : pacer: Reducing sleep to 88.204411ms 2026/09/06 02:50:36 DEBUG : pacer: Reducing sleep to 63.831145ms 2026/09/06 02:50:40 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/06 02:50:45 DEBUG : pacer: Reducing sleep to 86.191713ms 2026/09/06 02:50:45 DEBUG : pacer: Reducing sleep to 203.04054ms 2026/09/06 02:50:45 DEBUG : pacer: Reducing sleep to 187.458966ms 2026/09/06 02:50:45 DEBUG : pacer: Reducing sleep to 220.426247ms 2026/09/06 02:50:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:50:49 DEBUG : pacer: Reducing sleep to 109.092459ms 2026/09/06 02:50:49 DEBUG : pacer: Reducing sleep to 104.102434ms 2026/09/06 02:50:49 DEBUG : pacer: Reducing sleep to 164.866264ms 2026/09/06 02:50:50 DEBUG : pacer: Reducing sleep to 225.990323ms 2026/09/06 02:50:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/06 02:50:58 DEBUG : pacer: Reducing sleep to 77.181691ms 2026/09/06 02:50:58 DEBUG : pacer: Reducing sleep to 204.768213ms 2026/09/06 02:50:59 DEBUG : pacer: Reducing sleep to 155.044137ms 2026/09/06 02:50:59 DEBUG : pacer: Reducing sleep to 241.239659ms 2026/09/06 02:51:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:51:03 DEBUG : pacer: Reducing sleep to 107.642421ms 2026/09/06 02:51:03 DEBUG : pacer: Reducing sleep to 80.831642ms 2026/09/06 02:51:03 DEBUG : pacer: Reducing sleep to 143.955097ms 2026/09/06 02:51:03 DEBUG : pacer: Reducing sleep to 225.664778ms 2026/09/06 02:51:08 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/06 02:51:12 DEBUG : pacer: Reducing sleep to 90.339575ms 2026/09/06 02:51:12 DEBUG : pacer: Reducing sleep to 218.449687ms 2026/09/06 02:51:12 DEBUG : pacer: Reducing sleep to 201.009021ms 2026/09/06 02:51:13 DEBUG : pacer: Reducing sleep to 220.308554ms 2026/09/06 02:51:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:51:17 DEBUG : pacer: Reducing sleep to 118.559974ms 2026/09/06 02:51:17 DEBUG : pacer: Reducing sleep to 97.77261ms 2026/09/06 02:51:17 DEBUG : pacer: Reducing sleep to 172.326178ms 2026/09/06 02:51:17 DEBUG : pacer: Reducing sleep to 219.638697ms 2026/09/06 02:51:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/06 02:51:26 DEBUG : pacer: Reducing sleep to 102.482547ms 2026/09/06 02:51:26 DEBUG : pacer: Reducing sleep to 223.636462ms 2026/09/06 02:51:26 DEBUG : pacer: Reducing sleep to 162.203346ms 2026/09/06 02:51:26 DEBUG : pacer: Reducing sleep to 229.130292ms 2026/09/06 02:51:31 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:51:31 DEBUG : pacer: Reducing sleep to 119.755955ms 2026/09/06 02:51:31 DEBUG : pacer: Reducing sleep to 89.451529ms 2026/09/06 02:51:31 DEBUG : pacer: Reducing sleep to 156.801576ms 2026/09/06 02:51:31 DEBUG : pacer: Reducing sleep to 212.019882ms 2026/09/06 02:51:31 DEBUG : pacer: Reducing sleep to 245.078235ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/06 02:51:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:51:32 DEBUG : pacer: Reducing sleep to 101.078324ms 2026/09/06 02:51:32 DEBUG : zoho root 'rclone-test-dosihib4gusa': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/06 02:51:32 DEBUG : pacer: Reducing sleep to 74.33274ms 2026/09/06 02:51:32 DEBUG : pacer: Reducing sleep to 77.224718ms 2026/09/06 02:51:32 DEBUG : pacer: Reducing sleep to 198.972894ms 2026/09/06 02:51:32 DEBUG : pacer: Reducing sleep to 279.460257ms 2026/09/06 02:51:33 DEBUG : pacer: Reducing sleep to 253.771888ms 2026/09/06 02:51:33 DEBUG : pacer: Reducing sleep to 98.869715ms 2026/09/06 02:51:33 DEBUG : pacer: Reducing sleep to 53.695029ms 2026/09/06 02:51:33 DEBUG : pacer: Reducing sleep to 78.705094ms 2026/09/06 02:51:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/06 02:51:34 DEBUG : pacer: Reducing sleep to 100.453345ms 2026/09/06 02:51:34 DEBUG : pacer: Reducing sleep to 223.015007ms 2026/09/06 02:51:35 DEBUG : pacer: Reducing sleep to 157.73144ms 2026/09/06 02:51:35 DEBUG : pacer: Reducing sleep to 229.545605ms 2026/09/06 02:51:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:51:39 DEBUG : pacer: Reducing sleep to 118.467391ms 2026/09/06 02:51:39 DEBUG : pacer: Reducing sleep to 93.192933ms 2026/09/06 02:51:39 DEBUG : pacer: Reducing sleep to 163.276601ms 2026/09/06 02:51:39 DEBUG : pacer: Reducing sleep to 216.105207ms 2026/09/06 02:51:43 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/06 02:51:48 DEBUG : pacer: Reducing sleep to 104.676871ms 2026/09/06 02:51:48 DEBUG : pacer: Reducing sleep to 227.198035ms 2026/09/06 02:51:48 DEBUG : pacer: Reducing sleep to 162.929977ms 2026/09/06 02:51:49 DEBUG : pacer: Reducing sleep to 226.064833ms 2026/09/06 02:51:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:51:53 DEBUG : pacer: Reducing sleep to 119.981459ms 2026/09/06 02:51:53 DEBUG : pacer: Reducing sleep to 73.288666ms 2026/09/06 02:51:53 DEBUG : pacer: Reducing sleep to 168.511906ms 2026/09/06 02:51:53 DEBUG : pacer: Reducing sleep to 229.323264ms 2026/09/06 02:51:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/06 02:52:16 DEBUG : pacer: Reducing sleep to 87.759985ms 2026/09/06 02:52:16 DEBUG : pacer: Reducing sleep to 215.825509ms 2026/09/06 02:52:16 DEBUG : pacer: Reducing sleep to 228.685181ms 2026/09/06 02:52:16 DEBUG : pacer: Reducing sleep to 244.368324ms 2026/09/06 02:52:16 DEBUG : pacer: Reducing sleep to 123.224134ms 2026/09/06 02:52:17 DEBUG : pacer: Reducing sleep to 103.452917ms 2026/09/06 02:52:17 DEBUG : pacer: Reducing sleep to 97.447254ms 2026/09/06 02:52:17 DEBUG : pacer: Reducing sleep to 246.691682ms 2026/09/06 02:52:17 DEBUG : pacer: Reducing sleep to 275.364262ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/06 02:52:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:52:21 DEBUG : pacer: Reducing sleep to 17.41998ms 2026/09/06 02:52:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:52:26 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:52:26 UTC": parsing time "Sun, 06 Sep 2026 02:52:26 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:52:26 UTC" as " " 2026/09/06 02:52:26 DEBUG : pacer: Reducing sleep to 99.139213ms === 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/06 02:52:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:52:30 DEBUG : pacer: Reducing sleep to 12.256952ms 2026/09/06 02:52:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:52:34 DEBUG : zoho root 'rclone-test-dosihib4gusa': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/06 02:52:34 DEBUG : pacer: Reducing sleep to 125.774688ms 2026/09/06 02:52:34 DEBUG : pacer: Reducing sleep to 122.836377ms 2026/09/06 02:52:35 DEBUG : pacer: Reducing sleep to 237.741051ms 2026/09/06 02:52:35 DEBUG : pacer: Reducing sleep to 287.736406ms 2026/09/06 02:52:35 DEBUG : pacer: Reducing sleep to 138.168092ms 2026/09/06 02:52:35 DEBUG : pacer: Reducing sleep to 67.935509ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/06 02:52:35 DEBUG : pacer: Reducing sleep to 121.623225ms 2026/09/06 02:52:35 DEBUG : pacer: Reducing sleep to 200.220088ms 2026/09/06 02:52:36 DEBUG : pacer: Reducing sleep to 262.174986ms 2026/09/06 02:52:36 DEBUG : pacer: Reducing sleep to 207.683486ms 2026/09/06 02:52:36 DEBUG : pacer: Reducing sleep to 135.55893ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/06 02:52:36 DEBUG : pacer: Reducing sleep to 83.118384ms 2026/09/06 02:52:36 DEBUG : pacer: Reducing sleep to 122.12572ms 2026/09/06 02:52:36 DEBUG : pacer: Reducing sleep to 186.731442ms 2026/09/06 02:52:37 DEBUG : pacer: Reducing sleep to 248.929931ms 2026/09/06 02:52:37 DEBUG : pacer: Reducing sleep to 203.616212ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/06 02:52:37 DEBUG : pacer: Reducing sleep to 145.379972ms 2026/09/06 02:52:37 DEBUG : pacer: Reducing sleep to 99.76321ms 2026/09/06 02:52:37 DEBUG : pacer: Reducing sleep to 130.602551ms 2026/09/06 02:52:37 DEBUG : pacer: Reducing sleep to 189.341397ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/06 02:52:37 DEBUG : Creating backend with remote "TestZoho:" 2026/09/06 02:52:37 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/06 02:52:42 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/06 02:52:47 DEBUG : pacer: Reducing sleep to 120.619796ms 2026/09/06 02:52:47 DEBUG : pacer: Reducing sleep to 222.948301ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/06 02:52:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:52:51 DEBUG : pacer: Reducing sleep to 104.578394ms 2026/09/06 02:52:51 DEBUG : pacer: Reducing sleep to 109.123224ms 2026/09/06 02:52:52 DEBUG : pacer: Reducing sleep to 148.26328ms 2026/09/06 02:52:52 DEBUG : pacer: Reducing sleep to 228.365634ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/06 02:52:56 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/06 02:53:01 DEBUG : pacer: Reducing sleep to 109.121452ms 2026/09/06 02:53:01 DEBUG : pacer: Reducing sleep to 231.65907ms 2026/09/06 02:53:01 DEBUG : pacer: Reducing sleep to 271.852956ms 2026/09/06 02:53:01 DEBUG : pacer: Reducing sleep to 219.46155ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/06 02:53:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/06 02:53:05 DEBUG : pacer: Reducing sleep to 116.342848ms 2026/09/06 02:53:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:10 DEBUG : pacer: Reducing sleep to 103.548078ms 2026/09/06 02:53:10 DEBUG : pacer: Reducing sleep to 138.927212ms 2026/09/06 02:53:10 DEBUG : pacer: Reducing sleep to 254.607991ms 2026/09/06 02:53:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:15 DEBUG : pacer: Reducing sleep to 83.485533ms 2026/09/06 02:53:15 DEBUG : pacer: Reducing sleep to 207.54817ms 2026/09/06 02:53:15 DEBUG : pacer: Reducing sleep to 260.286633ms 2026/09/06 02:53:15 DEBUG : pacer: Reducing sleep to 264.075405ms 2026/09/06 02:53:15 DEBUG : pacer: Reducing sleep to 163.518941ms 2026/09/06 02:53:16 DEBUG : pacer: Reducing sleep to 64.838229ms 2026/09/06 02:53:16 DEBUG : pacer: Reducing sleep to 50.887081ms 2026/09/06 02:53:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:19 DEBUG : pacer: Reducing sleep to 114.839821ms 2026/09/06 02:53:19 DEBUG : pacer: Reducing sleep to 235.482628ms 2026/09/06 02:53:19 DEBUG : pacer: Reducing sleep to 281.762066ms 2026/09/06 02:53:20 DEBUG : pacer: Reducing sleep to 216.146571ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/06 02:53:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:28 DEBUG : pacer: Reducing sleep to 88.312339ms 2026/09/06 02:53:29 DEBUG : pacer: Reducing sleep to 132.466553ms 2026/09/06 02:53:29 DEBUG : pacer: Reducing sleep to 252.878926ms 2026/09/06 02:53:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:33 DEBUG : pacer: Reducing sleep to 123.018904ms 2026/09/06 02:53:33 DEBUG : pacer: Reducing sleep to 271.837649ms 2026/09/06 02:53:33 DEBUG : pacer: Reducing sleep to 253.955629ms 2026/09/06 02:53:34 DEBUG : pacer: Reducing sleep to 116.365242ms 2026/09/06 02:53:34 DEBUG : pacer: Reducing sleep to 59.892771ms 2026/09/06 02:53:34 DEBUG : Creating backend with remote "TestZoho:rclone-test-dosihib4gusa/dirToPurgeFromRoot" 2026/09/06 02:53:34 DEBUG : pacer: Reducing sleep to 112.751467ms 2026/09/06 02:53:34 DEBUG : pacer: Reducing sleep to 235.089255ms 2026/09/06 02:53:34 DEBUG : pacer: Reducing sleep to 270.926714ms 2026/09/06 02:53:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:34 DEBUG : zoho root 'rclone-test-dosihib4gusa': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/06 02:53:34 DEBUG : pacer: Reducing sleep to 121.756302ms 2026/09/06 02:53:34 DEBUG : pacer: Reducing sleep to 237.663468ms 2026/09/06 02:53:35 DEBUG : pacer: Reducing sleep to 288.238397ms 2026/09/06 02:53:35 DEBUG : pacer: Reducing sleep to 209.627641ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/06 02:53:35 DEBUG : Creating backend with remote "TestZoho:rclone-test-dosihib4gusa/hello? sausage/êé" 2026/09/06 02:53:35 DEBUG : pacer: Reducing sleep to 114.901116ms 2026/09/06 02:53:35 DEBUG : pacer: Reducing sleep to 235.025356ms 2026/09/06 02:53:35 DEBUG : pacer: Reducing sleep to 281.529358ms 2026/09/06 02:53:35 DEBUG : pacer: Reducing sleep to 218.393264ms 2026/09/06 02:53:36 DEBUG : pacer: Reducing sleep to 63.600324ms 2026/09/06 02:53:36 DEBUG : pacer: Reducing sleep to 53.567986ms 2026/09/06 02:53:36 DEBUG : pacer: Reducing sleep to 116.954487ms 2026/09/06 02:53:36 DEBUG : pacer: Reducing sleep to 244.635837ms 2026/09/06 02:53:36 DEBUG : pacer: Reducing sleep to 250.589826ms 2026/09/06 02:53:36 DEBUG : pacer: Reducing sleep to 212.854507ms 2026/09/06 02:53:37 DEBUG : pacer: Reducing sleep to 70.387682ms 2026/09/06 02:53:37 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:37 DEBUG : pacer: Reducing sleep to 119.230622ms 2026/09/06 02:53:37 DEBUG : pacer: Reducing sleep to 233.498741ms 2026/09/06 02:53:37 DEBUG : pacer: Reducing sleep to 288.112667ms 2026/09/06 02:53:37 DEBUG : pacer: Reducing sleep to 190.35144ms 2026/09/06 02:53:38 DEBUG : pacer: Reducing sleep to 96.771499ms 2026/09/06 02:53:38 DEBUG : pacer: Reducing sleep to 55.232537ms 2026/09/06 02:53:38 DEBUG : pacer: Reducing sleep to 140.542717ms 2026/09/06 02:53:38 DEBUG : pacer: Reducing sleep to 245.738096ms 2026/09/06 02:53:38 DEBUG : pacer: Reducing sleep to 278.39481ms 2026/09/06 02:53:38 DEBUG : pacer: Reducing sleep to 179.094948ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/06 02:53:38 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:39 DEBUG : pacer: Reducing sleep to 103.722938ms 2026/09/06 02:53:39 DEBUG : pacer: Reducing sleep to 217.70033ms 2026/09/06 02:53:39 DEBUG : pacer: Reducing sleep to 287.525178ms 2026/09/06 02:53:42 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:42 DEBUG : pacer: Reducing sleep to 120.719954ms 2026/09/06 02:53:42 DEBUG : pacer: Reducing sleep to 56.220256ms 2026/09/06 02:53:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:46 DEBUG : pacer: Reducing sleep to 75.337998ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/06 02:53:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:53:51 DEBUG : pacer: Reducing sleep to 97.530823ms 2026/09/06 02:53:51 DEBUG : pacer: Reducing sleep to 189.024961ms 2026/09/06 02:53:51 DEBUG : pacer: Reducing sleep to 272.117683ms 2026/09/06 02:53:51 DEBUG : pacer: Reducing sleep to 262.185705ms 2026/09/06 02:53:52 DEBUG : pacer: Reducing sleep to 150.174781ms 2026/09/06 02:53:52 DEBUG : pacer: Reducing sleep to 54.861655ms 2026/09/06 02:53:52 DEBUG : pacer: Reducing sleep to 70.755364ms 2026/09/06 02:53:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:05 DEBUG : pacer: Reducing sleep to 102.348162ms 2026/09/06 02:54:05 DEBUG : pacer: Reducing sleep to 205.025227ms 2026/09/06 02:54:05 DEBUG : pacer: Reducing sleep to 247.036563ms 2026/09/06 02:54:06 DEBUG : pacer: Reducing sleep to 200.470488ms 2026/09/06 02:54:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:10 DEBUG : pacer: Reducing sleep to 123.567201ms 2026/09/06 02:54:10 DEBUG : pacer: Reducing sleep to 273.158623ms 2026/09/06 02:54:10 DEBUG : pacer: Reducing sleep to 288.62725ms 2026/09/06 02:54:10 DEBUG : pacer: Reducing sleep to 190.879317ms 2026/09/06 02:54:11 DEBUG : pacer: Reducing sleep to 67.885903ms 2026/09/06 02:54:11 DEBUG : pacer: Reducing sleep to 41.334071ms 2026/09/06 02:54:11 DEBUG : pacer: Reducing sleep to 117.201039ms 2026/09/06 02:54:11 DEBUG : pacer: Reducing sleep to 185.380341ms 2026/09/06 02:54:11 DEBUG : pacer: Reducing sleep to 266.381512ms 2026/09/06 02:54:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:14 DEBUG : pacer: Reducing sleep to 121.917264ms 2026/09/06 02:54:14 DEBUG : pacer: Reducing sleep to 288.461781ms 2026/09/06 02:54:15 DEBUG : pacer: Reducing sleep to 104.186067ms 2026/09/06 02:54:15 DEBUG : pacer: Reducing sleep to 110.811845ms 2026/09/06 02:54:15 DEBUG : pacer: Reducing sleep to 165.295424ms 2026/09/06 02:54:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:19 DEBUG : pacer: Reducing sleep to 112.485904ms 2026/09/06 02:54:19 DEBUG : pacer: Reducing sleep to 127.936637ms 2026/09/06 02:54:19 DEBUG : pacer: Reducing sleep to 220.644657ms 2026/09/06 02:54:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:24 DEBUG : pacer: Reducing sleep to 127.026443ms 2026/09/06 02:54:24 DEBUG : pacer: Reducing sleep to 258.515112ms 2026/09/06 02:54:24 DEBUG : pacer: Reducing sleep to 352.904585ms 2026/09/06 02:54:24 DEBUG : pacer: Reducing sleep to 251.498035ms 2026/09/06 02:54:24 DEBUG : pacer: Reducing sleep to 73.51329ms 2026/09/06 02:54:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:25 DEBUG : pacer: Reducing sleep to 114.616107ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/06 02:54:25 DEBUG : Creating backend with remote "TestZoho:rclone-test-niqelud0yuva" 2026/09/06 02:54:25 DEBUG : pacer: Reducing sleep to 19.847293ms 2026/09/06 02:54:25 DEBUG : pacer: Reducing sleep to 60.157579ms 2026/09/06 02:54:25 DEBUG : pacer: Reducing sleep to 74.701098ms 2026/09/06 02:54:25 DEBUG : pacer: Reducing sleep to 198.155716ms 2026/09/06 02:54:26 DEBUG : pacer: Reducing sleep to 214.762061ms 2026/09/06 02:54:26 DEBUG : pacer: Reducing sleep to 257.063066ms 2026/09/06 02:54:26 DEBUG : pacer: Reducing sleep to 158.687678ms 2026/09/06 02:54:26 DEBUG : pacer: Reducing sleep to 98.754221ms 2026/09/06 02:54:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:26 DEBUG : pacer: Reducing sleep to 73.622392ms 2026/09/06 02:54:26 DEBUG : pacer: Reducing sleep to 152.54019ms 2026/09/06 02:54:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:28 DEBUG : pacer: Reducing sleep to 118.694979ms 2026/09/06 02:54:28 DEBUG : pacer: Reducing sleep to 183.651956ms 2026/09/06 02:54:28 DEBUG : pacer: Reducing sleep to 238.562791ms 2026/09/06 02:54:29 DEBUG : pacer: Reducing sleep to 201.754941ms 2026/09/06 02:54:31 DEBUG : pacer: Reducing sleep to 35.36642ms 2026/09/06 02:54:31 DEBUG : pacer: Reducing sleep to 154.325158ms 2026/09/06 02:54:31 DEBUG : pacer: Reducing sleep to 268.915187ms 2026/09/06 02:54:31 DEBUG : pacer: Reducing sleep to 287.319035ms 2026/09/06 02:54:31 DEBUG : pacer: Reducing sleep to 175.507686ms 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 49.606462ms 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 113.583178ms 2026/09/06 02:54:32 DEBUG : zoho root 'rclone-test-niqelud0yuva': Purge remote 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 134.307519ms 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 161.855204ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 119.768395ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 227.041558ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 285.63088ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/06 02:54:32 DEBUG : pacer: Reducing sleep to 213.429858ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/06 02:54:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/09/06 02:54:38 DEBUG : zoho root 'rclone-test-dosihib4gusa': folder listing limiter sweep: evicted 6 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/06 02:55:01 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:01 UTC": parsing time "Sun, 06 Sep 2026 02:55:01 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:01 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/06 02:55:06 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:06 UTC": parsing time "Sun, 06 Sep 2026 02:55:06 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:06 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/06 02:55:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:11 UTC": parsing time "Sun, 06 Sep 2026 02:55:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:11 UTC" as " " 2026/09/06 02:55:11 DEBUG : pacer: Reducing sleep to 10.519619ms 2026/09/06 02:55:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:11 UTC": parsing time "Sun, 06 Sep 2026 02:55:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:11 UTC" as " " 2026/09/06 02:55:11 DEBUG : pacer: Reducing sleep to 6.303465ms 2026/09/06 02:55:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:11 UTC": parsing time "Sun, 06 Sep 2026 02:55:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:11 UTC" as " " 2026/09/06 02:55:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:55:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:11 UTC": parsing time "Sun, 06 Sep 2026 02:55:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:11 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/06 02:55:15 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:15 UTC": parsing time "Sun, 06 Sep 2026 02:55:15 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:15 UTC" as " " 2026/09/06 02:55:15 DEBUG : pacer: Reducing sleep to 4.449548ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/06 02:55:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:55:20 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:20 UTC": parsing time "Sun, 06 Sep 2026 02:55:20 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:20 UTC" as " " 2026/09/06 02:55:24 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:24 UTC": parsing time "Sun, 06 Sep 2026 02:55:24 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:24 UTC" as " " 2026/09/06 02:55:24 DEBUG : pacer: Reducing sleep to 12.719192ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/06 02:55:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:25 UTC": parsing time "Sun, 06 Sep 2026 02:55:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:25 UTC" as " " 2026/09/06 02:55:25 DEBUG : pacer: Reducing sleep to 8.196971ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/06 02:55:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:25 UTC": parsing time "Sun, 06 Sep 2026 02:55:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:25 UTC" as " " 2026/09/06 02:55:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/06 02:55:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:25 UTC": parsing time "Sun, 06 Sep 2026 02:55:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:25 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/06 02:55:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:25 UTC": parsing time "Sun, 06 Sep 2026 02:55:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:25 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/06 02:55:29 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:29 UTC": parsing time "Sun, 06 Sep 2026 02:55:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:29 UTC" as " " 2026/09/06 02:55:29 DEBUG : pacer: Reducing sleep to 7.751051ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/06 02:55:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:55:34 DEBUG : pacer: Reducing sleep to 121.073943ms 2026/09/06 02:55:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 06 Sep 2026 02:55:34 UTC": parsing time "Sun, 06 Sep 2026 02:55:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 06 Sep 2026 02:55:34 UTC" as " " 2026/09/06 02:55:34 DEBUG : pacer: Reducing sleep to 123.789807ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/06 02:55:34 DEBUG : pacer: Reducing sleep to 237.225134ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/06 02:55:34 DEBUG : Creating backend with remote "TestZoho:rclone-test-dosihib4gusa/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/06 02:55:34 DEBUG : pacer: Reducing sleep to 91.037955ms 2026/09/06 02:55:34 DEBUG : pacer: Reducing sleep to 171.692142ms 2026/09/06 02:55:35 DEBUG : pacer: Reducing sleep to 259.204017ms 2026/09/06 02:55:35 DEBUG : pacer: Reducing sleep to 246.296801ms 2026/09/06 02:55:35 DEBUG : pacer: Reducing sleep to 158.271276ms 2026/09/06 02:55:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:55:35 DEBUG : pacer: Reducing sleep to 92.401039ms 2026/09/06 02:55:35 DEBUG : pacer: Reducing sleep to 173.186544ms 2026/09/06 02:55:36 DEBUG : pacer: Reducing sleep to 252.512663ms 2026/09/06 02:55:36 DEBUG : pacer: Reducing sleep to 238.735714ms 2026/09/06 02:55:36 DEBUG : pacer: Reducing sleep to 161.805881ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/06 02:55:36 DEBUG : pacer: Reducing sleep to 83.435629ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/06 02:55:36 DEBUG : Creating backend with remote "TestZoho:rclone-test-dosihib4gusa/not found.txt" 2026/09/06 02:55:37 DEBUG : pacer: Reducing sleep to 89.407697ms 2026/09/06 02:55:37 DEBUG : pacer: Reducing sleep to 88.175896ms 2026/09/06 02:55:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:55:41 DEBUG : zoho root 'rclone-test-dosihib4gusa/not found.txt': folder listing limiter sweep: evicted 3 of 9 idle limiters 2026/09/06 02:55:46 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-dosihib4gusa" from "TestZoho:rclone-test-dosihib4gusa" 2026/09/06 02:55:46 DEBUG : Creating backend with remote "TestZoho:" 2026/09/06 02:55:46 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/06 02:55:51 DEBUG : pacer: Reducing sleep to 109.762032ms 2026/09/06 02:55:51 DEBUG : pacer: Reducing sleep to 227.94759ms 2026/09/06 02:55:51 DEBUG : pacer: Reducing sleep to 275.496474ms 2026/09/06 02:55:51 DEBUG : pacer: Reducing sleep to 217.162726ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/06 02:55:55 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/06 02:56:00 DEBUG : pacer: Reducing sleep to 56.040147ms 2026/09/06 02:56:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:56:09 DEBUG : pacer: Reducing sleep to 109.551494ms 2026/09/06 02:56:09 DEBUG : pacer: Reducing sleep to 225.643719ms 2026/09/06 02:56:09 DEBUG : pacer: Reducing sleep to 274.829614ms 2026/09/06 02:56:09 DEBUG : pacer: Reducing sleep to 223.357434ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/06 02:56:10 DEBUG : pacer: Reducing sleep to 63.763145ms 2026/09/06 02:56:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:56:14 DEBUG : pacer: Reducing sleep to 60.048084ms 2026/09/06 02:56:14 DEBUG : pacer: Reducing sleep to 176.746183ms 2026/09/06 02:56:14 DEBUG : pacer: Reducing sleep to 261.518456ms 2026/09/06 02:56:14 DEBUG : pacer: Reducing sleep to 272.181758ms === 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/06 02:56:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:56:23 DEBUG : pacer: Reducing sleep to 118.257714ms 2026/09/06 02:56:23 DEBUG : pacer: Reducing sleep to 221.167477ms 2026/09/06 02:56:23 DEBUG : pacer: Reducing sleep to 282.223036ms 2026/09/06 02:56:23 DEBUG : pacer: Reducing sleep to 216.332119ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/06 02:56:24 DEBUG : pacer: Reducing sleep to 109.348554ms === 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/06 02:56:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:56:27 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/06 02:56:27 DEBUG : pacer: Rate limited, increasing sleep to 1.807987886s 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/06 02:56:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:56:32 DEBUG : pacer: Reducing sleep to 104.479015ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/06 02:56:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/06 02:56:34 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=cgyo2a9839c86332d4c98accb0014e6d23a59": write tcp: unexpected EOF) 2026/09/06 02:56:34 DEBUG : pacer: Rate limited, increasing sleep to 1.565205782s 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=cgyo2a9839c86332d4c98accb0014e6d23a59": write tcp: unexpected EOF 2026/09/06 02:56:34 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/06 02:56:35 DEBUG : pacer: Reducing sleep to 135.611794ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n