"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/08/28 03:57:02 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/08/28 03:57:02 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/08/28 03:57:02 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/08/28 03:57:02 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/08/28 03:57:02 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/08/28 03:57:02 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/08/28 03:57:02 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:457: Using remote "TestZoho:" 2026/08/28 03:57:02 DEBUG : Creating backend with remote "TestZoho:rclone-test-wevakan6mapo" 2026/08/28 03:57:02 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/28 03:57:02 DEBUG : zoho root 'rclone-test-wevakan6mapo': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/08/28 03:57:02 DEBUG : TestZoho: Token expired 2026/08/28 03:57:02 DEBUG : TestZoho: No updated token found in the config file 2026/08/28 03:57:03 DEBUG : TestZoho: Token refresh successful 2026/08/28 03:57:03 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/08/28 03:57:03 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:498: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:526: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/08/28 03:57:04 DEBUG : pacer: Reducing sleep to 32.282657ms 2026/08/28 03:57:04 DEBUG : pacer: Reducing sleep to 62.329342ms 2026/08/28 03:57:04 DEBUG : pacer: Reducing sleep to 55.384848ms 2026/08/28 03:57:04 DEBUG : pacer: Reducing sleep to 177.300951ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/08/28 03:57:04 DEBUG : pacer: Reducing sleep to 285.713181ms 2026/08/28 03:57:05 DEBUG : pacer: Reducing sleep to 255.41285ms === RUN TestIntegration/FsMkdir 2026/08/28 03:57:05 DEBUG : pacer: Reducing sleep to 75.866551ms 2026/08/28 03:57:05 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:57:05 DEBUG : pacer: Reducing sleep to 124.504922ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/08/28 03:57:05 INFO : dir/subdir: Making directory 2026/08/28 03:57:05 DEBUG : pacer: Reducing sleep to 245.577163ms 2026/08/28 03:57:05 DEBUG : pacer: Reducing sleep to 260.20646ms 2026/08/28 03:57:06 DEBUG : pacer: Reducing sleep to 206.888609ms 2026/08/28 03:57:06 DEBUG : pacer: Reducing sleep to 88.036373ms 2026/08/28 03:57:06 DEBUG : pacer: Reducing sleep to 57.029538ms 2026/08/28 03:57:06 DEBUG : pacer: Reducing sleep to 145.534904ms 2026/08/28 03:57:06 DEBUG : pacer: Reducing sleep to 257.288785ms 2026/08/28 03:57:06 INFO : dir/subdir: Removing directory 2026/08/28 03:57:06 DEBUG : pacer: Reducing sleep to 276.85633ms 2026/08/28 03:57:07 DEBUG : pacer: Reducing sleep to 169.849085ms 2026/08/28 03:57:07 DEBUG : pacer: Reducing sleep to 72.676672ms 2026/08/28 03:57:07 DEBUG : pacer: Reducing sleep to 70.447073ms 2026/08/28 03:57:07 INFO : dir: Removing directory 2026/08/28 03:57:07 DEBUG : pacer: Reducing sleep to 163.729581ms 2026/08/28 03:57:07 DEBUG : pacer: Reducing sleep to 243.91843ms 2026/08/28 03:57:07 DEBUG : pacer: Reducing sleep to 266.846121ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/08/28 03:57:08 DEBUG : pacer: Reducing sleep to 184.230029ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/08/28 03:57:12 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/08/28 03:57:17 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:727: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/08/28 03:57:26 DEBUG : pacer: Reducing sleep to 97.167746ms 2026/08/28 03:57:26 DEBUG : pacer: Reducing sleep to 220.915658ms 2026/08/28 03:57:26 DEBUG : pacer: Reducing sleep to 165.050718ms 2026/08/28 03:57:26 DEBUG : pacer: Reducing sleep to 229.69965ms 2026/08/28 03:57:31 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:57:31 DEBUG : pacer: Reducing sleep to 114.118356ms 2026/08/28 03:57:31 DEBUG : pacer: Reducing sleep to 86.930062ms 2026/08/28 03:57:31 DEBUG : pacer: Reducing sleep to 156.52155ms 2026/08/28 03:57:31 DEBUG : pacer: Reducing sleep to 221.956577ms 2026/08/28 03:57:35 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:727: testing "." 2026/08/28 03:57:40 DEBUG : pacer: Reducing sleep to 92.691673ms 2026/08/28 03:57:40 DEBUG : pacer: Reducing sleep to 216.997709ms 2026/08/28 03:57:40 DEBUG : pacer: Reducing sleep to 106.919676ms 2026/08/28 03:57:40 DEBUG : pacer: Reducing sleep to 224.572628ms 2026/08/28 03:57:44 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:57:45 DEBUG : pacer: Reducing sleep to 113.135832ms 2026/08/28 03:57:45 DEBUG : pacer: Reducing sleep to 57.08988ms 2026/08/28 03:57:45 DEBUG : pacer: Reducing sleep to 160.967939ms 2026/08/28 03:57:45 DEBUG : pacer: Reducing sleep to 252.446179ms 2026/08/28 03:57:49 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:727: testing ".." 2026/08/28 03:57:54 DEBUG : pacer: Reducing sleep to 99.773426ms 2026/08/28 03:57:54 DEBUG : pacer: Reducing sleep to 221.154328ms 2026/08/28 03:57:54 DEBUG : pacer: Reducing sleep to 198.18911ms 2026/08/28 03:57:54 DEBUG : pacer: Reducing sleep to 228.783791ms 2026/08/28 03:57:58 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:57:58 DEBUG : pacer: Reducing sleep to 111.866345ms 2026/08/28 03:57:59 DEBUG : pacer: Reducing sleep to 96.077349ms 2026/08/28 03:57:59 DEBUG : pacer: Reducing sleep to 159.69755ms 2026/08/28 03:57:59 DEBUG : pacer: Reducing sleep to 220.042084ms 2026/08/28 03:58:03 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:727: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/08/28 03:58:03 DEBUG : zoho root 'rclone-test-wevakan6mapo': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/08/28 03:58:08 DEBUG : pacer: Reducing sleep to 88.58615ms 2026/08/28 03:58:08 DEBUG : pacer: Reducing sleep to 209.336702ms 2026/08/28 03:58:08 DEBUG : pacer: Reducing sleep to 158.376095ms 2026/08/28 03:58:08 DEBUG : pacer: Reducing sleep to 242.150406ms 2026/08/28 03:58:08 DEBUG : pacer: Reducing sleep to 248.389171ms 2026/08/28 03:58:08 DEBUG : pacer: Reducing sleep to 172.537406ms 2026/08/28 03:58:09 DEBUG : pacer: Reducing sleep to 79.078671ms 2026/08/28 03:58:09 DEBUG : pacer: Reducing sleep to 87.119428ms 2026/08/28 03:58:09 DEBUG : pacer: Reducing sleep to 163.33682ms 2026/08/28 03:58:09 DEBUG : pacer: Reducing sleep to 255.673792ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:727: testing " leading space" 2026/08/28 03:58:09 DEBUG : pacer: Reducing sleep to 256.161796ms 2026/08/28 03:58:09 DEBUG : pacer: Reducing sleep to 144.093646ms 2026/08/28 03:58:10 DEBUG : pacer: Reducing sleep to 68.430818ms 2026/08/28 03:58:10 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:58:10 DEBUG : pacer: Reducing sleep to 117.861404ms 2026/08/28 03:58:10 DEBUG : pacer: Reducing sleep to 233.998074ms 2026/08/28 03:58:10 DEBUG : pacer: Reducing sleep to 280.35805ms 2026/08/28 03:58:10 DEBUG : pacer: Reducing sleep to 205.580634ms 2026/08/28 03:58:11 DEBUG : pacer: Reducing sleep to 103.733105ms 2026/08/28 03:58:11 DEBUG : pacer: Reducing sleep to 54.797972ms 2026/08/28 03:58:11 DEBUG : pacer: Reducing sleep to 128.284876ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:727: testing "~leading tilde" 2026/08/28 03:58:16 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:58:16 DEBUG : pacer: Reducing sleep to 78.281487ms 2026/08/28 03:58:16 DEBUG : pacer: Reducing sleep to 197.463187ms 2026/08/28 03:58:16 DEBUG : pacer: Reducing sleep to 155.830657ms 2026/08/28 03:58:16 DEBUG : pacer: Reducing sleep to 252.062174ms 2026/08/28 03:58:20 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:58:20 DEBUG : pacer: Reducing sleep to 108.630733ms 2026/08/28 03:58:20 DEBUG : pacer: Reducing sleep to 71.573785ms 2026/08/28 03:58:21 DEBUG : pacer: Reducing sleep to 137.122307ms 2026/08/28 03:58:21 DEBUG : pacer: Reducing sleep to 222.943745ms 2026/08/28 03:58:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:727: testing "␍leading CR" 2026/08/28 03:58:29 DEBUG : pacer: Reducing sleep to 94.503013ms 2026/08/28 03:58:29 DEBUG : pacer: Reducing sleep to 219.68252ms 2026/08/28 03:58:30 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:58:30 DEBUG : pacer: Reducing sleep to 116.051238ms 2026/08/28 03:58:34 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:58:34 DEBUG : pacer: Reducing sleep to 111.799529ms 2026/08/28 03:58:34 DEBUG : pacer: Reducing sleep to 211.312643ms 2026/08/28 03:58:34 DEBUG : pacer: Reducing sleep to 277.360138ms 2026/08/28 03:58:34 DEBUG : pacer: Reducing sleep to 218.407788ms 2026/08/28 03:58:39 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:727: testing "␊leading LF" 2026/08/28 03:58:43 DEBUG : pacer: Reducing sleep to 98.101449ms 2026/08/28 03:58:43 DEBUG : pacer: Reducing sleep to 220.556521ms 2026/08/28 03:58:44 DEBUG : pacer: Reducing sleep to 180.781709ms 2026/08/28 03:58:44 DEBUG : pacer: Reducing sleep to 229.281535ms 2026/08/28 03:58:48 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:58:48 DEBUG : pacer: Reducing sleep to 109.346786ms 2026/08/28 03:58:48 DEBUG : pacer: Reducing sleep to 96.371043ms 2026/08/28 03:58:48 DEBUG : pacer: Reducing sleep to 161.774953ms 2026/08/28 03:58:48 DEBUG : pacer: Reducing sleep to 223.603592ms 2026/08/28 03:58:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:727: testing "␉leading HT" 2026/08/28 03:58:57 DEBUG : pacer: Reducing sleep to 98.251061ms 2026/08/28 03:58:57 DEBUG : pacer: Reducing sleep to 219.59733ms 2026/08/28 03:58:57 DEBUG : pacer: Reducing sleep to 144.484241ms 2026/08/28 03:58:57 DEBUG : pacer: Reducing sleep to 230.840971ms 2026/08/28 03:59:02 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:59:02 DEBUG : pacer: Reducing sleep to 106.727038ms 2026/08/28 03:59:02 DEBUG : pacer: Reducing sleep to 91.653146ms 2026/08/28 03:59:02 DEBUG : pacer: Reducing sleep to 160.576252ms 2026/08/28 03:59:02 DEBUG : pacer: Reducing sleep to 227.324851ms 2026/08/28 03:59:06 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:727: testing "␋leading VT" 2026/08/28 03:59:06 DEBUG : zoho root 'rclone-test-wevakan6mapo': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/08/28 03:59:06 DEBUG : pacer: Reducing sleep to 121.576204ms 2026/08/28 03:59:07 DEBUG : pacer: Reducing sleep to 77.003783ms 2026/08/28 03:59:07 DEBUG : pacer: Reducing sleep to 147.239347ms 2026/08/28 03:59:07 DEBUG : pacer: Reducing sleep to 95.776411ms 2026/08/28 03:59:07 DEBUG : pacer: Reducing sleep to 213.19847ms 2026/08/28 03:59:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:59:08 DEBUG : pacer: Reducing sleep to 119.023801ms 2026/08/28 03:59:08 DEBUG : pacer: Reducing sleep to 108.796601ms 2026/08/28 03:59:08 DEBUG : pacer: Reducing sleep to 167.561444ms 2026/08/28 03:59:08 DEBUG : pacer: Reducing sleep to 211.428131ms 2026/08/28 03:59:09 DEBUG : pacer: Reducing sleep to 223.076895ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:727: testing ".leading dot" 2026/08/28 03:59:09 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:59:10 DEBUG : pacer: Reducing sleep to 95.412683ms 2026/08/28 03:59:10 DEBUG : pacer: Reducing sleep to 108.878988ms 2026/08/28 03:59:10 DEBUG : pacer: Reducing sleep to 21.431496ms 2026/08/28 03:59:10 DEBUG : pacer: Reducing sleep to 137.064314ms 2026/08/28 03:59:10 DEBUG : pacer: Reducing sleep to 257.141205ms 2026/08/28 03:59:10 DEBUG : pacer: Reducing sleep to 281.184505ms 2026/08/28 03:59:10 DEBUG : pacer: Reducing sleep to 178.784495ms 2026/08/28 03:59:11 DEBUG : pacer: Reducing sleep to 79.579238ms 2026/08/28 03:59:11 DEBUG : pacer: Reducing sleep to 55.338484ms 2026/08/28 03:59:15 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:727: testing "trailing space " 2026/08/28 03:59:19 DEBUG : pacer: Reducing sleep to 86.687785ms 2026/08/28 03:59:19 DEBUG : pacer: Reducing sleep to 211.699405ms 2026/08/28 03:59:20 DEBUG : pacer: Reducing sleep to 216.019979ms 2026/08/28 03:59:20 DEBUG : pacer: Reducing sleep to 235.127438ms 2026/08/28 03:59:24 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:59:24 DEBUG : pacer: Reducing sleep to 113.709255ms 2026/08/28 03:59:24 DEBUG : pacer: Reducing sleep to 84.308726ms 2026/08/28 03:59:24 DEBUG : pacer: Reducing sleep to 153.216163ms 2026/08/28 03:59:24 DEBUG : pacer: Reducing sleep to 222.847377ms 2026/08/28 03:59:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:727: testing "trailing CR␍" 2026/08/28 03:59:33 DEBUG : pacer: Reducing sleep to 93.154988ms 2026/08/28 03:59:33 DEBUG : pacer: Reducing sleep to 216.434408ms 2026/08/28 03:59:33 DEBUG : pacer: Reducing sleep to 205.085329ms 2026/08/28 03:59:34 DEBUG : pacer: Reducing sleep to 230.095064ms 2026/08/28 03:59:38 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:59:38 DEBUG : pacer: Reducing sleep to 109.581239ms 2026/08/28 03:59:38 DEBUG : pacer: Reducing sleep to 93.017453ms 2026/08/28 03:59:38 DEBUG : pacer: Reducing sleep to 161.038747ms 2026/08/28 03:59:38 DEBUG : pacer: Reducing sleep to 224.976339ms 2026/08/28 03:59:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:727: testing "trailing LF␊" 2026/08/28 03:59:47 DEBUG : pacer: Reducing sleep to 98.156895ms 2026/08/28 03:59:47 DEBUG : pacer: Reducing sleep to 219.886447ms 2026/08/28 03:59:47 DEBUG : pacer: Reducing sleep to 175.972611ms 2026/08/28 03:59:47 DEBUG : pacer: Reducing sleep to 230.406795ms 2026/08/28 03:59:52 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 03:59:52 DEBUG : pacer: Reducing sleep to 91.919706ms 2026/08/28 03:59:52 DEBUG : pacer: Reducing sleep to 96.217377ms 2026/08/28 03:59:52 DEBUG : pacer: Reducing sleep to 179.711481ms 2026/08/28 03:59:52 DEBUG : pacer: Reducing sleep to 244.222696ms 2026/08/28 03:59:56 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:727: testing "trailing HT␉" 2026/08/28 04:00:01 DEBUG : pacer: Reducing sleep to 84.249731ms 2026/08/28 04:00:01 DEBUG : pacer: Reducing sleep to 208.619369ms 2026/08/28 04:00:01 DEBUG : pacer: Reducing sleep to 195.774146ms 2026/08/28 04:00:01 DEBUG : pacer: Reducing sleep to 236.595301ms 2026/08/28 04:00:05 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:00:05 DEBUG : pacer: Reducing sleep to 110.865633ms 2026/08/28 04:00:06 DEBUG : pacer: Reducing sleep to 79.156177ms 2026/08/28 04:00:06 DEBUG : pacer: Reducing sleep to 148.524874ms 2026/08/28 04:00:06 DEBUG : pacer: Reducing sleep to 228.725749ms 2026/08/28 04:00:06 DEBUG : pacer: Reducing sleep to 255.213417ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:727: testing "trailing VT␋" 2026/08/28 04:00:07 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:00:07 DEBUG : pacer: Reducing sleep to 78.199938ms 2026/08/28 04:00:07 DEBUG : zoho root 'rclone-test-wevakan6mapo': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/08/28 04:00:07 DEBUG : pacer: Reducing sleep to 79.235297ms 2026/08/28 04:00:07 DEBUG : pacer: Reducing sleep to 94.275366ms 2026/08/28 04:00:07 DEBUG : pacer: Reducing sleep to 207.513011ms 2026/08/28 04:00:07 DEBUG : pacer: Reducing sleep to 283.93028ms 2026/08/28 04:00:08 DEBUG : pacer: Reducing sleep to 207.077459ms 2026/08/28 04:00:08 DEBUG : pacer: Reducing sleep to 113.40328ms 2026/08/28 04:00:08 DEBUG : pacer: Reducing sleep to 83.638735ms 2026/08/28 04:00:08 DEBUG : pacer: Reducing sleep to 129.276318ms 2026/08/28 04:00:09 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:727: testing "trailing dot." 2026/08/28 04:00:09 DEBUG : pacer: Reducing sleep to 86.243696ms 2026/08/28 04:00:09 DEBUG : pacer: Reducing sleep to 207.950676ms 2026/08/28 04:00:09 DEBUG : pacer: Reducing sleep to 160.576816ms 2026/08/28 04:00:09 DEBUG : pacer: Reducing sleep to 242.083118ms 2026/08/28 04:00:14 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:00:14 DEBUG : pacer: Reducing sleep to 115.288328ms 2026/08/28 04:00:14 DEBUG : pacer: Reducing sleep to 87.871226ms 2026/08/28 04:00:14 DEBUG : pacer: Reducing sleep to 156.863343ms 2026/08/28 04:00:14 DEBUG : pacer: Reducing sleep to 220.965968ms 2026/08/28 04:00:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:718: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:727: testing "test%46.txt" 2026/08/28 04:00:23 DEBUG : pacer: Reducing sleep to 96.321738ms 2026/08/28 04:00:23 DEBUG : pacer: Reducing sleep to 219.303356ms 2026/08/28 04:00:23 DEBUG : pacer: Reducing sleep to 198.977376ms 2026/08/28 04:00:23 DEBUG : pacer: Reducing sleep to 230.25507ms 2026/08/28 04:00:28 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:00:28 DEBUG : pacer: Reducing sleep to 84.322148ms 2026/08/28 04:00:28 DEBUG : pacer: Reducing sleep to 75.854222ms 2026/08/28 04:00:28 DEBUG : pacer: Reducing sleep to 184.948303ms 2026/08/28 04:00:28 DEBUG : pacer: Reducing sleep to 266.209009ms 2026/08/28 04:00:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/08/28 04:00:51 DEBUG : pacer: Reducing sleep to 101.938566ms 2026/08/28 04:00:51 DEBUG : pacer: Reducing sleep to 223.791933ms 2026/08/28 04:00:51 DEBUG : pacer: Reducing sleep to 262.269531ms 2026/08/28 04:00:51 DEBUG : pacer: Reducing sleep to 230.490472ms 2026/08/28 04:00:51 DEBUG : pacer: Reducing sleep to 101.35903ms 2026/08/28 04:00:52 DEBUG : pacer: Reducing sleep to 69.389081ms 2026/08/28 04:00:52 DEBUG : pacer: Reducing sleep to 93.28814ms 2026/08/28 04:00:52 DEBUG : pacer: Reducing sleep to 259.444976ms 2026/08/28 04:00:52 DEBUG : pacer: Reducing sleep to 288.326863ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/08/28 04:00:55 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:00:55 DEBUG : pacer: Reducing sleep to 57.361992ms 2026/08/28 04:01:00 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:01:00 UTC": parsing time "Fri, 28 Aug 2026 04:01:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:01:00 UTC" as " " 2026/08/28 04:01:00 DEBUG : pacer: Reducing sleep to 85.977481ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:806: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:839: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:892: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/08/28 04:01:04 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:05 DEBUG : pacer: Reducing sleep to 11.292412ms 2026/08/28 04:01:09 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:09 DEBUG : zoho root 'rclone-test-wevakan6mapo': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/08/28 04:01:09 DEBUG : pacer: Reducing sleep to 117.63851ms 2026/08/28 04:01:09 DEBUG : pacer: Reducing sleep to 159.214195ms 2026/08/28 04:01:09 DEBUG : pacer: Reducing sleep to 274.242035ms 2026/08/28 04:01:09 DEBUG : pacer: Reducing sleep to 282.453377ms 2026/08/28 04:01:10 DEBUG : pacer: Reducing sleep to 45.869498ms 2026/08/28 04:01:10 DEBUG : pacer: Reducing sleep to 55.429681ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/08/28 04:01:10 DEBUG : pacer: Reducing sleep to 170.499952ms 2026/08/28 04:01:10 DEBUG : pacer: Reducing sleep to 269.787764ms 2026/08/28 04:01:10 DEBUG : pacer: Reducing sleep to 272.65114ms 2026/08/28 04:01:11 DEBUG : pacer: Reducing sleep to 170.615258ms 2026/08/28 04:01:11 DEBUG : pacer: Reducing sleep to 69.813458ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/08/28 04:01:11 DEBUG : pacer: Reducing sleep to 60.205012ms 2026/08/28 04:01:11 DEBUG : pacer: Reducing sleep to 161.565992ms 2026/08/28 04:01:11 DEBUG : pacer: Reducing sleep to 264.994277ms 2026/08/28 04:01:11 DEBUG : pacer: Reducing sleep to 272.431428ms 2026/08/28 04:01:12 DEBUG : pacer: Reducing sleep to 160.285715ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/08/28 04:01:12 DEBUG : pacer: Reducing sleep to 61.929653ms 2026/08/28 04:01:12 DEBUG : pacer: Reducing sleep to 72.7569ms 2026/08/28 04:01:12 DEBUG : pacer: Reducing sleep to 177.518056ms 2026/08/28 04:01:12 DEBUG : pacer: Reducing sleep to 267.411719ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/08/28 04:01:12 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/08/28 04:01:17 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/08/28 04:01:21 DEBUG : pacer: Reducing sleep to 117.157469ms 2026/08/28 04:01:21 DEBUG : pacer: Reducing sleep to 230.9781ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/08/28 04:01:26 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:26 DEBUG : pacer: Reducing sleep to 114.093895ms 2026/08/28 04:01:26 DEBUG : pacer: Reducing sleep to 101.382514ms 2026/08/28 04:01:26 DEBUG : pacer: Reducing sleep to 151.009615ms 2026/08/28 04:01:26 DEBUG : pacer: Reducing sleep to 215.057939ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/08/28 04:01:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1145: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/08/28 04:01:35 DEBUG : pacer: Reducing sleep to 117.092372ms 2026/08/28 04:01:35 DEBUG : pacer: Reducing sleep to 237.226203ms 2026/08/28 04:01:35 DEBUG : pacer: Reducing sleep to 281.066895ms 2026/08/28 04:01:36 DEBUG : pacer: Reducing sleep to 211.302304ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/08/28 04:01:40 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/08/28 04:01:40 DEBUG : pacer: Reducing sleep to 116.772046ms 2026/08/28 04:01:45 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:45 DEBUG : pacer: Reducing sleep to 44.197964ms 2026/08/28 04:01:45 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:45 DEBUG : pacer: Reducing sleep to 117.169703ms 2026/08/28 04:01:49 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:49 DEBUG : pacer: Reducing sleep to 118.343784ms 2026/08/28 04:01:49 DEBUG : pacer: Reducing sleep to 277.189241ms 2026/08/28 04:01:49 DEBUG : pacer: Reducing sleep to 384.979209ms 2026/08/28 04:01:50 DEBUG : pacer: Reducing sleep to 272.393321ms 2026/08/28 04:01:50 DEBUG : pacer: Reducing sleep to 56.936726ms 2026/08/28 04:01:50 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:50 DEBUG : pacer: Reducing sleep to 107.987659ms 2026/08/28 04:01:54 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:01:54 DEBUG : pacer: Reducing sleep to 117.644134ms 2026/08/28 04:01:54 DEBUG : pacer: Reducing sleep to 228.212625ms 2026/08/28 04:01:54 DEBUG : pacer: Reducing sleep to 277.696384ms 2026/08/28 04:01:54 DEBUG : pacer: Reducing sleep to 212.750653ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/08/28 04:01:59 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:03 DEBUG : pacer: Reducing sleep to 90.817815ms 2026/08/28 04:02:03 DEBUG : pacer: Reducing sleep to 125.249892ms 2026/08/28 04:02:03 DEBUG : pacer: Reducing sleep to 243.751439ms 2026/08/28 04:02:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:08 DEBUG : pacer: Reducing sleep to 117.009084ms 2026/08/28 04:02:08 DEBUG : pacer: Reducing sleep to 282.114226ms 2026/08/28 04:02:08 DEBUG : pacer: Reducing sleep to 262.053683ms 2026/08/28 04:02:08 DEBUG : pacer: Reducing sleep to 142.962551ms 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 48.634495ms 2026/08/28 04:02:09 DEBUG : Creating backend with remote "TestZoho:rclone-test-wevakan6mapo/dirToPurgeFromRoot" 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 121.49192ms 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 242.667946ms 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 278.029047ms 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 118.488551ms 2026/08/28 04:02:09 DEBUG : zoho root 'rclone-test-wevakan6mapo': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 234.382402ms 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 284.31847ms 2026/08/28 04:02:09 DEBUG : pacer: Reducing sleep to 213.709234ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/08/28 04:02:09 DEBUG : Creating backend with remote "TestZoho:rclone-test-wevakan6mapo/hello? sausage/êé" 2026/08/28 04:02:10 DEBUG : pacer: Reducing sleep to 111.890947ms 2026/08/28 04:02:10 DEBUG : pacer: Reducing sleep to 226.772481ms 2026/08/28 04:02:10 DEBUG : pacer: Reducing sleep to 277.401255ms 2026/08/28 04:02:10 DEBUG : pacer: Reducing sleep to 217.723688ms 2026/08/28 04:02:11 DEBUG : pacer: Reducing sleep to 75.607426ms 2026/08/28 04:02:11 DEBUG : pacer: Reducing sleep to 63.284001ms 2026/08/28 04:02:11 DEBUG : pacer: Reducing sleep to 118.995674ms 2026/08/28 04:02:11 DEBUG : pacer: Reducing sleep to 239.093235ms 2026/08/28 04:02:11 DEBUG : pacer: Reducing sleep to 251.567765ms 2026/08/28 04:02:11 DEBUG : pacer: Reducing sleep to 212.858251ms 2026/08/28 04:02:12 DEBUG : pacer: Reducing sleep to 85.593759ms 2026/08/28 04:02:12 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:12 DEBUG : pacer: Reducing sleep to 112.946965ms 2026/08/28 04:02:12 DEBUG : pacer: Reducing sleep to 221.964436ms 2026/08/28 04:02:12 DEBUG : pacer: Reducing sleep to 269.540076ms 2026/08/28 04:02:12 DEBUG : pacer: Reducing sleep to 218.495034ms 2026/08/28 04:02:13 DEBUG : pacer: Reducing sleep to 111.247823ms 2026/08/28 04:02:13 DEBUG : pacer: Reducing sleep to 61.042573ms 2026/08/28 04:02:13 DEBUG : pacer: Reducing sleep to 117.275787ms 2026/08/28 04:02:13 DEBUG : pacer: Reducing sleep to 222.420028ms 2026/08/28 04:02:13 DEBUG : pacer: Reducing sleep to 268.619543ms 2026/08/28 04:02:13 DEBUG : pacer: Reducing sleep to 200.79086ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/08/28 04:02:14 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:14 DEBUG : pacer: Reducing sleep to 94.974675ms 2026/08/28 04:02:14 DEBUG : pacer: Reducing sleep to 211.20066ms 2026/08/28 04:02:14 DEBUG : pacer: Reducing sleep to 280.257641ms 2026/08/28 04:02:17 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:17 DEBUG : pacer: Reducing sleep to 116.810406ms 2026/08/28 04:02:17 DEBUG : pacer: Reducing sleep to 53.30232ms 2026/08/28 04:02:21 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:22 DEBUG : pacer: Reducing sleep to 61.082468ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1318: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/08/28 04:02:26 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:26 DEBUG : pacer: Reducing sleep to 104.567799ms 2026/08/28 04:02:26 DEBUG : pacer: Reducing sleep to 177.046401ms 2026/08/28 04:02:26 DEBUG : pacer: Reducing sleep to 274.304985ms 2026/08/28 04:02:26 DEBUG : pacer: Reducing sleep to 267.009889ms 2026/08/28 04:02:27 DEBUG : pacer: Reducing sleep to 162.274809ms 2026/08/28 04:02:27 DEBUG : pacer: Reducing sleep to 64.638366ms 2026/08/28 04:02:27 DEBUG : pacer: Reducing sleep to 63.411674ms 2026/08/28 04:02:31 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:40 DEBUG : pacer: Reducing sleep to 96.953723ms 2026/08/28 04:02:40 DEBUG : pacer: Reducing sleep to 203.222603ms 2026/08/28 04:02:40 DEBUG : pacer: Reducing sleep to 254.267008ms 2026/08/28 04:02:41 DEBUG : pacer: Reducing sleep to 238.264794ms 2026/08/28 04:02:45 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:45 DEBUG : pacer: Reducing sleep to 99.360526ms 2026/08/28 04:02:45 DEBUG : pacer: Reducing sleep to 265.376717ms 2026/08/28 04:02:45 DEBUG : pacer: Reducing sleep to 260.027918ms 2026/08/28 04:02:45 DEBUG : pacer: Reducing sleep to 166.554082ms 2026/08/28 04:02:46 DEBUG : pacer: Reducing sleep to 62.599169ms 2026/08/28 04:02:46 DEBUG : pacer: Reducing sleep to 69.002811ms 2026/08/28 04:02:46 DEBUG : pacer: Reducing sleep to 171.210174ms 2026/08/28 04:02:46 DEBUG : pacer: Reducing sleep to 241.701588ms 2026/08/28 04:02:46 DEBUG : pacer: Reducing sleep to 260.993993ms 2026/08/28 04:02:49 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:49 DEBUG : pacer: Reducing sleep to 124.568517ms 2026/08/28 04:02:49 DEBUG : pacer: Reducing sleep to 282.751674ms 2026/08/28 04:02:50 DEBUG : pacer: Reducing sleep to 245.549132ms 2026/08/28 04:02:50 DEBUG : pacer: Reducing sleep to 115.535486ms 2026/08/28 04:02:50 DEBUG : pacer: Reducing sleep to 45.485863ms 2026/08/28 04:02:54 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:54 DEBUG : pacer: Reducing sleep to 107.752069ms 2026/08/28 04:02:54 DEBUG : pacer: Reducing sleep to 195.664787ms 2026/08/28 04:02:54 DEBUG : pacer: Reducing sleep to 271.962891ms 2026/08/28 04:02:59 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:02:59 DEBUG : pacer: Reducing sleep to 124.406661ms 2026/08/28 04:02:59 DEBUG : pacer: Reducing sleep to 283.536711ms 2026/08/28 04:02:59 DEBUG : pacer: Reducing sleep to 230.42479ms 2026/08/28 04:02:59 DEBUG : pacer: Reducing sleep to 107.166542ms 2026/08/28 04:02:59 DEBUG : pacer: Reducing sleep to 47.56104ms 2026/08/28 04:03:00 DEBUG : pacer: Reducing sleep to 61.982051ms 2026/08/28 04:03:00 DEBUG : pacer: Reducing sleep to 13.761628ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1430: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/08/28 04:03:00 DEBUG : Creating backend with remote "TestZoho:rclone-test-vuzozif0rivi" 2026/08/28 04:03:00 DEBUG : pacer: Reducing sleep to 31.625027ms 2026/08/28 04:03:00 DEBUG : pacer: Reducing sleep to 67.210499ms 2026/08/28 04:03:00 DEBUG : pacer: Reducing sleep to 96.871876ms 2026/08/28 04:03:01 DEBUG : pacer: Reducing sleep to 220.778353ms 2026/08/28 04:03:01 DEBUG : pacer: Reducing sleep to 261.018388ms 2026/08/28 04:03:01 DEBUG : pacer: Reducing sleep to 236.503106ms 2026/08/28 04:03:01 DEBUG : pacer: Reducing sleep to 118.047808ms 2026/08/28 04:03:01 DEBUG : pacer: Reducing sleep to 46.647783ms 2026/08/28 04:03:01 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:03:02 DEBUG : pacer: Reducing sleep to 28.318441ms 2026/08/28 04:03:02 DEBUG : pacer: Reducing sleep to 123.485197ms 2026/08/28 04:03:03 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:03:03 DEBUG : pacer: Reducing sleep to 116.807205ms 2026/08/28 04:03:03 DEBUG : pacer: Reducing sleep to 212.663179ms 2026/08/28 04:03:04 DEBUG : pacer: Reducing sleep to 250.946213ms 2026/08/28 04:03:04 DEBUG : pacer: Reducing sleep to 203.711755ms 2026/08/28 04:03:06 DEBUG : pacer: Reducing sleep to 37.100425ms 2026/08/28 04:03:06 DEBUG : pacer: Reducing sleep to 127.742539ms 2026/08/28 04:03:06 DEBUG : pacer: Reducing sleep to 243.96418ms 2026/08/28 04:03:06 DEBUG : pacer: Reducing sleep to 278.286308ms 2026/08/28 04:03:06 DEBUG : pacer: Reducing sleep to 205.437836ms 2026/08/28 04:03:06 DEBUG : pacer: Reducing sleep to 85.243068ms 2026/08/28 04:03:06 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:03:06 DEBUG : pacer: Reducing sleep to 126.641763ms 2026/08/28 04:03:06 DEBUG : zoho root 'rclone-test-vuzozif0rivi': Purge remote 2026/08/28 04:03:07 DEBUG : pacer: Reducing sleep to 125.943056ms 2026/08/28 04:03:07 DEBUG : pacer: Reducing sleep to 160.981844ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/08/28 04:03:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/08/28 04:03:07 DEBUG : pacer: Reducing sleep to 112.716023ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/08/28 04:03:07 DEBUG : pacer: Reducing sleep to 229.135666ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/08/28 04:03:07 DEBUG : pacer: Reducing sleep to 277.207508ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/08/28 04:03:07 DEBUG : pacer: Reducing sleep to 217.728856ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/08/28 04:03:08 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1626: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/08/28 04:03:13 DEBUG : zoho root 'rclone-test-wevakan6mapo': folder listing limiter sweep: evicted 5 of 15 idle limiters fstests.go:1657: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1728: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1785: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/08/28 04:03:36 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:36 UTC": parsing time "Fri, 28 Aug 2026 04:03:36 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:36 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/08/28 04:03:41 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:41 UTC": parsing time "Fri, 28 Aug 2026 04:03:41 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:41 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/08/28 04:03:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:45 UTC": parsing time "Fri, 28 Aug 2026 04:03:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:45 UTC" as " " 2026/08/28 04:03:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:46 UTC": parsing time "Fri, 28 Aug 2026 04:03:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:46 UTC" as " " 2026/08/28 04:03:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:46 UTC": parsing time "Fri, 28 Aug 2026 04:03:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:46 UTC" as " " 2026/08/28 04:03:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:46 UTC": parsing time "Fri, 28 Aug 2026 04:03:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:46 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/08/28 04:03:50 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:50 UTC": parsing time "Fri, 28 Aug 2026 04:03:50 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:50 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/08/28 04:03:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:55 UTC": parsing time "Fri, 28 Aug 2026 04:03:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:55 UTC" as " " 2026/08/28 04:03:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:59 UTC": parsing time "Fri, 28 Aug 2026 04:03:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:59 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/08/28 04:03:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:59 UTC": parsing time "Fri, 28 Aug 2026 04:03:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:59 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/08/28 04:03:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:03:59 UTC": parsing time "Fri, 28 Aug 2026 04:03:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:03:59 UTC" as " " 2026/08/28 04:03:59 DEBUG : pacer: Reducing sleep to 20.222006ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/08/28 04:04:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:04:00 UTC": parsing time "Fri, 28 Aug 2026 04:04:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:04:00 UTC" as " " 2026/08/28 04:04:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/08/28 04:04:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:04:00 UTC": parsing time "Fri, 28 Aug 2026 04:04:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:04:00 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/08/28 04:04:04 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:04:04 UTC": parsing time "Fri, 28 Aug 2026 04:04:04 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:04:04 UTC" as " " 2026/08/28 04:04:04 DEBUG : pacer: Reducing sleep to 26.510586ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/08/28 04:04:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:04:08 DEBUG : pacer: Reducing sleep to 47.234113ms 2026/08/28 04:04:08 DEBUG : pacer: Reducing sleep to 163.494419ms 2026/08/28 04:04:09 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 28 Aug 2026 04:04:09 UTC": parsing time "Fri, 28 Aug 2026 04:04:09 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 28 Aug 2026 04:04:09 UTC" as " " 2026/08/28 04:04:09 DEBUG : pacer: Reducing sleep to 138.622733ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/08/28 04:04:09 DEBUG : pacer: Reducing sleep to 253.421184ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/08/28 04:04:09 DEBUG : Creating backend with remote "TestZoho:rclone-test-wevakan6mapo/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/08/28 04:04:09 DEBUG : pacer: Reducing sleep to 121.103777ms 2026/08/28 04:04:09 DEBUG : pacer: Reducing sleep to 240.664626ms 2026/08/28 04:04:09 DEBUG : pacer: Reducing sleep to 285.723768ms 2026/08/28 04:04:10 DEBUG : pacer: Reducing sleep to 208.440512ms 2026/08/28 04:04:10 DEBUG : pacer: Reducing sleep to 88.460989ms 2026/08/28 04:04:10 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:04:10 DEBUG : pacer: Reducing sleep to 119.11109ms 2026/08/28 04:04:10 DEBUG : pacer: Reducing sleep to 237.525307ms 2026/08/28 04:04:10 DEBUG : pacer: Reducing sleep to 287.289257ms 2026/08/28 04:04:11 DEBUG : pacer: Reducing sleep to 212.56321ms 2026/08/28 04:04:11 DEBUG : pacer: Reducing sleep to 90.398357ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/08/28 04:04:11 DEBUG : pacer: Reducing sleep to 43.880089ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/08/28 04:04:11 DEBUG : Creating backend with remote "TestZoho:rclone-test-wevakan6mapo/not found.txt" 2026/08/28 04:04:11 DEBUG : pacer: Reducing sleep to 114.492922ms 2026/08/28 04:04:11 DEBUG : pacer: Reducing sleep to 155.799721ms 2026/08/28 04:04:16 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:04:16 DEBUG : zoho root 'rclone-test-wevakan6mapo/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/08/28 04:04:21 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1987: Opening root remote "TestZoho:" path "rclone-test-wevakan6mapo" from "TestZoho:rclone-test-wevakan6mapo" 2026/08/28 04:04:21 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/08/28 04:04:25 DEBUG : pacer: Reducing sleep to 120.067951ms 2026/08/28 04:04:25 DEBUG : pacer: Reducing sleep to 233.815526ms 2026/08/28 04:04:25 DEBUG : pacer: Reducing sleep to 285.291645ms 2026/08/28 04:04:26 DEBUG : pacer: Reducing sleep to 198.935593ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/08/28 04:04:30 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2024: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/08/28 04:04:35 DEBUG : pacer: Reducing sleep to 32.779023ms 2026/08/28 04:04:39 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:04:44 DEBUG : pacer: Reducing sleep to 112.238054ms 2026/08/28 04:04:44 DEBUG : pacer: Reducing sleep to 231.681983ms 2026/08/28 04:04:44 DEBUG : pacer: Reducing sleep to 282.822641ms 2026/08/28 04:04:44 DEBUG : pacer: Reducing sleep to 218.950778ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/08/28 04:04:44 DEBUG : pacer: Reducing sleep to 72.414776ms 2026/08/28 04:04:48 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:04:48 DEBUG : pacer: Reducing sleep to 117.002235ms 2026/08/28 04:04:48 DEBUG : pacer: Reducing sleep to 234.877846ms 2026/08/28 04:04:48 DEBUG : pacer: Reducing sleep to 284.545555ms 2026/08/28 04:04:49 DEBUG : pacer: Reducing sleep to 212.030862ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2083: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:431: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2188: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2204: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/08/28 04:04:53 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:04:57 DEBUG : pacer: Reducing sleep to 117.558472ms 2026/08/28 04:04:58 DEBUG : pacer: Reducing sleep to 223.192831ms 2026/08/28 04:04:58 DEBUG : pacer: Reducing sleep to 274.882556ms 2026/08/28 04:04:58 DEBUG : pacer: Reducing sleep to 222.75415ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/08/28 04:04:58 DEBUG : pacer: Reducing sleep to 109.083316ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2378: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2424: *zoho.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2445: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/08/28 04:05:02 DEBUG : pacer: Reducing sleep to 0s 2026/08/28 04:05:02 DEBUG : pacer: Reducing sleep to 135.012992ms fstests.go:2596: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n