"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/14 04:29:28 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/14 04:29:28 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/14 04:29:28 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/14 04:29:28 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/14 04:29:28 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/14 04:29:28 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/14 04:29:28 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/14 04:29:28 DEBUG : Creating backend with remote "TestZoho:rclone-test-gufubuf5noli" 2026/09/14 04:29:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/14 04:29:28 DEBUG : zoho root 'rclone-test-gufubuf5noli': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/14 04:29:28 DEBUG : TestZoho: Token expired 2026/09/14 04:29:28 DEBUG : TestZoho: No updated token found in the config file 2026/09/14 04:29:28 DEBUG : TestZoho: Token refresh successful 2026/09/14 04:29:28 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/14 04:29:28 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/14 04:29:29 DEBUG : pacer: Reducing sleep to 3.761983ms 2026/09/14 04:29:29 DEBUG : pacer: Reducing sleep to 7.332777ms 2026/09/14 04:29:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:29:29 DEBUG : pacer: Reducing sleep to 95.502296ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/14 04:29:30 DEBUG : pacer: Reducing sleep to 184.389748ms 2026/09/14 04:29:30 DEBUG : pacer: Reducing sleep to 5.872903ms === RUN TestIntegration/FsMkdir 2026/09/14 04:29:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:29:30 DEBUG : pacer: Reducing sleep to 93.504543ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/14 04:29:30 INFO : dir/subdir: Making directory 2026/09/14 04:29:30 DEBUG : pacer: Reducing sleep to 188.479032ms 2026/09/14 04:29:31 DEBUG : pacer: Reducing sleep to 230.963709ms 2026/09/14 04:29:31 DEBUG : pacer: Reducing sleep to 237.16448ms 2026/09/14 04:29:31 DEBUG : pacer: Reducing sleep to 140.217837ms 2026/09/14 04:29:31 DEBUG : pacer: Reducing sleep to 90.439286ms 2026/09/14 04:29:31 DEBUG : pacer: Reducing sleep to 120.995554ms 2026/09/14 04:29:31 DEBUG : pacer: Reducing sleep to 202.335514ms 2026/09/14 04:29:31 INFO : dir/subdir: Removing directory 2026/09/14 04:29:31 DEBUG : pacer: Reducing sleep to 240.182041ms 2026/09/14 04:29:32 DEBUG : pacer: Reducing sleep to 195.216159ms 2026/09/14 04:29:32 DEBUG : pacer: Reducing sleep to 130.752624ms 2026/09/14 04:29:32 DEBUG : pacer: Reducing sleep to 108.161749ms 2026/09/14 04:29:32 INFO : dir: Removing directory 2026/09/14 04:29:32 DEBUG : pacer: Reducing sleep to 139.591549ms 2026/09/14 04:29:32 DEBUG : pacer: Reducing sleep to 184.655537ms 2026/09/14 04:29:33 DEBUG : pacer: Reducing sleep to 226.383605ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/14 04:29:33 DEBUG : pacer: Reducing sleep to 209.047794ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/14 04:29:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/14 04:29:42 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/14 04:29:51 DEBUG : pacer: Reducing sleep to 60.677194ms 2026/09/14 04:29:51 DEBUG : pacer: Reducing sleep to 155.382735ms 2026/09/14 04:29:52 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:29:52 DEBUG : pacer: Reducing sleep to 76.270155ms 2026/09/14 04:29:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:29:56 DEBUG : pacer: Reducing sleep to 88.248587ms 2026/09/14 04:29:56 DEBUG : pacer: Reducing sleep to 164.492712ms 2026/09/14 04:29:56 DEBUG : pacer: Reducing sleep to 258.07944ms 2026/09/14 04:29:56 DEBUG : pacer: Reducing sleep to 247.500903ms 2026/09/14 04:30:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/14 04:30:05 DEBUG : pacer: Reducing sleep to 63.822145ms 2026/09/14 04:30:05 DEBUG : pacer: Reducing sleep to 157.453508ms 2026/09/14 04:30:05 DEBUG : pacer: Reducing sleep to 161.580295ms 2026/09/14 04:30:05 DEBUG : pacer: Reducing sleep to 249.810867ms 2026/09/14 04:30:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:30:10 DEBUG : pacer: Reducing sleep to 48.629523ms 2026/09/14 04:30:10 DEBUG : pacer: Reducing sleep to 65.936332ms 2026/09/14 04:30:10 DEBUG : pacer: Reducing sleep to 156.643652ms 2026/09/14 04:30:10 DEBUG : pacer: Reducing sleep to 235.519628ms 2026/09/14 04:30:14 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/14 04:30:19 DEBUG : pacer: Reducing sleep to 66.189796ms 2026/09/14 04:30:19 DEBUG : pacer: Reducing sleep to 159.216739ms 2026/09/14 04:30:19 DEBUG : pacer: Reducing sleep to 8.456038ms 2026/09/14 04:30:19 DEBUG : pacer: Reducing sleep to 95.359755ms 2026/09/14 04:30:23 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:30:23 DEBUG : pacer: Reducing sleep to 89.826748ms 2026/09/14 04:30:24 DEBUG : pacer: Reducing sleep to 165.763132ms 2026/09/14 04:30:24 DEBUG : pacer: Reducing sleep to 249.39459ms 2026/09/14 04:30:24 DEBUG : pacer: Reducing sleep to 245.930411ms 2026/09/14 04:30:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/14 04:30:28 DEBUG : zoho root 'rclone-test-gufubuf5noli': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/14 04:30:33 DEBUG : pacer: Reducing sleep to 66.532052ms 2026/09/14 04:30:33 DEBUG : pacer: Reducing sleep to 163.013929ms 2026/09/14 04:30:33 DEBUG : pacer: Reducing sleep to 165.994463ms 2026/09/14 04:30:33 DEBUG : pacer: Reducing sleep to 252.917206ms 2026/09/14 04:30:33 DEBUG : pacer: Reducing sleep to 254.975437ms 2026/09/14 04:30:33 DEBUG : pacer: Reducing sleep to 161.310776ms 2026/09/14 04:30:34 DEBUG : pacer: Reducing sleep to 55.196906ms 2026/09/14 04:30:34 DEBUG : pacer: Reducing sleep to 84.828221ms 2026/09/14 04:30:34 DEBUG : pacer: Reducing sleep to 164.757116ms 2026/09/14 04:30:34 DEBUG : pacer: Reducing sleep to 258.117578ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/14 04:30:34 DEBUG : pacer: Reducing sleep to 259.738512ms 2026/09/14 04:30:34 DEBUG : pacer: Reducing sleep to 140.925167ms 2026/09/14 04:30:35 DEBUG : pacer: Reducing sleep to 73.642213ms 2026/09/14 04:30:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:30:35 DEBUG : pacer: Reducing sleep to 88.688889ms 2026/09/14 04:30:35 DEBUG : pacer: Reducing sleep to 177.845311ms 2026/09/14 04:30:35 DEBUG : pacer: Reducing sleep to 255.566838ms 2026/09/14 04:30:35 DEBUG : pacer: Reducing sleep to 230.596773ms 2026/09/14 04:30:36 DEBUG : pacer: Reducing sleep to 154.457131ms 2026/09/14 04:30:36 DEBUG : pacer: Reducing sleep to 77.431857ms 2026/09/14 04:30:36 DEBUG : pacer: Reducing sleep to 104.944138ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/14 04:30:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:30:41 DEBUG : pacer: Reducing sleep to 60.691634ms 2026/09/14 04:30:41 DEBUG : pacer: Reducing sleep to 154.789116ms 2026/09/14 04:30:41 DEBUG : pacer: Reducing sleep to 159.350827ms 2026/09/14 04:30:41 DEBUG : pacer: Reducing sleep to 249.650587ms 2026/09/14 04:30:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:30:45 DEBUG : pacer: Reducing sleep to 73.376129ms 2026/09/14 04:30:46 DEBUG : pacer: Reducing sleep to 75.936369ms 2026/09/14 04:30:46 DEBUG : pacer: Reducing sleep to 138.147042ms 2026/09/14 04:30:46 DEBUG : pacer: Reducing sleep to 216.083494ms 2026/09/14 04:30:50 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/14 04:30:55 DEBUG : pacer: Reducing sleep to 67.400303ms 2026/09/14 04:30:55 DEBUG : pacer: Reducing sleep to 161.748761ms 2026/09/14 04:30:55 DEBUG : pacer: Reducing sleep to 71.9156ms 2026/09/14 04:30:55 DEBUG : pacer: Reducing sleep to 159.432094ms 2026/09/14 04:30:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:30:59 DEBUG : pacer: Reducing sleep to 85.491682ms 2026/09/14 04:30:59 DEBUG : pacer: Reducing sleep to 162.47317ms 2026/09/14 04:30:59 DEBUG : pacer: Reducing sleep to 252.950588ms 2026/09/14 04:31:00 DEBUG : pacer: Reducing sleep to 246.798433ms 2026/09/14 04:31:04 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/14 04:31:08 DEBUG : pacer: Reducing sleep to 63.045491ms 2026/09/14 04:31:08 DEBUG : pacer: Reducing sleep to 157.120081ms 2026/09/14 04:31:09 DEBUG : pacer: Reducing sleep to 154.636036ms 2026/09/14 04:31:09 DEBUG : pacer: Reducing sleep to 240.197269ms 2026/09/14 04:31:13 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:31:13 DEBUG : pacer: Reducing sleep to 87.144804ms 2026/09/14 04:31:13 DEBUG : pacer: Reducing sleep to 85.066805ms 2026/09/14 04:31:13 DEBUG : pacer: Reducing sleep to 170.901753ms 2026/09/14 04:31:13 DEBUG : pacer: Reducing sleep to 244.740659ms 2026/09/14 04:31:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/14 04:31:22 DEBUG : pacer: Reducing sleep to 60.375317ms 2026/09/14 04:31:22 DEBUG : pacer: Reducing sleep to 154.638304ms 2026/09/14 04:31:23 DEBUG : pacer: Reducing sleep to 42.000195ms 2026/09/14 04:31:23 DEBUG : pacer: Reducing sleep to 127.577479ms 2026/09/14 04:31:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:31:27 DEBUG : pacer: Reducing sleep to 85.196936ms 2026/09/14 04:31:27 DEBUG : pacer: Reducing sleep to 166.189913ms 2026/09/14 04:31:27 DEBUG : pacer: Reducing sleep to 256.413125ms 2026/09/14 04:31:27 DEBUG : pacer: Reducing sleep to 245.585074ms 2026/09/14 04:31:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/14 04:31:31 DEBUG : zoho root 'rclone-test-gufubuf5noli': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/14 04:31:31 DEBUG : pacer: Reducing sleep to 94.02473ms 2026/09/14 04:31:32 DEBUG : pacer: Reducing sleep to 19.117237ms 2026/09/14 04:31:32 DEBUG : pacer: Reducing sleep to 114.613421ms 2026/09/14 04:31:32 DEBUG : pacer: Reducing sleep to 111.337567ms 2026/09/14 04:31:32 DEBUG : pacer: Reducing sleep to 196.586366ms 2026/09/14 04:31:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:31:33 DEBUG : pacer: Reducing sleep to 85.532804ms 2026/09/14 04:31:33 DEBUG : pacer: Reducing sleep to 125.691047ms 2026/09/14 04:31:33 DEBUG : pacer: Reducing sleep to 215.284509ms 2026/09/14 04:31:34 DEBUG : pacer: Reducing sleep to 248.440441ms 2026/09/14 04:31:34 DEBUG : pacer: Reducing sleep to 211.390886ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/14 04:31:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:31:35 DEBUG : pacer: Reducing sleep to 59.610391ms 2026/09/14 04:31:35 DEBUG : pacer: Reducing sleep to 120.479395ms 2026/09/14 04:31:35 DEBUG : pacer: Reducing sleep to 37.698364ms 2026/09/14 04:31:35 DEBUG : pacer: Reducing sleep to 125.256671ms 2026/09/14 04:31:35 DEBUG : pacer: Reducing sleep to 217.988325ms 2026/09/14 04:31:35 DEBUG : pacer: Reducing sleep to 251.53942ms 2026/09/14 04:31:35 DEBUG : pacer: Reducing sleep to 196.926759ms 2026/09/14 04:31:36 DEBUG : pacer: Reducing sleep to 115.537175ms 2026/09/14 04:31:36 DEBUG : pacer: Reducing sleep to 80.316201ms 2026/09/14 04:31:40 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/14 04:31:44 DEBUG : pacer: Reducing sleep to 62.000506ms 2026/09/14 04:31:45 DEBUG : pacer: Reducing sleep to 157.263925ms 2026/09/14 04:31:45 DEBUG : pacer: Reducing sleep to 128.548981ms 2026/09/14 04:31:45 DEBUG : pacer: Reducing sleep to 158.616963ms 2026/09/14 04:31:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:31:49 DEBUG : pacer: Reducing sleep to 85.877864ms 2026/09/14 04:31:49 DEBUG : pacer: Reducing sleep to 165.629837ms 2026/09/14 04:31:49 DEBUG : pacer: Reducing sleep to 259.025824ms 2026/09/14 04:31:49 DEBUG : pacer: Reducing sleep to 246.723785ms 2026/09/14 04:31:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/14 04:31:58 DEBUG : pacer: Reducing sleep to 46.203485ms 2026/09/14 04:31:58 DEBUG : pacer: Reducing sleep to 140.410954ms 2026/09/14 04:31:59 DEBUG : pacer: Reducing sleep to 44.228659ms 2026/09/14 04:31:59 DEBUG : pacer: Reducing sleep to 132.996682ms 2026/09/14 04:32:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:32:03 DEBUG : pacer: Reducing sleep to 87.566576ms 2026/09/14 04:32:03 DEBUG : pacer: Reducing sleep to 163.234956ms 2026/09/14 04:32:03 DEBUG : pacer: Reducing sleep to 250.432768ms 2026/09/14 04:32:03 DEBUG : pacer: Reducing sleep to 244.056643ms 2026/09/14 04:32:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/14 04:32:12 DEBUG : pacer: Reducing sleep to 67.732054ms 2026/09/14 04:32:12 DEBUG : pacer: Reducing sleep to 162.001232ms 2026/09/14 04:32:12 DEBUG : pacer: Reducing sleep to 158.217018ms 2026/09/14 04:32:12 DEBUG : pacer: Reducing sleep to 247.528525ms 2026/09/14 04:32:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:32:17 DEBUG : pacer: Reducing sleep to 89.63258ms 2026/09/14 04:32:17 DEBUG : pacer: Reducing sleep to 74.408706ms 2026/09/14 04:32:17 DEBUG : pacer: Reducing sleep to 165.884209ms 2026/09/14 04:32:17 DEBUG : pacer: Reducing sleep to 245.027316ms 2026/09/14 04:32:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/14 04:32:26 DEBUG : pacer: Reducing sleep to 71.028883ms 2026/09/14 04:32:26 DEBUG : pacer: Reducing sleep to 165.505025ms 2026/09/14 04:32:26 DEBUG : pacer: Reducing sleep to 60.585632ms 2026/09/14 04:32:26 DEBUG : pacer: Reducing sleep to 150.400782ms 2026/09/14 04:32:31 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:32:31 DEBUG : pacer: Reducing sleep to 88.60195ms 2026/09/14 04:32:31 DEBUG : pacer: Reducing sleep to 163.417845ms 2026/09/14 04:32:31 DEBUG : pacer: Reducing sleep to 248.215136ms 2026/09/14 04:32:31 DEBUG : pacer: Reducing sleep to 246.917923ms 2026/09/14 04:32:31 DEBUG : pacer: Reducing sleep to 180.14277ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/14 04:32:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:32:32 DEBUG : pacer: Reducing sleep to 66.892705ms 2026/09/14 04:32:32 DEBUG : zoho root 'rclone-test-gufubuf5noli': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/14 04:32:32 DEBUG : pacer: Reducing sleep to 155.056499ms 2026/09/14 04:32:32 DEBUG : pacer: Reducing sleep to 167.239084ms 2026/09/14 04:32:32 DEBUG : pacer: Reducing sleep to 256.845437ms 2026/09/14 04:32:32 DEBUG : pacer: Reducing sleep to 251.241076ms 2026/09/14 04:32:33 DEBUG : pacer: Reducing sleep to 161.636366ms 2026/09/14 04:32:33 DEBUG : pacer: Reducing sleep to 66.839692ms 2026/09/14 04:32:33 DEBUG : pacer: Reducing sleep to 85.646707ms 2026/09/14 04:32:33 DEBUG : pacer: Reducing sleep to 166.715893ms 2026/09/14 04:32:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/14 04:32:34 DEBUG : pacer: Reducing sleep to 71.90525ms 2026/09/14 04:32:34 DEBUG : pacer: Reducing sleep to 166.833647ms 2026/09/14 04:32:35 DEBUG : pacer: Reducing sleep to 56.813638ms 2026/09/14 04:32:35 DEBUG : pacer: Reducing sleep to 145.346336ms 2026/09/14 04:32:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:32:39 DEBUG : pacer: Reducing sleep to 87.014995ms 2026/09/14 04:32:39 DEBUG : pacer: Reducing sleep to 167.857371ms 2026/09/14 04:32:39 DEBUG : pacer: Reducing sleep to 257.492353ms 2026/09/14 04:32:39 DEBUG : pacer: Reducing sleep to 244.899828ms 2026/09/14 04:32:43 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/14 04:32:48 DEBUG : pacer: Reducing sleep to 69.31111ms 2026/09/14 04:32:48 DEBUG : pacer: Reducing sleep to 163.383019ms 2026/09/14 04:32:48 DEBUG : pacer: Reducing sleep to 168.57874ms 2026/09/14 04:32:48 DEBUG : pacer: Reducing sleep to 257.074801ms 2026/09/14 04:32:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:32:53 DEBUG : pacer: Reducing sleep to 87.556386ms 2026/09/14 04:32:53 DEBUG : pacer: Reducing sleep to 49.755052ms 2026/09/14 04:32:53 DEBUG : pacer: Reducing sleep to 141.145895ms 2026/09/14 04:32:53 DEBUG : pacer: Reducing sleep to 223.941554ms 2026/09/14 04:32:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/14 04:33:16 DEBUG : pacer: Reducing sleep to 62.393616ms 2026/09/14 04:33:16 DEBUG : pacer: Reducing sleep to 155.104296ms 2026/09/14 04:33:16 DEBUG : pacer: Reducing sleep to 218.33469ms 2026/09/14 04:33:16 DEBUG : pacer: Reducing sleep to 257.496978ms 2026/09/14 04:33:16 DEBUG : pacer: Reducing sleep to 179.356673ms 2026/09/14 04:33:17 DEBUG : pacer: Reducing sleep to 113.180545ms 2026/09/14 04:33:17 DEBUG : pacer: Reducing sleep to 64.718327ms 2026/09/14 04:33:17 DEBUG : pacer: Reducing sleep to 226.118068ms 2026/09/14 04:33:17 DEBUG : pacer: Reducing sleep to 254.247775ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/14 04:33:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:33:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:33:25 UTC": parsing time "Mon, 14 Sep 2026 04:33:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:33:25 UTC" as " " 2026/09/14 04:33:25 DEBUG : pacer: Reducing sleep to 77.799554ms === 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/14 04:33:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:33:34 DEBUG : zoho root 'rclone-test-gufubuf5noli': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/14 04:33:34 DEBUG : pacer: Reducing sleep to 92.259408ms 2026/09/14 04:33:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:33:35 DEBUG : pacer: Reducing sleep to 87.690678ms 2026/09/14 04:33:35 DEBUG : pacer: Reducing sleep to 172.60255ms 2026/09/14 04:33:35 DEBUG : pacer: Reducing sleep to 191.17513ms 2026/09/14 04:33:35 DEBUG : pacer: Reducing sleep to 246.3654ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/14 04:33:35 DEBUG : pacer: Reducing sleep to 217.631745ms 2026/09/14 04:33:35 DEBUG : pacer: Reducing sleep to 136.083498ms 2026/09/14 04:33:36 DEBUG : pacer: Reducing sleep to 75.817197ms 2026/09/14 04:33:36 DEBUG : pacer: Reducing sleep to 116.317902ms 2026/09/14 04:33:36 DEBUG : pacer: Reducing sleep to 200.400941ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/14 04:33:36 DEBUG : pacer: Reducing sleep to 252.523457ms 2026/09/14 04:33:36 DEBUG : pacer: Reducing sleep to 218.741008ms 2026/09/14 04:33:36 DEBUG : pacer: Reducing sleep to 131.955969ms 2026/09/14 04:33:37 DEBUG : pacer: Reducing sleep to 78.008245ms 2026/09/14 04:33:37 DEBUG : pacer: Reducing sleep to 111.469672ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/14 04:33:37 DEBUG : pacer: Reducing sleep to 199.04396ms 2026/09/14 04:33:37 DEBUG : pacer: Reducing sleep to 255.179048ms 2026/09/14 04:33:37 DEBUG : pacer: Reducing sleep to 218.515417ms 2026/09/14 04:33:37 DEBUG : pacer: Reducing sleep to 129.221289ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/14 04:33:37 DEBUG : Creating backend with remote "TestZoho:" 2026/09/14 04:33:37 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/14 04:33:42 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/14 04:33:46 DEBUG : pacer: Reducing sleep to 87.244713ms 2026/09/14 04:33:47 DEBUG : pacer: Reducing sleep to 176.822893ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/14 04:33:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:33:51 DEBUG : pacer: Reducing sleep to 87.808313ms 2026/09/14 04:33:51 DEBUG : pacer: Reducing sleep to 161.040974ms 2026/09/14 04:33:51 DEBUG : pacer: Reducing sleep to 238.804755ms 2026/09/14 04:33:52 DEBUG : pacer: Reducing sleep to 243.30647ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/14 04:33:56 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/14 04:34:00 DEBUG : pacer: Reducing sleep to 86.561449ms 2026/09/14 04:34:00 DEBUG : pacer: Reducing sleep to 169.40583ms 2026/09/14 04:34:01 DEBUG : pacer: Reducing sleep to 254.245382ms 2026/09/14 04:34:01 DEBUG : pacer: Reducing sleep to 248.556731ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/14 04:34:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/14 04:34:05 DEBUG : pacer: Reducing sleep to 86.35705ms 2026/09/14 04:34:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:10 DEBUG : pacer: Reducing sleep to 60.798865ms 2026/09/14 04:34:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:10 DEBUG : pacer: Reducing sleep to 85.678128ms 2026/09/14 04:34:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:15 DEBUG : pacer: Reducing sleep to 50.137476ms 2026/09/14 04:34:15 DEBUG : pacer: Reducing sleep to 186.27536ms 2026/09/14 04:34:15 DEBUG : pacer: Reducing sleep to 238.627992ms 2026/09/14 04:34:15 DEBUG : pacer: Reducing sleep to 234.927974ms 2026/09/14 04:34:15 DEBUG : pacer: Reducing sleep to 179.760008ms 2026/09/14 04:34:15 DEBUG : pacer: Reducing sleep to 109.386171ms 2026/09/14 04:34:16 DEBUG : pacer: Reducing sleep to 76.62185ms 2026/09/14 04:34:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:19 DEBUG : pacer: Reducing sleep to 86.336914ms 2026/09/14 04:34:19 DEBUG : pacer: Reducing sleep to 174.584632ms 2026/09/14 04:34:19 DEBUG : pacer: Reducing sleep to 254.269389ms 2026/09/14 04:34:20 DEBUG : pacer: Reducing sleep to 233.927918ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/14 04:34:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:28 DEBUG : pacer: Reducing sleep to 67.204117ms 2026/09/14 04:34:29 DEBUG : pacer: Reducing sleep to 66.237939ms 2026/09/14 04:34:29 DEBUG : pacer: Reducing sleep to 153.202612ms 2026/09/14 04:34:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:33 DEBUG : pacer: Reducing sleep to 84.708147ms 2026/09/14 04:34:33 DEBUG : pacer: Reducing sleep to 251.200965ms 2026/09/14 04:34:33 DEBUG : pacer: Reducing sleep to 338.719621ms 2026/09/14 04:34:33 DEBUG : pacer: Reducing sleep to 255.748074ms 2026/09/14 04:34:34 DEBUG : pacer: Reducing sleep to 75.139843ms 2026/09/14 04:34:34 DEBUG : Creating backend with remote "TestZoho:rclone-test-gufubuf5noli/dirToPurgeFromRoot" 2026/09/14 04:34:34 DEBUG : Config file has changed externally - reloading 2026/09/14 04:34:34 DEBUG : zoho root 'rclone-test-gufubuf5noli/dirToPurgeFromRoot': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/14 04:34:34 DEBUG : pacer: Reducing sleep to 119.420448ms 2026/09/14 04:34:34 DEBUG : pacer: Reducing sleep to 239.679579ms 2026/09/14 04:34:34 DEBUG : pacer: Reducing sleep to 273.461262ms 2026/09/14 04:34:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:35 DEBUG : pacer: Reducing sleep to 86.958629ms 2026/09/14 04:34:35 DEBUG : pacer: Reducing sleep to 177.614198ms 2026/09/14 04:34:35 DEBUG : pacer: Reducing sleep to 251.979149ms 2026/09/14 04:34:35 DEBUG : pacer: Reducing sleep to 240.292266ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/14 04:34:35 DEBUG : Creating backend with remote "TestZoho:rclone-test-gufubuf5noli/hello? sausage/êé" 2026/09/14 04:34:35 DEBUG : pacer: Reducing sleep to 116.594683ms 2026/09/14 04:34:35 DEBUG : pacer: Reducing sleep to 233.294783ms 2026/09/14 04:34:36 DEBUG : pacer: Reducing sleep to 283.81562ms 2026/09/14 04:34:36 DEBUG : pacer: Reducing sleep to 214.647706ms 2026/09/14 04:34:36 DEBUG : pacer: Reducing sleep to 73.799929ms 2026/09/14 04:34:36 DEBUG : pacer: Reducing sleep to 54.111271ms 2026/09/14 04:34:36 DEBUG : pacer: Reducing sleep to 119.880328ms 2026/09/14 04:34:36 DEBUG : pacer: Reducing sleep to 242.335999ms 2026/09/14 04:34:37 DEBUG : pacer: Reducing sleep to 261.362047ms 2026/09/14 04:34:37 DEBUG : pacer: Reducing sleep to 211.566289ms 2026/09/14 04:34:37 DEBUG : pacer: Reducing sleep to 88.497572ms 2026/09/14 04:34:37 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:37 DEBUG : pacer: Reducing sleep to 117.619192ms 2026/09/14 04:34:37 DEBUG : pacer: Reducing sleep to 234.159041ms 2026/09/14 04:34:38 DEBUG : pacer: Reducing sleep to 261.956523ms 2026/09/14 04:34:38 DEBUG : pacer: Reducing sleep to 216.351462ms 2026/09/14 04:34:38 DEBUG : pacer: Reducing sleep to 109.773377ms 2026/09/14 04:34:38 DEBUG : pacer: Reducing sleep to 68.305425ms 2026/09/14 04:34:38 DEBUG : pacer: Reducing sleep to 123.370195ms 2026/09/14 04:34:38 DEBUG : pacer: Reducing sleep to 226.487252ms 2026/09/14 04:34:39 DEBUG : pacer: Reducing sleep to 265.958318ms 2026/09/14 04:34:39 DEBUG : pacer: Reducing sleep to 194.793375ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/14 04:34:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:39 DEBUG : pacer: Reducing sleep to 72.162337ms 2026/09/14 04:34:39 DEBUG : pacer: Reducing sleep to 160.530482ms 2026/09/14 04:34:39 DEBUG : pacer: Reducing sleep to 247.546788ms 2026/09/14 04:34:42 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:34:42 DEBUG : pacer: Reducing sleep to 87.163093ms 2026/09/14 04:34:42 DEBUG : pacer: Reducing sleep to 82.541361ms 2026/09/14 04:34:47 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/14 04:34:52 DEBUG : pacer: Reducing sleep to 76.949775ms 2026/09/14 04:34:52 DEBUG : pacer: Reducing sleep to 114.644142ms 2026/09/14 04:34:52 DEBUG : pacer: Reducing sleep to 193.862342ms 2026/09/14 04:34:52 DEBUG : pacer: Reducing sleep to 252.917985ms 2026/09/14 04:34:52 DEBUG : pacer: Reducing sleep to 226.996091ms 2026/09/14 04:34:52 DEBUG : pacer: Reducing sleep to 137.898989ms 2026/09/14 04:34:53 DEBUG : pacer: Reducing sleep to 81.980269ms 2026/09/14 04:34:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:06 DEBUG : pacer: Reducing sleep to 80.783064ms 2026/09/14 04:35:06 DEBUG : pacer: Reducing sleep to 46.162858ms 2026/09/14 04:35:06 DEBUG : pacer: Reducing sleep to 125.70352ms 2026/09/14 04:35:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:10 DEBUG : pacer: Reducing sleep to 85.837441ms 2026/09/14 04:35:10 DEBUG : pacer: Reducing sleep to 251.863238ms 2026/09/14 04:35:10 DEBUG : pacer: Reducing sleep to 337.729963ms 2026/09/14 04:35:11 DEBUG : pacer: Reducing sleep to 251.338305ms 2026/09/14 04:35:11 DEBUG : pacer: Reducing sleep to 91.140058ms 2026/09/14 04:35:11 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:11 DEBUG : pacer: Reducing sleep to 78.982106ms 2026/09/14 04:35:11 DEBUG : pacer: Reducing sleep to 142.694356ms 2026/09/14 04:35:12 DEBUG : pacer: Reducing sleep to 213.433143ms 2026/09/14 04:35:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:15 DEBUG : pacer: Reducing sleep to 85.705749ms 2026/09/14 04:35:15 DEBUG : pacer: Reducing sleep to 252.170335ms 2026/09/14 04:35:15 DEBUG : pacer: Reducing sleep to 295.831794ms 2026/09/14 04:35:15 DEBUG : pacer: Reducing sleep to 210.39502ms 2026/09/14 04:35:16 DEBUG : pacer: Reducing sleep to 78.199486ms 2026/09/14 04:35:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:20 DEBUG : pacer: Reducing sleep to 70.420792ms 2026/09/14 04:35:20 DEBUG : pacer: Reducing sleep to 122.93056ms 2026/09/14 04:35:20 DEBUG : pacer: Reducing sleep to 197.416235ms 2026/09/14 04:35:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:24 DEBUG : pacer: Reducing sleep to 94.013017ms 2026/09/14 04:35:24 DEBUG : pacer: Reducing sleep to 253.591469ms 2026/09/14 04:35:24 DEBUG : pacer: Reducing sleep to 305.979684ms 2026/09/14 04:35:25 DEBUG : pacer: Reducing sleep to 213.72777ms 2026/09/14 04:35:25 DEBUG : pacer: Reducing sleep to 79.687082ms 2026/09/14 04:35:25 DEBUG : pacer: Reducing sleep to 30.122754ms 2026/09/14 04:35:25 DEBUG : pacer: Reducing sleep to 111.092561ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/14 04:35:25 DEBUG : Creating backend with remote "TestZoho:rclone-test-cahodep6kico" 2026/09/14 04:35:26 DEBUG : pacer: Reducing sleep to 34.396626ms 2026/09/14 04:35:26 DEBUG : pacer: Reducing sleep to 75.677701ms 2026/09/14 04:35:26 DEBUG : pacer: Reducing sleep to 98.090564ms 2026/09/14 04:35:26 DEBUG : pacer: Reducing sleep to 226.158031ms 2026/09/14 04:35:26 DEBUG : pacer: Reducing sleep to 262.25886ms 2026/09/14 04:35:26 DEBUG : pacer: Reducing sleep to 233.392639ms 2026/09/14 04:35:27 DEBUG : pacer: Reducing sleep to 91.873722ms 2026/09/14 04:35:27 DEBUG : pacer: Reducing sleep to 51.375221ms 2026/09/14 04:35:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:27 DEBUG : pacer: Reducing sleep to 3.356042ms 2026/09/14 04:35:27 DEBUG : pacer: Reducing sleep to 118.956266ms 2026/09/14 04:35:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:29 DEBUG : pacer: Reducing sleep to 121.977867ms 2026/09/14 04:35:29 DEBUG : pacer: Reducing sleep to 216.410724ms 2026/09/14 04:35:29 DEBUG : pacer: Reducing sleep to 258.763548ms 2026/09/14 04:35:29 DEBUG : pacer: Reducing sleep to 202.401188ms 2026/09/14 04:35:31 DEBUG : pacer: Reducing sleep to 9.185131ms 2026/09/14 04:35:31 DEBUG : pacer: Reducing sleep to 98.681051ms 2026/09/14 04:35:31 DEBUG : pacer: Reducing sleep to 182.120012ms 2026/09/14 04:35:31 DEBUG : pacer: Reducing sleep to 256.985747ms 2026/09/14 04:35:32 DEBUG : pacer: Reducing sleep to 208.586546ms 2026/09/14 04:35:32 DEBUG : pacer: Reducing sleep to 142.548592ms 2026/09/14 04:35:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:35:32 DEBUG : pacer: Reducing sleep to 127.002939ms 2026/09/14 04:35:32 DEBUG : zoho root 'rclone-test-cahodep6kico': Purge remote 2026/09/14 04:35:32 DEBUG : pacer: Reducing sleep to 128.556832ms 2026/09/14 04:35:32 DEBUG : pacer: Reducing sleep to 162.298309ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/14 04:35:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/14 04:35:32 DEBUG : pacer: Reducing sleep to 90.029754ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/14 04:35:33 DEBUG : pacer: Reducing sleep to 173.437275ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/14 04:35:33 DEBUG : pacer: Reducing sleep to 253.427863ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/14 04:35:33 DEBUG : pacer: Reducing sleep to 240.078909ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/14 04:35:33 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/14 04:35:38 DEBUG : zoho root 'rclone-test-gufubuf5noli': 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/14 04:36:01 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:01 UTC": parsing time "Mon, 14 Sep 2026 04:36:01 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:01 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/14 04:36:06 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:06 UTC": parsing time "Mon, 14 Sep 2026 04:36:06 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:06 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/14 04:36:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:11 UTC": parsing time "Mon, 14 Sep 2026 04:36:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:11 UTC" as " " 2026/09/14 04:36:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:11 UTC": parsing time "Mon, 14 Sep 2026 04:36:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:11 UTC" as " " 2026/09/14 04:36:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:12 UTC": parsing time "Mon, 14 Sep 2026 04:36:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:12 UTC" as " " 2026/09/14 04:36:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:12 UTC": parsing time "Mon, 14 Sep 2026 04:36:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/14 04:36:15 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:15 UTC": parsing time "Mon, 14 Sep 2026 04:36:15 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:15 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/14 04:36:20 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:20 UTC": parsing time "Mon, 14 Sep 2026 04:36:20 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:20 UTC" as " " 2026/09/14 04:36:20 DEBUG : pacer: Reducing sleep to 13.798491ms 2026/09/14 04:36:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:36:24 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:24 UTC": parsing time "Mon, 14 Sep 2026 04:36:24 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:24 UTC" as " " 2026/09/14 04:36:24 DEBUG : pacer: Reducing sleep to 2.288075ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/14 04:36:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:25 UTC": parsing time "Mon, 14 Sep 2026 04:36:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:25 UTC" as " " 2026/09/14 04:36:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/14 04:36:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:25 UTC": parsing time "Mon, 14 Sep 2026 04:36:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:25 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/14 04:36:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:25 UTC": parsing time "Mon, 14 Sep 2026 04:36:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:25 UTC" as " " 2026/09/14 04:36:25 DEBUG : pacer: Reducing sleep to 7.436433ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/14 04:36:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:25 UTC": parsing time "Mon, 14 Sep 2026 04:36:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:25 UTC" as " " 2026/09/14 04:36:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/14 04:36:29 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:29 UTC": parsing time "Mon, 14 Sep 2026 04:36:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:29 UTC" as " " 2026/09/14 04:36:29 DEBUG : pacer: Reducing sleep to 5.952802ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/14 04:36:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:36:34 DEBUG : pacer: Reducing sleep to 88.697459ms 2026/09/14 04:36:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 14 Sep 2026 04:36:34 UTC": parsing time "Mon, 14 Sep 2026 04:36:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 14 Sep 2026 04:36:34 UTC" as " " 2026/09/14 04:36:34 DEBUG : pacer: Reducing sleep to 75.414699ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/14 04:36:34 DEBUG : pacer: Reducing sleep to 159.650987ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/14 04:36:34 DEBUG : Creating backend with remote "TestZoho:rclone-test-gufubuf5noli/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/14 04:36:34 DEBUG : Config file has changed externally - reloading 2026/09/14 04:36:34 DEBUG : pacer: Reducing sleep to 114.445751ms 2026/09/14 04:36:35 DEBUG : pacer: Reducing sleep to 228.461077ms 2026/09/14 04:36:35 DEBUG : pacer: Reducing sleep to 280.701326ms 2026/09/14 04:36:35 DEBUG : pacer: Reducing sleep to 219.287007ms 2026/09/14 04:36:35 DEBUG : pacer: Reducing sleep to 99.687146ms 2026/09/14 04:36:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:36:36 DEBUG : pacer: Reducing sleep to 106.302886ms 2026/09/14 04:36:36 DEBUG : pacer: Reducing sleep to 218.687508ms 2026/09/14 04:36:36 DEBUG : pacer: Reducing sleep to 278.207301ms 2026/09/14 04:36:36 DEBUG : pacer: Reducing sleep to 227.814124ms 2026/09/14 04:36:36 DEBUG : pacer: Reducing sleep to 114.479104ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/14 04:36:36 DEBUG : pacer: Reducing sleep to 54.72669ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/14 04:36:36 DEBUG : Creating backend with remote "TestZoho:rclone-test-gufubuf5noli/not found.txt" 2026/09/14 04:36:37 DEBUG : pacer: Reducing sleep to 115.891887ms 2026/09/14 04:36:37 DEBUG : pacer: Reducing sleep to 159.123134ms 2026/09/14 04:36:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:36:41 DEBUG : zoho root 'rclone-test-gufubuf5noli/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/09/14 04:36:46 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-gufubuf5noli" from "TestZoho:rclone-test-gufubuf5noli" 2026/09/14 04:36:46 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/14 04:36:51 DEBUG : pacer: Reducing sleep to 118.127569ms 2026/09/14 04:36:51 DEBUG : pacer: Reducing sleep to 236.862282ms 2026/09/14 04:36:51 DEBUG : pacer: Reducing sleep to 283.970703ms 2026/09/14 04:36:51 DEBUG : pacer: Reducing sleep to 212.947009ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/14 04:36:55 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/14 04:37:09 DEBUG : pacer: Reducing sleep to 120.35253ms 2026/09/14 04:37:09 DEBUG : pacer: Reducing sleep to 240.271594ms 2026/09/14 04:37:09 DEBUG : pacer: Reducing sleep to 284.028495ms 2026/09/14 04:37:10 DEBUG : pacer: Reducing sleep to 198.742098ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/14 04:37:10 DEBUG : pacer: Reducing sleep to 66.338958ms 2026/09/14 04:37:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:37:14 DEBUG : pacer: Reducing sleep to 114.866856ms 2026/09/14 04:37:14 DEBUG : pacer: Reducing sleep to 232.129247ms 2026/09/14 04:37:14 DEBUG : pacer: Reducing sleep to 282.176399ms 2026/09/14 04:37:14 DEBUG : pacer: Reducing sleep to 214.870412ms === 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/14 04:37:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:37:23 DEBUG : pacer: Reducing sleep to 117.837935ms 2026/09/14 04:37:23 DEBUG : pacer: Reducing sleep to 233.496201ms 2026/09/14 04:37:23 DEBUG : pacer: Reducing sleep to 282.077584ms 2026/09/14 04:37:23 DEBUG : pacer: Reducing sleep to 213.721987ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/14 04:37:24 DEBUG : pacer: Reducing sleep to 92.426288ms === 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/14 04:37:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:37:28 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/14 04:37:28 DEBUG : pacer: Rate limited, increasing sleep to 1.447240587s 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/14 04:37:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:37:32 DEBUG : pacer: Reducing sleep to 117.298016ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/14 04:37:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/14 04:37:34 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=q1u4d8276de6dca0a48948c2ea4ad37ba049e": write tcp: unexpected EOF) 2026/09/14 04:37:34 DEBUG : pacer: Rate limited, increasing sleep to 1.620591624s 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=q1u4d8276de6dca0a48948c2ea4ad37ba049e": write tcp: unexpected EOF 2026/09/14 04:37:34 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/14 04:37:35 DEBUG : pacer: Reducing sleep to 136.865225ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n