"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/19 03:24:54 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/19 03:24:54 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/19 03:24:54 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/19 03:24:54 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/19 03:24:54 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/19 03:24:54 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/19 03:24:54 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/19 03:24:54 DEBUG : Creating backend with remote "TestZoho:rclone-test-hijihaz2rufo" 2026/09/19 03:24:54 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/19 03:24:54 DEBUG : zoho root 'rclone-test-hijihaz2rufo': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/19 03:24:54 DEBUG : TestZoho: Token expired 2026/09/19 03:24:54 DEBUG : TestZoho: No updated token found in the config file 2026/09/19 03:24:54 DEBUG : TestZoho: Token refresh successful 2026/09/19 03:24:54 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/19 03:24:54 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/19 03:24:55 DEBUG : pacer: Reducing sleep to 82.724785ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/19 03:24:56 DEBUG : pacer: Reducing sleep to 158.674448ms 2026/09/19 03:24:56 DEBUG : pacer: Reducing sleep to 212.401704ms === RUN TestIntegration/FsMkdir 2026/09/19 03:24:56 DEBUG : pacer: Reducing sleep to 157.062439ms 2026/09/19 03:24:56 DEBUG : pacer: Reducing sleep to 99.124316ms 2026/09/19 03:24:56 DEBUG : pacer: Reducing sleep to 173.786958ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/19 03:24:56 INFO : dir/subdir: Making directory 2026/09/19 03:24:56 DEBUG : pacer: Reducing sleep to 232.733371ms 2026/09/19 03:24:56 DEBUG : pacer: Reducing sleep to 200.809393ms 2026/09/19 03:24:57 DEBUG : pacer: Reducing sleep to 166.660581ms 2026/09/19 03:24:57 DEBUG : pacer: Reducing sleep to 98.491388ms 2026/09/19 03:24:57 DEBUG : pacer: Reducing sleep to 119.262384ms 2026/09/19 03:24:57 DEBUG : pacer: Reducing sleep to 191.564468ms 2026/09/19 03:24:57 DEBUG : pacer: Reducing sleep to 248.268377ms 2026/09/19 03:24:57 INFO : dir/subdir: Removing directory 2026/09/19 03:24:57 DEBUG : pacer: Reducing sleep to 203.236556ms 2026/09/19 03:24:58 DEBUG : pacer: Reducing sleep to 106.885776ms 2026/09/19 03:24:58 DEBUG : pacer: Reducing sleep to 79.356586ms 2026/09/19 03:24:58 DEBUG : pacer: Reducing sleep to 128.354143ms 2026/09/19 03:24:58 INFO : dir: Removing directory 2026/09/19 03:24:58 DEBUG : pacer: Reducing sleep to 183.358022ms 2026/09/19 03:24:58 DEBUG : pacer: Reducing sleep to 230.170904ms 2026/09/19 03:24:58 DEBUG : pacer: Reducing sleep to 223.247213ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/19 03:24:59 DEBUG : pacer: Reducing sleep to 166.429145ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/19 03:25:03 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/19 03:25:08 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/19 03:25:17 DEBUG : pacer: Reducing sleep to 41.540014ms 2026/09/19 03:25:17 DEBUG : pacer: Reducing sleep to 118.28093ms 2026/09/19 03:25:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:25:18 DEBUG : pacer: Reducing sleep to 74.496758ms 2026/09/19 03:25:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:25:22 DEBUG : pacer: Reducing sleep to 53.065738ms 2026/09/19 03:25:22 DEBUG : pacer: Reducing sleep to 122.562656ms 2026/09/19 03:25:22 DEBUG : pacer: Reducing sleep to 198.85261ms 2026/09/19 03:25:22 DEBUG : pacer: Reducing sleep to 211.744658ms 2026/09/19 03:25:26 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/19 03:25:31 DEBUG : pacer: Reducing sleep to 49.410367ms 2026/09/19 03:25:31 DEBUG : pacer: Reducing sleep to 134.307444ms 2026/09/19 03:25:31 DEBUG : pacer: Reducing sleep to 124.68169ms 2026/09/19 03:25:31 DEBUG : pacer: Reducing sleep to 195.760706ms 2026/09/19 03:25:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:25:36 DEBUG : pacer: Reducing sleep to 44.765736ms 2026/09/19 03:25:36 DEBUG : pacer: Reducing sleep to 86.958595ms 2026/09/19 03:25:36 DEBUG : pacer: Reducing sleep to 147.802211ms 2026/09/19 03:25:36 DEBUG : pacer: Reducing sleep to 196.370137ms 2026/09/19 03:25:40 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/19 03:25:45 DEBUG : pacer: Reducing sleep to 37.706916ms 2026/09/19 03:25:45 DEBUG : pacer: Reducing sleep to 116.024219ms 2026/09/19 03:25:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:25:46 DEBUG : pacer: Reducing sleep to 77.075917ms 2026/09/19 03:25:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:25:49 DEBUG : pacer: Reducing sleep to 77.409537ms 2026/09/19 03:25:50 DEBUG : pacer: Reducing sleep to 146.901877ms 2026/09/19 03:25:50 DEBUG : pacer: Reducing sleep to 219.182328ms 2026/09/19 03:25:50 DEBUG : pacer: Reducing sleep to 209.686382ms 2026/09/19 03:25:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/19 03:25:54 DEBUG : zoho root 'rclone-test-hijihaz2rufo': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/19 03:25:59 DEBUG : pacer: Reducing sleep to 50.769503ms 2026/09/19 03:25:59 DEBUG : pacer: Reducing sleep to 130.19055ms 2026/09/19 03:25:59 DEBUG : pacer: Reducing sleep to 84.41785ms 2026/09/19 03:25:59 DEBUG : pacer: Reducing sleep to 143.171571ms 2026/09/19 03:25:59 DEBUG : pacer: Reducing sleep to 203.721782ms 2026/09/19 03:25:59 DEBUG : pacer: Reducing sleep to 223.531993ms 2026/09/19 03:26:00 DEBUG : pacer: Reducing sleep to 187.335936ms 2026/09/19 03:26:00 DEBUG : pacer: Reducing sleep to 2.929979ms 2026/09/19 03:26:00 DEBUG : pacer: Reducing sleep to 20.850513ms 2026/09/19 03:26:00 DEBUG : pacer: Reducing sleep to 96.172379ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/19 03:26:00 DEBUG : pacer: Reducing sleep to 158.716026ms 2026/09/19 03:26:00 DEBUG : pacer: Reducing sleep to 217.435602ms 2026/09/19 03:26:00 DEBUG : pacer: Reducing sleep to 244.90662ms 2026/09/19 03:26:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:26:01 DEBUG : pacer: Reducing sleep to 39.650296ms 2026/09/19 03:26:01 DEBUG : pacer: Reducing sleep to 99.461151ms 2026/09/19 03:26:01 DEBUG : pacer: Reducing sleep to 143.93659ms 2026/09/19 03:26:01 DEBUG : pacer: Reducing sleep to 188.828354ms 2026/09/19 03:26:02 DEBUG : pacer: Reducing sleep to 227.08569ms 2026/09/19 03:26:02 DEBUG : pacer: Reducing sleep to 210.896782ms 2026/09/19 03:26:02 DEBUG : pacer: Reducing sleep to 163.790644ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/19 03:26:06 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:26:07 DEBUG : pacer: Reducing sleep to 52.687534ms 2026/09/19 03:26:07 DEBUG : pacer: Reducing sleep to 139.49546ms 2026/09/19 03:26:07 DEBUG : pacer: Reducing sleep to 137.235436ms 2026/09/19 03:26:07 DEBUG : pacer: Reducing sleep to 182.250784ms 2026/09/19 03:26:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:26:11 DEBUG : pacer: Reducing sleep to 67.261854ms 2026/09/19 03:26:11 DEBUG : pacer: Reducing sleep to 111.137333ms 2026/09/19 03:26:11 DEBUG : pacer: Reducing sleep to 171.017034ms 2026/09/19 03:26:11 DEBUG : pacer: Reducing sleep to 213.632821ms 2026/09/19 03:26:16 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/19 03:26:20 DEBUG : pacer: Reducing sleep to 40.014151ms 2026/09/19 03:26:20 DEBUG : pacer: Reducing sleep to 121.695999ms 2026/09/19 03:26:21 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:26:21 DEBUG : pacer: Reducing sleep to 69.220281ms 2026/09/19 03:26:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:26:25 DEBUG : pacer: Reducing sleep to 53.261855ms 2026/09/19 03:26:25 DEBUG : pacer: Reducing sleep to 100.559788ms 2026/09/19 03:26:25 DEBUG : pacer: Reducing sleep to 174.652361ms 2026/09/19 03:26:25 DEBUG : pacer: Reducing sleep to 217.246943ms 2026/09/19 03:26:29 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/19 03:26:34 DEBUG : pacer: Reducing sleep to 53.988413ms 2026/09/19 03:26:34 DEBUG : pacer: Reducing sleep to 115.78737ms 2026/09/19 03:26:34 DEBUG : pacer: Reducing sleep to 114.815554ms 2026/09/19 03:26:35 DEBUG : pacer: Reducing sleep to 197.513336ms 2026/09/19 03:26:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:26:39 DEBUG : pacer: Reducing sleep to 73.987888ms 2026/09/19 03:26:39 DEBUG : pacer: Reducing sleep to 133.150885ms 2026/09/19 03:26:39 DEBUG : pacer: Reducing sleep to 193.622264ms 2026/09/19 03:26:39 DEBUG : pacer: Reducing sleep to 224.737703ms 2026/09/19 03:26:43 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/19 03:26:48 DEBUG : pacer: Reducing sleep to 52.463294ms 2026/09/19 03:26:48 DEBUG : pacer: Reducing sleep to 126.143605ms 2026/09/19 03:26:48 DEBUG : pacer: Reducing sleep to 284.96µs 2026/09/19 03:26:49 DEBUG : pacer: Reducing sleep to 72.546068ms 2026/09/19 03:26:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:26:53 DEBUG : pacer: Reducing sleep to 59.075916ms 2026/09/19 03:26:53 DEBUG : pacer: Reducing sleep to 127.752551ms 2026/09/19 03:26:53 DEBUG : pacer: Reducing sleep to 188.40192ms 2026/09/19 03:26:53 DEBUG : pacer: Reducing sleep to 228.239743ms 2026/09/19 03:26:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/19 03:26:57 DEBUG : zoho root 'rclone-test-hijihaz2rufo': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/19 03:26:57 DEBUG : pacer: Reducing sleep to 58.809934ms 2026/09/19 03:26:57 DEBUG : pacer: Reducing sleep to 79.87305ms 2026/09/19 03:26:58 DEBUG : pacer: Reducing sleep to 158.977426ms 2026/09/19 03:26:58 DEBUG : pacer: Reducing sleep to 135.741539ms 2026/09/19 03:26:58 DEBUG : pacer: Reducing sleep to 207.163373ms 2026/09/19 03:26:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:26:59 DEBUG : pacer: Reducing sleep to 56.849671ms 2026/09/19 03:26:59 DEBUG : pacer: Reducing sleep to 119.04712ms 2026/09/19 03:26:59 DEBUG : pacer: Reducing sleep to 185.212178ms 2026/09/19 03:27:00 DEBUG : pacer: Reducing sleep to 238.431517ms 2026/09/19 03:27:00 DEBUG : pacer: Reducing sleep to 135.061862ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/19 03:27:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:27:01 DEBUG : pacer: Reducing sleep to 63.809115ms 2026/09/19 03:27:01 DEBUG : pacer: Reducing sleep to 143.011557ms 2026/09/19 03:27:01 DEBUG : pacer: Reducing sleep to 33.782657ms 2026/09/19 03:27:01 DEBUG : pacer: Reducing sleep to 110.484964ms 2026/09/19 03:27:01 DEBUG : pacer: Reducing sleep to 183.340679ms 2026/09/19 03:27:01 DEBUG : pacer: Reducing sleep to 239.404806ms 2026/09/19 03:27:02 DEBUG : pacer: Reducing sleep to 172.641174ms 2026/09/19 03:27:02 DEBUG : pacer: Reducing sleep to 140.924769ms 2026/09/19 03:27:02 DEBUG : pacer: Reducing sleep to 107.934737ms 2026/09/19 03:27:06 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/19 03:27:11 DEBUG : pacer: Reducing sleep to 38.955572ms 2026/09/19 03:27:11 DEBUG : pacer: Reducing sleep to 100.359814ms 2026/09/19 03:27:11 DEBUG : pacer: Reducing sleep to 64.020136ms 2026/09/19 03:27:11 DEBUG : pacer: Reducing sleep to 142.326535ms 2026/09/19 03:27:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:27:15 DEBUG : pacer: Reducing sleep to 53.319822ms 2026/09/19 03:27:15 DEBUG : pacer: Reducing sleep to 117.759453ms 2026/09/19 03:27:15 DEBUG : pacer: Reducing sleep to 178.43992ms 2026/09/19 03:27:16 DEBUG : pacer: Reducing sleep to 201.099683ms 2026/09/19 03:27:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/19 03:27:24 DEBUG : pacer: Reducing sleep to 64.038596ms 2026/09/19 03:27:25 DEBUG : pacer: Reducing sleep to 145.825719ms 2026/09/19 03:27:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:27:25 DEBUG : pacer: Reducing sleep to 77.4791ms 2026/09/19 03:27:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:27:29 DEBUG : pacer: Reducing sleep to 53.238505ms 2026/09/19 03:27:29 DEBUG : pacer: Reducing sleep to 81.470063ms 2026/09/19 03:27:29 DEBUG : pacer: Reducing sleep to 155.312841ms 2026/09/19 03:27:29 DEBUG : pacer: Reducing sleep to 203.832938ms 2026/09/19 03:27:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/19 03:27:38 DEBUG : pacer: Reducing sleep to 57.474834ms 2026/09/19 03:27:38 DEBUG : pacer: Reducing sleep to 133.265477ms 2026/09/19 03:27:39 DEBUG : pacer: Reducing sleep to 121.720718ms 2026/09/19 03:27:39 DEBUG : pacer: Reducing sleep to 178.255757ms 2026/09/19 03:27:43 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:27:43 DEBUG : pacer: Reducing sleep to 67.77767ms 2026/09/19 03:27:43 DEBUG : pacer: Reducing sleep to 119.924804ms 2026/09/19 03:27:43 DEBUG : pacer: Reducing sleep to 188.181249ms 2026/09/19 03:27:43 DEBUG : pacer: Reducing sleep to 224.667715ms 2026/09/19 03:27:47 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/19 03:27:52 DEBUG : pacer: Reducing sleep to 45.885182ms 2026/09/19 03:27:52 DEBUG : pacer: Reducing sleep to 113.122072ms 2026/09/19 03:27:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:27:53 DEBUG : pacer: Reducing sleep to 70.725903ms 2026/09/19 03:27:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:27:57 DEBUG : pacer: Reducing sleep to 57.919951ms 2026/09/19 03:27:57 DEBUG : pacer: Reducing sleep to 119.710548ms 2026/09/19 03:27:57 DEBUG : pacer: Reducing sleep to 193.020958ms 2026/09/19 03:27:57 DEBUG : pacer: Reducing sleep to 235.177877ms 2026/09/19 03:27:57 DEBUG : pacer: Reducing sleep to 207.211952ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/19 03:27:57 DEBUG : zoho root 'rclone-test-hijihaz2rufo': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/19 03:27:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:27:58 DEBUG : pacer: Reducing sleep to 50.479619ms 2026/09/19 03:27:58 DEBUG : pacer: Reducing sleep to 131.493368ms 2026/09/19 03:27:58 DEBUG : pacer: Reducing sleep to 138.028161ms 2026/09/19 03:27:58 DEBUG : pacer: Reducing sleep to 209.528161ms 2026/09/19 03:27:58 DEBUG : pacer: Reducing sleep to 235.340107ms 2026/09/19 03:27:59 DEBUG : pacer: Reducing sleep to 179.044728ms 2026/09/19 03:27:59 DEBUG : pacer: Reducing sleep to 114.896107ms 2026/09/19 03:27:59 DEBUG : pacer: Reducing sleep to 99.105655ms 2026/09/19 03:27:59 DEBUG : pacer: Reducing sleep to 155.220545ms 2026/09/19 03:28:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/19 03:28:00 DEBUG : pacer: Reducing sleep to 58.683605ms 2026/09/19 03:28:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:28:01 DEBUG : pacer: Reducing sleep to 70.903392ms 2026/09/19 03:28:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:28:05 DEBUG : pacer: Reducing sleep to 74.379149ms 2026/09/19 03:28:05 DEBUG : pacer: Reducing sleep to 135.781837ms 2026/09/19 03:28:05 DEBUG : pacer: Reducing sleep to 195.301003ms 2026/09/19 03:28:05 DEBUG : pacer: Reducing sleep to 202.081171ms 2026/09/19 03:28:09 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/19 03:28:14 DEBUG : pacer: Reducing sleep to 39.982043ms 2026/09/19 03:28:14 DEBUG : pacer: Reducing sleep to 107.835544ms 2026/09/19 03:28:14 DEBUG : pacer: Reducing sleep to 72.151041ms 2026/09/19 03:28:15 DEBUG : pacer: Reducing sleep to 103.683335ms 2026/09/19 03:28:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:28:19 DEBUG : pacer: Reducing sleep to 67.324692ms 2026/09/19 03:28:19 DEBUG : pacer: Reducing sleep to 108.263566ms 2026/09/19 03:28:19 DEBUG : pacer: Reducing sleep to 187.562009ms 2026/09/19 03:28:19 DEBUG : pacer: Reducing sleep to 225.209653ms 2026/09/19 03:28:23 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/19 03:28:42 DEBUG : pacer: Reducing sleep to 41.653651ms 2026/09/19 03:28:42 DEBUG : pacer: Reducing sleep to 124.322689ms 2026/09/19 03:28:42 DEBUG : pacer: Reducing sleep to 156.726982ms 2026/09/19 03:28:42 DEBUG : pacer: Reducing sleep to 239.607007ms 2026/09/19 03:28:42 DEBUG : pacer: Reducing sleep to 225.684915ms 2026/09/19 03:28:43 DEBUG : pacer: Reducing sleep to 160.448084ms 2026/09/19 03:28:43 DEBUG : pacer: Reducing sleep to 78.162001ms 2026/09/19 03:28:43 DEBUG : pacer: Reducing sleep to 203.506011ms 2026/09/19 03:28:43 DEBUG : pacer: Reducing sleep to 202.277158ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/19 03:28:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:28:51 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:28:51 UTC": parsing time "Sat, 19 Sep 2026 03:28:51 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:28:51 UTC" as " " 2026/09/19 03:28:52 DEBUG : pacer: Reducing sleep to 58.751437ms === 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/19 03:28:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:00 DEBUG : zoho root 'rclone-test-hijihaz2rufo': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/19 03:29:00 DEBUG : pacer: Reducing sleep to 82.049421ms 2026/09/19 03:29:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:01 DEBUG : pacer: Reducing sleep to 53.402202ms 2026/09/19 03:29:01 DEBUG : pacer: Reducing sleep to 108.688724ms 2026/09/19 03:29:01 DEBUG : pacer: Reducing sleep to 83.865429ms 2026/09/19 03:29:01 DEBUG : pacer: Reducing sleep to 143.412963ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/19 03:29:01 DEBUG : pacer: Reducing sleep to 215.471543ms 2026/09/19 03:29:01 DEBUG : pacer: Reducing sleep to 236.383681ms 2026/09/19 03:29:02 DEBUG : pacer: Reducing sleep to 174.412113ms 2026/09/19 03:29:02 DEBUG : pacer: Reducing sleep to 116.489444ms 2026/09/19 03:29:02 DEBUG : pacer: Reducing sleep to 94.275913ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/19 03:29:02 DEBUG : pacer: Reducing sleep to 144.061538ms 2026/09/19 03:29:02 DEBUG : pacer: Reducing sleep to 214.844156ms 2026/09/19 03:29:02 DEBUG : pacer: Reducing sleep to 238.278752ms 2026/09/19 03:29:03 DEBUG : pacer: Reducing sleep to 172.847945ms 2026/09/19 03:29:03 DEBUG : pacer: Reducing sleep to 76.043738ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/19 03:29:03 DEBUG : pacer: Reducing sleep to 110.457804ms 2026/09/19 03:29:03 DEBUG : pacer: Reducing sleep to 151.431317ms 2026/09/19 03:29:03 DEBUG : pacer: Reducing sleep to 225.709453ms 2026/09/19 03:29:03 DEBUG : pacer: Reducing sleep to 235.347584ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/19 03:29:03 DEBUG : Creating backend with remote "TestZoho:" 2026/09/19 03:29:03 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/09/19 03:29:08 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/19 03:29:13 DEBUG : pacer: Reducing sleep to 56.054476ms 2026/09/19 03:29:13 DEBUG : pacer: Reducing sleep to 109.618435ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/19 03:29:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:17 DEBUG : pacer: Reducing sleep to 73.226537ms 2026/09/19 03:29:17 DEBUG : pacer: Reducing sleep to 150.394907ms 2026/09/19 03:29:18 DEBUG : pacer: Reducing sleep to 210.61596ms 2026/09/19 03:29:18 DEBUG : pacer: Reducing sleep to 242.068164ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/19 03:29:22 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/19 03:29:27 DEBUG : pacer: Reducing sleep to 55.656418ms 2026/09/19 03:29:27 DEBUG : pacer: Reducing sleep to 113.789154ms 2026/09/19 03:29:27 DEBUG : pacer: Reducing sleep to 166.701407ms 2026/09/19 03:29:27 DEBUG : pacer: Reducing sleep to 240.322204ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/19 03:29:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/19 03:29:32 DEBUG : pacer: Reducing sleep to 47.131868ms 2026/09/19 03:29:36 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:36 DEBUG : pacer: Reducing sleep to 40.526245ms 2026/09/19 03:29:37 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:37 DEBUG : pacer: Reducing sleep to 72.770121ms 2026/09/19 03:29:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:41 DEBUG : pacer: Reducing sleep to 66.263865ms 2026/09/19 03:29:41 DEBUG : pacer: Reducing sleep to 218.249889ms 2026/09/19 03:29:41 DEBUG : pacer: Reducing sleep to 290.783143ms 2026/09/19 03:29:41 DEBUG : pacer: Reducing sleep to 236.318361ms 2026/09/19 03:29:41 DEBUG : pacer: Reducing sleep to 91.92467ms 2026/09/19 03:29:42 DEBUG : pacer: Reducing sleep to 48.020926ms 2026/09/19 03:29:42 DEBUG : pacer: Reducing sleep to 74.552808ms 2026/09/19 03:29:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:46 DEBUG : pacer: Reducing sleep to 53.194459ms 2026/09/19 03:29:46 DEBUG : pacer: Reducing sleep to 122.10493ms 2026/09/19 03:29:46 DEBUG : pacer: Reducing sleep to 191.641192ms 2026/09/19 03:29:46 DEBUG : pacer: Reducing sleep to 240.947292ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/19 03:29:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:55 DEBUG : pacer: Reducing sleep to 50.882471ms 2026/09/19 03:29:55 DEBUG : pacer: Reducing sleep to 25.546642ms 2026/09/19 03:29:55 DEBUG : pacer: Reducing sleep to 87.216701ms 2026/09/19 03:29:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:29:59 DEBUG : pacer: Reducing sleep to 55.798625ms 2026/09/19 03:29:59 DEBUG : pacer: Reducing sleep to 216.719943ms 2026/09/19 03:29:59 DEBUG : pacer: Reducing sleep to 292.050198ms 2026/09/19 03:30:00 DEBUG : pacer: Reducing sleep to 222.879282ms 2026/09/19 03:30:00 DEBUG : pacer: Reducing sleep to 113.93151ms 2026/09/19 03:30:00 DEBUG : Creating backend with remote "TestZoho:rclone-test-hijihaz2rufo/dirToPurgeFromRoot" 2026/09/19 03:30:00 DEBUG : Config file has changed externally - reloading 2026/09/19 03:30:00 DEBUG : zoho root 'rclone-test-hijihaz2rufo/dirToPurgeFromRoot': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/19 03:30:00 DEBUG : pacer: Reducing sleep to 71.063972ms 2026/09/19 03:30:00 DEBUG : pacer: Reducing sleep to 139.724471ms 2026/09/19 03:30:01 DEBUG : pacer: Reducing sleep to 190.847851ms 2026/09/19 03:30:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:01 DEBUG : pacer: Reducing sleep to 74.670217ms 2026/09/19 03:30:01 DEBUG : pacer: Reducing sleep to 143.831203ms 2026/09/19 03:30:01 DEBUG : pacer: Reducing sleep to 187.319965ms 2026/09/19 03:30:01 DEBUG : pacer: Reducing sleep to 232.232228ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/19 03:30:01 DEBUG : Creating backend with remote "TestZoho:rclone-test-hijihaz2rufo/hello? sausage/êé" 2026/09/19 03:30:01 DEBUG : pacer: Reducing sleep to 77.43499ms 2026/09/19 03:30:02 DEBUG : pacer: Reducing sleep to 139.428974ms 2026/09/19 03:30:02 DEBUG : pacer: Reducing sleep to 199.981392ms 2026/09/19 03:30:02 DEBUG : pacer: Reducing sleep to 216.278148ms 2026/09/19 03:30:02 DEBUG : pacer: Reducing sleep to 175.391985ms 2026/09/19 03:30:02 DEBUG : pacer: Reducing sleep to 153.442066ms 2026/09/19 03:30:02 DEBUG : pacer: Reducing sleep to 109.451516ms 2026/09/19 03:30:03 DEBUG : pacer: Reducing sleep to 141.095445ms 2026/09/19 03:30:03 DEBUG : pacer: Reducing sleep to 176.439667ms 2026/09/19 03:30:03 DEBUG : pacer: Reducing sleep to 226.92401ms 2026/09/19 03:30:03 DEBUG : pacer: Reducing sleep to 201.705925ms 2026/09/19 03:30:03 DEBUG : pacer: Reducing sleep to 66.654817ms 2026/09/19 03:30:03 DEBUG : pacer: Reducing sleep to 134.463146ms 2026/09/19 03:30:04 DEBUG : pacer: Reducing sleep to 204.677364ms 2026/09/19 03:30:04 DEBUG : pacer: Reducing sleep to 187.468986ms 2026/09/19 03:30:04 DEBUG : pacer: Reducing sleep to 191.119767ms 2026/09/19 03:30:04 DEBUG : pacer: Reducing sleep to 166.025136ms 2026/09/19 03:30:04 DEBUG : pacer: Reducing sleep to 150.804243ms 2026/09/19 03:30:04 DEBUG : pacer: Reducing sleep to 138.997994ms 2026/09/19 03:30:05 DEBUG : pacer: Reducing sleep to 172.551884ms 2026/09/19 03:30:05 DEBUG : pacer: Reducing sleep to 177.132701ms 2026/09/19 03:30:05 DEBUG : pacer: Reducing sleep to 148.612237ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/19 03:30:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:05 DEBUG : pacer: Reducing sleep to 45.403582ms 2026/09/19 03:30:06 DEBUG : pacer: Reducing sleep to 117.466362ms 2026/09/19 03:30:06 DEBUG : pacer: Reducing sleep to 137.807584ms 2026/09/19 03:30:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:08 DEBUG : pacer: Reducing sleep to 76.92059ms 2026/09/19 03:30:08 DEBUG : pacer: Reducing sleep to 128.149049ms 2026/09/19 03:30:13 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:13 DEBUG : pacer: Reducing sleep to 22.382421ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/19 03:30:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:18 DEBUG : pacer: Reducing sleep to 42.952704ms 2026/09/19 03:30:18 DEBUG : pacer: Reducing sleep to 75.956867ms 2026/09/19 03:30:18 DEBUG : pacer: Reducing sleep to 119.545413ms 2026/09/19 03:30:18 DEBUG : pacer: Reducing sleep to 176.460431ms 2026/09/19 03:30:18 DEBUG : pacer: Reducing sleep to 220.374552ms 2026/09/19 03:30:18 DEBUG : pacer: Reducing sleep to 232.889066ms 2026/09/19 03:30:19 DEBUG : pacer: Reducing sleep to 174.141446ms 2026/09/19 03:30:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:32 DEBUG : pacer: Reducing sleep to 53.637918ms 2026/09/19 03:30:32 DEBUG : pacer: Reducing sleep to 119.306981ms 2026/09/19 03:30:32 DEBUG : pacer: Reducing sleep to 162.024575ms 2026/09/19 03:30:32 DEBUG : pacer: Reducing sleep to 210.247881ms 2026/09/19 03:30:37 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:37 DEBUG : pacer: Reducing sleep to 72.598188ms 2026/09/19 03:30:37 DEBUG : pacer: Reducing sleep to 236.642569ms 2026/09/19 03:30:37 DEBUG : pacer: Reducing sleep to 256.223713ms 2026/09/19 03:30:37 DEBUG : pacer: Reducing sleep to 202.350293ms 2026/09/19 03:30:37 DEBUG : pacer: Reducing sleep to 104.259688ms 2026/09/19 03:30:37 DEBUG : pacer: Reducing sleep to 80.18133ms 2026/09/19 03:30:38 DEBUG : pacer: Reducing sleep to 136.674716ms 2026/09/19 03:30:38 DEBUG : pacer: Reducing sleep to 182.928842ms 2026/09/19 03:30:38 DEBUG : pacer: Reducing sleep to 229.000064ms 2026/09/19 03:30:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:41 DEBUG : pacer: Reducing sleep to 77.315614ms 2026/09/19 03:30:41 DEBUG : pacer: Reducing sleep to 226.091552ms 2026/09/19 03:30:41 DEBUG : pacer: Reducing sleep to 280.282619ms 2026/09/19 03:30:42 DEBUG : pacer: Reducing sleep to 198.839974ms 2026/09/19 03:30:42 DEBUG : pacer: Reducing sleep to 121.360209ms 2026/09/19 03:30:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:46 DEBUG : pacer: Reducing sleep to 30.622891ms 2026/09/19 03:30:46 DEBUG : pacer: Reducing sleep to 49.140317ms 2026/09/19 03:30:46 DEBUG : pacer: Reducing sleep to 108.424639ms 2026/09/19 03:30:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:50 DEBUG : pacer: Reducing sleep to 86.285454ms 2026/09/19 03:30:50 DEBUG : pacer: Reducing sleep to 224.663025ms 2026/09/19 03:30:51 DEBUG : pacer: Reducing sleep to 300.156908ms 2026/09/19 03:30:51 DEBUG : pacer: Reducing sleep to 226.443351ms 2026/09/19 03:30:51 DEBUG : pacer: Reducing sleep to 106.302285ms 2026/09/19 03:30:51 DEBUG : pacer: Reducing sleep to 36.773899ms 2026/09/19 03:30:51 DEBUG : pacer: Reducing sleep to 91.17827ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/19 03:30:51 DEBUG : Creating backend with remote "TestZoho:rclone-test-geriboj6nocu" 2026/09/19 03:30:52 DEBUG : pacer: Reducing sleep to 73.457405ms 2026/09/19 03:30:53 DEBUG : pacer: Reducing sleep to 108.964878ms 2026/09/19 03:30:53 DEBUG : pacer: Reducing sleep to 190.932133ms 2026/09/19 03:30:53 DEBUG : pacer: Reducing sleep to 212.652754ms 2026/09/19 03:30:53 DEBUG : pacer: Reducing sleep to 185.841218ms 2026/09/19 03:30:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:53 DEBUG : pacer: Reducing sleep to 71.549226ms 2026/09/19 03:30:53 DEBUG : pacer: Reducing sleep to 66.327126ms 2026/09/19 03:30:55 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:55 DEBUG : pacer: Reducing sleep to 60.934461ms 2026/09/19 03:30:55 DEBUG : pacer: Reducing sleep to 135.223058ms 2026/09/19 03:30:55 DEBUG : pacer: Reducing sleep to 192.61747ms 2026/09/19 03:30:55 DEBUG : pacer: Reducing sleep to 214.662776ms 2026/09/19 03:30:57 DEBUG : pacer: Reducing sleep to 3.31356ms 2026/09/19 03:30:58 DEBUG : pacer: Reducing sleep to 63.836705ms 2026/09/19 03:30:58 DEBUG : pacer: Reducing sleep to 136.765282ms 2026/09/19 03:30:58 DEBUG : pacer: Reducing sleep to 199.509373ms 2026/09/19 03:30:58 DEBUG : pacer: Reducing sleep to 208.941986ms 2026/09/19 03:30:58 DEBUG : pacer: Reducing sleep to 203.078405ms 2026/09/19 03:30:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:30:58 DEBUG : pacer: Reducing sleep to 82.248607ms 2026/09/19 03:30:58 DEBUG : zoho root 'rclone-test-geriboj6nocu': Purge remote 2026/09/19 03:30:58 DEBUG : pacer: Reducing sleep to 116.840201ms 2026/09/19 03:30:59 DEBUG : pacer: Reducing sleep to 94.386079ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/19 03:30:59 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/19 03:30:59 DEBUG : pacer: Reducing sleep to 54.760827ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/19 03:30:59 DEBUG : pacer: Reducing sleep to 96.032403ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/19 03:30:59 DEBUG : pacer: Reducing sleep to 165.750303ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/19 03:30:59 DEBUG : pacer: Reducing sleep to 245.930322ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/19 03:31:00 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/19 03:31:04 DEBUG : zoho root 'rclone-test-hijihaz2rufo': 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/19 03:31:28 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:28 UTC": parsing time "Sat, 19 Sep 2026 03:31:28 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:28 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/19 03:31:32 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:32 UTC": parsing time "Sat, 19 Sep 2026 03:31:32 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:32 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/19 03:31:37 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:37 UTC": parsing time "Sat, 19 Sep 2026 03:31:37 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:37 UTC" as " " 2026/09/19 03:31:37 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:37 UTC": parsing time "Sat, 19 Sep 2026 03:31:37 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:37 UTC" as " " 2026/09/19 03:31:38 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:38 UTC": parsing time "Sat, 19 Sep 2026 03:31:38 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:38 UTC" as " " 2026/09/19 03:31:38 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:38 UTC": parsing time "Sat, 19 Sep 2026 03:31:38 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:38 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/19 03:31:42 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:42 UTC": parsing time "Sat, 19 Sep 2026 03:31:42 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:42 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/19 03:31:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:46 UTC": parsing time "Sat, 19 Sep 2026 03:31:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:46 UTC" as " " 2026/09/19 03:31:51 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:51 UTC": parsing time "Sat, 19 Sep 2026 03:31:51 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:51 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/19 03:31:51 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:51 UTC": parsing time "Sat, 19 Sep 2026 03:31:51 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:51 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/19 03:31:51 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:51 UTC": parsing time "Sat, 19 Sep 2026 03:31:51 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:51 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/19 03:31:52 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:52 UTC": parsing time "Sat, 19 Sep 2026 03:31:52 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:52 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/19 03:31:52 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:52 UTC": parsing time "Sat, 19 Sep 2026 03:31:52 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:52 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/19 03:31:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:31:55 UTC": parsing time "Sat, 19 Sep 2026 03:31:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:31:55 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/19 03:32:00 DEBUG : pacer: Reducing sleep to 47.152311ms 2026/09/19 03:32:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 19 Sep 2026 03:32:00 UTC": parsing time "Sat, 19 Sep 2026 03:32:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 19 Sep 2026 03:32:00 UTC" as " " 2026/09/19 03:32:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/19 03:32:01 DEBUG : pacer: Reducing sleep to 69.478097ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/19 03:32:01 DEBUG : Creating backend with remote "TestZoho:rclone-test-hijihaz2rufo/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/19 03:32:01 DEBUG : Config file has changed externally - reloading 2026/09/19 03:32:01 DEBUG : pacer: Reducing sleep to 58.08529ms 2026/09/19 03:32:01 DEBUG : pacer: Reducing sleep to 135.89013ms 2026/09/19 03:32:01 DEBUG : pacer: Reducing sleep to 209.518609ms 2026/09/19 03:32:01 DEBUG : pacer: Reducing sleep to 227.937249ms 2026/09/19 03:32:01 DEBUG : pacer: Reducing sleep to 172.679547ms 2026/09/19 03:32:02 DEBUG : pacer: Reducing sleep to 26.66586ms 2026/09/19 03:32:02 DEBUG : pacer: Reducing sleep to 94.640888ms 2026/09/19 03:32:02 DEBUG : pacer: Reducing sleep to 157.885097ms 2026/09/19 03:32:02 DEBUG : pacer: Reducing sleep to 210.635146ms 2026/09/19 03:32:02 DEBUG : pacer: Reducing sleep to 224.236194ms 2026/09/19 03:32:02 DEBUG : pacer: Reducing sleep to 174.612697ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/19 03:32:03 DEBUG : pacer: Reducing sleep to 122.916195ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/19 03:32:03 DEBUG : Creating backend with remote "TestZoho:rclone-test-hijihaz2rufo/not found.txt" 2026/09/19 03:32:03 DEBUG : pacer: Reducing sleep to 76.817815ms 2026/09/19 03:32:03 DEBUG : pacer: Reducing sleep to 72.666229ms 2026/09/19 03:32:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:32:08 DEBUG : zoho root 'rclone-test-hijihaz2rufo/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/09/19 03:32:12 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-hijihaz2rufo" from "TestZoho:rclone-test-hijihaz2rufo" 2026/09/19 03:32:12 DEBUG : Creating backend with remote "TestZoho:" 2026/09/19 03:32:12 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/19 03:32:17 DEBUG : pacer: Reducing sleep to 66.593248ms 2026/09/19 03:32:17 DEBUG : pacer: Reducing sleep to 141.467504ms 2026/09/19 03:32:17 DEBUG : pacer: Reducing sleep to 210.994427ms 2026/09/19 03:32:17 DEBUG : pacer: Reducing sleep to 219.602002ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/19 03:32:22 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/19 03:32:26 DEBUG : pacer: Reducing sleep to 22.442949ms 2026/09/19 03:32:31 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:32:35 DEBUG : pacer: Reducing sleep to 65.651632ms 2026/09/19 03:32:36 DEBUG : pacer: Reducing sleep to 120.261242ms 2026/09/19 03:32:36 DEBUG : pacer: Reducing sleep to 169.490966ms 2026/09/19 03:32:36 DEBUG : pacer: Reducing sleep to 238.41958ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/19 03:32:36 DEBUG : pacer: Reducing sleep to 31.063507ms 2026/09/19 03:32:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:32:40 DEBUG : pacer: Reducing sleep to 73.781514ms 2026/09/19 03:32:40 DEBUG : pacer: Reducing sleep to 114.586892ms 2026/09/19 03:32:40 DEBUG : pacer: Reducing sleep to 170.316568ms 2026/09/19 03:32:40 DEBUG : pacer: Reducing sleep to 205.702555ms === 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/19 03:32:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:32:49 DEBUG : pacer: Reducing sleep to 74.067064ms 2026/09/19 03:32:49 DEBUG : pacer: Reducing sleep to 142.39242ms 2026/09/19 03:32:50 DEBUG : pacer: Reducing sleep to 212.272425ms 2026/09/19 03:32:50 DEBUG : pacer: Reducing sleep to 212.50198ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/19 03:32:50 DEBUG : pacer: Reducing sleep to 197.876295ms === 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/19 03:32:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:32:54 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/19 03:32:54 DEBUG : pacer: Rate limited, increasing sleep to 1.521500049s 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/19 03:32:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:32:59 DEBUG : pacer: Reducing sleep to 44.531911ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/19 03:33:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/19 03:33:00 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=16klqd28bf333f8534ebe8fe7ff599382dce3": write tcp: unexpected EOF) 2026/09/19 03:33:00 DEBUG : pacer: Rate limited, increasing sleep to 1.996861438s 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=16klqd28bf333f8534ebe8fe7ff599382dce3": write tcp: unexpected EOF 2026/09/19 03:33:01 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/19 03:33:01 DEBUG : pacer: Reducing sleep to 120.953274ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n