"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/28 04:54:15 NOTICE: zoho root '': Too many requests: Trying again in 6 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_without_Retry-After_falls_back_to_60s 2026/09/28 04:54:15 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_with_unparseable_Retry-After_falls_back 2026/09/28 04:54:15 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/28 04:54:15 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/09/28 04:54:15 ERROR : zoho: missing OAuth Scope. Run rclone config reconnect to fix this issue. === RUN TestShouldRetry/401_expired_token_retries === RUN TestShouldRetry/401_R008_deleted_folder_is_not_retried === RUN TestShouldRetry/cancelled_context_aborts --- PASS: TestShouldRetry (0.00s) --- PASS: TestShouldRetry/429_honours_Retry-After (0.00s) --- PASS: TestShouldRetry/429_without_Retry-After_falls_back_to_60s (0.00s) --- PASS: TestShouldRetry/429_with_unparseable_Retry-After_falls_back (0.00s) --- PASS: TestShouldRetry/401_missing_scope_aborts (0.00s) --- PASS: TestShouldRetry/401_expired_token_retries (0.00s) --- PASS: TestShouldRetry/401_R008_deleted_folder_is_not_retried (0.00s) --- PASS: TestShouldRetry/cancelled_context_aborts (0.00s) === RUN TestIsMissingResourceErr --- PASS: TestIsMissingResourceErr (0.00s) === RUN TestThrottleEpisode 2026/09/28 04:54:15 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/09/28 04:54:15 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleStateConcurrent (0.00s) === RUN TestConfigRootFolderID === RUN TestConfigRootFolderID/SetRootAsksBeforeChanging === RUN TestConfigRootFolderID/EmptyRootGoesToEdition === RUN TestConfigRootFolderID/KeepRootOnNo === RUN TestConfigRootFolderID/ChangeRootOnYes --- PASS: TestConfigRootFolderID (0.00s) --- PASS: TestConfigRootFolderID/SetRootAsksBeforeChanging (0.00s) --- PASS: TestConfigRootFolderID/EmptyRootGoesToEdition (0.00s) --- PASS: TestConfigRootFolderID/KeepRootOnNo (0.00s) --- PASS: TestConfigRootFolderID/ChangeRootOnYes (0.00s) === RUN TestIntegration fstests.go:464: Using remote "TestZoho:" 2026/09/28 04:54:15 DEBUG : Creating backend with remote "TestZoho:rclone-test-babacef3mafe" 2026/09/28 04:54:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/28 04:54:15 DEBUG : zoho root 'rclone-test-babacef3mafe': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/28 04:54:15 DEBUG : TestZoho: Token expired 2026/09/28 04:54:15 DEBUG : TestZoho: No updated token found in the config file 2026/09/28 04:54:15 DEBUG : TestZoho: Token refresh successful 2026/09/28 04:54:15 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/28 04:54:15 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:505: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:533: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/09/28 04:54:16 DEBUG : pacer: Reducing sleep to 33.579564ms 2026/09/28 04:54:16 DEBUG : pacer: Reducing sleep to 58.386268ms 2026/09/28 04:54:16 DEBUG : pacer: Reducing sleep to 53.070262ms 2026/09/28 04:54:16 DEBUG : pacer: Reducing sleep to 177.910856ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/28 04:54:16 DEBUG : pacer: Reducing sleep to 284.550151ms 2026/09/28 04:54:17 DEBUG : pacer: Reducing sleep to 259.150584ms === RUN TestIntegration/FsMkdir 2026/09/28 04:54:17 DEBUG : pacer: Reducing sleep to 74.962319ms 2026/09/28 04:54:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:54:17 DEBUG : pacer: Reducing sleep to 124.538764ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/28 04:54:17 INFO : dir/subdir: Making directory 2026/09/28 04:54:17 DEBUG : pacer: Reducing sleep to 248.270022ms 2026/09/28 04:54:18 DEBUG : pacer: Reducing sleep to 245.644895ms 2026/09/28 04:54:18 DEBUG : pacer: Reducing sleep to 210.289173ms 2026/09/28 04:54:18 DEBUG : pacer: Reducing sleep to 95.177691ms 2026/09/28 04:54:18 DEBUG : pacer: Reducing sleep to 68.781803ms 2026/09/28 04:54:18 DEBUG : pacer: Reducing sleep to 148.648802ms 2026/09/28 04:54:18 DEBUG : pacer: Reducing sleep to 250.168624ms 2026/09/28 04:54:18 INFO : dir/subdir: Removing directory 2026/09/28 04:54:19 DEBUG : pacer: Reducing sleep to 263.066368ms 2026/09/28 04:54:19 DEBUG : pacer: Reducing sleep to 168.620755ms 2026/09/28 04:54:19 DEBUG : pacer: Reducing sleep to 82.572435ms 2026/09/28 04:54:19 DEBUG : pacer: Reducing sleep to 83.432958ms 2026/09/28 04:54:19 INFO : dir: Removing directory 2026/09/28 04:54:19 DEBUG : pacer: Reducing sleep to 164.462821ms 2026/09/28 04:54:19 DEBUG : pacer: Reducing sleep to 238.295569ms 2026/09/28 04:54:20 DEBUG : pacer: Reducing sleep to 247.923932ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/28 04:54:20 DEBUG : pacer: Reducing sleep to 176.810532ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/28 04:54:24 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/28 04:54:29 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:734: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/09/28 04:54:38 DEBUG : pacer: Reducing sleep to 99.026864ms 2026/09/28 04:54:38 DEBUG : pacer: Reducing sleep to 220.633891ms 2026/09/28 04:54:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:54:39 DEBUG : pacer: Reducing sleep to 118.040856ms 2026/09/28 04:54:43 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:54:43 DEBUG : pacer: Reducing sleep to 115.328667ms 2026/09/28 04:54:43 DEBUG : pacer: Reducing sleep to 203.815113ms 2026/09/28 04:54:43 DEBUG : pacer: Reducing sleep to 269.296446ms 2026/09/28 04:54:43 DEBUG : pacer: Reducing sleep to 209.636121ms 2026/09/28 04:54:47 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/28 04:54:52 DEBUG : pacer: Reducing sleep to 104.653375ms 2026/09/28 04:54:52 DEBUG : pacer: Reducing sleep to 226.904709ms 2026/09/28 04:54:52 DEBUG : pacer: Reducing sleep to 187.78171ms 2026/09/28 04:54:52 DEBUG : pacer: Reducing sleep to 218.906436ms 2026/09/28 04:54:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:54:57 DEBUG : pacer: Reducing sleep to 118.065251ms 2026/09/28 04:54:57 DEBUG : pacer: Reducing sleep to 97.063259ms 2026/09/28 04:54:57 DEBUG : pacer: Reducing sleep to 167.720279ms 2026/09/28 04:54:57 DEBUG : pacer: Reducing sleep to 227.404897ms 2026/09/28 04:55:01 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/28 04:55:06 DEBUG : pacer: Reducing sleep to 101.329389ms 2026/09/28 04:55:06 DEBUG : pacer: Reducing sleep to 222.043761ms 2026/09/28 04:55:06 DEBUG : pacer: Reducing sleep to 206.031756ms 2026/09/28 04:55:06 DEBUG : pacer: Reducing sleep to 229.052924ms 2026/09/28 04:55:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:55:10 DEBUG : pacer: Reducing sleep to 119.536478ms 2026/09/28 04:55:11 DEBUG : pacer: Reducing sleep to 93.635065ms 2026/09/28 04:55:11 DEBUG : pacer: Reducing sleep to 158.908221ms 2026/09/28 04:55:11 DEBUG : pacer: Reducing sleep to 215.147462ms 2026/09/28 04:55:15 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/28 04:55:15 DEBUG : zoho root 'rclone-test-babacef3mafe': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/28 04:55:20 DEBUG : pacer: Reducing sleep to 102.191506ms 2026/09/28 04:55:20 DEBUG : pacer: Reducing sleep to 220.363397ms 2026/09/28 04:55:20 DEBUG : pacer: Reducing sleep to 127.968303ms 2026/09/28 04:55:20 DEBUG : pacer: Reducing sleep to 231.920082ms 2026/09/28 04:55:20 DEBUG : pacer: Reducing sleep to 261.272921ms 2026/09/28 04:55:20 DEBUG : pacer: Reducing sleep to 204.699145ms 2026/09/28 04:55:21 DEBUG : pacer: Reducing sleep to 93.137469ms 2026/09/28 04:55:21 DEBUG : pacer: Reducing sleep to 73.183392ms 2026/09/28 04:55:21 DEBUG : pacer: Reducing sleep to 129.740874ms 2026/09/28 04:55:21 DEBUG : pacer: Reducing sleep to 243.698762ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/28 04:55:21 DEBUG : pacer: Reducing sleep to 264.757143ms 2026/09/28 04:55:21 DEBUG : pacer: Reducing sleep to 173.410838ms 2026/09/28 04:55:22 DEBUG : pacer: Reducing sleep to 81.259943ms 2026/09/28 04:55:22 DEBUG : pacer: Reducing sleep to 25.278386ms 2026/09/28 04:55:22 DEBUG : pacer: Reducing sleep to 145.315799ms 2026/09/28 04:55:22 DEBUG : pacer: Reducing sleep to 248.085948ms 2026/09/28 04:55:22 DEBUG : pacer: Reducing sleep to 284.654518ms 2026/09/28 04:55:22 DEBUG : pacer: Reducing sleep to 182.957924ms 2026/09/28 04:55:23 DEBUG : pacer: Reducing sleep to 80.841897ms 2026/09/28 04:55:23 DEBUG : pacer: Reducing sleep to 59.758457ms 2026/09/28 04:55:23 DEBUG : pacer: Reducing sleep to 157.925777ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/28 04:55:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:55:28 DEBUG : pacer: Reducing sleep to 40.643963ms 2026/09/28 04:55:28 DEBUG : pacer: Reducing sleep to 165.947939ms 2026/09/28 04:55:28 DEBUG : pacer: Reducing sleep to 146.777267ms 2026/09/28 04:55:28 DEBUG : pacer: Reducing sleep to 259.891983ms 2026/09/28 04:55:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:55:32 DEBUG : pacer: Reducing sleep to 111.514125ms 2026/09/28 04:55:32 DEBUG : pacer: Reducing sleep to 62.996956ms 2026/09/28 04:55:33 DEBUG : pacer: Reducing sleep to 126.87108ms 2026/09/28 04:55:33 DEBUG : pacer: Reducing sleep to 221.384982ms 2026/09/28 04:55:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/28 04:55:41 DEBUG : pacer: Reducing sleep to 91.174007ms 2026/09/28 04:55:42 DEBUG : pacer: Reducing sleep to 208.693376ms 2026/09/28 04:55:42 DEBUG : pacer: Reducing sleep to 224.352093ms 2026/09/28 04:55:42 DEBUG : pacer: Reducing sleep to 232.789846ms 2026/09/28 04:55:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:55:46 DEBUG : pacer: Reducing sleep to 115.101975ms 2026/09/28 04:55:46 DEBUG : pacer: Reducing sleep to 92.122933ms 2026/09/28 04:55:46 DEBUG : pacer: Reducing sleep to 150.872233ms 2026/09/28 04:55:46 DEBUG : pacer: Reducing sleep to 217.09047ms 2026/09/28 04:55:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/28 04:55:55 DEBUG : pacer: Reducing sleep to 95.411085ms 2026/09/28 04:55:55 DEBUG : pacer: Reducing sleep to 221.487277ms 2026/09/28 04:55:56 DEBUG : pacer: Reducing sleep to 135.650565ms 2026/09/28 04:55:56 DEBUG : pacer: Reducing sleep to 224.324251ms 2026/09/28 04:56:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:56:00 DEBUG : pacer: Reducing sleep to 66.04019ms 2026/09/28 04:56:00 DEBUG : pacer: Reducing sleep to 131.32268ms 2026/09/28 04:56:00 DEBUG : pacer: Reducing sleep to 241.925763ms 2026/09/28 04:56:00 DEBUG : pacer: Reducing sleep to 271.259843ms 2026/09/28 04:56:04 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/28 04:56:09 DEBUG : pacer: Reducing sleep to 84.784693ms 2026/09/28 04:56:09 DEBUG : pacer: Reducing sleep to 209.192395ms 2026/09/28 04:56:09 DEBUG : pacer: Reducing sleep to 202.528338ms 2026/09/28 04:56:10 DEBUG : pacer: Reducing sleep to 225.222552ms 2026/09/28 04:56:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:56:14 DEBUG : pacer: Reducing sleep to 113.456469ms 2026/09/28 04:56:14 DEBUG : pacer: Reducing sleep to 92.178904ms 2026/09/28 04:56:14 DEBUG : pacer: Reducing sleep to 169.697161ms 2026/09/28 04:56:14 DEBUG : pacer: Reducing sleep to 228.796728ms 2026/09/28 04:56:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/28 04:56:18 DEBUG : zoho root 'rclone-test-babacef3mafe': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/28 04:56:18 DEBUG : pacer: Reducing sleep to 121.112842ms 2026/09/28 04:56:19 DEBUG : pacer: Reducing sleep to 80.041763ms 2026/09/28 04:56:19 DEBUG : pacer: Reducing sleep to 148.174883ms 2026/09/28 04:56:19 DEBUG : pacer: Reducing sleep to 90.63866ms 2026/09/28 04:56:19 DEBUG : pacer: Reducing sleep to 211.254915ms 2026/09/28 04:56:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:56:20 DEBUG : pacer: Reducing sleep to 119.045919ms 2026/09/28 04:56:20 DEBUG : pacer: Reducing sleep to 114.432372ms 2026/09/28 04:56:20 DEBUG : pacer: Reducing sleep to 173.772694ms 2026/09/28 04:56:21 DEBUG : pacer: Reducing sleep to 210.185143ms 2026/09/28 04:56:21 DEBUG : pacer: Reducing sleep to 220.487022ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/28 04:56:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:56:22 DEBUG : pacer: Reducing sleep to 91.678949ms 2026/09/28 04:56:22 DEBUG : pacer: Reducing sleep to 138.069523ms 2026/09/28 04:56:22 DEBUG : pacer: Reducing sleep to 151.963453ms 2026/09/28 04:56:22 DEBUG : pacer: Reducing sleep to 239.508015ms 2026/09/28 04:56:22 DEBUG : pacer: Reducing sleep to 242.440174ms 2026/09/28 04:56:22 DEBUG : pacer: Reducing sleep to 180.463558ms 2026/09/28 04:56:23 DEBUG : pacer: Reducing sleep to 84.50277ms 2026/09/28 04:56:23 DEBUG : pacer: Reducing sleep to 91.742107ms 2026/09/28 04:56:23 DEBUG : pacer: Reducing sleep to 160.672372ms 2026/09/28 04:56:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/28 04:56:31 DEBUG : pacer: Reducing sleep to 89.896934ms 2026/09/28 04:56:31 DEBUG : pacer: Reducing sleep to 216.988652ms 2026/09/28 04:56:32 DEBUG : pacer: Reducing sleep to 147.972628ms 2026/09/28 04:56:32 DEBUG : pacer: Reducing sleep to 232.627625ms 2026/09/28 04:56:36 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:56:36 DEBUG : pacer: Reducing sleep to 114.121444ms 2026/09/28 04:56:36 DEBUG : pacer: Reducing sleep to 93.198275ms 2026/09/28 04:56:36 DEBUG : pacer: Reducing sleep to 151.317311ms 2026/09/28 04:56:36 DEBUG : pacer: Reducing sleep to 219.975418ms 2026/09/28 04:56:41 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/28 04:56:45 DEBUG : pacer: Reducing sleep to 97.067999ms 2026/09/28 04:56:45 DEBUG : pacer: Reducing sleep to 217.706184ms 2026/09/28 04:56:45 DEBUG : pacer: Reducing sleep to 146.030847ms 2026/09/28 04:56:46 DEBUG : pacer: Reducing sleep to 227.641167ms 2026/09/28 04:56:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:56:50 DEBUG : pacer: Reducing sleep to 115.373005ms 2026/09/28 04:56:50 DEBUG : pacer: Reducing sleep to 88.768931ms 2026/09/28 04:56:50 DEBUG : pacer: Reducing sleep to 164.099226ms 2026/09/28 04:56:50 DEBUG : pacer: Reducing sleep to 225.355132ms 2026/09/28 04:56:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/28 04:56:59 DEBUG : pacer: Reducing sleep to 96.799943ms 2026/09/28 04:56:59 DEBUG : pacer: Reducing sleep to 220.09315ms 2026/09/28 04:56:59 DEBUG : pacer: Reducing sleep to 216.13756ms 2026/09/28 04:56:59 DEBUG : pacer: Reducing sleep to 231.427802ms 2026/09/28 04:57:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:57:04 DEBUG : pacer: Reducing sleep to 104.308026ms 2026/09/28 04:57:04 DEBUG : pacer: Reducing sleep to 99.076075ms 2026/09/28 04:57:04 DEBUG : pacer: Reducing sleep to 169.975093ms 2026/09/28 04:57:04 DEBUG : pacer: Reducing sleep to 229.364133ms 2026/09/28 04:57:08 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/28 04:57:13 DEBUG : pacer: Reducing sleep to 91.018829ms 2026/09/28 04:57:13 DEBUG : pacer: Reducing sleep to 218.061905ms 2026/09/28 04:57:13 DEBUG : pacer: Reducing sleep to 150.677774ms 2026/09/28 04:57:13 DEBUG : pacer: Reducing sleep to 227.994097ms 2026/09/28 04:57:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:57:17 DEBUG : pacer: Reducing sleep to 107.896632ms 2026/09/28 04:57:18 DEBUG : pacer: Reducing sleep to 84.540786ms 2026/09/28 04:57:18 DEBUG : pacer: Reducing sleep to 162.521062ms 2026/09/28 04:57:18 DEBUG : pacer: Reducing sleep to 227.12719ms 2026/09/28 04:57:18 DEBUG : pacer: Reducing sleep to 251.34398ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/28 04:57:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:57:19 DEBUG : pacer: Reducing sleep to 96.827063ms 2026/09/28 04:57:19 DEBUG : zoho root 'rclone-test-babacef3mafe': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/28 04:57:19 DEBUG : pacer: Reducing sleep to 79.908869ms 2026/09/28 04:57:19 DEBUG : pacer: Reducing sleep to 70.412548ms 2026/09/28 04:57:19 DEBUG : pacer: Reducing sleep to 189.106722ms 2026/09/28 04:57:19 DEBUG : pacer: Reducing sleep to 276.989481ms 2026/09/28 04:57:20 DEBUG : pacer: Reducing sleep to 232.049549ms 2026/09/28 04:57:20 DEBUG : pacer: Reducing sleep to 139.55244ms 2026/09/28 04:57:20 DEBUG : pacer: Reducing sleep to 77.371772ms 2026/09/28 04:57:20 DEBUG : pacer: Reducing sleep to 106.629369ms 2026/09/28 04:57:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/28 04:57:21 DEBUG : pacer: Reducing sleep to 84.793978ms 2026/09/28 04:57:21 DEBUG : pacer: Reducing sleep to 209.971241ms 2026/09/28 04:57:21 DEBUG : pacer: Reducing sleep to 151.702618ms 2026/09/28 04:57:22 DEBUG : pacer: Reducing sleep to 213.915378ms 2026/09/28 04:57:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:57:26 DEBUG : pacer: Reducing sleep to 113.575119ms 2026/09/28 04:57:26 DEBUG : pacer: Reducing sleep to 112.021731ms 2026/09/28 04:57:26 DEBUG : pacer: Reducing sleep to 176.982959ms 2026/09/28 04:57:26 DEBUG : pacer: Reducing sleep to 219.071348ms 2026/09/28 04:57:30 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/28 04:57:35 DEBUG : pacer: Reducing sleep to 99.593816ms 2026/09/28 04:57:35 DEBUG : pacer: Reducing sleep to 220.558676ms 2026/09/28 04:57:35 DEBUG : pacer: Reducing sleep to 219.167083ms 2026/09/28 04:57:35 DEBUG : pacer: Reducing sleep to 230.671543ms 2026/09/28 04:57:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:57:40 DEBUG : pacer: Reducing sleep to 94.605128ms 2026/09/28 04:57:40 DEBUG : pacer: Reducing sleep to 87.934196ms 2026/09/28 04:57:40 DEBUG : pacer: Reducing sleep to 179.002409ms 2026/09/28 04:57:40 DEBUG : pacer: Reducing sleep to 255.057669ms 2026/09/28 04:57:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/28 04:58:03 DEBUG : pacer: Reducing sleep to 82.686837ms 2026/09/28 04:58:03 DEBUG : pacer: Reducing sleep to 207.13913ms 2026/09/28 04:58:03 DEBUG : pacer: Reducing sleep to 253.277091ms 2026/09/28 04:58:03 DEBUG : pacer: Reducing sleep to 248.275049ms 2026/09/28 04:58:03 DEBUG : pacer: Reducing sleep to 122.435497ms 2026/09/28 04:58:04 DEBUG : pacer: Reducing sleep to 73.429314ms 2026/09/28 04:58:04 DEBUG : pacer: Reducing sleep to 76.902351ms 2026/09/28 04:58:04 DEBUG : pacer: Reducing sleep to 242.927285ms 2026/09/28 04:58:04 DEBUG : pacer: Reducing sleep to 290.059082ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/28 04:58:07 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:58:07 DEBUG : pacer: Reducing sleep to 60.176021ms 2026/09/28 04:58:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:58:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 04:58:12 UTC": parsing time "Mon, 28 Sep 2026 04:58:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 04:58:12 UTC" as " " 2026/09/28 04:58:12 DEBUG : pacer: Reducing sleep to 112.265385ms === 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/28 04:58:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:58:21 DEBUG : zoho root 'rclone-test-babacef3mafe': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/28 04:58:21 DEBUG : pacer: Reducing sleep to 126.616888ms 2026/09/28 04:58:21 DEBUG : pacer: Reducing sleep to 160.114877ms 2026/09/28 04:58:21 DEBUG : pacer: Reducing sleep to 271.02766ms 2026/09/28 04:58:22 DEBUG : pacer: Reducing sleep to 275.299444ms 2026/09/28 04:58:22 DEBUG : pacer: Reducing sleep to 13.937356ms 2026/09/28 04:58:22 DEBUG : pacer: Reducing sleep to 71.319613ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/28 04:58:22 DEBUG : pacer: Reducing sleep to 179.969594ms 2026/09/28 04:58:22 DEBUG : pacer: Reducing sleep to 281.130166ms 2026/09/28 04:58:22 DEBUG : pacer: Reducing sleep to 260.028379ms 2026/09/28 04:58:23 DEBUG : pacer: Reducing sleep to 156.572482ms 2026/09/28 04:58:23 DEBUG : pacer: Reducing sleep to 62.853976ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/28 04:58:23 DEBUG : pacer: Reducing sleep to 69.545438ms 2026/09/28 04:58:23 DEBUG : pacer: Reducing sleep to 165.887293ms 2026/09/28 04:58:23 DEBUG : pacer: Reducing sleep to 270.651849ms 2026/09/28 04:58:23 DEBUG : pacer: Reducing sleep to 262.022517ms 2026/09/28 04:58:24 DEBUG : pacer: Reducing sleep to 163.274959ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/28 04:58:24 DEBUG : pacer: Reducing sleep to 66.81061ms 2026/09/28 04:58:24 DEBUG : pacer: Reducing sleep to 72.062253ms 2026/09/28 04:58:24 DEBUG : pacer: Reducing sleep to 163.399029ms 2026/09/28 04:58:24 DEBUG : pacer: Reducing sleep to 265.393877ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/28 04:58:24 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/09/28 04:58:29 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/09/28 04:58:33 DEBUG : pacer: Reducing sleep to 113.675759ms 2026/09/28 04:58:34 DEBUG : pacer: Reducing sleep to 233.458305ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/28 04:58:38 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:58:38 DEBUG : pacer: Reducing sleep to 115.466288ms 2026/09/28 04:58:38 DEBUG : pacer: Reducing sleep to 100.008503ms 2026/09/28 04:58:38 DEBUG : pacer: Reducing sleep to 143.965505ms 2026/09/28 04:58:39 DEBUG : pacer: Reducing sleep to 216.408163ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/28 04:58:43 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/28 04:58:47 DEBUG : pacer: Reducing sleep to 116.89725ms 2026/09/28 04:58:47 DEBUG : pacer: Reducing sleep to 236.19968ms 2026/09/28 04:58:48 DEBUG : pacer: Reducing sleep to 232.46142ms 2026/09/28 04:58:48 DEBUG : pacer: Reducing sleep to 210.916433ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/28 04:58:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/28 04:58:52 DEBUG : pacer: Reducing sleep to 112.856831ms 2026/09/28 04:58:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:58:57 DEBUG : pacer: Reducing sleep to 102.013841ms 2026/09/28 04:58:57 DEBUG : pacer: Reducing sleep to 157.133625ms 2026/09/28 04:58:57 DEBUG : pacer: Reducing sleep to 277.875141ms 2026/09/28 04:59:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:01 DEBUG : pacer: Reducing sleep to 119.310464ms 2026/09/28 04:59:01 DEBUG : pacer: Reducing sleep to 278.150977ms 2026/09/28 04:59:02 DEBUG : pacer: Reducing sleep to 225.985049ms 2026/09/28 04:59:02 DEBUG : pacer: Reducing sleep to 112.564094ms 2026/09/28 04:59:02 DEBUG : pacer: Reducing sleep to 53.872409ms 2026/09/28 04:59:02 DEBUG : pacer: Reducing sleep to 109.969447ms 2026/09/28 04:59:02 DEBUG : pacer: Reducing sleep to 213.172534ms 2026/09/28 04:59:06 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:06 DEBUG : pacer: Reducing sleep to 115.917799ms 2026/09/28 04:59:06 DEBUG : pacer: Reducing sleep to 122.272612ms 2026/09/28 04:59:06 DEBUG : pacer: Reducing sleep to 170.020528ms 2026/09/28 04:59:06 DEBUG : pacer: Reducing sleep to 217.192385ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/28 04:59:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:15 DEBUG : pacer: Reducing sleep to 85.404164ms 2026/09/28 04:59:16 DEBUG : pacer: Reducing sleep to 39.417062ms 2026/09/28 04:59:16 DEBUG : pacer: Reducing sleep to 148.609987ms 2026/09/28 04:59:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:20 DEBUG : pacer: Reducing sleep to 117.005712ms 2026/09/28 04:59:20 DEBUG : pacer: Reducing sleep to 278.662111ms 2026/09/28 04:59:20 DEBUG : pacer: Reducing sleep to 345.281142ms 2026/09/28 04:59:20 DEBUG : pacer: Reducing sleep to 239.870226ms 2026/09/28 04:59:21 DEBUG : pacer: Reducing sleep to 52.877035ms 2026/09/28 04:59:21 DEBUG : Creating backend with remote "TestZoho:rclone-test-babacef3mafe/dirToPurgeFromRoot" 2026/09/28 04:59:21 DEBUG : pacer: Reducing sleep to 115.582858ms 2026/09/28 04:59:21 DEBUG : pacer: Reducing sleep to 238.767525ms 2026/09/28 04:59:21 DEBUG : pacer: Reducing sleep to 273.128187ms 2026/09/28 04:59:21 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:21 DEBUG : pacer: Reducing sleep to 115.361289ms 2026/09/28 04:59:21 DEBUG : zoho root 'rclone-test-babacef3mafe': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/28 04:59:21 DEBUG : pacer: Reducing sleep to 236.983879ms 2026/09/28 04:59:21 DEBUG : pacer: Reducing sleep to 278.824211ms 2026/09/28 04:59:22 DEBUG : pacer: Reducing sleep to 205.364112ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/28 04:59:22 DEBUG : Creating backend with remote "TestZoho:rclone-test-babacef3mafe/hello? sausage/êé" 2026/09/28 04:59:22 DEBUG : pacer: Reducing sleep to 92.386056ms 2026/09/28 04:59:22 DEBUG : pacer: Reducing sleep to 211.60135ms 2026/09/28 04:59:22 DEBUG : pacer: Reducing sleep to 273.379212ms 2026/09/28 04:59:22 DEBUG : pacer: Reducing sleep to 238.86641ms 2026/09/28 04:59:23 DEBUG : pacer: Reducing sleep to 86.529344ms 2026/09/28 04:59:23 DEBUG : pacer: Reducing sleep to 60.440296ms 2026/09/28 04:59:23 DEBUG : pacer: Reducing sleep to 87.248319ms 2026/09/28 04:59:23 DEBUG : pacer: Reducing sleep to 212.105815ms 2026/09/28 04:59:23 DEBUG : pacer: Reducing sleep to 249.213664ms 2026/09/28 04:59:23 DEBUG : pacer: Reducing sleep to 243.021398ms 2026/09/28 04:59:23 DEBUG : pacer: Reducing sleep to 122.663002ms 2026/09/28 04:59:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:24 DEBUG : pacer: Reducing sleep to 118.530432ms 2026/09/28 04:59:24 DEBUG : pacer: Reducing sleep to 232.558363ms 2026/09/28 04:59:24 DEBUG : pacer: Reducing sleep to 274.956841ms 2026/09/28 04:59:24 DEBUG : pacer: Reducing sleep to 212.447625ms 2026/09/28 04:59:24 DEBUG : pacer: Reducing sleep to 104.902357ms 2026/09/28 04:59:25 DEBUG : pacer: Reducing sleep to 61.222411ms 2026/09/28 04:59:25 DEBUG : pacer: Reducing sleep to 111.247104ms 2026/09/28 04:59:25 DEBUG : pacer: Reducing sleep to 225.73307ms 2026/09/28 04:59:25 DEBUG : pacer: Reducing sleep to 277.7321ms 2026/09/28 04:59:25 DEBUG : pacer: Reducing sleep to 213.152947ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/28 04:59:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:26 DEBUG : pacer: Reducing sleep to 95.05323ms 2026/09/28 04:59:26 DEBUG : pacer: Reducing sleep to 203.833144ms 2026/09/28 04:59:26 DEBUG : pacer: Reducing sleep to 285.309624ms 2026/09/28 04:59:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:29 DEBUG : pacer: Reducing sleep to 111.755992ms 2026/09/28 04:59:29 DEBUG : pacer: Reducing sleep to 48.672532ms 2026/09/28 04:59:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:33 DEBUG : pacer: Reducing sleep to 84.831742ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/28 04:59:38 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:38 DEBUG : pacer: Reducing sleep to 101.656045ms 2026/09/28 04:59:38 DEBUG : pacer: Reducing sleep to 189.525032ms 2026/09/28 04:59:38 DEBUG : pacer: Reducing sleep to 275.101881ms 2026/09/28 04:59:38 DEBUG : pacer: Reducing sleep to 258.431ms 2026/09/28 04:59:38 DEBUG : pacer: Reducing sleep to 150.719832ms 2026/09/28 04:59:39 DEBUG : pacer: Reducing sleep to 55.712161ms 2026/09/28 04:59:39 DEBUG : pacer: Reducing sleep to 76.172999ms 2026/09/28 04:59:42 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:52 DEBUG : pacer: Reducing sleep to 96.985085ms 2026/09/28 04:59:52 DEBUG : pacer: Reducing sleep to 202.015089ms 2026/09/28 04:59:52 DEBUG : pacer: Reducing sleep to 252.972482ms 2026/09/28 04:59:52 DEBUG : pacer: Reducing sleep to 237.659253ms 2026/09/28 04:59:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 04:59:57 DEBUG : pacer: Reducing sleep to 116.854747ms 2026/09/28 04:59:57 DEBUG : pacer: Reducing sleep to 282.578953ms 2026/09/28 04:59:57 DEBUG : pacer: Reducing sleep to 270.311385ms 2026/09/28 04:59:57 DEBUG : pacer: Reducing sleep to 150.098579ms 2026/09/28 04:59:57 DEBUG : pacer: Reducing sleep to 55.44651ms 2026/09/28 04:59:57 DEBUG : pacer: Reducing sleep to 62.525314ms 2026/09/28 04:59:58 DEBUG : pacer: Reducing sleep to 141.190515ms 2026/09/28 04:59:58 DEBUG : pacer: Reducing sleep to 207.444435ms 2026/09/28 04:59:58 DEBUG : pacer: Reducing sleep to 274.164646ms 2026/09/28 05:00:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:00:01 DEBUG : pacer: Reducing sleep to 124.236333ms 2026/09/28 05:00:01 DEBUG : pacer: Reducing sleep to 276.663006ms 2026/09/28 05:00:01 DEBUG : pacer: Reducing sleep to 231.351273ms 2026/09/28 05:00:02 DEBUG : pacer: Reducing sleep to 114.20568ms 2026/09/28 05:00:02 DEBUG : pacer: Reducing sleep to 65.490211ms 2026/09/28 05:00:06 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:00:06 DEBUG : pacer: Reducing sleep to 100.694468ms 2026/09/28 05:00:06 DEBUG : pacer: Reducing sleep to 190.0758ms 2026/09/28 05:00:06 DEBUG : pacer: Reducing sleep to 273.101662ms 2026/09/28 05:00:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:00:10 DEBUG : pacer: Reducing sleep to 123.776906ms 2026/09/28 05:00:10 DEBUG : pacer: Reducing sleep to 282.855657ms 2026/09/28 05:00:11 DEBUG : pacer: Reducing sleep to 230.796917ms 2026/09/28 05:00:11 DEBUG : pacer: Reducing sleep to 114.765905ms 2026/09/28 05:00:11 DEBUG : pacer: Reducing sleep to 49.06027ms 2026/09/28 05:00:11 DEBUG : pacer: Reducing sleep to 98.674646ms 2026/09/28 05:00:11 DEBUG : pacer: Reducing sleep to 205.207968ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/28 05:00:11 DEBUG : Creating backend with remote "TestZoho:rclone-test-farivaf9tuzo" 2026/09/28 05:00:12 DEBUG : pacer: Reducing sleep to 24.397088ms 2026/09/28 05:00:12 DEBUG : pacer: Reducing sleep to 59.116142ms 2026/09/28 05:00:12 DEBUG : pacer: Reducing sleep to 77.046975ms 2026/09/28 05:00:12 DEBUG : pacer: Reducing sleep to 197.155585ms 2026/09/28 05:00:12 DEBUG : pacer: Reducing sleep to 251.906788ms 2026/09/28 05:00:12 DEBUG : pacer: Reducing sleep to 256.27254ms 2026/09/28 05:00:13 DEBUG : pacer: Reducing sleep to 140.257227ms 2026/09/28 05:00:13 DEBUG : pacer: Reducing sleep to 61.527334ms 2026/09/28 05:00:13 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:00:13 DEBUG : pacer: Reducing sleep to 40.958724ms 2026/09/28 05:00:13 DEBUG : pacer: Reducing sleep to 149.245365ms 2026/09/28 05:00:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:00:15 DEBUG : pacer: Reducing sleep to 116.346498ms 2026/09/28 05:00:15 DEBUG : pacer: Reducing sleep to 199.314773ms 2026/09/28 05:00:15 DEBUG : pacer: Reducing sleep to 253.548379ms 2026/09/28 05:00:15 DEBUG : pacer: Reducing sleep to 209.745363ms 2026/09/28 05:00:17 DEBUG : pacer: Reducing sleep to 27.825772ms 2026/09/28 05:00:17 DEBUG : pacer: Reducing sleep to 141.962157ms 2026/09/28 05:00:17 DEBUG : pacer: Reducing sleep to 258.973759ms 2026/09/28 05:00:18 DEBUG : pacer: Reducing sleep to 262.990513ms 2026/09/28 05:00:18 DEBUG : pacer: Reducing sleep to 189.371578ms 2026/09/28 05:00:18 DEBUG : pacer: Reducing sleep to 85.78053ms 2026/09/28 05:00:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:00:18 DEBUG : pacer: Reducing sleep to 119.857506ms 2026/09/28 05:00:18 DEBUG : zoho root 'rclone-test-farivaf9tuzo': Purge remote 2026/09/28 05:00:18 DEBUG : pacer: Reducing sleep to 125.564959ms 2026/09/28 05:00:19 DEBUG : pacer: Reducing sleep to 159.734137ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/28 05:00:19 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/28 05:00:19 DEBUG : pacer: Reducing sleep to 115.331883ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/28 05:00:19 DEBUG : pacer: Reducing sleep to 220.918376ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/28 05:00:19 DEBUG : pacer: Reducing sleep to 281.728218ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/28 05:00:19 DEBUG : pacer: Reducing sleep to 229.951217ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/28 05:00:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/09/28 05:00:25 DEBUG : zoho root 'rclone-test-babacef3mafe': folder listing limiter sweep: evicted 6 of 15 idle limiters fstests.go:1664: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1735: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1792: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/09/28 05:00:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:00:48 UTC": parsing time "Mon, 28 Sep 2026 05:00:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:00:48 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/28 05:00:53 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:00:53 UTC": parsing time "Mon, 28 Sep 2026 05:00:53 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:00:53 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/28 05:00:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:00:57 UTC": parsing time "Mon, 28 Sep 2026 05:00:57 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:00:57 UTC" as " " 2026/09/28 05:00:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:00:58 UTC": parsing time "Mon, 28 Sep 2026 05:00:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:00:58 UTC" as " " 2026/09/28 05:00:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:00:58 UTC": parsing time "Mon, 28 Sep 2026 05:00:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:00:58 UTC" as " " 2026/09/28 05:00:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:00:58 UTC": parsing time "Mon, 28 Sep 2026 05:00:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:00:58 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/28 05:01:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:02 UTC": parsing time "Mon, 28 Sep 2026 05:01:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:02 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/28 05:01:07 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:07 UTC": parsing time "Mon, 28 Sep 2026 05:01:07 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:07 UTC" as " " 2026/09/28 05:01:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:11 UTC": parsing time "Mon, 28 Sep 2026 05:01:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:11 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/28 05:01:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:11 UTC": parsing time "Mon, 28 Sep 2026 05:01:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:11 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/28 05:01:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:12 UTC": parsing time "Mon, 28 Sep 2026 05:01:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/28 05:01:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:12 UTC": parsing time "Mon, 28 Sep 2026 05:01:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/28 05:01:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:12 UTC": parsing time "Mon, 28 Sep 2026 05:01:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/28 05:01:16 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:16 UTC": parsing time "Mon, 28 Sep 2026 05:01:16 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:16 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/28 05:01:21 DEBUG : pacer: Reducing sleep to 115.915594ms 2026/09/28 05:01:21 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 28 Sep 2026 05:01:21 UTC": parsing time "Mon, 28 Sep 2026 05:01:21 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Sep 2026 05:01:21 UTC" as " " 2026/09/28 05:01:21 DEBUG : pacer: Reducing sleep to 35.736537ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/28 05:01:21 DEBUG : pacer: Reducing sleep to 148.646246ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/28 05:01:21 DEBUG : Creating backend with remote "TestZoho:rclone-test-babacef3mafe/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/28 05:01:21 DEBUG : Config file has changed externally - reloading 2026/09/28 05:01:21 DEBUG : pacer: Reducing sleep to 114.527282ms 2026/09/28 05:01:21 DEBUG : pacer: Reducing sleep to 234.106942ms 2026/09/28 05:01:21 DEBUG : pacer: Reducing sleep to 278.973651ms 2026/09/28 05:01:22 DEBUG : pacer: Reducing sleep to 214.792267ms 2026/09/28 05:01:22 DEBUG : pacer: Reducing sleep to 95.337353ms 2026/09/28 05:01:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:01:22 DEBUG : pacer: Reducing sleep to 116.379319ms 2026/09/28 05:01:22 DEBUG : pacer: Reducing sleep to 235.887303ms 2026/09/28 05:01:22 DEBUG : pacer: Reducing sleep to 280.34479ms 2026/09/28 05:01:23 DEBUG : pacer: Reducing sleep to 212.299543ms 2026/09/28 05:01:23 DEBUG : pacer: Reducing sleep to 89.545538ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/28 05:01:23 DEBUG : pacer: Reducing sleep to 51.032093ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/28 05:01:23 DEBUG : Creating backend with remote "TestZoho:rclone-test-babacef3mafe/not found.txt" 2026/09/28 05:01:23 DEBUG : pacer: Reducing sleep to 117.569406ms 2026/09/28 05:01:23 DEBUG : pacer: Reducing sleep to 154.558382ms 2026/09/28 05:01:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:01:28 DEBUG : zoho root 'rclone-test-babacef3mafe/not found.txt': folder listing limiter sweep: evicted 3 of 9 idle limiters 2026/09/28 05:01:33 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-babacef3mafe" from "TestZoho:rclone-test-babacef3mafe" 2026/09/28 05:01:33 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/28 05:01:37 DEBUG : pacer: Reducing sleep to 108.706432ms 2026/09/28 05:01:37 DEBUG : pacer: Reducing sleep to 225.77387ms 2026/09/28 05:01:37 DEBUG : pacer: Reducing sleep to 278.708378ms 2026/09/28 05:01:38 DEBUG : pacer: Reducing sleep to 208.157899ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/28 05:01:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2031: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/09/28 05:01:47 DEBUG : pacer: Reducing sleep to 44.74622ms 2026/09/28 05:01:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:01:56 DEBUG : pacer: Reducing sleep to 116.02646ms 2026/09/28 05:01:56 DEBUG : pacer: Reducing sleep to 228.366643ms 2026/09/28 05:01:56 DEBUG : pacer: Reducing sleep to 279.984727ms 2026/09/28 05:01:56 DEBUG : pacer: Reducing sleep to 209.811437ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/28 05:01:56 DEBUG : pacer: Reducing sleep to 76.973302ms 2026/09/28 05:02:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:02:00 DEBUG : pacer: Reducing sleep to 113.264257ms 2026/09/28 05:02:00 DEBUG : pacer: Reducing sleep to 225.126847ms 2026/09/28 05:02:00 DEBUG : pacer: Reducing sleep to 279.015727ms 2026/09/28 05:02:01 DEBUG : pacer: Reducing sleep to 211.454068ms === 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/28 05:02:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:02:10 DEBUG : pacer: Reducing sleep to 102.395708ms 2026/09/28 05:02:10 DEBUG : pacer: Reducing sleep to 210.823974ms 2026/09/28 05:02:10 DEBUG : pacer: Reducing sleep to 279.125334ms 2026/09/28 05:02:10 DEBUG : pacer: Reducing sleep to 238.960468ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/28 05:02:10 DEBUG : pacer: Reducing sleep to 123.152492ms === 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/28 05:02:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:02:14 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/28 05:02:14 DEBUG : pacer: Rate limited, increasing sleep to 1.888400552s 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/28 05:02:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:02:19 DEBUG : pacer: Reducing sleep to 123.747037ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/28 05:02:21 DEBUG : pacer: Reducing sleep to 0s 2026/09/28 05:02:21 DEBUG : pacer: low level retry 1/1 (error Post "https://workdrive.zoho.eu/api/v1/upload?filename=retry-NetError.bin&override-name-exist=true&parent_id=q92v90f26e758632844c4a1657faa7f0e2f3b": write tcp: unexpected EOF) 2026/09/28 05:02:21 DEBUG : pacer: Rate limited, increasing sleep to 1.152409231s 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=q92v90f26e758632844c4a1657faa7f0e2f3b": write tcp: unexpected EOF 2026/09/28 05:02:21 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/28 05:02:22 DEBUG : pacer: Reducing sleep to 140.779022ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n