"go test -v -timeout 2h0m0s -remote TestZoho: -verbose" - Starting (try 1/5) === RUN TestFolderListLimiter --- PASS: TestFolderListLimiter (0.00s) === RUN TestFolderWindowLimiterShape --- PASS: TestFolderWindowLimiterShape (0.00s) === RUN TestFolderWindowLimiterIdleResume --- PASS: TestFolderWindowLimiterIdleResume (0.00s) === RUN TestFolderWindowLimiterClamps --- PASS: TestFolderWindowLimiterClamps (0.00s) === RUN TestFolderListLimiterConcurrent --- PASS: TestFolderListLimiterConcurrent (0.00s) === RUN TestTPSMinSleep --- PASS: TestTPSMinSleep (0.00s) === RUN TestShouldRetry === RUN TestShouldRetry/429_honours_Retry-After 2026/10/10 03:16:56 NOTICE: zoho root '': Too many requests: Trying again in 6 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_without_Retry-After_falls_back_to_60s 2026/10/10 03:16:56 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_with_unparseable_Retry-After_falls_back 2026/10/10 03:16:56 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/10/10 03:16:56 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/10/10 03:16:56 ERROR : zoho: missing OAuth Scope. Run rclone config reconnect to fix this issue. === RUN TestShouldRetry/401_expired_token_retries === RUN TestShouldRetry/401_R008_deleted_folder_is_not_retried === RUN TestShouldRetry/cancelled_context_aborts --- PASS: TestShouldRetry (0.00s) --- PASS: TestShouldRetry/429_honours_Retry-After (0.00s) --- PASS: TestShouldRetry/429_without_Retry-After_falls_back_to_60s (0.00s) --- PASS: TestShouldRetry/429_with_unparseable_Retry-After_falls_back (0.00s) --- PASS: TestShouldRetry/401_missing_scope_aborts (0.00s) --- PASS: TestShouldRetry/401_expired_token_retries (0.00s) --- PASS: TestShouldRetry/401_R008_deleted_folder_is_not_retried (0.00s) --- PASS: TestShouldRetry/cancelled_context_aborts (0.00s) === RUN TestIsMissingResourceErr --- PASS: TestIsMissingResourceErr (0.00s) === RUN TestThrottleEpisode 2026/10/10 03:16:56 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/10/10 03:16:56 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleStateConcurrent (0.00s) === RUN TestConfigRootFolderID === RUN TestConfigRootFolderID/SetRootAsksBeforeChanging === RUN TestConfigRootFolderID/EmptyRootGoesToEdition === RUN TestConfigRootFolderID/KeepRootOnNo === RUN TestConfigRootFolderID/ChangeRootOnYes --- PASS: TestConfigRootFolderID (0.00s) --- PASS: TestConfigRootFolderID/SetRootAsksBeforeChanging (0.00s) --- PASS: TestConfigRootFolderID/EmptyRootGoesToEdition (0.00s) --- PASS: TestConfigRootFolderID/KeepRootOnNo (0.00s) --- PASS: TestConfigRootFolderID/ChangeRootOnYes (0.00s) === RUN TestIntegration fstests.go:464: Using remote "TestZoho:" 2026/10/10 03:16:56 DEBUG : Creating backend with remote "TestZoho:rclone-test-jicubec9foja" 2026/10/10 03:16:56 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/10 03:16:56 DEBUG : zoho root 'rclone-test-jicubec9foja': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/10/10 03:16:56 DEBUG : TestZoho: Token expired 2026/10/10 03:16:56 DEBUG : TestZoho: No updated token found in the config file 2026/10/10 03:16:56 DEBUG : TestZoho: Token refresh successful 2026/10/10 03:16:56 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/10/10 03:16:56 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:505: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:533: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/10/10 03:16:57 DEBUG : pacer: Reducing sleep to 2.675334ms 2026/10/10 03:16:58 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:16:58 DEBUG : pacer: Reducing sleep to 124.036403ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRootCannotChangeHost 2026/10/10 03:16:58 DEBUG : Creating backend with remote "TestZoho://127.0.0.1:44721/" 2026/10/10 03:16:58 DEBUG : pacer: Reducing sleep to 17.697816ms 2026/10/10 03:17:03 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:17:03 DEBUG : Creating backend with remote "TestZoho://127.0.0.1:44721/rclone-test-jicubec9foja/" 2026/10/10 03:17:17 DEBUG : Creating backend with remote "TestZoho://user:pass@127.0.0.1:44721/" === RUN TestIntegration/FsRmdirEmpty 2026/10/10 03:17:30 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:17:31 DEBUG : pacer: Reducing sleep to 101.087366ms === RUN TestIntegration/FsMkdir 2026/10/10 03:17:35 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:17:35 DEBUG : pacer: Reducing sleep to 15.717262ms 2026/10/10 03:17:35 DEBUG : pacer: Reducing sleep to 138.849807ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/10/10 03:17:35 INFO : dir/subdir: Making directory 2026/10/10 03:17:35 DEBUG : pacer: Reducing sleep to 260.766087ms 2026/10/10 03:17:35 DEBUG : pacer: Reducing sleep to 256.115325ms 2026/10/10 03:17:36 DEBUG : pacer: Reducing sleep to 171.645489ms 2026/10/10 03:17:36 DEBUG : pacer: Reducing sleep to 71.361376ms 2026/10/10 03:17:36 DEBUG : pacer: Reducing sleep to 74.089595ms 2026/10/10 03:17:36 DEBUG : pacer: Reducing sleep to 188.142348ms 2026/10/10 03:17:36 DEBUG : pacer: Reducing sleep to 281.530028ms 2026/10/10 03:17:36 INFO : dir/subdir: Removing directory 2026/10/10 03:17:36 DEBUG : pacer: Reducing sleep to 256.357081ms 2026/10/10 03:17:37 DEBUG : pacer: Reducing sleep to 120.55872ms 2026/10/10 03:17:37 DEBUG : pacer: Reducing sleep to 53.954192ms 2026/10/10 03:17:37 DEBUG : pacer: Reducing sleep to 105.63981ms 2026/10/10 03:17:37 INFO : dir: Removing directory 2026/10/10 03:17:37 DEBUG : pacer: Reducing sleep to 212.081795ms 2026/10/10 03:17:37 DEBUG : pacer: Reducing sleep to 261.910917ms 2026/10/10 03:17:37 DEBUG : pacer: Reducing sleep to 231.672668ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/10/10 03:17:38 DEBUG : pacer: Reducing sleep to 128.612223ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/10/10 03:17:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/10/10 03:17:47 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:779: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/10/10 03:17:56 DEBUG : pacer: Reducing sleep to 96.740379ms 2026/10/10 03:17:56 DEBUG : pacer: Reducing sleep to 220.134628ms 2026/10/10 03:17:56 DEBUG : pacer: Reducing sleep to 45.917699ms 2026/10/10 03:17:56 DEBUG : zoho root 'rclone-test-jicubec9foja': folder listing limiter sweep: evicted 0 of 6 idle limiters 2026/10/10 03:17:56 DEBUG : pacer: Reducing sleep to 163.417439ms 2026/10/10 03:18:01 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:18:01 DEBUG : pacer: Reducing sleep to 110.976575ms 2026/10/10 03:18:01 DEBUG : pacer: Reducing sleep to 156.64509ms 2026/10/10 03:18:01 DEBUG : pacer: Reducing sleep to 226.631005ms 2026/10/10 03:18:01 DEBUG : pacer: Reducing sleep to 226.308633ms 2026/10/10 03:18:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:779: testing "." 2026/10/10 03:18:10 DEBUG : pacer: Reducing sleep to 97.414028ms 2026/10/10 03:18:10 DEBUG : pacer: Reducing sleep to 222.408087ms 2026/10/10 03:18:10 DEBUG : pacer: Reducing sleep to 182.078079ms 2026/10/10 03:18:10 DEBUG : pacer: Reducing sleep to 227.663136ms 2026/10/10 03:18:14 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:18:14 DEBUG : pacer: Reducing sleep to 117.409439ms 2026/10/10 03:18:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:18:15 DEBUG : pacer: Reducing sleep to 108.585941ms 2026/10/10 03:18:15 DEBUG : pacer: Reducing sleep to 214.379549ms 2026/10/10 03:18:19 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:779: testing ".." 2026/10/10 03:18:24 DEBUG : pacer: Reducing sleep to 96.20746ms 2026/10/10 03:18:24 DEBUG : pacer: Reducing sleep to 217.767586ms 2026/10/10 03:18:24 DEBUG : pacer: Reducing sleep to 132.95871ms 2026/10/10 03:18:24 DEBUG : pacer: Reducing sleep to 232.233932ms 2026/10/10 03:18:28 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:18:28 DEBUG : pacer: Reducing sleep to 113.446446ms 2026/10/10 03:18:28 DEBUG : pacer: Reducing sleep to 94.069695ms 2026/10/10 03:18:29 DEBUG : pacer: Reducing sleep to 161.654547ms 2026/10/10 03:18:29 DEBUG : pacer: Reducing sleep to 226.339673ms 2026/10/10 03:18:33 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:779: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/10/10 03:18:38 DEBUG : pacer: Reducing sleep to 82.518534ms 2026/10/10 03:18:38 DEBUG : pacer: Reducing sleep to 184.839096ms 2026/10/10 03:18:38 DEBUG : pacer: Reducing sleep to 191.320313ms 2026/10/10 03:18:38 DEBUG : pacer: Reducing sleep to 261.656968ms 2026/10/10 03:18:38 DEBUG : pacer: Reducing sleep to 232.793806ms 2026/10/10 03:18:38 DEBUG : pacer: Reducing sleep to 141.770264ms 2026/10/10 03:18:39 DEBUG : pacer: Reducing sleep to 67.661196ms 2026/10/10 03:18:39 DEBUG : pacer: Reducing sleep to 100.301872ms 2026/10/10 03:18:39 DEBUG : pacer: Reducing sleep to 187.332055ms 2026/10/10 03:18:39 DEBUG : pacer: Reducing sleep to 267.912281ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:779: testing " leading space" 2026/10/10 03:18:39 DEBUG : pacer: Reducing sleep to 242.232348ms 2026/10/10 03:18:39 DEBUG : pacer: Reducing sleep to 119.626139ms 2026/10/10 03:18:40 DEBUG : pacer: Reducing sleep to 42.900522ms 2026/10/10 03:18:40 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:18:40 DEBUG : pacer: Reducing sleep to 115.373474ms 2026/10/10 03:18:40 DEBUG : pacer: Reducing sleep to 227.099754ms 2026/10/10 03:18:40 DEBUG : pacer: Reducing sleep to 284.288406ms 2026/10/10 03:18:40 DEBUG : pacer: Reducing sleep to 209.141459ms 2026/10/10 03:18:41 DEBUG : pacer: Reducing sleep to 104.773845ms 2026/10/10 03:18:41 DEBUG : pacer: Reducing sleep to 49.232814ms 2026/10/10 03:18:41 DEBUG : pacer: Reducing sleep to 127.505242ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:779: testing "~leading tilde" 2026/10/10 03:18:45 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:18:46 DEBUG : pacer: Reducing sleep to 65.286079ms 2026/10/10 03:18:46 DEBUG : pacer: Reducing sleep to 181.972122ms 2026/10/10 03:18:46 DEBUG : pacer: Reducing sleep to 198.355784ms 2026/10/10 03:18:46 DEBUG : pacer: Reducing sleep to 269.761377ms 2026/10/10 03:18:50 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:18:50 DEBUG : pacer: Reducing sleep to 116.00728ms 2026/10/10 03:18:50 DEBUG : pacer: Reducing sleep to 48.18393ms 2026/10/10 03:18:50 DEBUG : pacer: Reducing sleep to 112.579758ms 2026/10/10 03:18:51 DEBUG : pacer: Reducing sleep to 217.958643ms 2026/10/10 03:18:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:779: testing "␍leading CR" 2026/10/10 03:18:59 DEBUG : pacer: Reducing sleep to 96.532322ms 2026/10/10 03:18:59 DEBUG : zoho root 'rclone-test-jicubec9foja': folder listing limiter sweep: evicted 4 of 11 idle limiters 2026/10/10 03:18:59 DEBUG : pacer: Reducing sleep to 218.845308ms 2026/10/10 03:19:00 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:19:00 DEBUG : pacer: Reducing sleep to 117.879095ms 2026/10/10 03:19:04 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:19:04 DEBUG : pacer: Reducing sleep to 113.540543ms 2026/10/10 03:19:04 DEBUG : pacer: Reducing sleep to 205.199925ms 2026/10/10 03:19:04 DEBUG : pacer: Reducing sleep to 272.620283ms 2026/10/10 03:19:04 DEBUG : pacer: Reducing sleep to 221.564426ms 2026/10/10 03:19:09 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:779: testing "␊leading LF" 2026/10/10 03:19:13 DEBUG : pacer: Reducing sleep to 95.130425ms 2026/10/10 03:19:13 DEBUG : pacer: Reducing sleep to 217.112515ms 2026/10/10 03:19:13 DEBUG : pacer: Reducing sleep to 191.134826ms 2026/10/10 03:19:14 DEBUG : pacer: Reducing sleep to 215.605233ms 2026/10/10 03:19:18 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:19:18 DEBUG : pacer: Reducing sleep to 115.110888ms 2026/10/10 03:19:18 DEBUG : pacer: Reducing sleep to 110.568668ms 2026/10/10 03:19:18 DEBUG : pacer: Reducing sleep to 169.011955ms 2026/10/10 03:19:18 DEBUG : pacer: Reducing sleep to 219.555939ms 2026/10/10 03:19:22 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:779: testing "␉leading HT" 2026/10/10 03:19:27 DEBUG : pacer: Reducing sleep to 93.913325ms 2026/10/10 03:19:27 DEBUG : pacer: Reducing sleep to 216.312031ms 2026/10/10 03:19:27 DEBUG : pacer: Reducing sleep to 115.90129ms 2026/10/10 03:19:27 DEBUG : pacer: Reducing sleep to 233.823154ms 2026/10/10 03:19:32 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:19:32 DEBUG : pacer: Reducing sleep to 111.54836ms 2026/10/10 03:19:32 DEBUG : pacer: Reducing sleep to 93.175556ms 2026/10/10 03:19:32 DEBUG : pacer: Reducing sleep to 168.927812ms 2026/10/10 03:19:32 DEBUG : pacer: Reducing sleep to 225.955789ms 2026/10/10 03:19:36 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:779: testing "␋leading VT" 2026/10/10 03:19:36 DEBUG : pacer: Reducing sleep to 119.283094ms 2026/10/10 03:19:37 DEBUG : pacer: Reducing sleep to 83.472356ms 2026/10/10 03:19:37 DEBUG : pacer: Reducing sleep to 153.846149ms 2026/10/10 03:19:37 DEBUG : pacer: Reducing sleep to 145.808948ms 2026/10/10 03:19:37 DEBUG : pacer: Reducing sleep to 248.582041ms 2026/10/10 03:19:38 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:19:38 DEBUG : pacer: Reducing sleep to 118.21109ms 2026/10/10 03:19:38 DEBUG : pacer: Reducing sleep to 78.390991ms 2026/10/10 03:19:38 DEBUG : pacer: Reducing sleep to 139.26093ms 2026/10/10 03:19:38 DEBUG : pacer: Reducing sleep to 220.517538ms 2026/10/10 03:19:39 DEBUG : pacer: Reducing sleep to 255.772696ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:779: testing ".leading dot" 2026/10/10 03:19:39 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:19:39 DEBUG : pacer: Reducing sleep to 101.340328ms 2026/10/10 03:19:40 DEBUG : pacer: Reducing sleep to 48.525124ms 2026/10/10 03:19:40 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:19:40 DEBUG : pacer: Reducing sleep to 119.342356ms 2026/10/10 03:19:40 DEBUG : pacer: Reducing sleep to 234.989516ms 2026/10/10 03:19:40 DEBUG : pacer: Reducing sleep to 284.000674ms 2026/10/10 03:19:40 DEBUG : pacer: Reducing sleep to 198.55803ms 2026/10/10 03:19:41 DEBUG : pacer: Reducing sleep to 101.30141ms 2026/10/10 03:19:41 DEBUG : pacer: Reducing sleep to 50.767422ms 2026/10/10 03:19:45 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:779: testing "trailing space " 2026/10/10 03:19:49 DEBUG : pacer: Reducing sleep to 94.532803ms 2026/10/10 03:19:49 DEBUG : pacer: Reducing sleep to 216.924607ms 2026/10/10 03:19:50 DEBUG : pacer: Reducing sleep to 200.01821ms 2026/10/10 03:19:50 DEBUG : pacer: Reducing sleep to 227.713142ms 2026/10/10 03:19:54 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:19:54 DEBUG : pacer: Reducing sleep to 113.086665ms 2026/10/10 03:19:54 DEBUG : pacer: Reducing sleep to 91.777604ms 2026/10/10 03:19:54 DEBUG : pacer: Reducing sleep to 154.778113ms 2026/10/10 03:19:54 DEBUG : pacer: Reducing sleep to 219.263587ms 2026/10/10 03:19:59 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:779: testing "trailing CR␍" 2026/10/10 03:20:03 DEBUG : pacer: Reducing sleep to 94.012711ms 2026/10/10 03:20:03 DEBUG : zoho root 'rclone-test-jicubec9foja': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/10/10 03:20:03 DEBUG : pacer: Reducing sleep to 215.568034ms 2026/10/10 03:20:03 DEBUG : pacer: Reducing sleep to 123.905502ms 2026/10/10 03:20:04 DEBUG : pacer: Reducing sleep to 220.592924ms 2026/10/10 03:20:08 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:20:08 DEBUG : pacer: Reducing sleep to 110.180687ms 2026/10/10 03:20:08 DEBUG : pacer: Reducing sleep to 105.989725ms 2026/10/10 03:20:08 DEBUG : pacer: Reducing sleep to 181.0636ms 2026/10/10 03:20:08 DEBUG : pacer: Reducing sleep to 218.431032ms 2026/10/10 03:20:12 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:779: testing "trailing LF␊" 2026/10/10 03:20:17 DEBUG : pacer: Reducing sleep to 95.847265ms 2026/10/10 03:20:17 DEBUG : pacer: Reducing sleep to 218.778877ms 2026/10/10 03:20:17 DEBUG : pacer: Reducing sleep to 191.10114ms 2026/10/10 03:20:17 DEBUG : pacer: Reducing sleep to 230.698021ms 2026/10/10 03:20:22 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:20:22 DEBUG : pacer: Reducing sleep to 108.785413ms 2026/10/10 03:20:22 DEBUG : pacer: Reducing sleep to 97.461943ms 2026/10/10 03:20:22 DEBUG : pacer: Reducing sleep to 163.508843ms 2026/10/10 03:20:22 DEBUG : pacer: Reducing sleep to 217.932247ms 2026/10/10 03:20:26 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:779: testing "trailing HT␉" 2026/10/10 03:20:31 DEBUG : pacer: Reducing sleep to 97.961366ms 2026/10/10 03:20:31 DEBUG : pacer: Reducing sleep to 217.542673ms 2026/10/10 03:20:31 DEBUG : pacer: Reducing sleep to 118.931436ms 2026/10/10 03:20:31 DEBUG : pacer: Reducing sleep to 231.500953ms 2026/10/10 03:20:35 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:20:35 DEBUG : pacer: Reducing sleep to 114.22369ms 2026/10/10 03:20:36 DEBUG : pacer: Reducing sleep to 85.44431ms 2026/10/10 03:20:36 DEBUG : pacer: Reducing sleep to 166.384089ms 2026/10/10 03:20:36 DEBUG : pacer: Reducing sleep to 226.001428ms 2026/10/10 03:20:36 DEBUG : pacer: Reducing sleep to 250.355789ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:779: testing "trailing VT␋" 2026/10/10 03:20:37 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:20:37 DEBUG : pacer: Reducing sleep to 96.754777ms 2026/10/10 03:20:37 DEBUG : pacer: Reducing sleep to 75.680234ms 2026/10/10 03:20:37 DEBUG : pacer: Reducing sleep to 72.321021ms 2026/10/10 03:20:37 DEBUG : pacer: Reducing sleep to 190.960068ms 2026/10/10 03:20:37 DEBUG : pacer: Reducing sleep to 255.043469ms 2026/10/10 03:20:38 DEBUG : pacer: Reducing sleep to 259.972673ms 2026/10/10 03:20:38 DEBUG : pacer: Reducing sleep to 129.016888ms 2026/10/10 03:20:38 DEBUG : pacer: Reducing sleep to 77.85064ms 2026/10/10 03:20:38 DEBUG : pacer: Reducing sleep to 94.382692ms 2026/10/10 03:20:38 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:779: testing "trailing dot." 2026/10/10 03:20:39 DEBUG : pacer: Reducing sleep to 96.398835ms 2026/10/10 03:20:39 DEBUG : pacer: Reducing sleep to 218.079863ms 2026/10/10 03:20:39 DEBUG : pacer: Reducing sleep to 131.371581ms 2026/10/10 03:20:39 DEBUG : pacer: Reducing sleep to 230.952734ms 2026/10/10 03:20:44 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:20:44 DEBUG : pacer: Reducing sleep to 116.133985ms 2026/10/10 03:20:44 DEBUG : pacer: Reducing sleep to 121.635149ms 2026/10/10 03:20:44 DEBUG : pacer: Reducing sleep to 194.143789ms 2026/10/10 03:20:44 DEBUG : pacer: Reducing sleep to 224.971552ms 2026/10/10 03:20:48 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:770: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:779: testing "test%46.txt" 2026/10/10 03:20:53 DEBUG : pacer: Reducing sleep to 94.119ms 2026/10/10 03:20:53 DEBUG : pacer: Reducing sleep to 215.48786ms 2026/10/10 03:20:53 DEBUG : pacer: Reducing sleep to 192.669625ms 2026/10/10 03:20:53 DEBUG : pacer: Reducing sleep to 233.346663ms 2026/10/10 03:20:57 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:20:58 DEBUG : pacer: Reducing sleep to 113.413539ms 2026/10/10 03:20:58 DEBUG : pacer: Reducing sleep to 69.357253ms 2026/10/10 03:20:58 DEBUG : pacer: Reducing sleep to 162.367861ms 2026/10/10 03:20:58 DEBUG : pacer: Reducing sleep to 243.199784ms 2026/10/10 03:21:02 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound 2026/10/10 03:21:07 DEBUG : zoho root 'rclone-test-jicubec9foja': folder listing limiter sweep: evicted 6 of 13 idle limiters === RUN TestIntegration/FsMkdir/FsPutError 2026/10/10 03:21:21 DEBUG : pacer: Reducing sleep to 95.71355ms 2026/10/10 03:21:21 DEBUG : pacer: Reducing sleep to 217.822607ms 2026/10/10 03:21:21 DEBUG : pacer: Reducing sleep to 259.503819ms 2026/10/10 03:21:21 DEBUG : pacer: Reducing sleep to 232.594145ms 2026/10/10 03:21:21 DEBUG : pacer: Reducing sleep to 113.084224ms 2026/10/10 03:21:21 DEBUG : pacer: Reducing sleep to 74.277377ms 2026/10/10 03:21:22 DEBUG : pacer: Reducing sleep to 74.939651ms 2026/10/10 03:21:22 DEBUG : pacer: Reducing sleep to 241.13174ms 2026/10/10 03:21:22 DEBUG : pacer: Reducing sleep to 284.970227ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/10/10 03:21:25 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:21:30 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:21:30 UTC": parsing time "Sat, 10 Oct 2026 03:21:30 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:21:30 UTC" as " " 2026/10/10 03:21:30 DEBUG : pacer: Reducing sleep to 100.762172ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:858: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:895: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:977: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/10/10 03:21:34 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:21:34 DEBUG : pacer: Reducing sleep to 58.933044ms 2026/10/10 03:21:39 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:21:39 DEBUG : pacer: Reducing sleep to 123.083045ms 2026/10/10 03:21:39 DEBUG : pacer: Reducing sleep to 75.345374ms 2026/10/10 03:21:39 DEBUG : pacer: Reducing sleep to 182.283047ms 2026/10/10 03:21:39 DEBUG : pacer: Reducing sleep to 284.014308ms 2026/10/10 03:21:40 DEBUG : pacer: Reducing sleep to 178.232333ms 2026/10/10 03:21:40 DEBUG : pacer: Reducing sleep to 141.253987ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/10/10 03:21:40 DEBUG : pacer: Reducing sleep to 135.27428ms 2026/10/10 03:21:40 DEBUG : pacer: Reducing sleep to 151.676641ms 2026/10/10 03:21:40 DEBUG : pacer: Reducing sleep to 188.428346ms 2026/10/10 03:21:40 DEBUG : pacer: Reducing sleep to 200.79649ms 2026/10/10 03:21:41 DEBUG : pacer: Reducing sleep to 181.952192ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/10/10 03:21:41 DEBUG : pacer: Reducing sleep to 140.992329ms 2026/10/10 03:21:41 DEBUG : pacer: Reducing sleep to 131.578677ms 2026/10/10 03:21:41 DEBUG : pacer: Reducing sleep to 153.126965ms 2026/10/10 03:21:41 DEBUG : pacer: Reducing sleep to 190.966232ms 2026/10/10 03:21:41 DEBUG : pacer: Reducing sleep to 189.658445ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/10/10 03:21:42 DEBUG : pacer: Reducing sleep to 175.63494ms 2026/10/10 03:21:42 DEBUG : pacer: Reducing sleep to 147.366389ms 2026/10/10 03:21:42 DEBUG : pacer: Reducing sleep to 142.119965ms 2026/10/10 03:21:42 DEBUG : pacer: Reducing sleep to 152.58174ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/10/10 03:21:42 DEBUG : Creating backend with remote "TestZoho:" 2026/10/10 03:21:42 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/10/10 03:21:47 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/10/10 03:21:51 DEBUG : pacer: Reducing sleep to 117.132981ms 2026/10/10 03:21:51 DEBUG : pacer: Reducing sleep to 228.024087ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/10/10 03:21:56 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:21:56 DEBUG : pacer: Reducing sleep to 112.619208ms 2026/10/10 03:21:56 DEBUG : pacer: Reducing sleep to 104.319208ms 2026/10/10 03:21:56 DEBUG : pacer: Reducing sleep to 151.22406ms 2026/10/10 03:21:56 DEBUG : pacer: Reducing sleep to 221.057655ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/10/10 03:22:01 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1230: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/10/10 03:22:05 DEBUG : pacer: Reducing sleep to 113.240731ms 2026/10/10 03:22:05 DEBUG : pacer: Reducing sleep to 233.501377ms 2026/10/10 03:22:05 DEBUG : pacer: Reducing sleep to 277.239421ms 2026/10/10 03:22:06 DEBUG : pacer: Reducing sleep to 214.886082ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/10/10 03:22:10 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/10/10 03:22:10 DEBUG : zoho root 'rclone-test-jicubec9foja': folder listing limiter sweep: evicted 6 of 12 idle limiters 2026/10/10 03:22:10 DEBUG : pacer: Reducing sleep to 116.463587ms 2026/10/10 03:22:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:15 DEBUG : pacer: Reducing sleep to 76.99174ms 2026/10/10 03:22:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:15 DEBUG : pacer: Reducing sleep to 115.236048ms 2026/10/10 03:22:19 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:19 DEBUG : pacer: Reducing sleep to 114.285624ms 2026/10/10 03:22:19 DEBUG : pacer: Reducing sleep to 280.140115ms 2026/10/10 03:22:19 DEBUG : pacer: Reducing sleep to 382.421396ms 2026/10/10 03:22:20 DEBUG : pacer: Reducing sleep to 271.634024ms 2026/10/10 03:22:20 DEBUG : pacer: Reducing sleep to 50.393767ms 2026/10/10 03:22:20 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:20 DEBUG : pacer: Reducing sleep to 102.210916ms 2026/10/10 03:22:24 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:24 DEBUG : pacer: Reducing sleep to 114.767704ms 2026/10/10 03:22:24 DEBUG : pacer: Reducing sleep to 230.435818ms 2026/10/10 03:22:24 DEBUG : pacer: Reducing sleep to 277.214852ms 2026/10/10 03:22:24 DEBUG : pacer: Reducing sleep to 219.191446ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/10/10 03:22:28 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:33 DEBUG : pacer: Reducing sleep to 82.885867ms 2026/10/10 03:22:33 DEBUG : pacer: Reducing sleep to 129.912838ms 2026/10/10 03:22:33 DEBUG : pacer: Reducing sleep to 247.513232ms 2026/10/10 03:22:38 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:38 DEBUG : pacer: Reducing sleep to 118.428407ms 2026/10/10 03:22:38 DEBUG : pacer: Reducing sleep to 277.058939ms 2026/10/10 03:22:38 DEBUG : pacer: Reducing sleep to 255.774569ms 2026/10/10 03:22:38 DEBUG : pacer: Reducing sleep to 129.095481ms 2026/10/10 03:22:38 DEBUG : pacer: Reducing sleep to 53.53377ms 2026/10/10 03:22:38 DEBUG : Creating backend with remote "TestZoho:rclone-test-jicubec9foja/dirToPurgeFromRoot" 2026/10/10 03:22:39 DEBUG : pacer: Reducing sleep to 121.394296ms 2026/10/10 03:22:39 DEBUG : pacer: Reducing sleep to 240.506132ms 2026/10/10 03:22:39 DEBUG : pacer: Reducing sleep to 279.159634ms 2026/10/10 03:22:39 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:39 DEBUG : pacer: Reducing sleep to 120.042783ms 2026/10/10 03:22:39 DEBUG : pacer: Reducing sleep to 229.543002ms 2026/10/10 03:22:39 DEBUG : pacer: Reducing sleep to 283.370392ms 2026/10/10 03:22:39 DEBUG : pacer: Reducing sleep to 208.501098ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/10/10 03:22:39 DEBUG : Creating backend with remote "TestZoho:rclone-test-jicubec9foja/hello? sausage/êé" 2026/10/10 03:22:40 DEBUG : pacer: Reducing sleep to 118.692735ms 2026/10/10 03:22:40 DEBUG : pacer: Reducing sleep to 229.363363ms 2026/10/10 03:22:40 DEBUG : pacer: Reducing sleep to 285.396399ms 2026/10/10 03:22:40 DEBUG : pacer: Reducing sleep to 215.368996ms 2026/10/10 03:22:40 DEBUG : pacer: Reducing sleep to 80.506706ms 2026/10/10 03:22:41 DEBUG : pacer: Reducing sleep to 58.235182ms 2026/10/10 03:22:41 DEBUG : pacer: Reducing sleep to 116.781903ms 2026/10/10 03:22:41 DEBUG : pacer: Reducing sleep to 244.488828ms 2026/10/10 03:22:41 DEBUG : pacer: Reducing sleep to 263.672277ms 2026/10/10 03:22:41 DEBUG : pacer: Reducing sleep to 207.616916ms 2026/10/10 03:22:41 DEBUG : pacer: Reducing sleep to 84.717583ms 2026/10/10 03:22:42 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:42 DEBUG : pacer: Reducing sleep to 112.08064ms 2026/10/10 03:22:42 DEBUG : pacer: Reducing sleep to 230.813822ms 2026/10/10 03:22:42 DEBUG : pacer: Reducing sleep to 267.95671ms 2026/10/10 03:22:42 DEBUG : pacer: Reducing sleep to 224.114679ms 2026/10/10 03:22:43 DEBUG : pacer: Reducing sleep to 95.489054ms 2026/10/10 03:22:43 DEBUG : pacer: Reducing sleep to 59.256841ms 2026/10/10 03:22:43 DEBUG : pacer: Reducing sleep to 129.474227ms 2026/10/10 03:22:43 DEBUG : pacer: Reducing sleep to 234.61165ms 2026/10/10 03:22:43 DEBUG : pacer: Reducing sleep to 271.759717ms 2026/10/10 03:22:43 DEBUG : pacer: Reducing sleep to 194.83464ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/10/10 03:22:43 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:44 DEBUG : pacer: Reducing sleep to 101.496425ms 2026/10/10 03:22:44 DEBUG : pacer: Reducing sleep to 217.557923ms 2026/10/10 03:22:44 DEBUG : pacer: Reducing sleep to 285.0265ms 2026/10/10 03:22:47 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:47 DEBUG : pacer: Reducing sleep to 117.473291ms 2026/10/10 03:22:47 DEBUG : pacer: Reducing sleep to 45.367223ms 2026/10/10 03:22:51 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:51 DEBUG : pacer: Reducing sleep to 89.098115ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1403: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/10/10 03:22:56 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:22:56 DEBUG : pacer: Reducing sleep to 94.63512ms 2026/10/10 03:22:56 DEBUG : pacer: Reducing sleep to 187.944047ms 2026/10/10 03:22:56 DEBUG : pacer: Reducing sleep to 267.758776ms 2026/10/10 03:22:56 DEBUG : pacer: Reducing sleep to 260.288667ms 2026/10/10 03:22:57 DEBUG : pacer: Reducing sleep to 161.953163ms 2026/10/10 03:22:57 DEBUG : pacer: Reducing sleep to 61.141835ms 2026/10/10 03:22:57 DEBUG : pacer: Reducing sleep to 69.167355ms 2026/10/10 03:23:01 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:23:10 DEBUG : pacer: Reducing sleep to 96.017049ms 2026/10/10 03:23:10 DEBUG : pacer: Reducing sleep to 198.196735ms 2026/10/10 03:23:10 DEBUG : pacer: Reducing sleep to 250.223024ms 2026/10/10 03:23:11 DEBUG : pacer: Reducing sleep to 235.908923ms 2026/10/10 03:23:11 DEBUG : zoho root 'rclone-test-jicubec9foja': folder listing limiter sweep: evicted 0 of 12 idle limiters 2026/10/10 03:23:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:23:15 DEBUG : pacer: Reducing sleep to 109.116963ms 2026/10/10 03:23:15 DEBUG : pacer: Reducing sleep to 250.942065ms 2026/10/10 03:23:15 DEBUG : pacer: Reducing sleep to 322.505363ms 2026/10/10 03:23:15 DEBUG : pacer: Reducing sleep to 240.809564ms 2026/10/10 03:23:15 DEBUG : pacer: Reducing sleep to 88.71322ms 2026/10/10 03:23:16 DEBUG : pacer: Reducing sleep to 9.703684ms 2026/10/10 03:23:16 DEBUG : pacer: Reducing sleep to 72.805896ms 2026/10/10 03:23:16 DEBUG : pacer: Reducing sleep to 167.544832ms 2026/10/10 03:23:16 DEBUG : pacer: Reducing sleep to 265.682233ms 2026/10/10 03:23:19 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:23:19 DEBUG : pacer: Reducing sleep to 122.64187ms 2026/10/10 03:23:20 DEBUG : pacer: Reducing sleep to 60.85311ms 2026/10/10 03:23:20 DEBUG : pacer: Reducing sleep to 101.001901ms 2026/10/10 03:23:20 DEBUG : pacer: Reducing sleep to 219.986076ms 2026/10/10 03:23:20 DEBUG : pacer: Reducing sleep to 288.315557ms 2026/10/10 03:23:24 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:23:24 DEBUG : pacer: Reducing sleep to 101.13882ms 2026/10/10 03:23:24 DEBUG : pacer: Reducing sleep to 25.253662ms 2026/10/10 03:23:24 DEBUG : pacer: Reducing sleep to 91.217346ms 2026/10/10 03:23:29 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:23:29 DEBUG : pacer: Reducing sleep to 121.103146ms 2026/10/10 03:23:29 DEBUG : pacer: Reducing sleep to 284.606017ms 2026/10/10 03:23:29 DEBUG : pacer: Reducing sleep to 395.380291ms 2026/10/10 03:23:29 DEBUG : pacer: Reducing sleep to 282.746376ms 2026/10/10 03:23:29 DEBUG : pacer: Reducing sleep to 53.084523ms 2026/10/10 03:23:30 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1515: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/10/10 03:23:30 DEBUG : Creating backend with remote "TestZoho:rclone-test-doduliq0vasu" 2026/10/10 03:23:30 DEBUG : pacer: Reducing sleep to 40.548818ms 2026/10/10 03:23:31 DEBUG : pacer: Reducing sleep to 82.867716ms 2026/10/10 03:23:31 DEBUG : pacer: Reducing sleep to 115.71435ms 2026/10/10 03:23:31 DEBUG : pacer: Reducing sleep to 243.621802ms 2026/10/10 03:23:31 DEBUG : pacer: Reducing sleep to 266.670156ms 2026/10/10 03:23:31 DEBUG : pacer: Reducing sleep to 213.039989ms 2026/10/10 03:23:31 DEBUG : pacer: Reducing sleep to 55.561129ms 2026/10/10 03:23:32 DEBUG : pacer: Reducing sleep to 20.961925ms 2026/10/10 03:23:32 DEBUG : pacer: Reducing sleep to 8.86597ms 2026/10/10 03:23:32 DEBUG : pacer: Reducing sleep to 129.191137ms 2026/10/10 03:23:33 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:23:33 DEBUG : pacer: Reducing sleep to 120.230126ms 2026/10/10 03:23:33 DEBUG : pacer: Reducing sleep to 203.706226ms 2026/10/10 03:23:33 DEBUG : pacer: Reducing sleep to 254.99445ms 2026/10/10 03:23:34 DEBUG : pacer: Reducing sleep to 203.17418ms 2026/10/10 03:23:36 DEBUG : pacer: Reducing sleep to 34.859903ms 2026/10/10 03:23:36 DEBUG : pacer: Reducing sleep to 111.164894ms 2026/10/10 03:23:36 DEBUG : pacer: Reducing sleep to 227.732585ms 2026/10/10 03:23:36 DEBUG : pacer: Reducing sleep to 281.049168ms 2026/10/10 03:23:36 DEBUG : pacer: Reducing sleep to 220.8834ms 2026/10/10 03:23:36 DEBUG : pacer: Reducing sleep to 106.637791ms 2026/10/10 03:23:36 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:23:36 DEBUG : pacer: Reducing sleep to 128.455558ms 2026/10/10 03:23:36 DEBUG : zoho root 'rclone-test-doduliq0vasu': Purge remote 2026/10/10 03:23:37 DEBUG : pacer: Reducing sleep to 127.295573ms 2026/10/10 03:23:37 DEBUG : pacer: Reducing sleep to 149.657108ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/10/10 03:23:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/10/10 03:23:37 DEBUG : pacer: Reducing sleep to 111.277963ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/10/10 03:23:37 DEBUG : pacer: Reducing sleep to 221.781795ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/10/10 03:23:37 DEBUG : pacer: Reducing sleep to 274.700377ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/10/10 03:23:37 DEBUG : pacer: Reducing sleep to 219.066244ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/10/10 03:23:38 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1711: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1742: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1813: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1870: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/10/10 03:24:06 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:06 UTC": parsing time "Sat, 10 Oct 2026 03:24:06 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:06 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/10/10 03:24:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:11 UTC": parsing time "Sat, 10 Oct 2026 03:24:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:11 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/10/10 03:24:11 DEBUG : zoho root 'rclone-test-jicubec9foja': folder listing limiter sweep: evicted 6 of 15 idle limiters 2026/10/10 03:24:15 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:15 UTC": parsing time "Sat, 10 Oct 2026 03:24:15 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:15 UTC" as " " 2026/10/10 03:24:16 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:16 UTC": parsing time "Sat, 10 Oct 2026 03:24:16 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:16 UTC" as " " 2026/10/10 03:24:16 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:16 UTC": parsing time "Sat, 10 Oct 2026 03:24:16 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:16 UTC" as " " 2026/10/10 03:24:16 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:16 UTC": parsing time "Sat, 10 Oct 2026 03:24:16 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:16 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/10/10 03:24:20 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:20 UTC": parsing time "Sat, 10 Oct 2026 03:24:20 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:20 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/10/10 03:24:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:25 UTC": parsing time "Sat, 10 Oct 2026 03:24:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:25 UTC" as " " 2026/10/10 03:24:29 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:29 UTC": parsing time "Sat, 10 Oct 2026 03:24:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:29 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/10/10 03:24:29 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:29 UTC": parsing time "Sat, 10 Oct 2026 03:24:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:29 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/10/10 03:24:30 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:29 UTC": parsing time "Sat, 10 Oct 2026 03:24:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:29 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/10/10 03:24:30 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:30 UTC": parsing time "Sat, 10 Oct 2026 03:24:30 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:30 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/10/10 03:24:30 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:30 UTC": parsing time "Sat, 10 Oct 2026 03:24:30 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:30 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/10/10 03:24:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:34 UTC": parsing time "Sat, 10 Oct 2026 03:24:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:34 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/10/10 03:24:38 DEBUG : pacer: Reducing sleep to 112.227164ms 2026/10/10 03:24:39 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 10 Oct 2026 03:24:39 UTC": parsing time "Sat, 10 Oct 2026 03:24:39 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 10 Oct 2026 03:24:39 UTC" as " " 2026/10/10 03:24:39 DEBUG : pacer: Reducing sleep to 80.860298ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/10/10 03:24:39 DEBUG : pacer: Reducing sleep to 198.185709ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/10/10 03:24:39 DEBUG : Creating backend with remote "TestZoho:rclone-test-jicubec9foja/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/10/10 03:24:39 DEBUG : pacer: Reducing sleep to 119.757253ms 2026/10/10 03:24:39 DEBUG : pacer: Reducing sleep to 235.544289ms 2026/10/10 03:24:39 DEBUG : pacer: Reducing sleep to 286.32997ms 2026/10/10 03:24:39 DEBUG : pacer: Reducing sleep to 209.033221ms 2026/10/10 03:24:40 DEBUG : pacer: Reducing sleep to 90.481285ms 2026/10/10 03:24:40 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:24:40 DEBUG : pacer: Reducing sleep to 121.401615ms 2026/10/10 03:24:40 DEBUG : pacer: Reducing sleep to 239.801586ms 2026/10/10 03:24:40 DEBUG : pacer: Reducing sleep to 287.370496ms 2026/10/10 03:24:40 DEBUG : pacer: Reducing sleep to 207.97895ms 2026/10/10 03:24:41 DEBUG : pacer: Reducing sleep to 87.34921ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/10/10 03:24:41 DEBUG : pacer: Reducing sleep to 49.267725ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/10/10 03:24:41 DEBUG : Creating backend with remote "TestZoho:rclone-test-jicubec9foja/not found.txt" 2026/10/10 03:24:41 DEBUG : pacer: Reducing sleep to 116.242406ms 2026/10/10 03:24:41 DEBUG : pacer: Reducing sleep to 152.906734ms 2026/10/10 03:24:46 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:24:51 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:2072: Opening root remote "TestZoho:" path "rclone-test-jicubec9foja" from "TestZoho:rclone-test-jicubec9foja" 2026/10/10 03:24:51 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/10/10 03:24:55 DEBUG : pacer: Reducing sleep to 121.560258ms 2026/10/10 03:24:55 DEBUG : pacer: Reducing sleep to 239.413455ms 2026/10/10 03:24:55 DEBUG : pacer: Reducing sleep to 284.881245ms 2026/10/10 03:24:56 DEBUG : pacer: Reducing sleep to 199.61129ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/10/10 03:25:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2109: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/10/10 03:25:14 DEBUG : zoho root '': folder listing limiter sweep: evicted 3 of 9 idle limiters 2026/10/10 03:25:14 DEBUG : pacer: Reducing sleep to 110.58803ms 2026/10/10 03:25:14 DEBUG : pacer: Reducing sleep to 228.88867ms 2026/10/10 03:25:14 DEBUG : pacer: Reducing sleep to 280.975037ms 2026/10/10 03:25:14 DEBUG : pacer: Reducing sleep to 220.900063ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/10/10 03:25:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:25:18 DEBUG : pacer: Reducing sleep to 123.011697ms 2026/10/10 03:25:18 DEBUG : pacer: Reducing sleep to 236.604048ms 2026/10/10 03:25:18 DEBUG : pacer: Reducing sleep to 283.566851ms 2026/10/10 03:25:19 DEBUG : pacer: Reducing sleep to 212.871694ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2168: 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:2273: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2289: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/10/10 03:25:23 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:25:28 DEBUG : pacer: Reducing sleep to 112.591742ms 2026/10/10 03:25:28 DEBUG : pacer: Reducing sleep to 222.509634ms 2026/10/10 03:25:28 DEBUG : pacer: Reducing sleep to 283.817574ms 2026/10/10 03:25:28 DEBUG : pacer: Reducing sleep to 223.920054ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/10/10 03:25:28 DEBUG : pacer: Reducing sleep to 86.631022ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2463: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2509: *zoho.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2530: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutRetry === RUN TestIntegration/FsMkdir/FsPutRetry/Simple === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/Status500 2026/10/10 03:25:32 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:25:32 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/10/10 03:25:32 DEBUG : pacer: Rate limited, increasing sleep to 1.713703099s fstests.go:2738: Put returned an error after the injected failure (acceptable): upload error: HTTP error 500 (500 Internal Server Error) returned body: "" 2026/10/10 03:25:37 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:25:37 DEBUG : pacer: Reducing sleep to 121.054062ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/10/10 03:25:39 DEBUG : pacer: Reducing sleep to 0s 2026/10/10 03:25:39 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=5uvn5466613c684fe4910bbff383a03dda4ee": write tcp: unexpected EOF) 2026/10/10 03:25:39 DEBUG : pacer: Rate limited, increasing sleep to 1.29057375s fstests.go:2738: 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=5uvn5466613c684fe4910bbff383a03dda4ee": write tcp: unexpected EOF 2026/10/10 03:25:39 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutRetry/Chunked fstests.go:2775: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/10/10 03:25:39 DEBUG : pacer: Reducing sleep to 138.180419ms fstests.go:2862: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n