"go test -v -timeout 2h0m0s -remote TestZoho: -verbose" - Starting (try 1/5) === RUN TestFolderListLimiter --- PASS: TestFolderListLimiter (0.00s) === RUN TestFolderWindowLimiterShape --- PASS: TestFolderWindowLimiterShape (0.00s) === RUN TestFolderWindowLimiterIdleResume --- PASS: TestFolderWindowLimiterIdleResume (0.00s) === RUN TestFolderWindowLimiterClamps --- PASS: TestFolderWindowLimiterClamps (0.00s) === RUN TestFolderListLimiterConcurrent --- PASS: TestFolderListLimiterConcurrent (0.00s) === RUN TestTPSMinSleep --- PASS: TestTPSMinSleep (0.00s) === RUN TestShouldRetry === RUN TestShouldRetry/429_honours_Retry-After 2026/08/25 02:50:15 NOTICE: zoho root '': Too many requests: Trying again in 6 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_without_Retry-After_falls_back_to_60s 2026/08/25 02:50:15 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_with_unparseable_Retry-After_falls_back 2026/08/25 02:50:15 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/08/25 02:50:15 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/08/25 02:50:15 ERROR : zoho: missing OAuth Scope. Run rclone config reconnect to fix this issue. === RUN TestShouldRetry/401_expired_token_retries === RUN TestShouldRetry/401_R008_deleted_folder_is_not_retried === RUN TestShouldRetry/cancelled_context_aborts --- PASS: TestShouldRetry (0.00s) --- PASS: TestShouldRetry/429_honours_Retry-After (0.00s) --- PASS: TestShouldRetry/429_without_Retry-After_falls_back_to_60s (0.00s) --- PASS: TestShouldRetry/429_with_unparseable_Retry-After_falls_back (0.00s) --- PASS: TestShouldRetry/401_missing_scope_aborts (0.00s) --- PASS: TestShouldRetry/401_expired_token_retries (0.00s) --- PASS: TestShouldRetry/401_R008_deleted_folder_is_not_retried (0.00s) --- PASS: TestShouldRetry/cancelled_context_aborts (0.00s) === RUN TestIsMissingResourceErr --- PASS: TestIsMissingResourceErr (0.00s) === RUN TestThrottleEpisode 2026/08/25 02:50:15 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/08/25 02:50:15 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleStateConcurrent (0.00s) === RUN TestConfigRootFolderID === RUN TestConfigRootFolderID/SetRootAsksBeforeChanging === RUN TestConfigRootFolderID/EmptyRootGoesToEdition === RUN TestConfigRootFolderID/KeepRootOnNo === RUN TestConfigRootFolderID/ChangeRootOnYes --- PASS: TestConfigRootFolderID (0.00s) --- PASS: TestConfigRootFolderID/SetRootAsksBeforeChanging (0.00s) --- PASS: TestConfigRootFolderID/EmptyRootGoesToEdition (0.00s) --- PASS: TestConfigRootFolderID/KeepRootOnNo (0.00s) --- PASS: TestConfigRootFolderID/ChangeRootOnYes (0.00s) === RUN TestIntegration fstests.go:457: Using remote "TestZoho:" 2026/08/25 02:50:15 DEBUG : Creating backend with remote "TestZoho:rclone-test-kujemev2zemi" 2026/08/25 02:50:15 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/25 02:50:15 DEBUG : zoho root 'rclone-test-kujemev2zemi': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/08/25 02:50:15 DEBUG : TestZoho: Token expired 2026/08/25 02:50:15 DEBUG : TestZoho: No updated token found in the config file 2026/08/25 02:50:15 DEBUG : TestZoho: Token refresh successful 2026/08/25 02:50:15 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/08/25 02:50:15 DEBUG : TestZoho: Saved new token in config file 2026/08/25 02:50:16 DEBUG : pacer: Reducing sleep to 1.562255ms === RUN TestIntegration/FsCheckWrap fstests.go:498: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:526: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/08/25 02:50:16 DEBUG : pacer: Reducing sleep to 27.708004ms 2026/08/25 02:50:16 DEBUG : pacer: Reducing sleep to 61.671408ms 2026/08/25 02:50:17 DEBUG : pacer: Reducing sleep to 9.043619ms 2026/08/25 02:50:17 DEBUG : pacer: Reducing sleep to 135.020605ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/08/25 02:50:17 DEBUG : pacer: Reducing sleep to 252.06383ms 2026/08/25 02:50:17 DEBUG : pacer: Reducing sleep to 273.141179ms === RUN TestIntegration/FsMkdir 2026/08/25 02:50:17 DEBUG : pacer: Reducing sleep to 115.163614ms 2026/08/25 02:50:17 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:50:17 DEBUG : pacer: Reducing sleep to 122.748248ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/08/25 02:50:17 INFO : dir/subdir: Making directory 2026/08/25 02:50:18 DEBUG : pacer: Reducing sleep to 248.594869ms 2026/08/25 02:50:18 DEBUG : pacer: Reducing sleep to 211.08538ms 2026/08/25 02:50:18 DEBUG : pacer: Reducing sleep to 209.14936ms 2026/08/25 02:50:18 DEBUG : pacer: Reducing sleep to 120.728969ms 2026/08/25 02:50:18 DEBUG : pacer: Reducing sleep to 109.685349ms 2026/08/25 02:50:18 DEBUG : pacer: Reducing sleep to 153.21584ms 2026/08/25 02:50:19 DEBUG : pacer: Reducing sleep to 221.905452ms 2026/08/25 02:50:19 INFO : dir/subdir: Removing directory 2026/08/25 02:50:19 DEBUG : pacer: Reducing sleep to 225.718299ms 2026/08/25 02:50:19 DEBUG : pacer: Reducing sleep to 163.827476ms 2026/08/25 02:50:19 DEBUG : pacer: Reducing sleep to 108.528074ms 2026/08/25 02:50:19 DEBUG : pacer: Reducing sleep to 120.68144ms 2026/08/25 02:50:19 INFO : dir: Removing directory 2026/08/25 02:50:19 DEBUG : pacer: Reducing sleep to 172.15449ms 2026/08/25 02:50:20 DEBUG : pacer: Reducing sleep to 187.00963ms 2026/08/25 02:50:20 DEBUG : pacer: Reducing sleep to 217.679473ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/08/25 02:50:20 DEBUG : pacer: Reducing sleep to 192.993761ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/08/25 02:50:24 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/08/25 02:50:29 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:727: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/08/25 02:50:38 DEBUG : pacer: Reducing sleep to 85.933867ms 2026/08/25 02:50:38 DEBUG : pacer: Reducing sleep to 212.299628ms 2026/08/25 02:50:39 DEBUG : pacer: Reducing sleep to 60.545ms 2026/08/25 02:50:39 DEBUG : pacer: Reducing sleep to 174.841449ms 2026/08/25 02:50:43 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:50:43 DEBUG : pacer: Reducing sleep to 108.186171ms 2026/08/25 02:50:43 DEBUG : pacer: Reducing sleep to 148.584029ms 2026/08/25 02:50:43 DEBUG : pacer: Reducing sleep to 216.395784ms 2026/08/25 02:50:43 DEBUG : pacer: Reducing sleep to 225.758675ms 2026/08/25 02:50:47 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:727: testing "." 2026/08/25 02:50:52 DEBUG : pacer: Reducing sleep to 86.840342ms 2026/08/25 02:50:52 DEBUG : pacer: Reducing sleep to 212.55273ms 2026/08/25 02:50:52 DEBUG : pacer: Reducing sleep to 182.364564ms 2026/08/25 02:50:52 DEBUG : pacer: Reducing sleep to 232.408016ms 2026/08/25 02:50:57 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:50:57 DEBUG : pacer: Reducing sleep to 113.972398ms 2026/08/25 02:50:57 DEBUG : pacer: Reducing sleep to 87.596642ms 2026/08/25 02:50:57 DEBUG : pacer: Reducing sleep to 161.532985ms 2026/08/25 02:50:57 DEBUG : pacer: Reducing sleep to 222.088721ms 2026/08/25 02:51:01 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:727: testing ".." 2026/08/25 02:51:06 DEBUG : pacer: Reducing sleep to 87.519344ms 2026/08/25 02:51:06 DEBUG : pacer: Reducing sleep to 212.708614ms 2026/08/25 02:51:06 DEBUG : pacer: Reducing sleep to 123.950647ms 2026/08/25 02:51:06 DEBUG : pacer: Reducing sleep to 233.094958ms 2026/08/25 02:51:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:51:11 DEBUG : pacer: Reducing sleep to 115.720349ms 2026/08/25 02:51:11 DEBUG : pacer: Reducing sleep to 82.122927ms 2026/08/25 02:51:11 DEBUG : pacer: Reducing sleep to 150.772398ms 2026/08/25 02:51:11 DEBUG : pacer: Reducing sleep to 219.691919ms 2026/08/25 02:51:15 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:727: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/08/25 02:51:20 DEBUG : pacer: Reducing sleep to 83.726382ms 2026/08/25 02:51:20 DEBUG : zoho root 'rclone-test-kujemev2zemi': folder listing limiter sweep: evicted 4 of 8 idle limiters 2026/08/25 02:51:20 DEBUG : pacer: Reducing sleep to 208.8719ms 2026/08/25 02:51:20 DEBUG : pacer: Reducing sleep to 206.669767ms 2026/08/25 02:51:20 DEBUG : pacer: Reducing sleep to 238.783617ms 2026/08/25 02:51:20 DEBUG : pacer: Reducing sleep to 201.6721ms 2026/08/25 02:51:21 DEBUG : pacer: Reducing sleep to 122.918217ms 2026/08/25 02:51:21 DEBUG : pacer: Reducing sleep to 85.135694ms 2026/08/25 02:51:21 DEBUG : pacer: Reducing sleep to 132.364556ms 2026/08/25 02:51:21 DEBUG : pacer: Reducing sleep to 207.947892ms 2026/08/25 02:51:21 DEBUG : pacer: Reducing sleep to 252.863345ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:727: testing " leading space" 2026/08/25 02:51:21 DEBUG : pacer: Reducing sleep to 213.415187ms 2026/08/25 02:51:22 DEBUG : pacer: Reducing sleep to 65.139171ms 2026/08/25 02:51:22 DEBUG : pacer: Reducing sleep to 78.694315ms 2026/08/25 02:51:22 DEBUG : pacer: Reducing sleep to 53.497969ms 2026/08/25 02:51:22 DEBUG : pacer: Reducing sleep to 165.977098ms 2026/08/25 02:51:22 DEBUG : pacer: Reducing sleep to 288.236496ms 2026/08/25 02:51:22 DEBUG : pacer: Reducing sleep to 282.764579ms 2026/08/25 02:51:23 DEBUG : pacer: Reducing sleep to 152.78376ms 2026/08/25 02:51:23 DEBUG : pacer: Reducing sleep to 44.378024ms 2026/08/25 02:51:23 DEBUG : pacer: Reducing sleep to 53.955556ms 2026/08/25 02:51:23 DEBUG : pacer: Reducing sleep to 173.67103ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:727: testing "~leading tilde" 2026/08/25 02:51:28 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:51:28 DEBUG : pacer: Reducing sleep to 88.660951ms 2026/08/25 02:51:28 DEBUG : pacer: Reducing sleep to 160.177066ms 2026/08/25 02:51:28 DEBUG : pacer: Reducing sleep to 149.117709ms 2026/08/25 02:51:28 DEBUG : pacer: Reducing sleep to 231.722885ms 2026/08/25 02:51:32 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:51:32 DEBUG : pacer: Reducing sleep to 106.985454ms 2026/08/25 02:51:33 DEBUG : pacer: Reducing sleep to 90.365427ms 2026/08/25 02:51:33 DEBUG : pacer: Reducing sleep to 155.010368ms 2026/08/25 02:51:33 DEBUG : pacer: Reducing sleep to 226.479626ms 2026/08/25 02:51:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:727: testing "␍leading CR" 2026/08/25 02:51:42 DEBUG : pacer: Reducing sleep to 64.072369ms 2026/08/25 02:51:42 DEBUG : pacer: Reducing sleep to 189.980328ms 2026/08/25 02:51:42 DEBUG : pacer: Reducing sleep to 209.759637ms 2026/08/25 02:51:42 DEBUG : pacer: Reducing sleep to 258.129906ms 2026/08/25 02:51:46 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:51:46 DEBUG : pacer: Reducing sleep to 110.565716ms 2026/08/25 02:51:46 DEBUG : pacer: Reducing sleep to 62.757596ms 2026/08/25 02:51:47 DEBUG : pacer: Reducing sleep to 125.058252ms 2026/08/25 02:51:47 DEBUG : pacer: Reducing sleep to 224.255782ms 2026/08/25 02:51:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:727: testing "␊leading LF" 2026/08/25 02:51:55 DEBUG : pacer: Reducing sleep to 99.677678ms 2026/08/25 02:51:55 DEBUG : pacer: Reducing sleep to 220.813896ms 2026/08/25 02:51:56 DEBUG : pacer: Reducing sleep to 148.379454ms 2026/08/25 02:51:56 DEBUG : pacer: Reducing sleep to 231.404514ms 2026/08/25 02:52:00 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:52:00 DEBUG : pacer: Reducing sleep to 103.925107ms 2026/08/25 02:52:00 DEBUG : pacer: Reducing sleep to 102.800364ms 2026/08/25 02:52:00 DEBUG : pacer: Reducing sleep to 188.498111ms 2026/08/25 02:52:00 DEBUG : pacer: Reducing sleep to 232.943169ms 2026/08/25 02:52:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:727: testing "␉leading HT" 2026/08/25 02:52:09 DEBUG : pacer: Reducing sleep to 89.154045ms 2026/08/25 02:52:09 DEBUG : pacer: Reducing sleep to 215.404842ms 2026/08/25 02:52:10 DEBUG : pacer: Reducing sleep to 206.386163ms 2026/08/25 02:52:10 DEBUG : pacer: Reducing sleep to 233.784924ms 2026/08/25 02:52:14 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:52:14 DEBUG : pacer: Reducing sleep to 108.647876ms 2026/08/25 02:52:14 DEBUG : pacer: Reducing sleep to 95.713014ms 2026/08/25 02:52:14 DEBUG : pacer: Reducing sleep to 157.968174ms 2026/08/25 02:52:14 DEBUG : pacer: Reducing sleep to 222.288726ms 2026/08/25 02:52:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:727: testing "␋leading VT" 2026/08/25 02:52:18 DEBUG : pacer: Reducing sleep to 124.494702ms 2026/08/25 02:52:19 DEBUG : pacer: Reducing sleep to 77.580585ms 2026/08/25 02:52:19 DEBUG : pacer: Reducing sleep to 154.228205ms 2026/08/25 02:52:19 DEBUG : pacer: Reducing sleep to 118.814404ms 2026/08/25 02:52:19 DEBUG : pacer: Reducing sleep to 232.723907ms 2026/08/25 02:52:20 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:52:20 DEBUG : zoho root 'rclone-test-kujemev2zemi': folder listing limiter sweep: evicted 3 of 11 idle limiters 2026/08/25 02:52:20 DEBUG : pacer: Reducing sleep to 118.525587ms 2026/08/25 02:52:20 DEBUG : pacer: Reducing sleep to 85.66574ms 2026/08/25 02:52:21 DEBUG : pacer: Reducing sleep to 149.074801ms 2026/08/25 02:52:21 DEBUG : pacer: Reducing sleep to 214.940461ms 2026/08/25 02:52:21 DEBUG : pacer: Reducing sleep to 251.98587ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:727: testing ".leading dot" 2026/08/25 02:52:22 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:52:22 DEBUG : pacer: Reducing sleep to 100.715779ms 2026/08/25 02:52:22 DEBUG : pacer: Reducing sleep to 89.411356ms 2026/08/25 02:52:22 DEBUG : pacer: Reducing sleep to 80.230341ms 2026/08/25 02:52:22 DEBUG : pacer: Reducing sleep to 200.612887ms 2026/08/25 02:52:22 DEBUG : pacer: Reducing sleep to 279.712518ms 2026/08/25 02:52:22 DEBUG : pacer: Reducing sleep to 253.608708ms 2026/08/25 02:52:23 DEBUG : pacer: Reducing sleep to 120.129759ms 2026/08/25 02:52:23 DEBUG : pacer: Reducing sleep to 52.424923ms 2026/08/25 02:52:23 DEBUG : pacer: Reducing sleep to 76.190104ms 2026/08/25 02:52:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:727: testing "trailing space " 2026/08/25 02:52:31 DEBUG : pacer: Reducing sleep to 89.405471ms 2026/08/25 02:52:31 DEBUG : pacer: Reducing sleep to 215.511486ms 2026/08/25 02:52:32 DEBUG : pacer: Reducing sleep to 119.8406ms 2026/08/25 02:52:32 DEBUG : pacer: Reducing sleep to 225.858021ms 2026/08/25 02:52:36 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:52:36 DEBUG : pacer: Reducing sleep to 114.753116ms 2026/08/25 02:52:36 DEBUG : pacer: Reducing sleep to 102.05312ms 2026/08/25 02:52:36 DEBUG : pacer: Reducing sleep to 154.819364ms 2026/08/25 02:52:36 DEBUG : pacer: Reducing sleep to 220.774955ms 2026/08/25 02:52:41 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:727: testing "trailing CR␍" 2026/08/25 02:52:45 DEBUG : pacer: Reducing sleep to 91.119428ms 2026/08/25 02:52:45 DEBUG : pacer: Reducing sleep to 217.039532ms 2026/08/25 02:52:45 DEBUG : pacer: Reducing sleep to 203.237906ms 2026/08/25 02:52:46 DEBUG : pacer: Reducing sleep to 223.58783ms 2026/08/25 02:52:50 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:52:50 DEBUG : pacer: Reducing sleep to 114.530936ms 2026/08/25 02:52:50 DEBUG : pacer: Reducing sleep to 104.929093ms 2026/08/25 02:52:50 DEBUG : pacer: Reducing sleep to 165.050638ms 2026/08/25 02:52:50 DEBUG : pacer: Reducing sleep to 218.05352ms 2026/08/25 02:52:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:727: testing "trailing LF␊" 2026/08/25 02:52:59 DEBUG : pacer: Reducing sleep to 92.636062ms 2026/08/25 02:52:59 DEBUG : pacer: Reducing sleep to 217.952016ms 2026/08/25 02:52:59 DEBUG : pacer: Reducing sleep to 159.340587ms 2026/08/25 02:52:59 DEBUG : pacer: Reducing sleep to 232.175899ms 2026/08/25 02:53:04 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:53:04 DEBUG : pacer: Reducing sleep to 103.69174ms 2026/08/25 02:53:04 DEBUG : pacer: Reducing sleep to 91.423643ms 2026/08/25 02:53:04 DEBUG : pacer: Reducing sleep to 159.690489ms 2026/08/25 02:53:04 DEBUG : pacer: Reducing sleep to 229.769488ms 2026/08/25 02:53:08 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:727: testing "trailing HT␉" 2026/08/25 02:53:13 DEBUG : pacer: Reducing sleep to 89.98136ms 2026/08/25 02:53:13 DEBUG : pacer: Reducing sleep to 211.241114ms 2026/08/25 02:53:13 DEBUG : pacer: Reducing sleep to 216.296901ms 2026/08/25 02:53:13 DEBUG : pacer: Reducing sleep to 241.150121ms 2026/08/25 02:53:17 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:53:18 DEBUG : pacer: Reducing sleep to 105.797398ms 2026/08/25 02:53:18 DEBUG : pacer: Reducing sleep to 77.65725ms 2026/08/25 02:53:18 DEBUG : pacer: Reducing sleep to 159.425943ms 2026/08/25 02:53:18 DEBUG : pacer: Reducing sleep to 231.405963ms 2026/08/25 02:53:18 DEBUG : pacer: Reducing sleep to 258.864814ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:727: testing "trailing VT␋" 2026/08/25 02:53:19 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:53:19 DEBUG : pacer: Reducing sleep to 100.668162ms 2026/08/25 02:53:19 DEBUG : pacer: Reducing sleep to 74.409719ms 2026/08/25 02:53:19 DEBUG : pacer: Reducing sleep to 4.629793ms 2026/08/25 02:53:19 DEBUG : pacer: Reducing sleep to 123.912264ms 2026/08/25 02:53:20 DEBUG : pacer: Reducing sleep to 222.702299ms 2026/08/25 02:53:20 DEBUG : pacer: Reducing sleep to 278.942989ms 2026/08/25 02:53:20 DEBUG : pacer: Reducing sleep to 219.407979ms 2026/08/25 02:53:20 DEBUG : pacer: Reducing sleep to 117.124107ms 2026/08/25 02:53:20 DEBUG : pacer: Reducing sleep to 58.260896ms 2026/08/25 02:53:20 DEBUG : zoho root 'rclone-test-kujemev2zemi': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/08/25 02:53:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:727: testing "trailing dot." 2026/08/25 02:53:21 DEBUG : pacer: Reducing sleep to 81.78747ms 2026/08/25 02:53:21 DEBUG : pacer: Reducing sleep to 208.337133ms 2026/08/25 02:53:22 DEBUG : pacer: Reducing sleep to 207.223961ms 2026/08/25 02:53:22 DEBUG : pacer: Reducing sleep to 233.501291ms 2026/08/25 02:53:26 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:53:26 DEBUG : pacer: Reducing sleep to 110.007116ms 2026/08/25 02:53:26 DEBUG : pacer: Reducing sleep to 92.901266ms 2026/08/25 02:53:26 DEBUG : pacer: Reducing sleep to 156.159357ms 2026/08/25 02:53:26 DEBUG : pacer: Reducing sleep to 221.532862ms 2026/08/25 02:53:30 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:718: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:727: testing "test%46.txt" 2026/08/25 02:53:35 DEBUG : pacer: Reducing sleep to 96.848979ms 2026/08/25 02:53:35 DEBUG : pacer: Reducing sleep to 218.760144ms 2026/08/25 02:53:35 DEBUG : pacer: Reducing sleep to 141.154985ms 2026/08/25 02:53:36 DEBUG : pacer: Reducing sleep to 227.978714ms 2026/08/25 02:53:40 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:53:40 DEBUG : pacer: Reducing sleep to 116.354082ms 2026/08/25 02:53:40 DEBUG : pacer: Reducing sleep to 78.226899ms 2026/08/25 02:53:40 DEBUG : pacer: Reducing sleep to 159.435616ms 2026/08/25 02:53:40 DEBUG : pacer: Reducing sleep to 232.096594ms 2026/08/25 02:53:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/08/25 02:54:03 DEBUG : pacer: Reducing sleep to 94.375201ms 2026/08/25 02:54:03 DEBUG : pacer: Reducing sleep to 220.344619ms 2026/08/25 02:54:03 DEBUG : pacer: Reducing sleep to 252.549816ms 2026/08/25 02:54:03 DEBUG : pacer: Reducing sleep to 237.115738ms 2026/08/25 02:54:04 DEBUG : pacer: Reducing sleep to 113.247686ms 2026/08/25 02:54:04 DEBUG : pacer: Reducing sleep to 78.318225ms 2026/08/25 02:54:04 DEBUG : pacer: Reducing sleep to 87.270835ms 2026/08/25 02:54:04 DEBUG : pacer: Reducing sleep to 253.34483ms 2026/08/25 02:54:04 DEBUG : pacer: Reducing sleep to 291.121729ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/08/25 02:54:07 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:54:08 DEBUG : pacer: Reducing sleep to 35.079864ms 2026/08/25 02:54:12 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:54:13 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:54:12 UTC": parsing time "Tue, 25 Aug 2026 02:54:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:54:12 UTC" as " " 2026/08/25 02:54:13 DEBUG : pacer: Reducing sleep to 111.711556ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:806: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:839: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:892: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/08/25 02:54:17 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:54:21 DEBUG : zoho root 'rclone-test-kujemev2zemi': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/08/25 02:54:21 DEBUG : pacer: Reducing sleep to 124.173256ms 2026/08/25 02:54:21 DEBUG : pacer: Reducing sleep to 156.924316ms 2026/08/25 02:54:21 DEBUG : pacer: Reducing sleep to 267.492473ms 2026/08/25 02:54:22 DEBUG : pacer: Reducing sleep to 286.532533ms 2026/08/25 02:54:22 DEBUG : pacer: Reducing sleep to 68.189724ms 2026/08/25 02:54:22 DEBUG : pacer: Reducing sleep to 60.788474ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/08/25 02:54:22 DEBUG : pacer: Reducing sleep to 160.528165ms 2026/08/25 02:54:22 DEBUG : pacer: Reducing sleep to 263.633141ms 2026/08/25 02:54:22 DEBUG : pacer: Reducing sleep to 267.930753ms 2026/08/25 02:54:23 DEBUG : pacer: Reducing sleep to 167.303049ms 2026/08/25 02:54:23 DEBUG : pacer: Reducing sleep to 71.759515ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/08/25 02:54:23 DEBUG : pacer: Reducing sleep to 65.414451ms 2026/08/25 02:54:23 DEBUG : pacer: Reducing sleep to 162.862525ms 2026/08/25 02:54:23 DEBUG : pacer: Reducing sleep to 258.575621ms 2026/08/25 02:54:23 DEBUG : pacer: Reducing sleep to 271.383354ms 2026/08/25 02:54:24 DEBUG : pacer: Reducing sleep to 161.773739ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/08/25 02:54:24 DEBUG : pacer: Reducing sleep to 70.900685ms 2026/08/25 02:54:24 DEBUG : pacer: Reducing sleep to 71.348577ms 2026/08/25 02:54:24 DEBUG : pacer: Reducing sleep to 170.132053ms 2026/08/25 02:54:24 DEBUG : pacer: Reducing sleep to 257.726371ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/08/25 02:54:24 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/08/25 02:54:29 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/08/25 02:54:34 DEBUG : pacer: Reducing sleep to 83.677843ms 2026/08/25 02:54:34 DEBUG : pacer: Reducing sleep to 205.353815ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/08/25 02:54:38 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:54:38 DEBUG : pacer: Reducing sleep to 119.910318ms 2026/08/25 02:54:38 DEBUG : pacer: Reducing sleep to 127.348633ms 2026/08/25 02:54:39 DEBUG : pacer: Reducing sleep to 176.827849ms 2026/08/25 02:54:39 DEBUG : pacer: Reducing sleep to 213.040686ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/08/25 02:54:43 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1145: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/08/25 02:54:47 DEBUG : pacer: Reducing sleep to 119.912664ms 2026/08/25 02:54:48 DEBUG : pacer: Reducing sleep to 217.731007ms 2026/08/25 02:54:48 DEBUG : pacer: Reducing sleep to 284.10697ms 2026/08/25 02:54:48 DEBUG : pacer: Reducing sleep to 230.381744ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/08/25 02:54:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/08/25 02:54:52 DEBUG : pacer: Reducing sleep to 117.070727ms 2026/08/25 02:54:57 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:54:57 DEBUG : pacer: Reducing sleep to 99.153124ms 2026/08/25 02:54:57 DEBUG : pacer: Reducing sleep to 155.666475ms 2026/08/25 02:54:57 DEBUG : pacer: Reducing sleep to 271.870755ms 2026/08/25 02:55:02 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:02 DEBUG : pacer: Reducing sleep to 113.760717ms 2026/08/25 02:55:02 DEBUG : pacer: Reducing sleep to 244.98017ms 2026/08/25 02:55:02 DEBUG : pacer: Reducing sleep to 236.516328ms 2026/08/25 02:55:02 DEBUG : pacer: Reducing sleep to 152.466073ms 2026/08/25 02:55:02 DEBUG : pacer: Reducing sleep to 86.986613ms 2026/08/25 02:55:02 DEBUG : pacer: Reducing sleep to 96.885495ms 2026/08/25 02:55:03 DEBUG : pacer: Reducing sleep to 174.039721ms 2026/08/25 02:55:06 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:06 DEBUG : pacer: Reducing sleep to 119.43441ms 2026/08/25 02:55:06 DEBUG : pacer: Reducing sleep to 152.38493ms 2026/08/25 02:55:06 DEBUG : pacer: Reducing sleep to 215.370091ms 2026/08/25 02:55:07 DEBUG : pacer: Reducing sleep to 219.75371ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/08/25 02:55:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:15 DEBUG : pacer: Reducing sleep to 92.601144ms 2026/08/25 02:55:16 DEBUG : pacer: Reducing sleep to 71.046846ms 2026/08/25 02:55:16 DEBUG : pacer: Reducing sleep to 190.61013ms 2026/08/25 02:55:20 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:20 DEBUG : pacer: Reducing sleep to 120.867997ms 2026/08/25 02:55:20 DEBUG : pacer: Reducing sleep to 284.601816ms 2026/08/25 02:55:20 DEBUG : pacer: Reducing sleep to 314.981311ms 2026/08/25 02:55:20 DEBUG : pacer: Reducing sleep to 195.637005ms 2026/08/25 02:55:21 DEBUG : pacer: Reducing sleep to 44.492823ms 2026/08/25 02:55:21 DEBUG : Creating backend with remote "TestZoho:rclone-test-kujemev2zemi/dirToPurgeFromRoot" 2026/08/25 02:55:21 DEBUG : Config file has changed externally - reloading 2026/08/25 02:55:21 DEBUG : zoho root 'rclone-test-kujemev2zemi/dirToPurgeFromRoot': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/08/25 02:55:21 DEBUG : pacer: Reducing sleep to 116.310853ms 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 219.171418ms 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 276.387679ms 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 117.503165ms 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 237.421219ms 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 279.908848ms 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 214.767589ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/08/25 02:55:22 DEBUG : Creating backend with remote "TestZoho:rclone-test-kujemev2zemi/hello? sausage/êé" 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 113.256356ms 2026/08/25 02:55:22 DEBUG : pacer: Reducing sleep to 233.918886ms 2026/08/25 02:55:23 DEBUG : pacer: Reducing sleep to 279.441426ms 2026/08/25 02:55:23 DEBUG : pacer: Reducing sleep to 218.79847ms 2026/08/25 02:55:23 DEBUG : pacer: Reducing sleep to 76.661177ms 2026/08/25 02:55:23 DEBUG : pacer: Reducing sleep to 56.507693ms 2026/08/25 02:55:23 DEBUG : pacer: Reducing sleep to 111.086653ms 2026/08/25 02:55:23 DEBUG : pacer: Reducing sleep to 237.358917ms 2026/08/25 02:55:24 DEBUG : pacer: Reducing sleep to 221.310196ms 2026/08/25 02:55:24 DEBUG : pacer: Reducing sleep to 220.351869ms 2026/08/25 02:55:24 DEBUG : pacer: Reducing sleep to 122.569166ms 2026/08/25 02:55:24 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:24 DEBUG : pacer: Reducing sleep to 120.119605ms 2026/08/25 02:55:24 DEBUG : pacer: Reducing sleep to 237.272718ms 2026/08/25 02:55:25 DEBUG : pacer: Reducing sleep to 276.108262ms 2026/08/25 02:55:25 DEBUG : pacer: Reducing sleep to 208.739047ms 2026/08/25 02:55:25 DEBUG : pacer: Reducing sleep to 98.208543ms 2026/08/25 02:55:25 DEBUG : pacer: Reducing sleep to 52.885576ms 2026/08/25 02:55:25 DEBUG : pacer: Reducing sleep to 130.558662ms 2026/08/25 02:55:25 DEBUG : pacer: Reducing sleep to 237.633216ms 2026/08/25 02:55:26 DEBUG : pacer: Reducing sleep to 272.247378ms 2026/08/25 02:55:26 DEBUG : pacer: Reducing sleep to 181.562617ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/08/25 02:55:26 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:26 DEBUG : pacer: Reducing sleep to 104.794338ms 2026/08/25 02:55:26 DEBUG : pacer: Reducing sleep to 220.268472ms 2026/08/25 02:55:26 DEBUG : pacer: Reducing sleep to 289.280664ms 2026/08/25 02:55:29 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:29 DEBUG : pacer: Reducing sleep to 119.263398ms 2026/08/25 02:55:29 DEBUG : pacer: Reducing sleep to 43.041127ms 2026/08/25 02:55:34 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:34 DEBUG : pacer: Reducing sleep to 74.97322ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1318: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/08/25 02:55:38 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:38 DEBUG : pacer: Reducing sleep to 109.144101ms 2026/08/25 02:55:39 DEBUG : pacer: Reducing sleep to 196.594019ms 2026/08/25 02:55:39 DEBUG : pacer: Reducing sleep to 277.670763ms 2026/08/25 02:55:39 DEBUG : pacer: Reducing sleep to 249.998546ms 2026/08/25 02:55:39 DEBUG : pacer: Reducing sleep to 144.011323ms 2026/08/25 02:55:39 DEBUG : pacer: Reducing sleep to 55.82876ms 2026/08/25 02:55:39 DEBUG : pacer: Reducing sleep to 80.738788ms 2026/08/25 02:55:43 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:53 DEBUG : pacer: Reducing sleep to 88.927488ms 2026/08/25 02:55:53 DEBUG : pacer: Reducing sleep to 199.483256ms 2026/08/25 02:55:53 DEBUG : pacer: Reducing sleep to 250.90235ms 2026/08/25 02:55:53 DEBUG : pacer: Reducing sleep to 241.251182ms 2026/08/25 02:55:57 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:55:57 DEBUG : pacer: Reducing sleep to 117.84438ms 2026/08/25 02:55:57 DEBUG : pacer: Reducing sleep to 279.239051ms 2026/08/25 02:55:57 DEBUG : pacer: Reducing sleep to 268.065939ms 2026/08/25 02:55:58 DEBUG : pacer: Reducing sleep to 137.153265ms 2026/08/25 02:55:58 DEBUG : pacer: Reducing sleep to 57.027377ms 2026/08/25 02:55:58 DEBUG : pacer: Reducing sleep to 68.992084ms 2026/08/25 02:55:58 DEBUG : pacer: Reducing sleep to 177.360181ms 2026/08/25 02:55:58 DEBUG : pacer: Reducing sleep to 238.749592ms 2026/08/25 02:55:58 DEBUG : pacer: Reducing sleep to 266.934055ms 2026/08/25 02:56:02 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:56:02 DEBUG : pacer: Reducing sleep to 125.946748ms 2026/08/25 02:56:02 DEBUG : pacer: Reducing sleep to 279.780494ms 2026/08/25 02:56:02 DEBUG : pacer: Reducing sleep to 240.87041ms 2026/08/25 02:56:02 DEBUG : pacer: Reducing sleep to 121.080198ms 2026/08/25 02:56:03 DEBUG : pacer: Reducing sleep to 61.377062ms 2026/08/25 02:56:06 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:56:06 DEBUG : pacer: Reducing sleep to 112.681512ms 2026/08/25 02:56:07 DEBUG : pacer: Reducing sleep to 180.978365ms 2026/08/25 02:56:07 DEBUG : pacer: Reducing sleep to 275.916024ms 2026/08/25 02:56:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:56:11 DEBUG : pacer: Reducing sleep to 121.737751ms 2026/08/25 02:56:11 DEBUG : pacer: Reducing sleep to 288.282747ms 2026/08/25 02:56:11 DEBUG : pacer: Reducing sleep to 232.044083ms 2026/08/25 02:56:12 DEBUG : pacer: Reducing sleep to 84.044157ms 2026/08/25 02:56:12 DEBUG : pacer: Reducing sleep to 41.872276ms 2026/08/25 02:56:12 DEBUG : pacer: Reducing sleep to 118.461679ms 2026/08/25 02:56:12 DEBUG : pacer: Reducing sleep to 232.77443ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1430: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/08/25 02:56:12 DEBUG : Creating backend with remote "TestZoho:rclone-test-fogezoq3luhe" 2026/08/25 02:56:12 DEBUG : pacer: Reducing sleep to 41.435834ms 2026/08/25 02:56:12 DEBUG : pacer: Reducing sleep to 75.677165ms 2026/08/25 02:56:13 DEBUG : pacer: Reducing sleep to 100.657229ms 2026/08/25 02:56:13 DEBUG : pacer: Reducing sleep to 220.703146ms 2026/08/25 02:56:13 DEBUG : pacer: Reducing sleep to 262.155314ms 2026/08/25 02:56:13 DEBUG : pacer: Reducing sleep to 231.635974ms 2026/08/25 02:56:13 DEBUG : pacer: Reducing sleep to 83.197581ms 2026/08/25 02:56:13 DEBUG : pacer: Reducing sleep to 45.818797ms 2026/08/25 02:56:14 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:56:14 DEBUG : pacer: Reducing sleep to 15.712918ms 2026/08/25 02:56:14 DEBUG : pacer: Reducing sleep to 122.228998ms 2026/08/25 02:56:16 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:56:16 DEBUG : pacer: Reducing sleep to 107.111013ms 2026/08/25 02:56:16 DEBUG : pacer: Reducing sleep to 216.664538ms 2026/08/25 02:56:16 DEBUG : pacer: Reducing sleep to 269.920604ms 2026/08/25 02:56:16 DEBUG : pacer: Reducing sleep to 220.186182ms 2026/08/25 02:56:18 DEBUG : pacer: Reducing sleep to 35.242111ms 2026/08/25 02:56:18 DEBUG : pacer: Reducing sleep to 156.26409ms 2026/08/25 02:56:18 DEBUG : pacer: Reducing sleep to 270.601519ms 2026/08/25 02:56:18 DEBUG : pacer: Reducing sleep to 287.065623ms 2026/08/25 02:56:18 DEBUG : pacer: Reducing sleep to 175.335642ms 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 54.783293ms 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 124.433341ms 2026/08/25 02:56:19 DEBUG : zoho root 'rclone-test-fogezoq3luhe': Purge remote 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 111.377557ms 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 148.070177ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 118.792961ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 234.704792ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/08/25 02:56:19 DEBUG : pacer: Reducing sleep to 284.072674ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/08/25 02:56:20 DEBUG : pacer: Reducing sleep to 186.790063ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/08/25 02:56:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1626: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/08/25 02:56:25 DEBUG : zoho root 'rclone-test-kujemev2zemi': folder listing limiter sweep: evicted 4 of 15 idle limiters fstests.go:1657: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1728: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1785: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/08/25 02:56:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:56:49 UTC": parsing time "Tue, 25 Aug 2026 02:56:49 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:56:49 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/08/25 02:56:53 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:56:53 UTC": parsing time "Tue, 25 Aug 2026 02:56:53 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:56:53 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/08/25 02:56:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:56:58 UTC": parsing time "Tue, 25 Aug 2026 02:56:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:56:58 UTC" as " " 2026/08/25 02:56:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:56:58 UTC": parsing time "Tue, 25 Aug 2026 02:56:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:56:58 UTC" as " " 2026/08/25 02:56:58 DEBUG : pacer: Reducing sleep to 1.498678ms 2026/08/25 02:56:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:56:58 UTC": parsing time "Tue, 25 Aug 2026 02:56:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:56:58 UTC" as " " 2026/08/25 02:56:58 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:56:58 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:56:58 UTC": parsing time "Tue, 25 Aug 2026 02:56:58 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:56:58 UTC" as " " 2026/08/25 02:56:58 DEBUG : pacer: Reducing sleep to 12.126857ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/08/25 02:57:02 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:57:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:02 UTC": parsing time "Tue, 25 Aug 2026 02:57:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:02 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/08/25 02:57:07 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:07 UTC": parsing time "Tue, 25 Aug 2026 02:57:07 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:07 UTC" as " " 2026/08/25 02:57:07 DEBUG : pacer: Reducing sleep to 11.595954ms 2026/08/25 02:57:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:57:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:12 UTC": parsing time "Tue, 25 Aug 2026 02:57:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/08/25 02:57:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:12 UTC": parsing time "Tue, 25 Aug 2026 02:57:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:12 UTC" as " " 2026/08/25 02:57:12 DEBUG : pacer: Reducing sleep to 6.543847ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/08/25 02:57:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:12 UTC": parsing time "Tue, 25 Aug 2026 02:57:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:12 UTC" as " " 2026/08/25 02:57:12 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/08/25 02:57:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:12 UTC": parsing time "Tue, 25 Aug 2026 02:57:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/08/25 02:57:12 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:12 UTC": parsing time "Tue, 25 Aug 2026 02:57:12 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:12 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/08/25 02:57:16 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:16 UTC": parsing time "Tue, 25 Aug 2026 02:57:16 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:16 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/08/25 02:57:21 DEBUG : pacer: Reducing sleep to 52.471026ms 2026/08/25 02:57:21 DEBUG : pacer: Reducing sleep to 173.752248ms 2026/08/25 02:57:21 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Tue, 25 Aug 2026 02:57:21 UTC": parsing time "Tue, 25 Aug 2026 02:57:21 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 25 Aug 2026 02:57:21 UTC" as " " 2026/08/25 02:57:21 DEBUG : pacer: Reducing sleep to 156.910569ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/08/25 02:57:21 DEBUG : pacer: Reducing sleep to 275.365033ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/08/25 02:57:21 DEBUG : Creating backend with remote "TestZoho:rclone-test-kujemev2zemi/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/08/25 02:57:21 DEBUG : pacer: Reducing sleep to 114.334645ms 2026/08/25 02:57:21 DEBUG : pacer: Reducing sleep to 231.1422ms 2026/08/25 02:57:21 DEBUG : pacer: Reducing sleep to 279.925902ms 2026/08/25 02:57:22 DEBUG : pacer: Reducing sleep to 216.243101ms 2026/08/25 02:57:22 DEBUG : pacer: Reducing sleep to 94.443656ms 2026/08/25 02:57:22 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:57:22 DEBUG : pacer: Reducing sleep to 109.333621ms 2026/08/25 02:57:22 DEBUG : pacer: Reducing sleep to 225.941018ms 2026/08/25 02:57:22 DEBUG : pacer: Reducing sleep to 278.008181ms 2026/08/25 02:57:23 DEBUG : pacer: Reducing sleep to 221.071866ms 2026/08/25 02:57:23 DEBUG : pacer: Reducing sleep to 102.499464ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/08/25 02:57:23 DEBUG : pacer: Reducing sleep to 51.503106ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/08/25 02:57:23 DEBUG : Creating backend with remote "TestZoho:rclone-test-kujemev2zemi/not found.txt" 2026/08/25 02:57:23 DEBUG : pacer: Reducing sleep to 113.99856ms 2026/08/25 02:57:24 DEBUG : pacer: Reducing sleep to 154.941047ms 2026/08/25 02:57:28 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:57:28 DEBUG : zoho root 'rclone-test-kujemev2zemi/not found.txt': folder listing limiter sweep: evicted 5 of 11 idle limiters 2026/08/25 02:57:33 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1987: Opening root remote "TestZoho:" path "rclone-test-kujemev2zemi" from "TestZoho:rclone-test-kujemev2zemi" 2026/08/25 02:57:33 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/08/25 02:57:37 DEBUG : pacer: Reducing sleep to 118.531619ms 2026/08/25 02:57:37 DEBUG : pacer: Reducing sleep to 240.301553ms 2026/08/25 02:57:38 DEBUG : pacer: Reducing sleep to 281.486069ms 2026/08/25 02:57:38 DEBUG : pacer: Reducing sleep to 210.63837ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/08/25 02:57:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2024: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/08/25 02:57:56 DEBUG : pacer: Reducing sleep to 118.12969ms 2026/08/25 02:57:56 DEBUG : pacer: Reducing sleep to 238.166231ms 2026/08/25 02:57:56 DEBUG : pacer: Reducing sleep to 281.672723ms 2026/08/25 02:57:56 DEBUG : pacer: Reducing sleep to 211.832319ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/08/25 02:57:57 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:58:00 DEBUG : pacer: Reducing sleep to 120.596589ms 2026/08/25 02:58:00 DEBUG : pacer: Reducing sleep to 242.196111ms 2026/08/25 02:58:01 DEBUG : pacer: Reducing sleep to 281.660792ms 2026/08/25 02:58:01 DEBUG : pacer: Reducing sleep to 209.535182ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2083: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:431: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2188: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2204: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/08/25 02:58:05 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:58:10 DEBUG : pacer: Reducing sleep to 103.216532ms 2026/08/25 02:58:10 DEBUG : pacer: Reducing sleep to 220.981394ms 2026/08/25 02:58:10 DEBUG : pacer: Reducing sleep to 279.364165ms 2026/08/25 02:58:10 DEBUG : pacer: Reducing sleep to 225.636706ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/08/25 02:58:10 DEBUG : pacer: Reducing sleep to 105.743694ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2378: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2424: *zoho.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2445: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/08/25 02:58:14 DEBUG : pacer: Reducing sleep to 0s 2026/08/25 02:58:14 DEBUG : pacer: Reducing sleep to 137.092074ms fstests.go:2596: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n