"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/18 05:14:58 NOTICE: zoho root '': Too many requests: Trying again in 6 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_without_Retry-After_falls_back_to_60s 2026/09/18 05:14:58 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_with_unparseable_Retry-After_falls_back 2026/09/18 05:14:58 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/18 05:14:58 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/09/18 05:14:58 ERROR : zoho: missing OAuth Scope. Run rclone config reconnect to fix this issue. === RUN TestShouldRetry/401_expired_token_retries === RUN TestShouldRetry/401_R008_deleted_folder_is_not_retried === RUN TestShouldRetry/cancelled_context_aborts --- PASS: TestShouldRetry (0.00s) --- PASS: TestShouldRetry/429_honours_Retry-After (0.00s) --- PASS: TestShouldRetry/429_without_Retry-After_falls_back_to_60s (0.00s) --- PASS: TestShouldRetry/429_with_unparseable_Retry-After_falls_back (0.00s) --- PASS: TestShouldRetry/401_missing_scope_aborts (0.00s) --- PASS: TestShouldRetry/401_expired_token_retries (0.00s) --- PASS: TestShouldRetry/401_R008_deleted_folder_is_not_retried (0.00s) --- PASS: TestShouldRetry/cancelled_context_aborts (0.00s) === RUN TestIsMissingResourceErr --- PASS: TestIsMissingResourceErr (0.00s) === RUN TestThrottleEpisode 2026/09/18 05:14:58 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/09/18 05:14:58 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleStateConcurrent (0.00s) === RUN TestConfigRootFolderID === RUN TestConfigRootFolderID/SetRootAsksBeforeChanging === RUN TestConfigRootFolderID/EmptyRootGoesToEdition === RUN TestConfigRootFolderID/KeepRootOnNo === RUN TestConfigRootFolderID/ChangeRootOnYes --- PASS: TestConfigRootFolderID (0.00s) --- PASS: TestConfigRootFolderID/SetRootAsksBeforeChanging (0.00s) --- PASS: TestConfigRootFolderID/EmptyRootGoesToEdition (0.00s) --- PASS: TestConfigRootFolderID/KeepRootOnNo (0.00s) --- PASS: TestConfigRootFolderID/ChangeRootOnYes (0.00s) === RUN TestIntegration fstests.go:464: Using remote "TestZoho:" 2026/09/18 05:14:58 DEBUG : Creating backend with remote "TestZoho:rclone-test-bokupir9dewu" 2026/09/18 05:14:58 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/18 05:14:58 DEBUG : zoho root 'rclone-test-bokupir9dewu': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/18 05:14:58 DEBUG : TestZoho: Token expired 2026/09/18 05:14:58 DEBUG : TestZoho: No updated token found in the config file 2026/09/18 05:14:58 DEBUG : TestZoho: Token refresh successful 2026/09/18 05:14:58 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/18 05:14:58 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/18 05:14:59 DEBUG : pacer: Reducing sleep to 10.78163ms 2026/09/18 05:14:59 DEBUG : pacer: Reducing sleep to 39.217256ms 2026/09/18 05:14:59 DEBUG : pacer: Reducing sleep to 36.084479ms 2026/09/18 05:14:59 DEBUG : pacer: Reducing sleep to 159.941837ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/18 05:14:59 DEBUG : pacer: Reducing sleep to 282.295865ms 2026/09/18 05:15:00 DEBUG : pacer: Reducing sleep to 119.253632ms === RUN TestIntegration/FsMkdir 2026/09/18 05:15:00 DEBUG : pacer: Reducing sleep to 37.218179ms 2026/09/18 05:15:00 DEBUG : pacer: Reducing sleep to 51.526799ms 2026/09/18 05:15:00 DEBUG : pacer: Reducing sleep to 179.65489ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/18 05:15:00 INFO : dir/subdir: Making directory 2026/09/18 05:15:00 DEBUG : pacer: Reducing sleep to 292.937298ms 2026/09/18 05:15:01 DEBUG : pacer: Reducing sleep to 238.350708ms 2026/09/18 05:15:01 DEBUG : pacer: Reducing sleep to 151.259909ms 2026/09/18 05:15:01 DEBUG : pacer: Reducing sleep to 36.533354ms 2026/09/18 05:15:01 DEBUG : pacer: Reducing sleep to 61.235469ms 2026/09/18 05:15:01 DEBUG : pacer: Reducing sleep to 181.263045ms 2026/09/18 05:15:01 DEBUG : pacer: Reducing sleep to 287.020603ms 2026/09/18 05:15:01 INFO : dir/subdir: Removing directory 2026/09/18 05:15:01 DEBUG : pacer: Reducing sleep to 272.615329ms 2026/09/18 05:15:02 DEBUG : pacer: Reducing sleep to 129.624782ms 2026/09/18 05:15:02 DEBUG : pacer: Reducing sleep to 39.690764ms 2026/09/18 05:15:02 DEBUG : pacer: Reducing sleep to 78.506281ms 2026/09/18 05:15:02 INFO : dir: Removing directory 2026/09/18 05:15:02 DEBUG : pacer: Reducing sleep to 201.793902ms 2026/09/18 05:15:02 DEBUG : pacer: Reducing sleep to 277.26613ms 2026/09/18 05:15:03 DEBUG : pacer: Reducing sleep to 257.40205ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/18 05:15:03 DEBUG : pacer: Reducing sleep to 142.216517ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/18 05:15:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/18 05:15:12 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/18 05:15:21 DEBUG : pacer: Reducing sleep to 95.431979ms 2026/09/18 05:15:21 DEBUG : pacer: Reducing sleep to 219.945755ms 2026/09/18 05:15:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:15:22 DEBUG : pacer: Reducing sleep to 118.075877ms 2026/09/18 05:15:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:15:26 DEBUG : pacer: Reducing sleep to 105.471166ms 2026/09/18 05:15:26 DEBUG : pacer: Reducing sleep to 212.258347ms 2026/09/18 05:15:26 DEBUG : pacer: Reducing sleep to 277.527097ms 2026/09/18 05:15:26 DEBUG : pacer: Reducing sleep to 215.016732ms 2026/09/18 05:15:30 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/18 05:15:35 DEBUG : pacer: Reducing sleep to 92.164005ms 2026/09/18 05:15:35 DEBUG : pacer: Reducing sleep to 219.376148ms 2026/09/18 05:15:35 DEBUG : pacer: Reducing sleep to 201.069205ms 2026/09/18 05:15:35 DEBUG : pacer: Reducing sleep to 231.992275ms 2026/09/18 05:15:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:15:40 DEBUG : pacer: Reducing sleep to 112.497294ms 2026/09/18 05:15:40 DEBUG : pacer: Reducing sleep to 96.041366ms 2026/09/18 05:15:40 DEBUG : pacer: Reducing sleep to 156.373223ms 2026/09/18 05:15:40 DEBUG : pacer: Reducing sleep to 218.245466ms 2026/09/18 05:15:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/18 05:15:49 DEBUG : pacer: Reducing sleep to 103.502418ms 2026/09/18 05:15:49 DEBUG : pacer: Reducing sleep to 226.1136ms 2026/09/18 05:15:49 DEBUG : pacer: Reducing sleep to 98.537173ms 2026/09/18 05:15:49 DEBUG : pacer: Reducing sleep to 213.390638ms 2026/09/18 05:15:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:15:53 DEBUG : pacer: Reducing sleep to 106.845531ms 2026/09/18 05:15:54 DEBUG : pacer: Reducing sleep to 97.364884ms 2026/09/18 05:15:54 DEBUG : pacer: Reducing sleep to 172.288894ms 2026/09/18 05:15:54 DEBUG : pacer: Reducing sleep to 239.256401ms 2026/09/18 05:15:58 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/18 05:15:58 DEBUG : zoho root 'rclone-test-bokupir9dewu': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/18 05:16:03 DEBUG : pacer: Reducing sleep to 78.823896ms 2026/09/18 05:16:03 DEBUG : pacer: Reducing sleep to 205.523729ms 2026/09/18 05:16:03 DEBUG : pacer: Reducing sleep to 199.717318ms 2026/09/18 05:16:03 DEBUG : pacer: Reducing sleep to 249.045045ms 2026/09/18 05:16:03 DEBUG : pacer: Reducing sleep to 186.835756ms 2026/09/18 05:16:03 DEBUG : pacer: Reducing sleep to 129.658787ms 2026/09/18 05:16:04 DEBUG : pacer: Reducing sleep to 93.220665ms 2026/09/18 05:16:04 DEBUG : pacer: Reducing sleep to 148.397636ms 2026/09/18 05:16:04 DEBUG : pacer: Reducing sleep to 211.505062ms 2026/09/18 05:16:04 DEBUG : pacer: Reducing sleep to 236.704625ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/18 05:16:04 DEBUG : pacer: Reducing sleep to 195.997188ms 2026/09/18 05:16:04 DEBUG : pacer: Reducing sleep to 100.996327ms 2026/09/18 05:16:05 DEBUG : pacer: Reducing sleep to 86.860427ms 2026/09/18 05:16:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:16:05 DEBUG : pacer: Reducing sleep to 122.209102ms 2026/09/18 05:16:05 DEBUG : pacer: Reducing sleep to 239.22547ms 2026/09/18 05:16:05 DEBUG : pacer: Reducing sleep to 266.699067ms 2026/09/18 05:16:05 DEBUG : pacer: Reducing sleep to 205.299816ms 2026/09/18 05:16:06 DEBUG : pacer: Reducing sleep to 107.926955ms 2026/09/18 05:16:06 DEBUG : pacer: Reducing sleep to 56.096424ms 2026/09/18 05:16:06 DEBUG : pacer: Reducing sleep to 138.031204ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/18 05:16:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:16:11 DEBUG : pacer: Reducing sleep to 100.211752ms 2026/09/18 05:16:11 DEBUG : pacer: Reducing sleep to 194.784517ms 2026/09/18 05:16:11 DEBUG : pacer: Reducing sleep to 180.755359ms 2026/09/18 05:16:11 DEBUG : pacer: Reducing sleep to 225.394961ms 2026/09/18 05:16:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:16:15 DEBUG : pacer: Reducing sleep to 124.572164ms 2026/09/18 05:16:15 DEBUG : pacer: Reducing sleep to 96.057482ms 2026/09/18 05:16:16 DEBUG : pacer: Reducing sleep to 144.146707ms 2026/09/18 05:16:16 DEBUG : pacer: Reducing sleep to 201.700737ms 2026/09/18 05:16:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/18 05:16:24 DEBUG : pacer: Reducing sleep to 101.457895ms 2026/09/18 05:16:25 DEBUG : pacer: Reducing sleep to 226.13609ms 2026/09/18 05:16:25 DEBUG : pacer: Reducing sleep to 79.36294ms 2026/09/18 05:16:25 DEBUG : pacer: Reducing sleep to 202.024768ms 2026/09/18 05:16:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:16:29 DEBUG : pacer: Reducing sleep to 116.300365ms 2026/09/18 05:16:29 DEBUG : pacer: Reducing sleep to 108.72335ms 2026/09/18 05:16:29 DEBUG : pacer: Reducing sleep to 183.684621ms 2026/09/18 05:16:29 DEBUG : pacer: Reducing sleep to 227.04147ms 2026/09/18 05:16:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/18 05:16:38 DEBUG : pacer: Reducing sleep to 102.088846ms 2026/09/18 05:16:38 DEBUG : pacer: Reducing sleep to 224.748259ms 2026/09/18 05:16:39 DEBUG : pacer: Reducing sleep to 214.948457ms 2026/09/18 05:16:39 DEBUG : pacer: Reducing sleep to 227.210256ms 2026/09/18 05:16:43 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:16:43 DEBUG : pacer: Reducing sleep to 115.985989ms 2026/09/18 05:16:43 DEBUG : pacer: Reducing sleep to 94.323477ms 2026/09/18 05:16:43 DEBUG : pacer: Reducing sleep to 157.50989ms 2026/09/18 05:16:43 DEBUG : pacer: Reducing sleep to 222.119461ms 2026/09/18 05:16:48 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/18 05:16:52 DEBUG : pacer: Reducing sleep to 100.85162ms 2026/09/18 05:16:52 DEBUG : pacer: Reducing sleep to 225.353874ms 2026/09/18 05:16:52 DEBUG : pacer: Reducing sleep to 127.825624ms 2026/09/18 05:16:53 DEBUG : pacer: Reducing sleep to 225.919618ms 2026/09/18 05:16:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:16:57 DEBUG : pacer: Reducing sleep to 116.114773ms 2026/09/18 05:16:57 DEBUG : pacer: Reducing sleep to 98.26078ms 2026/09/18 05:16:57 DEBUG : pacer: Reducing sleep to 160.546362ms 2026/09/18 05:16:57 DEBUG : pacer: Reducing sleep to 223.698401ms 2026/09/18 05:17:01 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/18 05:17:01 DEBUG : zoho root 'rclone-test-bokupir9dewu': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/18 05:17:01 DEBUG : pacer: Reducing sleep to 118.139716ms 2026/09/18 05:17:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:17:02 DEBUG : pacer: Reducing sleep to 120.838274ms 2026/09/18 05:17:02 DEBUG : pacer: Reducing sleep to 154.870664ms 2026/09/18 05:17:02 DEBUG : pacer: Reducing sleep to 225.032229ms 2026/09/18 05:17:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:17:03 DEBUG : pacer: Reducing sleep to 116.448744ms 2026/09/18 05:17:03 DEBUG : pacer: Reducing sleep to 95.859455ms 2026/09/18 05:17:03 DEBUG : pacer: Reducing sleep to 150.698236ms 2026/09/18 05:17:04 DEBUG : pacer: Reducing sleep to 213.147797ms 2026/09/18 05:17:04 DEBUG : pacer: Reducing sleep to 223.434034ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/18 05:17:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:17:04 DEBUG : pacer: Reducing sleep to 96.542162ms 2026/09/18 05:17:05 DEBUG : pacer: Reducing sleep to 110.386638ms 2026/09/18 05:17:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:17:05 DEBUG : pacer: Reducing sleep to 117.951662ms 2026/09/18 05:17:05 DEBUG : pacer: Reducing sleep to 240.949194ms 2026/09/18 05:17:05 DEBUG : pacer: Reducing sleep to 284.751111ms 2026/09/18 05:17:05 DEBUG : pacer: Reducing sleep to 193.924986ms 2026/09/18 05:17:06 DEBUG : pacer: Reducing sleep to 97.191977ms 2026/09/18 05:17:06 DEBUG : pacer: Reducing sleep to 51.994227ms 2026/09/18 05:17:10 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/18 05:17:14 DEBUG : pacer: Reducing sleep to 102.419453ms 2026/09/18 05:17:14 DEBUG : pacer: Reducing sleep to 226.409066ms 2026/09/18 05:17:15 DEBUG : pacer: Reducing sleep to 209.640872ms 2026/09/18 05:17:15 DEBUG : pacer: Reducing sleep to 181.292621ms 2026/09/18 05:17:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:17:19 DEBUG : pacer: Reducing sleep to 119.383617ms 2026/09/18 05:17:19 DEBUG : pacer: Reducing sleep to 141.09645ms 2026/09/18 05:17:19 DEBUG : pacer: Reducing sleep to 203.493843ms 2026/09/18 05:17:19 DEBUG : pacer: Reducing sleep to 217.38155ms 2026/09/18 05:17:23 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/18 05:17:28 DEBUG : pacer: Reducing sleep to 100.271203ms 2026/09/18 05:17:28 DEBUG : pacer: Reducing sleep to 227.883669ms 2026/09/18 05:17:28 DEBUG : pacer: Reducing sleep to 140.828945ms 2026/09/18 05:17:29 DEBUG : pacer: Reducing sleep to 221.073771ms 2026/09/18 05:17:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:17:33 DEBUG : pacer: Reducing sleep to 110.624135ms 2026/09/18 05:17:33 DEBUG : pacer: Reducing sleep to 102.575226ms 2026/09/18 05:17:33 DEBUG : pacer: Reducing sleep to 168.016728ms 2026/09/18 05:17:33 DEBUG : pacer: Reducing sleep to 225.195924ms 2026/09/18 05:17:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/18 05:17:42 DEBUG : pacer: Reducing sleep to 88.077426ms 2026/09/18 05:17:42 DEBUG : pacer: Reducing sleep to 213.082539ms 2026/09/18 05:17:42 DEBUG : pacer: Reducing sleep to 226.95808ms 2026/09/18 05:17:42 DEBUG : pacer: Reducing sleep to 233.490647ms 2026/09/18 05:17:47 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:17:47 DEBUG : pacer: Reducing sleep to 110.789767ms 2026/09/18 05:17:47 DEBUG : pacer: Reducing sleep to 93.334193ms 2026/09/18 05:17:47 DEBUG : pacer: Reducing sleep to 154.016576ms 2026/09/18 05:17:47 DEBUG : pacer: Reducing sleep to 223.098922ms 2026/09/18 05:17:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/18 05:17:56 DEBUG : pacer: Reducing sleep to 89.596484ms 2026/09/18 05:17:56 DEBUG : pacer: Reducing sleep to 216.806049ms 2026/09/18 05:17:56 DEBUG : pacer: Reducing sleep to 100.165651ms 2026/09/18 05:17:56 DEBUG : pacer: Reducing sleep to 213.78919ms 2026/09/18 05:18:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:18:00 DEBUG : pacer: Reducing sleep to 106.164682ms 2026/09/18 05:18:01 DEBUG : pacer: Reducing sleep to 105.690315ms 2026/09/18 05:18:01 DEBUG : pacer: Reducing sleep to 176.842949ms 2026/09/18 05:18:01 DEBUG : pacer: Reducing sleep to 233.074278ms 2026/09/18 05:18:01 DEBUG : pacer: Reducing sleep to 213.400444ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/18 05:18:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:18:02 DEBUG : pacer: Reducing sleep to 76.736298ms 2026/09/18 05:18:02 DEBUG : zoho root 'rclone-test-bokupir9dewu': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/18 05:18:02 DEBUG : pacer: Reducing sleep to 118.668233ms 2026/09/18 05:18:02 DEBUG : pacer: Reducing sleep to 136.95749ms 2026/09/18 05:18:02 DEBUG : pacer: Reducing sleep to 242.865819ms 2026/09/18 05:18:02 DEBUG : pacer: Reducing sleep to 275.39128ms 2026/09/18 05:18:03 DEBUG : pacer: Reducing sleep to 193.491026ms 2026/09/18 05:18:03 DEBUG : pacer: Reducing sleep to 85.610873ms 2026/09/18 05:18:03 DEBUG : pacer: Reducing sleep to 62.737787ms 2026/09/18 05:18:03 DEBUG : pacer: Reducing sleep to 139.252378ms 2026/09/18 05:18:04 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/18 05:18:04 DEBUG : pacer: Reducing sleep to 72.948245ms 2026/09/18 05:18:04 DEBUG : pacer: Reducing sleep to 199.155287ms 2026/09/18 05:18:04 DEBUG : pacer: Reducing sleep to 103.757635ms 2026/09/18 05:18:04 DEBUG : pacer: Reducing sleep to 218.722586ms 2026/09/18 05:18:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:18:09 DEBUG : pacer: Reducing sleep to 108.456681ms 2026/09/18 05:18:09 DEBUG : pacer: Reducing sleep to 105.175995ms 2026/09/18 05:18:09 DEBUG : pacer: Reducing sleep to 168.651141ms 2026/09/18 05:18:09 DEBUG : pacer: Reducing sleep to 226.325474ms 2026/09/18 05:18:13 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:725: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:734: testing "test%46.txt" 2026/09/18 05:18:18 DEBUG : pacer: Reducing sleep to 86.704404ms 2026/09/18 05:18:18 DEBUG : pacer: Reducing sleep to 215.24961ms 2026/09/18 05:18:18 DEBUG : pacer: Reducing sleep to 192.838926ms 2026/09/18 05:18:18 DEBUG : pacer: Reducing sleep to 234.06143ms 2026/09/18 05:18:23 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:18:23 DEBUG : pacer: Reducing sleep to 104.195323ms 2026/09/18 05:18:23 DEBUG : pacer: Reducing sleep to 69.132798ms 2026/09/18 05:18:23 DEBUG : pacer: Reducing sleep to 155.052539ms 2026/09/18 05:18:23 DEBUG : pacer: Reducing sleep to 250.519401ms 2026/09/18 05:18:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/18 05:18:46 DEBUG : pacer: Reducing sleep to 94.856045ms 2026/09/18 05:18:46 DEBUG : pacer: Reducing sleep to 214.208723ms 2026/09/18 05:18:46 DEBUG : pacer: Reducing sleep to 258.649605ms 2026/09/18 05:18:46 DEBUG : pacer: Reducing sleep to 238.755682ms 2026/09/18 05:18:46 DEBUG : pacer: Reducing sleep to 119.542803ms 2026/09/18 05:18:47 DEBUG : pacer: Reducing sleep to 70.128244ms 2026/09/18 05:18:47 DEBUG : pacer: Reducing sleep to 86.283078ms 2026/09/18 05:18:47 DEBUG : pacer: Reducing sleep to 252.334302ms 2026/09/18 05:18:47 DEBUG : pacer: Reducing sleep to 283.883878ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/18 05:18:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:18:50 DEBUG : pacer: Reducing sleep to 44.928341ms 2026/09/18 05:18:55 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:18:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:18:55 UTC": parsing time "Fri, 18 Sep 2026 05:18:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:18:55 UTC" as " " 2026/09/18 05:18:55 DEBUG : pacer: Reducing sleep to 111.475492ms === 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/18 05:18:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:19:04 DEBUG : zoho root 'rclone-test-bokupir9dewu': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/18 05:19:04 DEBUG : pacer: Reducing sleep to 117.658217ms 2026/09/18 05:19:04 DEBUG : pacer: Reducing sleep to 161.369958ms 2026/09/18 05:19:04 DEBUG : pacer: Reducing sleep to 276.342825ms 2026/09/18 05:19:04 DEBUG : pacer: Reducing sleep to 279.894778ms 2026/09/18 05:19:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:19:05 DEBUG : pacer: Reducing sleep to 115.874599ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/18 05:19:05 DEBUG : pacer: Reducing sleep to 229.317745ms 2026/09/18 05:19:05 DEBUG : pacer: Reducing sleep to 275.084038ms 2026/09/18 05:19:05 DEBUG : pacer: Reducing sleep to 207.180653ms 2026/09/18 05:19:06 DEBUG : pacer: Reducing sleep to 106.418405ms 2026/09/18 05:19:06 DEBUG : pacer: Reducing sleep to 69.642562ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/18 05:19:06 DEBUG : pacer: Reducing sleep to 123.975121ms 2026/09/18 05:19:06 DEBUG : pacer: Reducing sleep to 226.307626ms 2026/09/18 05:19:06 DEBUG : pacer: Reducing sleep to 263.382878ms 2026/09/18 05:19:06 DEBUG : pacer: Reducing sleep to 192.706234ms 2026/09/18 05:19:07 DEBUG : pacer: Reducing sleep to 103.168595ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/18 05:19:07 DEBUG : pacer: Reducing sleep to 73.612716ms 2026/09/18 05:19:07 DEBUG : pacer: Reducing sleep to 128.043211ms 2026/09/18 05:19:07 DEBUG : pacer: Reducing sleep to 238.761381ms 2026/09/18 05:19:07 DEBUG : pacer: Reducing sleep to 265.483742ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/18 05:19:07 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/18 05:19:12 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/18 05:19:16 DEBUG : pacer: Reducing sleep to 119.951466ms 2026/09/18 05:19:16 DEBUG : pacer: Reducing sleep to 232.052459ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/18 05:19:21 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:19:21 DEBUG : pacer: Reducing sleep to 113.515874ms 2026/09/18 05:19:21 DEBUG : pacer: Reducing sleep to 102.400058ms 2026/09/18 05:19:21 DEBUG : pacer: Reducing sleep to 154.99203ms 2026/09/18 05:19:21 DEBUG : pacer: Reducing sleep to 211.326149ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/18 05:19:26 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/18 05:19:30 DEBUG : pacer: Reducing sleep to 107.369517ms 2026/09/18 05:19:30 DEBUG : pacer: Reducing sleep to 225.685915ms 2026/09/18 05:19:30 DEBUG : pacer: Reducing sleep to 277.085335ms 2026/09/18 05:19:31 DEBUG : pacer: Reducing sleep to 223.585929ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/18 05:19:35 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/18 05:19:35 DEBUG : pacer: Reducing sleep to 111.860007ms 2026/09/18 05:19:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:19:40 DEBUG : pacer: Reducing sleep to 96.350438ms 2026/09/18 05:19:40 DEBUG : pacer: Reducing sleep to 146.568346ms 2026/09/18 05:19:40 DEBUG : pacer: Reducing sleep to 256.574531ms 2026/09/18 05:19:44 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:19:44 DEBUG : pacer: Reducing sleep to 116.636186ms 2026/09/18 05:19:44 DEBUG : pacer: Reducing sleep to 273.670451ms 2026/09/18 05:19:45 DEBUG : pacer: Reducing sleep to 201.94158ms 2026/09/18 05:19:45 DEBUG : pacer: Reducing sleep to 133.940017ms 2026/09/18 05:19:45 DEBUG : pacer: Reducing sleep to 105.419239ms 2026/09/18 05:19:45 DEBUG : pacer: Reducing sleep to 141.706692ms 2026/09/18 05:19:45 DEBUG : pacer: Reducing sleep to 176.573531ms 2026/09/18 05:19:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:19:49 DEBUG : pacer: Reducing sleep to 117.554329ms 2026/09/18 05:19:49 DEBUG : pacer: Reducing sleep to 153.662331ms 2026/09/18 05:19:49 DEBUG : pacer: Reducing sleep to 201.373897ms 2026/09/18 05:19:49 DEBUG : pacer: Reducing sleep to 222.245134ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/18 05:19:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:19:58 DEBUG : pacer: Reducing sleep to 101.755886ms 2026/09/18 05:19:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:19:59 DEBUG : pacer: Reducing sleep to 113.803987ms 2026/09/18 05:20:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:03 DEBUG : pacer: Reducing sleep to 113.031388ms 2026/09/18 05:20:03 DEBUG : pacer: Reducing sleep to 277.257886ms 2026/09/18 05:20:03 DEBUG : pacer: Reducing sleep to 379.27266ms 2026/09/18 05:20:03 DEBUG : pacer: Reducing sleep to 278.340858ms 2026/09/18 05:20:04 DEBUG : pacer: Reducing sleep to 57.837331ms 2026/09/18 05:20:04 DEBUG : Creating backend with remote "TestZoho:rclone-test-bokupir9dewu/dirToPurgeFromRoot" 2026/09/18 05:20:04 DEBUG : zoho root 'rclone-test-bokupir9dewu/dirToPurgeFromRoot': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/18 05:20:04 DEBUG : pacer: Reducing sleep to 91.417491ms 2026/09/18 05:20:04 DEBUG : pacer: Reducing sleep to 187.827961ms 2026/09/18 05:20:04 DEBUG : pacer: Reducing sleep to 242.873727ms 2026/09/18 05:20:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:04 DEBUG : pacer: Reducing sleep to 114.056955ms 2026/09/18 05:20:04 DEBUG : pacer: Reducing sleep to 231.613208ms 2026/09/18 05:20:05 DEBUG : pacer: Reducing sleep to 277.82942ms 2026/09/18 05:20:05 DEBUG : pacer: Reducing sleep to 213.313568ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/18 05:20:05 DEBUG : Creating backend with remote "TestZoho:rclone-test-bokupir9dewu/hello? sausage/êé" 2026/09/18 05:20:05 DEBUG : pacer: Reducing sleep to 108.767976ms 2026/09/18 05:20:05 DEBUG : pacer: Reducing sleep to 219.530319ms 2026/09/18 05:20:05 DEBUG : pacer: Reducing sleep to 282.898962ms 2026/09/18 05:20:05 DEBUG : pacer: Reducing sleep to 217.120085ms 2026/09/18 05:20:06 DEBUG : pacer: Reducing sleep to 91.595281ms 2026/09/18 05:20:06 DEBUG : pacer: Reducing sleep to 60.612055ms 2026/09/18 05:20:06 DEBUG : pacer: Reducing sleep to 111.064846ms 2026/09/18 05:20:06 DEBUG : pacer: Reducing sleep to 227.079898ms 2026/09/18 05:20:06 DEBUG : pacer: Reducing sleep to 258.512893ms 2026/09/18 05:20:06 DEBUG : pacer: Reducing sleep to 221.046694ms 2026/09/18 05:20:07 DEBUG : pacer: Reducing sleep to 102.598302ms 2026/09/18 05:20:07 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:07 DEBUG : pacer: Reducing sleep to 104.388727ms 2026/09/18 05:20:07 DEBUG : pacer: Reducing sleep to 220.912529ms 2026/09/18 05:20:07 DEBUG : pacer: Reducing sleep to 257.04641ms 2026/09/18 05:20:07 DEBUG : pacer: Reducing sleep to 227.745681ms 2026/09/18 05:20:08 DEBUG : pacer: Reducing sleep to 115.150333ms 2026/09/18 05:20:08 DEBUG : pacer: Reducing sleep to 75.401974ms 2026/09/18 05:20:08 DEBUG : pacer: Reducing sleep to 115.334719ms 2026/09/18 05:20:08 DEBUG : pacer: Reducing sleep to 216.491733ms 2026/09/18 05:20:08 DEBUG : pacer: Reducing sleep to 262.232146ms 2026/09/18 05:20:09 DEBUG : pacer: Reducing sleep to 205.707234ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/18 05:20:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:09 DEBUG : pacer: Reducing sleep to 90.624294ms 2026/09/18 05:20:09 DEBUG : pacer: Reducing sleep to 203.323876ms 2026/09/18 05:20:09 DEBUG : pacer: Reducing sleep to 280.47169ms 2026/09/18 05:20:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:12 DEBUG : pacer: Reducing sleep to 112.190611ms 2026/09/18 05:20:12 DEBUG : pacer: Reducing sleep to 53.028769ms 2026/09/18 05:20:16 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/18 05:20:21 DEBUG : pacer: Reducing sleep to 103.753577ms 2026/09/18 05:20:21 DEBUG : pacer: Reducing sleep to 190.113656ms 2026/09/18 05:20:21 DEBUG : pacer: Reducing sleep to 266.651418ms 2026/09/18 05:20:22 DEBUG : pacer: Reducing sleep to 260.485548ms 2026/09/18 05:20:22 DEBUG : pacer: Reducing sleep to 151.407562ms 2026/09/18 05:20:22 DEBUG : pacer: Reducing sleep to 60.518906ms 2026/09/18 05:20:22 DEBUG : pacer: Reducing sleep to 79.774892ms 2026/09/18 05:20:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:35 DEBUG : pacer: Reducing sleep to 91.868372ms 2026/09/18 05:20:35 DEBUG : pacer: Reducing sleep to 199.58356ms 2026/09/18 05:20:35 DEBUG : pacer: Reducing sleep to 252.410785ms 2026/09/18 05:20:36 DEBUG : pacer: Reducing sleep to 237.842988ms 2026/09/18 05:20:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:40 DEBUG : pacer: Reducing sleep to 116.055478ms 2026/09/18 05:20:40 DEBUG : pacer: Reducing sleep to 280.970276ms 2026/09/18 05:20:40 DEBUG : pacer: Reducing sleep to 266.960312ms 2026/09/18 05:20:40 DEBUG : pacer: Reducing sleep to 145.007581ms 2026/09/18 05:20:41 DEBUG : pacer: Reducing sleep to 59.918415ms 2026/09/18 05:20:41 DEBUG : pacer: Reducing sleep to 66.305068ms 2026/09/18 05:20:41 DEBUG : pacer: Reducing sleep to 170.470153ms 2026/09/18 05:20:41 DEBUG : pacer: Reducing sleep to 249.459914ms 2026/09/18 05:20:41 DEBUG : pacer: Reducing sleep to 267.864753ms 2026/09/18 05:20:44 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:45 DEBUG : pacer: Reducing sleep to 122.158694ms 2026/09/18 05:20:45 DEBUG : pacer: Reducing sleep to 278.222026ms 2026/09/18 05:20:45 DEBUG : pacer: Reducing sleep to 220.126176ms 2026/09/18 05:20:45 DEBUG : pacer: Reducing sleep to 110.923351ms 2026/09/18 05:20:45 DEBUG : pacer: Reducing sleep to 77.683883ms 2026/09/18 05:20:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:49 DEBUG : pacer: Reducing sleep to 107.151985ms 2026/09/18 05:20:49 DEBUG : pacer: Reducing sleep to 192.155894ms 2026/09/18 05:20:49 DEBUG : pacer: Reducing sleep to 271.678121ms 2026/09/18 05:20:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:54 DEBUG : pacer: Reducing sleep to 123.600869ms 2026/09/18 05:20:54 DEBUG : pacer: Reducing sleep to 277.954912ms 2026/09/18 05:20:54 DEBUG : pacer: Reducing sleep to 234.553461ms 2026/09/18 05:20:54 DEBUG : pacer: Reducing sleep to 118.582036ms 2026/09/18 05:20:54 DEBUG : pacer: Reducing sleep to 55.672646ms 2026/09/18 05:20:55 DEBUG : pacer: Reducing sleep to 100.64796ms 2026/09/18 05:20:55 DEBUG : pacer: Reducing sleep to 205.660976ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/18 05:20:55 DEBUG : Creating backend with remote "TestZoho:rclone-test-xekuqih0dobu" 2026/09/18 05:20:55 DEBUG : pacer: Reducing sleep to 38.889925ms 2026/09/18 05:20:55 DEBUG : pacer: Reducing sleep to 69.354327ms 2026/09/18 05:20:55 DEBUG : pacer: Reducing sleep to 91.893443ms 2026/09/18 05:20:55 DEBUG : pacer: Reducing sleep to 208.46196ms 2026/09/18 05:20:55 DEBUG : pacer: Reducing sleep to 257.592413ms 2026/09/18 05:20:56 DEBUG : pacer: Reducing sleep to 237.708034ms 2026/09/18 05:20:56 DEBUG : pacer: Reducing sleep to 104.732567ms 2026/09/18 05:20:56 DEBUG : pacer: Reducing sleep to 50.102501ms 2026/09/18 05:20:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:56 DEBUG : pacer: Reducing sleep to 39.96517ms 2026/09/18 05:20:56 DEBUG : pacer: Reducing sleep to 145.818171ms 2026/09/18 05:20:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:20:58 DEBUG : pacer: Reducing sleep to 102.433063ms 2026/09/18 05:20:58 DEBUG : pacer: Reducing sleep to 192.602396ms 2026/09/18 05:20:59 DEBUG : pacer: Reducing sleep to 246.911639ms 2026/09/18 05:20:59 DEBUG : pacer: Reducing sleep to 221.55142ms 2026/09/18 05:21:01 DEBUG : pacer: Reducing sleep to 41.732932ms 2026/09/18 05:21:01 DEBUG : pacer: Reducing sleep to 152.023614ms 2026/09/18 05:21:01 DEBUG : pacer: Reducing sleep to 269.195741ms 2026/09/18 05:21:01 DEBUG : pacer: Reducing sleep to 269.534673ms 2026/09/18 05:21:01 DEBUG : pacer: Reducing sleep to 179.43279ms 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 13.722624ms 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 120.305333ms 2026/09/18 05:21:02 DEBUG : zoho root 'rclone-test-xekuqih0dobu': Purge remote 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 110.536367ms 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 162.87546ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 117.267126ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 229.531986ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/18 05:21:02 DEBUG : pacer: Reducing sleep to 282.546114ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/18 05:21:03 DEBUG : pacer: Reducing sleep to 212.850968ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/18 05:21:03 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/18 05:21:08 DEBUG : zoho root 'rclone-test-bokupir9dewu': folder listing limiter sweep: evicted 5 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/18 05:21:31 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:31 UTC": parsing time "Fri, 18 Sep 2026 05:21:31 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:31 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/18 05:21:36 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:36 UTC": parsing time "Fri, 18 Sep 2026 05:21:36 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:36 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/18 05:21:40 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:40 UTC": parsing time "Fri, 18 Sep 2026 05:21:40 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:40 UTC" as " " 2026/09/18 05:21:41 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:41 UTC": parsing time "Fri, 18 Sep 2026 05:21:41 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:41 UTC" as " " 2026/09/18 05:21:41 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:41 UTC": parsing time "Fri, 18 Sep 2026 05:21:41 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:41 UTC" as " " 2026/09/18 05:21:41 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:41 UTC": parsing time "Fri, 18 Sep 2026 05:21:41 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:41 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/18 05:21:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:45 UTC": parsing time "Fri, 18 Sep 2026 05:21:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:45 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/18 05:21:50 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:50 UTC": parsing time "Fri, 18 Sep 2026 05:21:50 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:50 UTC" as " " 2026/09/18 05:21:54 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:54 UTC": parsing time "Fri, 18 Sep 2026 05:21:54 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:54 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/18 05:21:54 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:54 UTC": parsing time "Fri, 18 Sep 2026 05:21:54 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:54 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/18 05:21:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:55 UTC": parsing time "Fri, 18 Sep 2026 05:21:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:55 UTC" as " " 2026/09/18 05:21:55 DEBUG : pacer: Reducing sleep to 4.393275ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/18 05:21:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:55 UTC": parsing time "Fri, 18 Sep 2026 05:21:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:55 UTC" as " " 2026/09/18 05:21:55 DEBUG : pacer: Reducing sleep to 8.993923ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/18 05:21:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:55 UTC": parsing time "Fri, 18 Sep 2026 05:21:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:55 UTC" as " " 2026/09/18 05:21:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/18 05:21:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:21:59 UTC": parsing time "Fri, 18 Sep 2026 05:21:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:21:59 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/18 05:22:03 DEBUG : pacer: Reducing sleep to 50.020371ms 2026/09/18 05:22:03 DEBUG : pacer: Reducing sleep to 166.778677ms 2026/09/18 05:22:04 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 18 Sep 2026 05:22:04 UTC": parsing time "Fri, 18 Sep 2026 05:22:04 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 18 Sep 2026 05:22:04 UTC" as " " 2026/09/18 05:22:04 DEBUG : pacer: Reducing sleep to 125.936586ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/18 05:22:04 DEBUG : pacer: Reducing sleep to 241.195721ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/18 05:22:04 DEBUG : Creating backend with remote "TestZoho:rclone-test-bokupir9dewu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/18 05:22:04 DEBUG : Config file has changed externally - reloading 2026/09/18 05:22:04 DEBUG : pacer: Reducing sleep to 113.615911ms 2026/09/18 05:22:04 DEBUG : pacer: Reducing sleep to 229.823997ms 2026/09/18 05:22:04 DEBUG : pacer: Reducing sleep to 285.985335ms 2026/09/18 05:22:04 DEBUG : pacer: Reducing sleep to 220.621488ms 2026/09/18 05:22:05 DEBUG : pacer: Reducing sleep to 96.406731ms 2026/09/18 05:22:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:22:05 DEBUG : pacer: Reducing sleep to 116.056049ms 2026/09/18 05:22:05 DEBUG : pacer: Reducing sleep to 234.340917ms 2026/09/18 05:22:05 DEBUG : pacer: Reducing sleep to 282.291882ms 2026/09/18 05:22:05 DEBUG : pacer: Reducing sleep to 213.826907ms 2026/09/18 05:22:06 DEBUG : pacer: Reducing sleep to 68.929741ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/18 05:22:06 DEBUG : pacer: Reducing sleep to 49.325859ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/18 05:22:06 DEBUG : Creating backend with remote "TestZoho:rclone-test-bokupir9dewu/not found.txt" 2026/09/18 05:22:06 DEBUG : pacer: Reducing sleep to 112.63078ms 2026/09/18 05:22:06 DEBUG : pacer: Reducing sleep to 152.70019ms 2026/09/18 05:22:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:22:11 DEBUG : zoho root 'rclone-test-bokupir9dewu/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/09/18 05:22:15 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-bokupir9dewu" from "TestZoho:rclone-test-bokupir9dewu" 2026/09/18 05:22:15 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/18 05:22:20 DEBUG : pacer: Reducing sleep to 109.384478ms 2026/09/18 05:22:20 DEBUG : pacer: Reducing sleep to 227.495888ms 2026/09/18 05:22:20 DEBUG : pacer: Reducing sleep to 278.792452ms 2026/09/18 05:22:20 DEBUG : pacer: Reducing sleep to 221.145334ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/18 05:22:25 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/18 05:22:29 DEBUG : pacer: Reducing sleep to 52.966023ms 2026/09/18 05:22:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:22:38 DEBUG : pacer: Reducing sleep to 112.246143ms 2026/09/18 05:22:39 DEBUG : pacer: Reducing sleep to 224.018252ms 2026/09/18 05:22:39 DEBUG : pacer: Reducing sleep to 282.588018ms 2026/09/18 05:22:39 DEBUG : pacer: Reducing sleep to 215.046155ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/18 05:22:39 DEBUG : pacer: Reducing sleep to 75.340081ms 2026/09/18 05:22:43 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:22:43 DEBUG : pacer: Reducing sleep to 119.839544ms 2026/09/18 05:22:43 DEBUG : pacer: Reducing sleep to 234.781799ms 2026/09/18 05:22:43 DEBUG : pacer: Reducing sleep to 282.662629ms 2026/09/18 05:22:44 DEBUG : pacer: Reducing sleep to 205.227072ms === 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/18 05:22:48 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:22:52 DEBUG : pacer: Reducing sleep to 117.392903ms 2026/09/18 05:22:52 DEBUG : pacer: Reducing sleep to 225.541469ms 2026/09/18 05:22:53 DEBUG : pacer: Reducing sleep to 282.093615ms 2026/09/18 05:22:53 DEBUG : pacer: Reducing sleep to 218.727497ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/18 05:22:53 DEBUG : pacer: Reducing sleep to 103.067011ms === 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/18 05:22:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:22:57 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/18 05:22:57 DEBUG : pacer: Rate limited, increasing sleep to 1.482266431s 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/18 05:23:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:23:02 DEBUG : pacer: Reducing sleep to 83.859074ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/18 05:23:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/18 05:23:04 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=aypu0cbfff104daf5410e98a5f32f2f664d1f": write tcp: unexpected EOF) 2026/09/18 05:23:04 DEBUG : pacer: Rate limited, increasing sleep to 1.250263996s 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=aypu0cbfff104daf5410e98a5f32f2f664d1f": write tcp: unexpected EOF 2026/09/18 05:23:04 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/18 05:23:04 DEBUG : pacer: Reducing sleep to 135.896647ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n