"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/11 01:41:03 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/11 01:41:03 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/11 01:41:03 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/11 01:41:03 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/11 01:41:03 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/11 01:41:03 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/11 01:41:03 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/11 01:41:03 DEBUG : Creating backend with remote "TestZoho:rclone-test-bekofiv7tuha" 2026/09/11 01:41:03 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/11 01:41:03 DEBUG : zoho root 'rclone-test-bekofiv7tuha': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/11 01:41:03 DEBUG : TestZoho: Token expired 2026/09/11 01:41:03 DEBUG : TestZoho: No updated token found in the config file 2026/09/11 01:41:03 DEBUG : TestZoho: Token refresh successful 2026/09/11 01:41:03 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/11 01:41:03 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/11 01:41:04 DEBUG : pacer: Reducing sleep to 26.056733ms 2026/09/11 01:41:04 DEBUG : pacer: Reducing sleep to 60.920667ms 2026/09/11 01:41:04 DEBUG : pacer: Reducing sleep to 32.396852ms 2026/09/11 01:41:05 DEBUG : pacer: Reducing sleep to 155.814771ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/11 01:41:05 DEBUG : pacer: Reducing sleep to 266.988104ms 2026/09/11 01:41:05 DEBUG : pacer: Reducing sleep to 262.28973ms === RUN TestIntegration/FsMkdir 2026/09/11 01:41:05 DEBUG : pacer: Reducing sleep to 95.853187ms 2026/09/11 01:41:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:41:05 DEBUG : pacer: Reducing sleep to 120.182378ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/11 01:41:05 INFO : dir/subdir: Making directory 2026/09/11 01:41:05 DEBUG : pacer: Reducing sleep to 243.758333ms 2026/09/11 01:41:06 DEBUG : pacer: Reducing sleep to 205.874733ms 2026/09/11 01:41:06 DEBUG : pacer: Reducing sleep to 212.304463ms 2026/09/11 01:41:06 DEBUG : pacer: Reducing sleep to 122.154989ms 2026/09/11 01:41:06 DEBUG : pacer: Reducing sleep to 114.884818ms 2026/09/11 01:41:06 DEBUG : pacer: Reducing sleep to 160.050106ms 2026/09/11 01:41:06 DEBUG : pacer: Reducing sleep to 218.716347ms 2026/09/11 01:41:06 INFO : dir/subdir: Removing directory 2026/09/11 01:41:07 DEBUG : pacer: Reducing sleep to 216.184205ms 2026/09/11 01:41:07 DEBUG : pacer: Reducing sleep to 157.621066ms 2026/09/11 01:41:07 DEBUG : pacer: Reducing sleep to 106.511982ms 2026/09/11 01:41:07 DEBUG : pacer: Reducing sleep to 130.031525ms 2026/09/11 01:41:07 INFO : dir: Removing directory 2026/09/11 01:41:07 DEBUG : pacer: Reducing sleep to 173.02511ms 2026/09/11 01:41:07 DEBUG : pacer: Reducing sleep to 210.876137ms 2026/09/11 01:41:08 DEBUG : pacer: Reducing sleep to 190.214439ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/11 01:41:08 DEBUG : pacer: Reducing sleep to 168.533837ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/11 01:41:12 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/11 01:41:17 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/11 01:41:26 DEBUG : pacer: Reducing sleep to 88.011937ms 2026/09/11 01:41:26 DEBUG : pacer: Reducing sleep to 212.603561ms 2026/09/11 01:41:27 DEBUG : pacer: Reducing sleep to 19.486707ms 2026/09/11 01:41:27 DEBUG : pacer: Reducing sleep to 137.619964ms 2026/09/11 01:41:31 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:41:31 DEBUG : pacer: Reducing sleep to 110.560877ms 2026/09/11 01:41:31 DEBUG : pacer: Reducing sleep to 185.44208ms 2026/09/11 01:41:31 DEBUG : pacer: Reducing sleep to 248.054465ms 2026/09/11 01:41:31 DEBUG : pacer: Reducing sleep to 223.463298ms 2026/09/11 01:41:35 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/11 01:41:40 DEBUG : pacer: Reducing sleep to 97.046729ms 2026/09/11 01:41:40 DEBUG : pacer: Reducing sleep to 219.10077ms 2026/09/11 01:41:40 DEBUG : pacer: Reducing sleep to 198.746554ms 2026/09/11 01:41:40 DEBUG : pacer: Reducing sleep to 231.575669ms 2026/09/11 01:41:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:41:45 DEBUG : pacer: Reducing sleep to 106.648549ms 2026/09/11 01:41:45 DEBUG : pacer: Reducing sleep to 154.350931ms 2026/09/11 01:41:45 DEBUG : pacer: Reducing sleep to 244.011131ms 2026/09/11 01:41:45 DEBUG : pacer: Reducing sleep to 242.10899ms 2026/09/11 01:41:49 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/11 01:41:54 DEBUG : pacer: Reducing sleep to 101.219799ms 2026/09/11 01:41:54 DEBUG : pacer: Reducing sleep to 222.957153ms 2026/09/11 01:41:54 DEBUG : pacer: Reducing sleep to 96.702292ms 2026/09/11 01:41:54 DEBUG : pacer: Reducing sleep to 215.471381ms 2026/09/11 01:41:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:41:59 DEBUG : pacer: Reducing sleep to 112.258153ms 2026/09/11 01:41:59 DEBUG : pacer: Reducing sleep to 110.962961ms 2026/09/11 01:41:59 DEBUG : pacer: Reducing sleep to 170.307816ms 2026/09/11 01:41:59 DEBUG : pacer: Reducing sleep to 220.502302ms 2026/09/11 01:42:03 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/11 01:42:03 DEBUG : zoho root 'rclone-test-bekofiv7tuha': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/11 01:42:08 DEBUG : pacer: Reducing sleep to 89.895897ms 2026/09/11 01:42:08 DEBUG : pacer: Reducing sleep to 214.335723ms 2026/09/11 01:42:08 DEBUG : pacer: Reducing sleep to 199.307247ms 2026/09/11 01:42:08 DEBUG : pacer: Reducing sleep to 235.577089ms 2026/09/11 01:42:08 DEBUG : pacer: Reducing sleep to 201.569684ms 2026/09/11 01:42:09 DEBUG : pacer: Reducing sleep to 132.433845ms 2026/09/11 01:42:09 DEBUG : pacer: Reducing sleep to 84.506964ms 2026/09/11 01:42:09 DEBUG : pacer: Reducing sleep to 134.376469ms 2026/09/11 01:42:09 DEBUG : pacer: Reducing sleep to 201.193162ms 2026/09/11 01:42:09 DEBUG : pacer: Reducing sleep to 248.717517ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/11 01:42:09 DEBUG : pacer: Reducing sleep to 209.658734ms 2026/09/11 01:42:10 DEBUG : pacer: Reducing sleep to 93.67063ms 2026/09/11 01:42:10 DEBUG : pacer: Reducing sleep to 83.096916ms 2026/09/11 01:42:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:42:10 DEBUG : pacer: Reducing sleep to 115.76139ms 2026/09/11 01:42:10 DEBUG : pacer: Reducing sleep to 234.430079ms 2026/09/11 01:42:10 DEBUG : pacer: Reducing sleep to 283.870552ms 2026/09/11 01:42:10 DEBUG : pacer: Reducing sleep to 204.299637ms 2026/09/11 01:42:11 DEBUG : pacer: Reducing sleep to 96.806165ms 2026/09/11 01:42:11 DEBUG : pacer: Reducing sleep to 51.429351ms 2026/09/11 01:42:11 DEBUG : pacer: Reducing sleep to 130.378345ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/11 01:42:16 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:42:16 DEBUG : pacer: Reducing sleep to 83.706539ms 2026/09/11 01:42:16 DEBUG : pacer: Reducing sleep to 203.050273ms 2026/09/11 01:42:16 DEBUG : pacer: Reducing sleep to 185.998727ms 2026/09/11 01:42:16 DEBUG : pacer: Reducing sleep to 236.76841ms 2026/09/11 01:42:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:42:20 DEBUG : pacer: Reducing sleep to 106.630679ms 2026/09/11 01:42:21 DEBUG : pacer: Reducing sleep to 84.879287ms 2026/09/11 01:42:21 DEBUG : pacer: Reducing sleep to 155.937632ms 2026/09/11 01:42:21 DEBUG : pacer: Reducing sleep to 229.606377ms 2026/09/11 01:42:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/11 01:42:30 DEBUG : pacer: Reducing sleep to 103.790444ms 2026/09/11 01:42:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:42:30 DEBUG : pacer: Reducing sleep to 117.299234ms 2026/09/11 01:42:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:42:34 DEBUG : pacer: Reducing sleep to 103.920058ms 2026/09/11 01:42:34 DEBUG : pacer: Reducing sleep to 203.767267ms 2026/09/11 01:42:34 DEBUG : pacer: Reducing sleep to 276.192082ms 2026/09/11 01:42:35 DEBUG : pacer: Reducing sleep to 231.520992ms 2026/09/11 01:42:39 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/11 01:42:43 DEBUG : pacer: Reducing sleep to 94.75375ms 2026/09/11 01:42:43 DEBUG : pacer: Reducing sleep to 221.528749ms 2026/09/11 01:42:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:42:45 DEBUG : pacer: Reducing sleep to 16.056813ms 2026/09/11 01:42:48 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:42:48 DEBUG : pacer: Reducing sleep to 108.089493ms 2026/09/11 01:42:48 DEBUG : pacer: Reducing sleep to 217.256259ms 2026/09/11 01:42:48 DEBUG : pacer: Reducing sleep to 284.015848ms 2026/09/11 01:42:48 DEBUG : pacer: Reducing sleep to 223.006007ms 2026/09/11 01:42:53 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/11 01:42:57 DEBUG : pacer: Reducing sleep to 96.995152ms 2026/09/11 01:42:57 DEBUG : pacer: Reducing sleep to 218.541194ms 2026/09/11 01:42:58 DEBUG : pacer: Reducing sleep to 100.456177ms 2026/09/11 01:42:58 DEBUG : pacer: Reducing sleep to 218.098829ms 2026/09/11 01:43:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:43:02 DEBUG : pacer: Reducing sleep to 108.202617ms 2026/09/11 01:43:02 DEBUG : pacer: Reducing sleep to 107.224584ms 2026/09/11 01:43:02 DEBUG : pacer: Reducing sleep to 179.871921ms 2026/09/11 01:43:02 DEBUG : pacer: Reducing sleep to 219.864023ms 2026/09/11 01:43:06 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/11 01:43:06 DEBUG : zoho root 'rclone-test-bekofiv7tuha': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/11 01:43:06 DEBUG : pacer: Reducing sleep to 122.601696ms 2026/09/11 01:43:07 DEBUG : pacer: Reducing sleep to 77.291671ms 2026/09/11 01:43:07 DEBUG : pacer: Reducing sleep to 159.339038ms 2026/09/11 01:43:07 DEBUG : pacer: Reducing sleep to 162.309513ms 2026/09/11 01:43:07 DEBUG : pacer: Reducing sleep to 242.910679ms 2026/09/11 01:43:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:43:08 DEBUG : pacer: Reducing sleep to 117.705581ms 2026/09/11 01:43:08 DEBUG : pacer: Reducing sleep to 79.853358ms 2026/09/11 01:43:09 DEBUG : pacer: Reducing sleep to 136.027549ms 2026/09/11 01:43:09 DEBUG : pacer: Reducing sleep to 216.45126ms 2026/09/11 01:43:09 DEBUG : pacer: Reducing sleep to 256.028289ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/11 01:43:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:43:10 DEBUG : pacer: Reducing sleep to 65.978043ms 2026/09/11 01:43:10 DEBUG : pacer: Reducing sleep to 82.145398ms 2026/09/11 01:43:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:43:10 DEBUG : pacer: Reducing sleep to 115.302023ms 2026/09/11 01:43:10 DEBUG : pacer: Reducing sleep to 235.721871ms 2026/09/11 01:43:10 DEBUG : pacer: Reducing sleep to 285.178465ms 2026/09/11 01:43:10 DEBUG : pacer: Reducing sleep to 207.177729ms 2026/09/11 01:43:11 DEBUG : pacer: Reducing sleep to 98.208985ms 2026/09/11 01:43:11 DEBUG : pacer: Reducing sleep to 40.513463ms 2026/09/11 01:43:15 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/11 01:43:19 DEBUG : pacer: Reducing sleep to 89.942074ms 2026/09/11 01:43:19 DEBUG : pacer: Reducing sleep to 210.528586ms 2026/09/11 01:43:20 DEBUG : pacer: Reducing sleep to 205.622007ms 2026/09/11 01:43:20 DEBUG : pacer: Reducing sleep to 228.461701ms 2026/09/11 01:43:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:43:24 DEBUG : pacer: Reducing sleep to 110.25893ms 2026/09/11 01:43:24 DEBUG : pacer: Reducing sleep to 86.431151ms 2026/09/11 01:43:24 DEBUG : pacer: Reducing sleep to 159.237018ms 2026/09/11 01:43:24 DEBUG : pacer: Reducing sleep to 230.910416ms 2026/09/11 01:43:29 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/11 01:43:33 DEBUG : pacer: Reducing sleep to 93.817885ms 2026/09/11 01:43:33 DEBUG : pacer: Reducing sleep to 216.985852ms 2026/09/11 01:43:34 DEBUG : pacer: Reducing sleep to 101.336896ms 2026/09/11 01:43:34 DEBUG : pacer: Reducing sleep to 214.816929ms 2026/09/11 01:43:38 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:43:38 DEBUG : pacer: Reducing sleep to 111.707226ms 2026/09/11 01:43:38 DEBUG : pacer: Reducing sleep to 107.994752ms 2026/09/11 01:43:38 DEBUG : pacer: Reducing sleep to 177.645701ms 2026/09/11 01:43:38 DEBUG : pacer: Reducing sleep to 224.679462ms 2026/09/11 01:43:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/11 01:43:47 DEBUG : pacer: Reducing sleep to 91.104461ms 2026/09/11 01:43:47 DEBUG : pacer: Reducing sleep to 215.520395ms 2026/09/11 01:43:47 DEBUG : pacer: Reducing sleep to 215.125165ms 2026/09/11 01:43:47 DEBUG : pacer: Reducing sleep to 233.35263ms 2026/09/11 01:43:52 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:43:52 DEBUG : pacer: Reducing sleep to 107.968484ms 2026/09/11 01:43:52 DEBUG : pacer: Reducing sleep to 86.703768ms 2026/09/11 01:43:52 DEBUG : pacer: Reducing sleep to 155.267205ms 2026/09/11 01:43:52 DEBUG : pacer: Reducing sleep to 227.920244ms 2026/09/11 01:43:56 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/11 01:44:01 DEBUG : pacer: Reducing sleep to 92.861268ms 2026/09/11 01:44:01 DEBUG : pacer: Reducing sleep to 214.201634ms 2026/09/11 01:44:01 DEBUG : pacer: Reducing sleep to 105.577235ms 2026/09/11 01:44:01 DEBUG : pacer: Reducing sleep to 226.101669ms 2026/09/11 01:44:06 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:44:06 DEBUG : pacer: Reducing sleep to 112.931648ms 2026/09/11 01:44:06 DEBUG : pacer: Reducing sleep to 132.465882ms 2026/09/11 01:44:06 DEBUG : pacer: Reducing sleep to 207.247732ms 2026/09/11 01:44:06 DEBUG : pacer: Reducing sleep to 228.885347ms 2026/09/11 01:44:06 DEBUG : pacer: Reducing sleep to 203.482697ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/11 01:44:07 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:44:07 DEBUG : pacer: Reducing sleep to 100.538686ms 2026/09/11 01:44:07 DEBUG : zoho root 'rclone-test-bekofiv7tuha': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/11 01:44:07 DEBUG : pacer: Reducing sleep to 126.699574ms 2026/09/11 01:44:07 DEBUG : pacer: Reducing sleep to 101.180974ms 2026/09/11 01:44:07 DEBUG : pacer: Reducing sleep to 218.587892ms 2026/09/11 01:44:07 DEBUG : pacer: Reducing sleep to 266.486056ms 2026/09/11 01:44:08 DEBUG : pacer: Reducing sleep to 227.70281ms 2026/09/11 01:44:08 DEBUG : pacer: Reducing sleep to 112.541716ms 2026/09/11 01:44:08 DEBUG : pacer: Reducing sleep to 68.909764ms 2026/09/11 01:44:08 DEBUG : pacer: Reducing sleep to 106.539918ms 2026/09/11 01:44:09 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/11 01:44:09 DEBUG : pacer: Reducing sleep to 99.827042ms 2026/09/11 01:44:09 DEBUG : pacer: Reducing sleep to 221.933815ms 2026/09/11 01:44:10 DEBUG : pacer: Reducing sleep to 78.728787ms 2026/09/11 01:44:10 DEBUG : pacer: Reducing sleep to 188.889142ms 2026/09/11 01:44:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:44:14 DEBUG : pacer: Reducing sleep to 117.600763ms 2026/09/11 01:44:14 DEBUG : pacer: Reducing sleep to 135.259288ms 2026/09/11 01:44:14 DEBUG : pacer: Reducing sleep to 200.008751ms 2026/09/11 01:44:14 DEBUG : pacer: Reducing sleep to 210.385966ms 2026/09/11 01:44:18 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/11 01:44:23 DEBUG : pacer: Reducing sleep to 90.734242ms 2026/09/11 01:44:23 DEBUG : pacer: Reducing sleep to 214.489519ms 2026/09/11 01:44:23 DEBUG : pacer: Reducing sleep to 210.575585ms 2026/09/11 01:44:23 DEBUG : pacer: Reducing sleep to 231.80444ms 2026/09/11 01:44:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:44:28 DEBUG : pacer: Reducing sleep to 118.397439ms 2026/09/11 01:44:28 DEBUG : pacer: Reducing sleep to 84.959105ms 2026/09/11 01:44:28 DEBUG : pacer: Reducing sleep to 165.200093ms 2026/09/11 01:44:28 DEBUG : pacer: Reducing sleep to 240.217347ms 2026/09/11 01:44:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/11 01:44:51 DEBUG : pacer: Reducing sleep to 99.660077ms 2026/09/11 01:44:51 DEBUG : pacer: Reducing sleep to 218.458974ms 2026/09/11 01:44:51 DEBUG : pacer: Reducing sleep to 264.486707ms 2026/09/11 01:44:51 DEBUG : pacer: Reducing sleep to 228.142208ms 2026/09/11 01:44:51 DEBUG : pacer: Reducing sleep to 110.003495ms 2026/09/11 01:44:52 DEBUG : pacer: Reducing sleep to 68.1272ms 2026/09/11 01:44:52 DEBUG : pacer: Reducing sleep to 97.074781ms 2026/09/11 01:44:52 DEBUG : pacer: Reducing sleep to 259.261608ms 2026/09/11 01:44:52 DEBUG : pacer: Reducing sleep to 256.96982ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/11 01:44:55 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:45:00 UTC": parsing time "Fri, 11 Sep 2026 01:45:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:45:00 UTC" as " " 2026/09/11 01:45:00 DEBUG : pacer: Reducing sleep to 90.391306ms === 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/11 01:45:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:05 DEBUG : pacer: Reducing sleep to 57.953672ms 2026/09/11 01:45:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:09 DEBUG : zoho root 'rclone-test-bekofiv7tuha': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/11 01:45:09 DEBUG : pacer: Reducing sleep to 125.526851ms 2026/09/11 01:45:09 DEBUG : pacer: Reducing sleep to 83.919664ms 2026/09/11 01:45:10 DEBUG : pacer: Reducing sleep to 194.564223ms 2026/09/11 01:45:10 DEBUG : pacer: Reducing sleep to 280.654038ms 2026/09/11 01:45:10 DEBUG : pacer: Reducing sleep to 182.104377ms 2026/09/11 01:45:10 DEBUG : pacer: Reducing sleep to 136.344619ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/11 01:45:10 DEBUG : pacer: Reducing sleep to 123.666588ms 2026/09/11 01:45:10 DEBUG : pacer: Reducing sleep to 141.728325ms 2026/09/11 01:45:11 DEBUG : pacer: Reducing sleep to 192.223555ms 2026/09/11 01:45:11 DEBUG : pacer: Reducing sleep to 214.180617ms 2026/09/11 01:45:11 DEBUG : pacer: Reducing sleep to 191.025594ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/11 01:45:11 DEBUG : pacer: Reducing sleep to 139.621847ms 2026/09/11 01:45:11 DEBUG : pacer: Reducing sleep to 117.135927ms 2026/09/11 01:45:11 DEBUG : pacer: Reducing sleep to 133.301983ms 2026/09/11 01:45:12 DEBUG : pacer: Reducing sleep to 189.084066ms 2026/09/11 01:45:12 DEBUG : pacer: Reducing sleep to 205.339401ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/11 01:45:12 DEBUG : pacer: Reducing sleep to 198.767953ms 2026/09/11 01:45:12 DEBUG : pacer: Reducing sleep to 145.345976ms 2026/09/11 01:45:12 DEBUG : pacer: Reducing sleep to 123.002094ms 2026/09/11 01:45:12 DEBUG : pacer: Reducing sleep to 129.637234ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/11 01:45:12 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/11 01:45:17 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/11 01:45:22 DEBUG : pacer: Reducing sleep to 80.573586ms 2026/09/11 01:45:22 DEBUG : pacer: Reducing sleep to 196.591513ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/11 01:45:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:26 DEBUG : pacer: Reducing sleep to 115.634932ms 2026/09/11 01:45:26 DEBUG : pacer: Reducing sleep to 141.361172ms 2026/09/11 01:45:27 DEBUG : pacer: Reducing sleep to 196.019895ms 2026/09/11 01:45:27 DEBUG : pacer: Reducing sleep to 216.325588ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/11 01:45:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/11 01:45:35 DEBUG : pacer: Reducing sleep to 112.599571ms 2026/09/11 01:45:36 DEBUG : pacer: Reducing sleep to 226.254426ms 2026/09/11 01:45:36 DEBUG : pacer: Reducing sleep to 270.104108ms 2026/09/11 01:45:36 DEBUG : pacer: Reducing sleep to 222.315368ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/11 01:45:40 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/11 01:45:40 DEBUG : pacer: Reducing sleep to 118.905971ms 2026/09/11 01:45:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:45 DEBUG : pacer: Reducing sleep to 27.181387ms 2026/09/11 01:45:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:45 DEBUG : pacer: Reducing sleep to 118.861275ms 2026/09/11 01:45:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:50 DEBUG : pacer: Reducing sleep to 110.376964ms 2026/09/11 01:45:50 DEBUG : pacer: Reducing sleep to 240.285069ms 2026/09/11 01:45:50 DEBUG : pacer: Reducing sleep to 393.971433ms 2026/09/11 01:45:50 DEBUG : pacer: Reducing sleep to 438.317914ms 2026/09/11 01:45:50 DEBUG : pacer: Reducing sleep to 221.853669ms 2026/09/11 01:45:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:51 DEBUG : pacer: Reducing sleep to 110.737745ms 2026/09/11 01:45:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:45:54 DEBUG : pacer: Reducing sleep to 114.712442ms 2026/09/11 01:45:54 DEBUG : pacer: Reducing sleep to 221.902469ms 2026/09/11 01:45:54 DEBUG : pacer: Reducing sleep to 264.089421ms 2026/09/11 01:45:55 DEBUG : pacer: Reducing sleep to 216.708955ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/11 01:45:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:03 DEBUG : pacer: Reducing sleep to 93.649038ms 2026/09/11 01:46:04 DEBUG : pacer: Reducing sleep to 123.947611ms 2026/09/11 01:46:04 DEBUG : pacer: Reducing sleep to 238.90194ms 2026/09/11 01:46:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:08 DEBUG : pacer: Reducing sleep to 120.582285ms 2026/09/11 01:46:08 DEBUG : pacer: Reducing sleep to 277.96528ms 2026/09/11 01:46:08 DEBUG : pacer: Reducing sleep to 268.95558ms 2026/09/11 01:46:08 DEBUG : pacer: Reducing sleep to 151.042629ms 2026/09/11 01:46:09 DEBUG : pacer: Reducing sleep to 54.046765ms 2026/09/11 01:46:09 DEBUG : Creating backend with remote "TestZoho:rclone-test-bekofiv7tuha/dirToPurgeFromRoot" 2026/09/11 01:46:09 DEBUG : Config file has changed externally - reloading 2026/09/11 01:46:09 DEBUG : zoho root 'rclone-test-bekofiv7tuha/dirToPurgeFromRoot': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/11 01:46:09 DEBUG : pacer: Reducing sleep to 87.020651ms 2026/09/11 01:46:09 DEBUG : pacer: Reducing sleep to 183.101332ms 2026/09/11 01:46:09 DEBUG : pacer: Reducing sleep to 244.225452ms 2026/09/11 01:46:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:10 DEBUG : pacer: Reducing sleep to 100.559476ms 2026/09/11 01:46:10 DEBUG : pacer: Reducing sleep to 218.528979ms 2026/09/11 01:46:10 DEBUG : pacer: Reducing sleep to 284.498ms 2026/09/11 01:46:10 DEBUG : pacer: Reducing sleep to 231.456803ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/11 01:46:10 DEBUG : Creating backend with remote "TestZoho:rclone-test-bekofiv7tuha/hello? sausage/êé" 2026/09/11 01:46:10 DEBUG : pacer: Reducing sleep to 118.114145ms 2026/09/11 01:46:10 DEBUG : pacer: Reducing sleep to 237.603056ms 2026/09/11 01:46:10 DEBUG : pacer: Reducing sleep to 283.713027ms 2026/09/11 01:46:11 DEBUG : pacer: Reducing sleep to 208.510434ms 2026/09/11 01:46:11 DEBUG : pacer: Reducing sleep to 73.486262ms 2026/09/11 01:46:11 DEBUG : pacer: Reducing sleep to 54.564434ms 2026/09/11 01:46:11 DEBUG : pacer: Reducing sleep to 119.983967ms 2026/09/11 01:46:11 DEBUG : pacer: Reducing sleep to 242.981755ms 2026/09/11 01:46:11 DEBUG : pacer: Reducing sleep to 266.14248ms 2026/09/11 01:46:12 DEBUG : pacer: Reducing sleep to 190.578335ms 2026/09/11 01:46:12 DEBUG : pacer: Reducing sleep to 86.465974ms 2026/09/11 01:46:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:12 DEBUG : pacer: Reducing sleep to 116.642064ms 2026/09/11 01:46:12 DEBUG : pacer: Reducing sleep to 237.080007ms 2026/09/11 01:46:12 DEBUG : pacer: Reducing sleep to 270.160545ms 2026/09/11 01:46:13 DEBUG : pacer: Reducing sleep to 213.923496ms 2026/09/11 01:46:13 DEBUG : pacer: Reducing sleep to 98.937802ms 2026/09/11 01:46:13 DEBUG : pacer: Reducing sleep to 61.269395ms 2026/09/11 01:46:13 DEBUG : pacer: Reducing sleep to 118.770145ms 2026/09/11 01:46:13 DEBUG : pacer: Reducing sleep to 232.451009ms 2026/09/11 01:46:14 DEBUG : pacer: Reducing sleep to 266.912033ms 2026/09/11 01:46:14 DEBUG : pacer: Reducing sleep to 204.262926ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/11 01:46:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:14 DEBUG : pacer: Reducing sleep to 95.050685ms 2026/09/11 01:46:14 DEBUG : pacer: Reducing sleep to 216.796618ms 2026/09/11 01:46:14 DEBUG : pacer: Reducing sleep to 285.863708ms 2026/09/11 01:46:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:17 DEBUG : pacer: Reducing sleep to 120.523564ms 2026/09/11 01:46:17 DEBUG : pacer: Reducing sleep to 38.706973ms 2026/09/11 01:46:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:22 DEBUG : pacer: Reducing sleep to 62.604447ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/11 01:46:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:26 DEBUG : pacer: Reducing sleep to 93.488565ms 2026/09/11 01:46:26 DEBUG : pacer: Reducing sleep to 181.708481ms 2026/09/11 01:46:27 DEBUG : pacer: Reducing sleep to 275.976829ms 2026/09/11 01:46:27 DEBUG : pacer: Reducing sleep to 268.034184ms 2026/09/11 01:46:27 DEBUG : pacer: Reducing sleep to 152.019568ms 2026/09/11 01:46:27 DEBUG : pacer: Reducing sleep to 47.698022ms 2026/09/11 01:46:27 DEBUG : pacer: Reducing sleep to 65.742017ms 2026/09/11 01:46:31 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:41 DEBUG : pacer: Reducing sleep to 81.806115ms 2026/09/11 01:46:41 DEBUG : pacer: Reducing sleep to 184.301046ms 2026/09/11 01:46:41 DEBUG : pacer: Reducing sleep to 260.260451ms 2026/09/11 01:46:41 DEBUG : pacer: Reducing sleep to 260.305168ms 2026/09/11 01:46:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:45 DEBUG : pacer: Reducing sleep to 121.324598ms 2026/09/11 01:46:45 DEBUG : pacer: Reducing sleep to 275.117294ms 2026/09/11 01:46:45 DEBUG : pacer: Reducing sleep to 229.84001ms 2026/09/11 01:46:46 DEBUG : pacer: Reducing sleep to 88.696575ms 2026/09/11 01:46:46 DEBUG : pacer: Reducing sleep to 73.925416ms 2026/09/11 01:46:46 DEBUG : pacer: Reducing sleep to 134.901149ms 2026/09/11 01:46:46 DEBUG : pacer: Reducing sleep to 226.8465ms 2026/09/11 01:46:46 DEBUG : pacer: Reducing sleep to 234.42257ms 2026/09/11 01:46:46 DEBUG : pacer: Reducing sleep to 186.998412ms 2026/09/11 01:46:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:50 DEBUG : pacer: Reducing sleep to 124.990099ms 2026/09/11 01:46:50 DEBUG : pacer: Reducing sleep to 278.735185ms 2026/09/11 01:46:50 DEBUG : pacer: Reducing sleep to 331.102682ms 2026/09/11 01:46:50 DEBUG : pacer: Reducing sleep to 213.961259ms 2026/09/11 01:46:50 DEBUG : pacer: Reducing sleep to 57.006538ms 2026/09/11 01:46:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:54 DEBUG : pacer: Reducing sleep to 110.239617ms 2026/09/11 01:46:54 DEBUG : pacer: Reducing sleep to 207.47159ms 2026/09/11 01:46:55 DEBUG : pacer: Reducing sleep to 265.319777ms 2026/09/11 01:46:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:46:59 DEBUG : pacer: Reducing sleep to 125.580422ms 2026/09/11 01:46:59 DEBUG : pacer: Reducing sleep to 282.745087ms 2026/09/11 01:46:59 DEBUG : pacer: Reducing sleep to 247.496381ms 2026/09/11 01:46:59 DEBUG : pacer: Reducing sleep to 128.943965ms 2026/09/11 01:47:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:47:00 DEBUG : pacer: Reducing sleep to 89.868759ms 2026/09/11 01:47:00 DEBUG : pacer: Reducing sleep to 117.787721ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/11 01:47:00 DEBUG : Creating backend with remote "TestZoho:rclone-test-yuziwis3rova" 2026/09/11 01:47:00 DEBUG : pacer: Reducing sleep to 36.177175ms 2026/09/11 01:47:01 DEBUG : pacer: Reducing sleep to 82.612442ms 2026/09/11 01:47:01 DEBUG : pacer: Reducing sleep to 94.430621ms 2026/09/11 01:47:01 DEBUG : pacer: Reducing sleep to 215.822505ms 2026/09/11 01:47:01 DEBUG : pacer: Reducing sleep to 262.914461ms 2026/09/11 01:47:01 DEBUG : pacer: Reducing sleep to 233.967274ms 2026/09/11 01:47:01 DEBUG : pacer: Reducing sleep to 111.068942ms 2026/09/11 01:47:02 DEBUG : pacer: Reducing sleep to 23.209874ms 2026/09/11 01:47:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:47:02 DEBUG : pacer: Reducing sleep to 14.815025ms 2026/09/11 01:47:02 DEBUG : pacer: Reducing sleep to 129.095513ms 2026/09/11 01:47:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:47:04 DEBUG : pacer: Reducing sleep to 113.999053ms 2026/09/11 01:47:04 DEBUG : pacer: Reducing sleep to 193.344684ms 2026/09/11 01:47:04 DEBUG : pacer: Reducing sleep to 261.113514ms 2026/09/11 01:47:04 DEBUG : pacer: Reducing sleep to 215.632187ms 2026/09/11 01:47:06 DEBUG : pacer: Reducing sleep to 40.330308ms 2026/09/11 01:47:06 DEBUG : pacer: Reducing sleep to 159.41737ms 2026/09/11 01:47:06 DEBUG : pacer: Reducing sleep to 258.80778ms 2026/09/11 01:47:06 DEBUG : pacer: Reducing sleep to 277.072502ms 2026/09/11 01:47:06 DEBUG : pacer: Reducing sleep to 190.27059ms 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 48.486477ms 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 124.153499ms 2026/09/11 01:47:07 DEBUG : zoho root 'rclone-test-yuziwis3rova': Purge remote 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 132.428969ms 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 161.377596ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 117.33291ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 236.023575ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 281.879687ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/11 01:47:07 DEBUG : pacer: Reducing sleep to 210.506539ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/11 01:47:08 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/11 01:47:13 DEBUG : zoho root 'rclone-test-bekofiv7tuha': 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/11 01:47:36 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:47:36 UTC": parsing time "Fri, 11 Sep 2026 01:47:36 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:47:36 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/11 01:47:41 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:47:41 UTC": parsing time "Fri, 11 Sep 2026 01:47:41 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:47:41 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/11 01:47:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:47:46 UTC": parsing time "Fri, 11 Sep 2026 01:47:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:47:46 UTC" as " " 2026/09/11 01:47:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:47:46 UTC": parsing time "Fri, 11 Sep 2026 01:47:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:47:46 UTC" as " " 2026/09/11 01:47:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:47:46 UTC": parsing time "Fri, 11 Sep 2026 01:47:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:47:46 UTC" as " " 2026/09/11 01:47:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:47:46 UTC": parsing time "Fri, 11 Sep 2026 01:47:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:47:46 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/11 01:47:50 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:47:50 UTC": parsing time "Fri, 11 Sep 2026 01:47:50 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:47:50 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/11 01:47:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:47:55 UTC": parsing time "Fri, 11 Sep 2026 01:47:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:47:55 UTC" as " " 2026/09/11 01:48:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:48:00 UTC": parsing time "Fri, 11 Sep 2026 01:48:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:48:00 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/11 01:48:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:48:00 UTC": parsing time "Fri, 11 Sep 2026 01:48:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:48:00 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/11 01:48:00 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:48:00 UTC": parsing time "Fri, 11 Sep 2026 01:48:00 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:48:00 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/11 01:48:01 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:48:01 UTC": parsing time "Fri, 11 Sep 2026 01:48:01 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:48:01 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/11 01:48:01 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:48:01 UTC": parsing time "Fri, 11 Sep 2026 01:48:01 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:48:01 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/11 01:48:04 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:48:04 UTC": parsing time "Fri, 11 Sep 2026 01:48:04 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:48:04 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/11 01:48:09 DEBUG : pacer: Reducing sleep to 112.929697ms 2026/09/11 01:48:09 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Fri, 11 Sep 2026 01:48:09 UTC": parsing time "Fri, 11 Sep 2026 01:48:09 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 11 Sep 2026 01:48:09 UTC" as " " 2026/09/11 01:48:09 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/11 01:48:09 DEBUG : pacer: Reducing sleep to 115.990206ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/11 01:48:09 DEBUG : Creating backend with remote "TestZoho:rclone-test-bekofiv7tuha/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/11 01:48:10 DEBUG : pacer: Reducing sleep to 116.507092ms 2026/09/11 01:48:10 DEBUG : pacer: Reducing sleep to 175.168726ms 2026/09/11 01:48:10 DEBUG : pacer: Reducing sleep to 286.727073ms 2026/09/11 01:48:10 DEBUG : pacer: Reducing sleep to 271.449059ms 2026/09/11 01:48:10 DEBUG : pacer: Reducing sleep to 154.380929ms 2026/09/11 01:48:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:48:11 DEBUG : pacer: Reducing sleep to 116.385783ms 2026/09/11 01:48:11 DEBUG : pacer: Reducing sleep to 231.281592ms 2026/09/11 01:48:11 DEBUG : pacer: Reducing sleep to 283.905434ms 2026/09/11 01:48:11 DEBUG : pacer: Reducing sleep to 211.822819ms 2026/09/11 01:48:11 DEBUG : pacer: Reducing sleep to 99.856903ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/11 01:48:11 DEBUG : pacer: Reducing sleep to 37.489843ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/11 01:48:11 DEBUG : Creating backend with remote "TestZoho:rclone-test-bekofiv7tuha/not found.txt" 2026/09/11 01:48:12 DEBUG : pacer: Reducing sleep to 120.990858ms 2026/09/11 01:48:12 DEBUG : pacer: Reducing sleep to 164.014815ms 2026/09/11 01:48:16 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:48:16 DEBUG : zoho root 'rclone-test-bekofiv7tuha/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/09/11 01:48:21 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-bekofiv7tuha" from "TestZoho:rclone-test-bekofiv7tuha" 2026/09/11 01:48:21 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/11 01:48:26 DEBUG : pacer: Reducing sleep to 119.951055ms 2026/09/11 01:48:26 DEBUG : pacer: Reducing sleep to 221.802882ms 2026/09/11 01:48:26 DEBUG : pacer: Reducing sleep to 285.287104ms 2026/09/11 01:48:26 DEBUG : pacer: Reducing sleep to 218.85609ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/11 01:48:30 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/11 01:48:44 DEBUG : pacer: Reducing sleep to 120.383822ms 2026/09/11 01:48:44 DEBUG : pacer: Reducing sleep to 237.32852ms 2026/09/11 01:48:44 DEBUG : pacer: Reducing sleep to 287.843952ms 2026/09/11 01:48:45 DEBUG : pacer: Reducing sleep to 214.5554ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/11 01:48:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:48:49 DEBUG : pacer: Reducing sleep to 119.988285ms 2026/09/11 01:48:49 DEBUG : pacer: Reducing sleep to 243.285519ms 2026/09/11 01:48:49 DEBUG : pacer: Reducing sleep to 284.700808ms 2026/09/11 01:48:49 DEBUG : pacer: Reducing sleep to 207.306432ms === 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/11 01:48:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:48:58 DEBUG : pacer: Reducing sleep to 120.201038ms 2026/09/11 01:48:58 DEBUG : pacer: Reducing sleep to 234.697273ms 2026/09/11 01:48:58 DEBUG : pacer: Reducing sleep to 286.860315ms 2026/09/11 01:48:58 DEBUG : pacer: Reducing sleep to 201.087921ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/11 01:48:59 DEBUG : pacer: Reducing sleep to 90.034501ms === 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/11 01:49:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:49:03 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/11 01:49:03 DEBUG : pacer: Rate limited, increasing sleep to 1.581515658s 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/11 01:49:07 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:49:07 DEBUG : pacer: Reducing sleep to 113.975672ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/11 01:49:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/11 01:49:09 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=dypbv0b501a66b4c04e99a30fa00f88abe327": write tcp: unexpected EOF) 2026/09/11 01:49:09 DEBUG : pacer: Rate limited, increasing sleep to 1.111729109s 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=dypbv0b501a66b4c04e99a30fa00f88abe327": write tcp: unexpected EOF 2026/09/11 01:49:09 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/11 01:49:10 DEBUG : pacer: Reducing sleep to 137.524569ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n