"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/26 01:21:58 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/26 01:21:58 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/26 01:21:58 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/26 01:21:58 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/26 01:21:58 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/26 01:21:58 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/26 01:21:58 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/26 01:21:58 DEBUG : Creating backend with remote "TestZoho:rclone-test-civamas1bawi" 2026/09/26 01:21:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/26 01:21:58 DEBUG : zoho root 'rclone-test-civamas1bawi': folder listing limiter sweep: evicted 0 of 0 idle limiters === 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/26 01:21:59 DEBUG : pacer: Reducing sleep to 22.024294ms 2026/09/26 01:21:59 DEBUG : pacer: Reducing sleep to 48.156247ms 2026/09/26 01:21:59 DEBUG : pacer: Reducing sleep to 29.683817ms 2026/09/26 01:21:59 DEBUG : pacer: Reducing sleep to 137.948001ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/26 01:21:59 DEBUG : pacer: Reducing sleep to 248.303727ms 2026/09/26 01:21:59 DEBUG : pacer: Reducing sleep to 256.786369ms === RUN TestIntegration/FsMkdir 2026/09/26 01:22:00 DEBUG : pacer: Reducing sleep to 48.35648ms 2026/09/26 01:22:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:22:00 DEBUG : pacer: Reducing sleep to 112.357016ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/26 01:22:00 INFO : dir/subdir: Making directory 2026/09/26 01:22:00 DEBUG : pacer: Reducing sleep to 220.527965ms 2026/09/26 01:22:00 DEBUG : pacer: Reducing sleep to 250.085099ms 2026/09/26 01:22:00 DEBUG : pacer: Reducing sleep to 220.702386ms 2026/09/26 01:22:01 DEBUG : pacer: Reducing sleep to 111.204105ms 2026/09/26 01:22:01 DEBUG : pacer: Reducing sleep to 71.730429ms 2026/09/26 01:22:01 DEBUG : pacer: Reducing sleep to 134.922977ms 2026/09/26 01:22:01 DEBUG : pacer: Reducing sleep to 232.57915ms 2026/09/26 01:22:01 INFO : dir/subdir: Removing directory 2026/09/26 01:22:01 DEBUG : pacer: Reducing sleep to 256.035761ms 2026/09/26 01:22:01 DEBUG : pacer: Reducing sleep to 179.8126ms 2026/09/26 01:22:02 DEBUG : pacer: Reducing sleep to 103.063345ms 2026/09/26 01:22:02 DEBUG : pacer: Reducing sleep to 93.003627ms 2026/09/26 01:22:02 INFO : dir: Removing directory 2026/09/26 01:22:02 DEBUG : pacer: Reducing sleep to 153.523125ms 2026/09/26 01:22:02 DEBUG : pacer: Reducing sleep to 216.316989ms 2026/09/26 01:22:02 DEBUG : pacer: Reducing sleep to 242.392186ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/26 01:22:02 DEBUG : pacer: Reducing sleep to 191.301104ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/26 01:22:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/26 01:22:11 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/26 01:22:21 DEBUG : pacer: Reducing sleep to 86.976238ms 2026/09/26 01:22:21 DEBUG : pacer: Reducing sleep to 197.792715ms 2026/09/26 01:22:21 DEBUG : pacer: Reducing sleep to 142.49778ms 2026/09/26 01:22:21 DEBUG : pacer: Reducing sleep to 240.681281ms 2026/09/26 01:22:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:22:25 DEBUG : pacer: Reducing sleep to 103.244933ms 2026/09/26 01:22:26 DEBUG : pacer: Reducing sleep to 78.577288ms 2026/09/26 01:22:26 DEBUG : pacer: Reducing sleep to 158.122328ms 2026/09/26 01:22:26 DEBUG : pacer: Reducing sleep to 234.98993ms 2026/09/26 01:22:30 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/26 01:22:35 DEBUG : pacer: Reducing sleep to 86.329618ms 2026/09/26 01:22:35 DEBUG : pacer: Reducing sleep to 196.863649ms 2026/09/26 01:22:35 DEBUG : pacer: Reducing sleep to 177.437467ms 2026/09/26 01:22:35 DEBUG : pacer: Reducing sleep to 240.344826ms 2026/09/26 01:22:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:22:39 DEBUG : pacer: Reducing sleep to 100.018597ms 2026/09/26 01:22:39 DEBUG : pacer: Reducing sleep to 82.556194ms 2026/09/26 01:22:39 DEBUG : pacer: Reducing sleep to 163.278018ms 2026/09/26 01:22:40 DEBUG : pacer: Reducing sleep to 228.642849ms 2026/09/26 01:22:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/26 01:22:48 DEBUG : pacer: Reducing sleep to 85.248554ms 2026/09/26 01:22:48 DEBUG : pacer: Reducing sleep to 197.263716ms 2026/09/26 01:22:49 DEBUG : pacer: Reducing sleep to 197.659791ms 2026/09/26 01:22:49 DEBUG : pacer: Reducing sleep to 239.981459ms 2026/09/26 01:22:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:22:53 DEBUG : pacer: Reducing sleep to 101.533127ms 2026/09/26 01:22:53 DEBUG : pacer: Reducing sleep to 83.675458ms 2026/09/26 01:22:53 DEBUG : pacer: Reducing sleep to 159.862054ms 2026/09/26 01:22:53 DEBUG : pacer: Reducing sleep to 232.474507ms 2026/09/26 01:22:58 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/26 01:23:02 DEBUG : pacer: Reducing sleep to 87.664879ms 2026/09/26 01:23:02 DEBUG : zoho root 'rclone-test-civamas1bawi': folder listing limiter sweep: evicted 4 of 8 idle limiters 2026/09/26 01:23:02 DEBUG : pacer: Reducing sleep to 199.204733ms 2026/09/26 01:23:03 DEBUG : pacer: Reducing sleep to 197.154827ms 2026/09/26 01:23:03 DEBUG : pacer: Reducing sleep to 238.569893ms 2026/09/26 01:23:03 DEBUG : pacer: Reducing sleep to 205.606487ms 2026/09/26 01:23:03 DEBUG : pacer: Reducing sleep to 136.43533ms 2026/09/26 01:23:03 DEBUG : pacer: Reducing sleep to 83.525873ms 2026/09/26 01:23:03 DEBUG : pacer: Reducing sleep to 126.883869ms 2026/09/26 01:23:04 DEBUG : pacer: Reducing sleep to 199.202527ms 2026/09/26 01:23:04 DEBUG : pacer: Reducing sleep to 252.284314ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/26 01:23:04 DEBUG : pacer: Reducing sleep to 218.947242ms 2026/09/26 01:23:04 DEBUG : pacer: Reducing sleep to 107.687793ms 2026/09/26 01:23:04 DEBUG : pacer: Reducing sleep to 79.072811ms 2026/09/26 01:23:04 DEBUG : pacer: Reducing sleep to 55.996534ms 2026/09/26 01:23:05 DEBUG : pacer: Reducing sleep to 157.51226ms 2026/09/26 01:23:05 DEBUG : pacer: Reducing sleep to 263.83138ms 2026/09/26 01:23:05 DEBUG : pacer: Reducing sleep to 271.427968ms 2026/09/26 01:23:05 DEBUG : pacer: Reducing sleep to 163.0222ms 2026/09/26 01:23:05 DEBUG : pacer: Reducing sleep to 71.678946ms 2026/09/26 01:23:05 DEBUG : pacer: Reducing sleep to 60.114975ms 2026/09/26 01:23:06 DEBUG : pacer: Reducing sleep to 170.96282ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/26 01:23:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:23:10 DEBUG : pacer: Reducing sleep to 86.361818ms 2026/09/26 01:23:10 DEBUG : pacer: Reducing sleep to 160.533687ms 2026/09/26 01:23:11 DEBUG : pacer: Reducing sleep to 145.960692ms 2026/09/26 01:23:11 DEBUG : pacer: Reducing sleep to 241.323354ms 2026/09/26 01:23:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:23:15 DEBUG : pacer: Reducing sleep to 101.370993ms 2026/09/26 01:23:15 DEBUG : pacer: Reducing sleep to 88.534308ms 2026/09/26 01:23:15 DEBUG : pacer: Reducing sleep to 165.16398ms 2026/09/26 01:23:15 DEBUG : pacer: Reducing sleep to 231.546894ms 2026/09/26 01:23:19 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/26 01:23:24 DEBUG : pacer: Reducing sleep to 86.384591ms 2026/09/26 01:23:24 DEBUG : pacer: Reducing sleep to 197.57313ms 2026/09/26 01:23:24 DEBUG : pacer: Reducing sleep to 202.36525ms 2026/09/26 01:23:24 DEBUG : pacer: Reducing sleep to 237.736068ms 2026/09/26 01:23:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:23:29 DEBUG : pacer: Reducing sleep to 101.322789ms 2026/09/26 01:23:29 DEBUG : pacer: Reducing sleep to 85.351335ms 2026/09/26 01:23:29 DEBUG : pacer: Reducing sleep to 161.516941ms 2026/09/26 01:23:29 DEBUG : pacer: Reducing sleep to 232.483033ms 2026/09/26 01:23:33 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/26 01:23:38 DEBUG : pacer: Reducing sleep to 86.882531ms 2026/09/26 01:23:38 DEBUG : pacer: Reducing sleep to 199.271389ms 2026/09/26 01:23:38 DEBUG : pacer: Reducing sleep to 180.839214ms 2026/09/26 01:23:38 DEBUG : pacer: Reducing sleep to 238.294193ms 2026/09/26 01:23:42 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:23:43 DEBUG : pacer: Reducing sleep to 99.916274ms 2026/09/26 01:23:43 DEBUG : pacer: Reducing sleep to 82.953837ms 2026/09/26 01:23:43 DEBUG : pacer: Reducing sleep to 159.306897ms 2026/09/26 01:23:43 DEBUG : pacer: Reducing sleep to 233.556433ms 2026/09/26 01:23:47 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/26 01:23:52 DEBUG : pacer: Reducing sleep to 87.785377ms 2026/09/26 01:23:52 DEBUG : pacer: Reducing sleep to 199.925203ms 2026/09/26 01:23:52 DEBUG : pacer: Reducing sleep to 203.974691ms 2026/09/26 01:23:52 DEBUG : pacer: Reducing sleep to 236.988296ms 2026/09/26 01:23:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:23:56 DEBUG : pacer: Reducing sleep to 100.187887ms 2026/09/26 01:23:57 DEBUG : pacer: Reducing sleep to 88.209805ms 2026/09/26 01:23:57 DEBUG : pacer: Reducing sleep to 165.713419ms 2026/09/26 01:23:57 DEBUG : pacer: Reducing sleep to 234.056326ms 2026/09/26 01:24:01 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/26 01:24:01 DEBUG : pacer: Reducing sleep to 111.870897ms 2026/09/26 01:24:01 DEBUG : pacer: Reducing sleep to 76.448535ms 2026/09/26 01:24:01 DEBUG : pacer: Reducing sleep to 154.93115ms 2026/09/26 01:24:01 DEBUG : pacer: Reducing sleep to 148.761099ms 2026/09/26 01:24:02 DEBUG : pacer: Reducing sleep to 248.902318ms 2026/09/26 01:24:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:24:03 DEBUG : zoho root 'rclone-test-civamas1bawi': folder listing limiter sweep: evicted 3 of 11 idle limiters 2026/09/26 01:24:03 DEBUG : pacer: Reducing sleep to 106.153216ms 2026/09/26 01:24:03 DEBUG : pacer: Reducing sleep to 72.329464ms 2026/09/26 01:24:03 DEBUG : pacer: Reducing sleep to 143.813386ms 2026/09/26 01:24:03 DEBUG : pacer: Reducing sleep to 229.67071ms 2026/09/26 01:24:03 DEBUG : pacer: Reducing sleep to 263.462013ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/26 01:24:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:24:04 DEBUG : pacer: Reducing sleep to 89.831953ms 2026/09/26 01:24:04 DEBUG : pacer: Reducing sleep to 68.04408ms 2026/09/26 01:24:04 DEBUG : pacer: Reducing sleep to 69.060826ms 2026/09/26 01:24:05 DEBUG : pacer: Reducing sleep to 173.467655ms 2026/09/26 01:24:05 DEBUG : pacer: Reducing sleep to 272.956632ms 2026/09/26 01:24:05 DEBUG : pacer: Reducing sleep to 263.790362ms 2026/09/26 01:24:05 DEBUG : pacer: Reducing sleep to 148.294029ms 2026/09/26 01:24:05 DEBUG : pacer: Reducing sleep to 62.970276ms 2026/09/26 01:24:05 DEBUG : pacer: Reducing sleep to 72.590347ms 2026/09/26 01:24:09 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/26 01:24:14 DEBUG : pacer: Reducing sleep to 90.061938ms 2026/09/26 01:24:14 DEBUG : pacer: Reducing sleep to 200.131703ms 2026/09/26 01:24:14 DEBUG : pacer: Reducing sleep to 189.031442ms 2026/09/26 01:24:14 DEBUG : pacer: Reducing sleep to 238.806411ms 2026/09/26 01:24:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:24:19 DEBUG : pacer: Reducing sleep to 102.437567ms 2026/09/26 01:24:19 DEBUG : pacer: Reducing sleep to 78.014265ms 2026/09/26 01:24:19 DEBUG : pacer: Reducing sleep to 157.702214ms 2026/09/26 01:24:19 DEBUG : pacer: Reducing sleep to 235.375749ms 2026/09/26 01:24:23 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/26 01:24:28 DEBUG : pacer: Reducing sleep to 87.819752ms 2026/09/26 01:24:28 DEBUG : pacer: Reducing sleep to 199.022799ms 2026/09/26 01:24:28 DEBUG : pacer: Reducing sleep to 197.276758ms 2026/09/26 01:24:28 DEBUG : pacer: Reducing sleep to 239.454816ms 2026/09/26 01:24:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:24:32 DEBUG : pacer: Reducing sleep to 101.985162ms 2026/09/26 01:24:33 DEBUG : pacer: Reducing sleep to 86.597129ms 2026/09/26 01:24:33 DEBUG : pacer: Reducing sleep to 162.413986ms 2026/09/26 01:24:33 DEBUG : pacer: Reducing sleep to 231.10512ms 2026/09/26 01:24:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/26 01:24:42 DEBUG : pacer: Reducing sleep to 83.223109ms 2026/09/26 01:24:42 DEBUG : pacer: Reducing sleep to 194.532016ms 2026/09/26 01:24:42 DEBUG : pacer: Reducing sleep to 203.846268ms 2026/09/26 01:24:42 DEBUG : pacer: Reducing sleep to 242.441779ms 2026/09/26 01:24:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:24:46 DEBUG : pacer: Reducing sleep to 102.84694ms 2026/09/26 01:24:46 DEBUG : pacer: Reducing sleep to 78.606532ms 2026/09/26 01:24:47 DEBUG : pacer: Reducing sleep to 156.478682ms 2026/09/26 01:24:47 DEBUG : pacer: Reducing sleep to 233.496608ms 2026/09/26 01:24:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/26 01:24:55 DEBUG : pacer: Reducing sleep to 90.893168ms 2026/09/26 01:24:56 DEBUG : pacer: Reducing sleep to 202.725935ms 2026/09/26 01:24:56 DEBUG : pacer: Reducing sleep to 185.17743ms 2026/09/26 01:24:56 DEBUG : pacer: Reducing sleep to 237.692697ms 2026/09/26 01:25:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:25:00 DEBUG : pacer: Reducing sleep to 101.164673ms 2026/09/26 01:25:00 DEBUG : pacer: Reducing sleep to 82.484259ms 2026/09/26 01:25:00 DEBUG : pacer: Reducing sleep to 167.616124ms 2026/09/26 01:25:00 DEBUG : pacer: Reducing sleep to 237.610732ms 2026/09/26 01:25:01 DEBUG : pacer: Reducing sleep to 251.423903ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/26 01:25:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:25:02 DEBUG : pacer: Reducing sleep to 84.39884ms 2026/09/26 01:25:02 DEBUG : pacer: Reducing sleep to 85.011073ms 2026/09/26 01:25:02 DEBUG : pacer: Reducing sleep to 85.971533ms 2026/09/26 01:25:02 DEBUG : pacer: Reducing sleep to 188.665196ms 2026/09/26 01:25:02 DEBUG : pacer: Reducing sleep to 269.924594ms 2026/09/26 01:25:02 DEBUG : pacer: Reducing sleep to 246.300231ms 2026/09/26 01:25:02 DEBUG : pacer: Reducing sleep to 134.707263ms 2026/09/26 01:25:03 DEBUG : pacer: Reducing sleep to 67.339311ms 2026/09/26 01:25:03 DEBUG : pacer: Reducing sleep to 84.103448ms 2026/09/26 01:25:03 DEBUG : zoho root 'rclone-test-civamas1bawi': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/26 01:25:03 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/26 01:25:04 DEBUG : pacer: Reducing sleep to 86.062882ms 2026/09/26 01:25:04 DEBUG : pacer: Reducing sleep to 197.368061ms 2026/09/26 01:25:04 DEBUG : pacer: Reducing sleep to 169.626752ms 2026/09/26 01:25:04 DEBUG : pacer: Reducing sleep to 240.197328ms 2026/09/26 01:25:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:25:08 DEBUG : pacer: Reducing sleep to 98.015454ms 2026/09/26 01:25:09 DEBUG : pacer: Reducing sleep to 86.585267ms 2026/09/26 01:25:09 DEBUG : pacer: Reducing sleep to 162.674108ms 2026/09/26 01:25:09 DEBUG : pacer: Reducing sleep to 231.81875ms 2026/09/26 01:25:13 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/26 01:25:18 DEBUG : pacer: Reducing sleep to 83.9913ms 2026/09/26 01:25:18 DEBUG : pacer: Reducing sleep to 195.245944ms 2026/09/26 01:25:18 DEBUG : pacer: Reducing sleep to 204.149682ms 2026/09/26 01:25:18 DEBUG : pacer: Reducing sleep to 241.623833ms 2026/09/26 01:25:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:25:22 DEBUG : pacer: Reducing sleep to 101.731133ms 2026/09/26 01:25:22 DEBUG : pacer: Reducing sleep to 62.404266ms 2026/09/26 01:25:23 DEBUG : pacer: Reducing sleep to 157.743604ms 2026/09/26 01:25:23 DEBUG : pacer: Reducing sleep to 249.385426ms 2026/09/26 01:25:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/26 01:25:45 DEBUG : pacer: Reducing sleep to 84.147754ms 2026/09/26 01:25:45 DEBUG : pacer: Reducing sleep to 195.813736ms 2026/09/26 01:25:45 DEBUG : pacer: Reducing sleep to 244.15634ms 2026/09/26 01:25:46 DEBUG : pacer: Reducing sleep to 246.302481ms 2026/09/26 01:25:46 DEBUG : pacer: Reducing sleep to 142.330438ms 2026/09/26 01:25:46 DEBUG : pacer: Reducing sleep to 86.251865ms 2026/09/26 01:25:46 DEBUG : pacer: Reducing sleep to 82.5716ms 2026/09/26 01:25:46 DEBUG : pacer: Reducing sleep to 246.002724ms 2026/09/26 01:25:46 DEBUG : pacer: Reducing sleep to 272.751884ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/26 01:25:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:25:50 DEBUG : pacer: Reducing sleep to 32.625131ms 2026/09/26 01:25:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:25:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:25:55 UTC": parsing time "Sat, 26 Sep 2026 01:25:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:25:55 UTC" as " " 2026/09/26 01:25:55 DEBUG : pacer: Reducing sleep to 98.621218ms === 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/26 01:25:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:25:59 DEBUG : pacer: Reducing sleep to 48.576575ms 2026/09/26 01:26:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:26:04 DEBUG : zoho root 'rclone-test-civamas1bawi': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/26 01:26:04 DEBUG : pacer: Reducing sleep to 111.594926ms 2026/09/26 01:26:04 DEBUG : pacer: Reducing sleep to 138.925065ms 2026/09/26 01:26:04 DEBUG : pacer: Reducing sleep to 245.851102ms 2026/09/26 01:26:04 DEBUG : pacer: Reducing sleep to 267.651444ms 2026/09/26 01:26:04 DEBUG : pacer: Reducing sleep to 122.397986ms 2026/09/26 01:26:05 DEBUG : pacer: Reducing sleep to 89.262384ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/26 01:26:05 DEBUG : pacer: Reducing sleep to 132.394275ms 2026/09/26 01:26:05 DEBUG : pacer: Reducing sleep to 207.859306ms 2026/09/26 01:26:05 DEBUG : pacer: Reducing sleep to 236.149879ms 2026/09/26 01:26:05 DEBUG : pacer: Reducing sleep to 199.689096ms 2026/09/26 01:26:05 DEBUG : pacer: Reducing sleep to 129.443479ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/26 01:26:06 DEBUG : pacer: Reducing sleep to 92.546122ms 2026/09/26 01:26:06 DEBUG : pacer: Reducing sleep to 135.386021ms 2026/09/26 01:26:06 DEBUG : pacer: Reducing sleep to 207.141695ms 2026/09/26 01:26:06 DEBUG : pacer: Reducing sleep to 238.833403ms 2026/09/26 01:26:06 DEBUG : pacer: Reducing sleep to 186.891664ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/26 01:26:06 DEBUG : pacer: Reducing sleep to 123.827154ms 2026/09/26 01:26:07 DEBUG : pacer: Reducing sleep to 101.772426ms 2026/09/26 01:26:07 DEBUG : pacer: Reducing sleep to 144.702214ms 2026/09/26 01:26:07 DEBUG : pacer: Reducing sleep to 206.432179ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/26 01:26:07 DEBUG : Creating backend with remote "TestZoho:" 2026/09/26 01:26:07 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/26 01:26:11 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/26 01:26:16 DEBUG : pacer: Reducing sleep to 107.344346ms 2026/09/26 01:26:16 DEBUG : pacer: Reducing sleep to 213.740172ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/26 01:26:21 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:26:21 DEBUG : pacer: Reducing sleep to 104.187443ms 2026/09/26 01:26:21 DEBUG : pacer: Reducing sleep to 117.048465ms 2026/09/26 01:26:21 DEBUG : pacer: Reducing sleep to 176.206021ms 2026/09/26 01:26:21 DEBUG : pacer: Reducing sleep to 228.469463ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/26 01:26:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/26 01:26:30 DEBUG : pacer: Reducing sleep to 108.318847ms 2026/09/26 01:26:30 DEBUG : pacer: Reducing sleep to 211.136984ms 2026/09/26 01:26:30 DEBUG : pacer: Reducing sleep to 271.178208ms 2026/09/26 01:26:30 DEBUG : pacer: Reducing sleep to 224.363436ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/26 01:26:35 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/26 01:26:35 DEBUG : pacer: Reducing sleep to 107.282038ms 2026/09/26 01:26:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:26:39 DEBUG : pacer: Reducing sleep to 91.497739ms 2026/09/26 01:26:40 DEBUG : pacer: Reducing sleep to 97.822095ms 2026/09/26 01:26:40 DEBUG : pacer: Reducing sleep to 203.904829ms 2026/09/26 01:26:44 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:26:44 DEBUG : pacer: Reducing sleep to 106.777256ms 2026/09/26 01:26:44 DEBUG : pacer: Reducing sleep to 265.092284ms 2026/09/26 01:26:44 DEBUG : pacer: Reducing sleep to 295.365492ms 2026/09/26 01:26:44 DEBUG : pacer: Reducing sleep to 199.346597ms 2026/09/26 01:26:45 DEBUG : pacer: Reducing sleep to 62.851464ms 2026/09/26 01:26:45 DEBUG : pacer: Reducing sleep to 35.820514ms 2026/09/26 01:26:45 DEBUG : pacer: Reducing sleep to 128.000323ms 2026/09/26 01:26:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:26:49 DEBUG : pacer: Reducing sleep to 104.978287ms 2026/09/26 01:26:49 DEBUG : pacer: Reducing sleep to 204.440915ms 2026/09/26 01:26:49 DEBUG : pacer: Reducing sleep to 263.653063ms 2026/09/26 01:26:49 DEBUG : pacer: Reducing sleep to 226.280739ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/26 01:26:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:26:58 DEBUG : pacer: Reducing sleep to 88.650191ms 2026/09/26 01:26:58 DEBUG : pacer: Reducing sleep to 113.16538ms 2026/09/26 01:26:58 DEBUG : pacer: Reducing sleep to 216.82126ms 2026/09/26 01:27:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:03 DEBUG : pacer: Reducing sleep to 106.084467ms 2026/09/26 01:27:03 DEBUG : pacer: Reducing sleep to 271.349235ms 2026/09/26 01:27:03 DEBUG : pacer: Reducing sleep to 282.930001ms 2026/09/26 01:27:03 DEBUG : pacer: Reducing sleep to 180.892113ms 2026/09/26 01:27:03 DEBUG : pacer: Reducing sleep to 62.294622ms 2026/09/26 01:27:03 DEBUG : Creating backend with remote "TestZoho:rclone-test-civamas1bawi/dirToPurgeFromRoot" 2026/09/26 01:27:04 DEBUG : pacer: Reducing sleep to 108.724985ms 2026/09/26 01:27:04 DEBUG : pacer: Reducing sleep to 219.398561ms 2026/09/26 01:27:04 DEBUG : pacer: Reducing sleep to 268.990756ms 2026/09/26 01:27:04 DEBUG : zoho root 'rclone-test-civamas1bawi': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/26 01:27:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:04 DEBUG : pacer: Reducing sleep to 107.019193ms 2026/09/26 01:27:04 DEBUG : pacer: Reducing sleep to 213.4369ms 2026/09/26 01:27:04 DEBUG : pacer: Reducing sleep to 271.190392ms 2026/09/26 01:27:04 DEBUG : pacer: Reducing sleep to 224.63025ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/26 01:27:04 DEBUG : Creating backend with remote "TestZoho:rclone-test-civamas1bawi/hello? sausage/êé" 2026/09/26 01:27:05 DEBUG : pacer: Reducing sleep to 12.769811ms 2026/09/26 01:27:05 DEBUG : pacer: Reducing sleep to 27.868893ms 2026/09/26 01:27:06 DEBUG : pacer: Reducing sleep to 51.773048ms 2026/09/26 01:27:06 DEBUG : pacer: Reducing sleep to 73.759683ms 2026/09/26 01:27:06 DEBUG : pacer: Reducing sleep to 68.909948ms 2026/09/26 01:27:06 DEBUG : pacer: Reducing sleep to 82.222726ms 2026/09/26 01:27:06 DEBUG : pacer: Reducing sleep to 66.579441ms 2026/09/26 01:27:06 DEBUG : pacer: Reducing sleep to 79.803801ms 2026/09/26 01:27:07 DEBUG : pacer: Reducing sleep to 73.470885ms 2026/09/26 01:27:07 DEBUG : pacer: Reducing sleep to 89.542815ms 2026/09/26 01:27:07 DEBUG : pacer: Reducing sleep to 76.537513ms 2026/09/26 01:27:07 DEBUG : pacer: Reducing sleep to 7.509296ms 2026/09/26 01:27:07 DEBUG : pacer: Reducing sleep to 22.196039ms 2026/09/26 01:27:07 DEBUG : pacer: Reducing sleep to 42.842871ms 2026/09/26 01:27:08 DEBUG : pacer: Reducing sleep to 40.386398ms 2026/09/26 01:27:08 DEBUG : pacer: Reducing sleep to 175.395509ms 2026/09/26 01:27:08 DEBUG : pacer: Reducing sleep to 204.882051ms 2026/09/26 01:27:08 DEBUG : pacer: Reducing sleep to 342.493778ms 2026/09/26 01:27:08 DEBUG : pacer: Reducing sleep to 331.022325ms 2026/09/26 01:27:08 DEBUG : pacer: Reducing sleep to 160.677805ms 2026/09/26 01:27:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:09 DEBUG : pacer: Reducing sleep to 7.037211ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/26 01:27:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:09 DEBUG : pacer: Reducing sleep to 86.706569ms 2026/09/26 01:27:09 DEBUG : pacer: Reducing sleep to 193.651582ms 2026/09/26 01:27:09 DEBUG : pacer: Reducing sleep to 270.633448ms 2026/09/26 01:27:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:12 DEBUG : pacer: Reducing sleep to 104.967116ms 2026/09/26 01:27:12 DEBUG : pacer: Reducing sleep to 64.1747ms 2026/09/26 01:27:17 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/26 01:27:21 DEBUG : pacer: Reducing sleep to 92.310365ms 2026/09/26 01:27:21 DEBUG : pacer: Reducing sleep to 159.055123ms 2026/09/26 01:27:22 DEBUG : pacer: Reducing sleep to 256.02963ms 2026/09/26 01:27:22 DEBUG : pacer: Reducing sleep to 271.153589ms 2026/09/26 01:27:22 DEBUG : pacer: Reducing sleep to 181.489678ms 2026/09/26 01:27:22 DEBUG : pacer: Reducing sleep to 76.764915ms 2026/09/26 01:27:22 DEBUG : pacer: Reducing sleep to 58.890205ms 2026/09/26 01:27:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:36 DEBUG : pacer: Reducing sleep to 86.30462ms 2026/09/26 01:27:36 DEBUG : pacer: Reducing sleep to 178.681681ms 2026/09/26 01:27:36 DEBUG : pacer: Reducing sleep to 237.552455ms 2026/09/26 01:27:36 DEBUG : pacer: Reducing sleep to 251.379534ms 2026/09/26 01:27:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:40 DEBUG : pacer: Reducing sleep to 104.791134ms 2026/09/26 01:27:40 DEBUG : pacer: Reducing sleep to 269.89126ms 2026/09/26 01:27:40 DEBUG : pacer: Reducing sleep to 249.213784ms 2026/09/26 01:27:41 DEBUG : pacer: Reducing sleep to 143.747291ms 2026/09/26 01:27:41 DEBUG : pacer: Reducing sleep to 68.491088ms 2026/09/26 01:27:41 DEBUG : pacer: Reducing sleep to 81.613827ms 2026/09/26 01:27:41 DEBUG : pacer: Reducing sleep to 171.803356ms 2026/09/26 01:27:41 DEBUG : pacer: Reducing sleep to 241.126054ms 2026/09/26 01:27:41 DEBUG : pacer: Reducing sleep to 250.584631ms 2026/09/26 01:27:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:45 DEBUG : pacer: Reducing sleep to 108.489941ms 2026/09/26 01:27:45 DEBUG : pacer: Reducing sleep to 266.235444ms 2026/09/26 01:27:45 DEBUG : pacer: Reducing sleep to 258.392356ms 2026/09/26 01:27:45 DEBUG : pacer: Reducing sleep to 155.563163ms 2026/09/26 01:27:45 DEBUG : pacer: Reducing sleep to 72.960271ms 2026/09/26 01:27:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:49 DEBUG : pacer: Reducing sleep to 95.575155ms 2026/09/26 01:27:49 DEBUG : pacer: Reducing sleep to 172.657591ms 2026/09/26 01:27:50 DEBUG : pacer: Reducing sleep to 261.632686ms 2026/09/26 01:27:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:54 DEBUG : pacer: Reducing sleep to 111.447959ms 2026/09/26 01:27:54 DEBUG : pacer: Reducing sleep to 272.117616ms 2026/09/26 01:27:54 DEBUG : pacer: Reducing sleep to 240.823915ms 2026/09/26 01:27:54 DEBUG : pacer: Reducing sleep to 132.788147ms 2026/09/26 01:27:55 DEBUG : pacer: Reducing sleep to 58.055112ms 2026/09/26 01:27:55 DEBUG : pacer: Reducing sleep to 87.91525ms 2026/09/26 01:27:55 DEBUG : pacer: Reducing sleep to 182.678821ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/26 01:27:55 DEBUG : Creating backend with remote "TestZoho:rclone-test-xamanez4viri" 2026/09/26 01:27:56 DEBUG : pacer: Reducing sleep to 17.640493ms 2026/09/26 01:27:56 DEBUG : pacer: Reducing sleep to 64.446µs 2026/09/26 01:27:57 DEBUG : pacer: Reducing sleep to 16.304553ms 2026/09/26 01:27:57 DEBUG : pacer: Reducing sleep to 7.796257ms 2026/09/26 01:27:57 DEBUG : pacer: Reducing sleep to 22.528908ms 2026/09/26 01:27:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:57 DEBUG : pacer: Reducing sleep to 6.429103ms 2026/09/26 01:27:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:27:59 DEBUG : pacer: Reducing sleep to 14.208139ms 2026/09/26 01:27:59 DEBUG : pacer: Reducing sleep to 34.683357ms 2026/09/26 01:27:59 DEBUG : pacer: Reducing sleep to 66.14004ms 2026/09/26 01:27:59 DEBUG : pacer: Reducing sleep to 78.092758ms 2026/09/26 01:28:01 DEBUG : pacer: Reducing sleep to 29.881769ms 2026/09/26 01:28:01 DEBUG : pacer: Reducing sleep to 133.573897ms 2026/09/26 01:28:01 DEBUG : pacer: Reducing sleep to 238.267446ms 2026/09/26 01:28:01 DEBUG : pacer: Reducing sleep to 264.667316ms 2026/09/26 01:28:01 DEBUG : pacer: Reducing sleep to 192.753978ms 2026/09/26 01:28:02 DEBUG : pacer: Reducing sleep to 92.743272ms 2026/09/26 01:28:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:28:02 DEBUG : pacer: Reducing sleep to 31.505115ms 2026/09/26 01:28:02 DEBUG : zoho root 'rclone-test-xamanez4viri': Purge remote 2026/09/26 01:28:02 DEBUG : pacer: Reducing sleep to 54.500905ms 2026/09/26 01:28:02 DEBUG : pacer: Reducing sleep to 63.345853ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/26 01:28:02 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/26 01:28:02 DEBUG : pacer: Reducing sleep to 105.499122ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/26 01:28:02 DEBUG : pacer: Reducing sleep to 208.572821ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/26 01:28:03 DEBUG : pacer: Reducing sleep to 271.84048ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/26 01:28:03 DEBUG : pacer: Reducing sleep to 227.313961ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/26 01:28:03 DEBUG : pacer: Reducing sleep to 123.518844ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType 2026/09/26 01:28:08 DEBUG : pacer: Reducing sleep to 0s fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/09/26 01:28:08 DEBUG : zoho root 'rclone-test-civamas1bawi': folder listing limiter sweep: evicted 4 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/26 01:28:31 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:31 UTC": parsing time "Sat, 26 Sep 2026 01:28:31 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:31 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/26 01:28:36 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:36 UTC": parsing time "Sat, 26 Sep 2026 01:28:36 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:36 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/26 01:28:41 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:41 UTC": parsing time "Sat, 26 Sep 2026 01:28:41 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:41 UTC" as " " 2026/09/26 01:28:41 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:41 UTC": parsing time "Sat, 26 Sep 2026 01:28:41 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:41 UTC" as " " 2026/09/26 01:28:42 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:42 UTC": parsing time "Sat, 26 Sep 2026 01:28:42 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:42 UTC" as " " 2026/09/26 01:28:42 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:42 UTC": parsing time "Sat, 26 Sep 2026 01:28:42 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:42 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/26 01:28:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:45 UTC": parsing time "Sat, 26 Sep 2026 01:28:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:45 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/26 01:28:50 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:50 UTC": parsing time "Sat, 26 Sep 2026 01:28:50 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:50 UTC" as " " 2026/09/26 01:28:54 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:54 UTC": parsing time "Sat, 26 Sep 2026 01:28:54 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:54 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/26 01:28:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:55 UTC": parsing time "Sat, 26 Sep 2026 01:28:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:55 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/26 01:28:56 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:55 UTC": parsing time "Sat, 26 Sep 2026 01:28:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:55 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/26 01:28:56 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:56 UTC": parsing time "Sat, 26 Sep 2026 01:28:56 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:56 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/26 01:28:56 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:56 UTC": parsing time "Sat, 26 Sep 2026 01:28:56 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:56 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/26 01:28:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:28:59 UTC": parsing time "Sat, 26 Sep 2026 01:28:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:28:59 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/26 01:29:03 DEBUG : pacer: Reducing sleep to 15.658969ms 2026/09/26 01:29:03 DEBUG : pacer: Reducing sleep to 120.880425ms 2026/09/26 01:29:04 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 26 Sep 2026 01:29:04 UTC": parsing time "Sat, 26 Sep 2026 01:29:04 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 26 Sep 2026 01:29:04 UTC" as " " 2026/09/26 01:29:04 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/26 01:29:04 DEBUG : pacer: Reducing sleep to 102.168851ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/26 01:29:04 DEBUG : Creating backend with remote "TestZoho:rclone-test-civamas1bawi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/26 01:29:05 DEBUG : pacer: Reducing sleep to 1.432202ms 2026/09/26 01:29:05 DEBUG : pacer: Reducing sleep to 7.465989ms 2026/09/26 01:29:05 DEBUG : pacer: Reducing sleep to 11.67105ms 2026/09/26 01:29:06 DEBUG : pacer: Reducing sleep to 23.020219ms 2026/09/26 01:29:06 DEBUG : pacer: Reducing sleep to 38.522352ms 2026/09/26 01:29:06 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:29:06 DEBUG : pacer: Reducing sleep to 11.904816ms 2026/09/26 01:29:06 DEBUG : pacer: Reducing sleep to 20.9541ms 2026/09/26 01:29:06 DEBUG : pacer: Reducing sleep to 22.407464ms 2026/09/26 01:29:07 DEBUG : pacer: Reducing sleep to 30.16207ms 2026/09/26 01:29:07 DEBUG : pacer: Reducing sleep to 39.180966ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/26 01:29:07 DEBUG : pacer: Reducing sleep to 61.363411ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/26 01:29:07 DEBUG : Creating backend with remote "TestZoho:rclone-test-civamas1bawi/not found.txt" 2026/09/26 01:29:08 DEBUG : zoho root 'rclone-test-civamas1bawi/not found.txt': folder listing limiter sweep: evicted 5 of 11 idle limiters 2026/09/26 01:29:08 DEBUG : pacer: Reducing sleep to 25.353874ms 2026/09/26 01:29:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:29:17 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-civamas1bawi" from "TestZoho:rclone-test-civamas1bawi" 2026/09/26 01:29:17 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/26 01:29:22 DEBUG : pacer: Reducing sleep to 100.172378ms 2026/09/26 01:29:22 DEBUG : pacer: Reducing sleep to 204.922504ms 2026/09/26 01:29:22 DEBUG : pacer: Reducing sleep to 271.089631ms 2026/09/26 01:29:22 DEBUG : pacer: Reducing sleep to 231.645863ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/26 01:29:26 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/26 01:29:31 DEBUG : pacer: Reducing sleep to 10.333188ms 2026/09/26 01:29:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:29:40 DEBUG : pacer: Reducing sleep to 105.668351ms 2026/09/26 01:29:40 DEBUG : pacer: Reducing sleep to 210.871403ms 2026/09/26 01:29:40 DEBUG : pacer: Reducing sleep to 271.136882ms 2026/09/26 01:29:40 DEBUG : pacer: Reducing sleep to 223.102816ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/26 01:29:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:29:45 DEBUG : pacer: Reducing sleep to 97.867985ms 2026/09/26 01:29:45 DEBUG : pacer: Reducing sleep to 200.99316ms 2026/09/26 01:29:45 DEBUG : pacer: Reducing sleep to 264.280308ms 2026/09/26 01:29:45 DEBUG : pacer: Reducing sleep to 235.695965ms === 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/26 01:29:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:29:54 DEBUG : pacer: Reducing sleep to 109.072252ms 2026/09/26 01:29:54 DEBUG : pacer: Reducing sleep to 215.984562ms 2026/09/26 01:29:54 DEBUG : pacer: Reducing sleep to 270.477083ms 2026/09/26 01:29:54 DEBUG : pacer: Reducing sleep to 221.30049ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/26 01:29:55 DEBUG : pacer: Reducing sleep to 119.810748ms === 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/26 01:29:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:29:58 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/26 01:29:58 DEBUG : pacer: Rate limited, increasing sleep to 1.777029561s 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/26 01:30:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:30:03 DEBUG : pacer: Reducing sleep to 108.703855ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/26 01:30:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/26 01:30:05 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=3rhx03a1df80434a64e8b8d3b014bc47744f5": write tcp: unexpected EOF) 2026/09/26 01:30:05 DEBUG : pacer: Rate limited, increasing sleep to 1.943252939s 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=3rhx03a1df80434a64e8b8d3b014bc47744f5": write tcp: unexpected EOF 2026/09/26 01:30:05 DEBUG : pacer: Reducing sleep to 57.307619ms 2026/09/26 01:30:07 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/26 01:30:07 DEBUG : pacer: Reducing sleep to 108.364926ms 2026/09/26 01:30:07 DEBUG : pacer: Reducing sleep to 233.939151ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n