"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/10/07 04:42:47 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/10/07 04:42:47 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/10/07 04:42:47 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/10/07 04:42:47 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/10/07 04:42:47 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/10/07 04:42:47 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/10/07 04:42:47 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/10/07 04:42:47 DEBUG : Creating backend with remote "TestZoho:rclone-test-levewas7hiho" 2026/10/07 04:42:47 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/07 04:42:47 DEBUG : zoho root 'rclone-test-levewas7hiho': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/10/07 04:42:47 DEBUG : TestZoho: Token expired 2026/10/07 04:42:47 DEBUG : TestZoho: No updated token found in the config file 2026/10/07 04:42:47 DEBUG : TestZoho: Token refresh successful 2026/10/07 04:42:47 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/10/07 04:42:47 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/10/07 04:42:49 DEBUG : pacer: Reducing sleep to 17.556929ms 2026/10/07 04:42:49 DEBUG : pacer: Reducing sleep to 15.933161ms 2026/10/07 04:42:49 DEBUG : pacer: Reducing sleep to 135.074522ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/10/07 04:42:49 DEBUG : pacer: Reducing sleep to 249.917316ms 2026/10/07 04:42:49 DEBUG : pacer: Reducing sleep to 266.865437ms === RUN TestIntegration/FsMkdir 2026/10/07 04:42:50 DEBUG : pacer: Reducing sleep to 77.573914ms 2026/10/07 04:42:50 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:42:50 DEBUG : pacer: Reducing sleep to 118.450464ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/10/07 04:42:50 INFO : dir/subdir: Making directory 2026/10/07 04:42:50 DEBUG : pacer: Reducing sleep to 237.705648ms 2026/10/07 04:42:50 DEBUG : pacer: Reducing sleep to 252.04226ms 2026/10/07 04:42:50 DEBUG : pacer: Reducing sleep to 214.277184ms 2026/10/07 04:42:51 DEBUG : pacer: Reducing sleep to 94.687412ms 2026/10/07 04:42:51 DEBUG : pacer: Reducing sleep to 64.644204ms 2026/10/07 04:42:51 DEBUG : pacer: Reducing sleep to 142.451854ms 2026/10/07 04:42:51 DEBUG : pacer: Reducing sleep to 250.309503ms 2026/10/07 04:42:51 INFO : dir/subdir: Removing directory 2026/10/07 04:42:51 DEBUG : pacer: Reducing sleep to 265.793099ms 2026/10/07 04:42:51 DEBUG : pacer: Reducing sleep to 175.957046ms 2026/10/07 04:42:52 DEBUG : pacer: Reducing sleep to 52.709207ms 2026/10/07 04:42:52 DEBUG : pacer: Reducing sleep to 79.333445ms 2026/10/07 04:42:52 INFO : dir: Removing directory 2026/10/07 04:42:52 DEBUG : pacer: Reducing sleep to 186.299369ms 2026/10/07 04:42:52 DEBUG : pacer: Reducing sleep to 265.398907ms 2026/10/07 04:42:52 DEBUG : pacer: Reducing sleep to 258.477508ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/10/07 04:42:52 DEBUG : pacer: Reducing sleep to 160.083288ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/10/07 04:42:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/10/07 04:43:01 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/10/07 04:43:11 DEBUG : pacer: Reducing sleep to 87.46612ms 2026/10/07 04:43:11 DEBUG : pacer: Reducing sleep to 205.692431ms 2026/10/07 04:43:11 DEBUG : pacer: Reducing sleep to 130.68256ms 2026/10/07 04:43:11 DEBUG : pacer: Reducing sleep to 237.579363ms 2026/10/07 04:43:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:43:15 DEBUG : pacer: Reducing sleep to 107.657267ms 2026/10/07 04:43:15 DEBUG : pacer: Reducing sleep to 84.528056ms 2026/10/07 04:43:16 DEBUG : pacer: Reducing sleep to 153.157577ms 2026/10/07 04:43:16 DEBUG : pacer: Reducing sleep to 224.117076ms 2026/10/07 04:43:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/10/07 04:43:25 DEBUG : pacer: Reducing sleep to 91.289747ms 2026/10/07 04:43:25 DEBUG : pacer: Reducing sleep to 211.886593ms 2026/10/07 04:43:25 DEBUG : pacer: Reducing sleep to 197.062919ms 2026/10/07 04:43:25 DEBUG : pacer: Reducing sleep to 231.954208ms 2026/10/07 04:43:29 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:43:29 DEBUG : pacer: Reducing sleep to 107.711037ms 2026/10/07 04:43:29 DEBUG : pacer: Reducing sleep to 94.157771ms 2026/10/07 04:43:29 DEBUG : pacer: Reducing sleep to 158.913408ms 2026/10/07 04:43:30 DEBUG : pacer: Reducing sleep to 224.637903ms 2026/10/07 04:43:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/10/07 04:43:38 DEBUG : pacer: Reducing sleep to 87.037249ms 2026/10/07 04:43:38 DEBUG : pacer: Reducing sleep to 208.89917ms 2026/10/07 04:43:39 DEBUG : pacer: Reducing sleep to 86.874897ms 2026/10/07 04:43:39 DEBUG : pacer: Reducing sleep to 196.795326ms 2026/10/07 04:43:43 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:43:43 DEBUG : pacer: Reducing sleep to 107.774447ms 2026/10/07 04:43:43 DEBUG : pacer: Reducing sleep to 127.791573ms 2026/10/07 04:43:43 DEBUG : pacer: Reducing sleep to 195.646628ms 2026/10/07 04:43:43 DEBUG : pacer: Reducing sleep to 227.38892ms 2026/10/07 04:43:48 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/10/07 04:43:48 DEBUG : zoho root 'rclone-test-levewas7hiho': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/10/07 04:43:52 DEBUG : pacer: Reducing sleep to 88.79707ms 2026/10/07 04:43:52 DEBUG : pacer: Reducing sleep to 207.859136ms 2026/10/07 04:43:53 DEBUG : pacer: Reducing sleep to 109.631914ms 2026/10/07 04:43:53 DEBUG : pacer: Reducing sleep to 223.607788ms 2026/10/07 04:43:53 DEBUG : pacer: Reducing sleep to 277.693318ms 2026/10/07 04:43:53 DEBUG : pacer: Reducing sleep to 220.848309ms 2026/10/07 04:43:53 DEBUG : pacer: Reducing sleep to 100.811444ms 2026/10/07 04:43:53 DEBUG : pacer: Reducing sleep to 55.794344ms 2026/10/07 04:43:54 DEBUG : pacer: Reducing sleep to 97.332266ms 2026/10/07 04:43:54 DEBUG : pacer: Reducing sleep to 210.219966ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/10/07 04:43:54 DEBUG : pacer: Reducing sleep to 284.373572ms 2026/10/07 04:43:54 DEBUG : pacer: Reducing sleep to 217.316905ms 2026/10/07 04:43:54 DEBUG : pacer: Reducing sleep to 122.685521ms 2026/10/07 04:43:54 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:43:55 DEBUG : pacer: Reducing sleep to 114.320763ms 2026/10/07 04:43:55 DEBUG : pacer: Reducing sleep to 226.955024ms 2026/10/07 04:43:55 DEBUG : pacer: Reducing sleep to 278.284072ms 2026/10/07 04:43:55 DEBUG : pacer: Reducing sleep to 207.952371ms 2026/10/07 04:43:55 DEBUG : pacer: Reducing sleep to 107.965876ms 2026/10/07 04:43:55 DEBUG : pacer: Reducing sleep to 55.662381ms 2026/10/07 04:43:56 DEBUG : pacer: Reducing sleep to 121.712519ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/10/07 04:44:00 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:44:00 DEBUG : pacer: Reducing sleep to 79.858073ms 2026/10/07 04:44:00 DEBUG : pacer: Reducing sleep to 195.295726ms 2026/10/07 04:44:01 DEBUG : pacer: Reducing sleep to 82.836064ms 2026/10/07 04:44:01 DEBUG : pacer: Reducing sleep to 195.395845ms 2026/10/07 04:44:05 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:44:05 DEBUG : pacer: Reducing sleep to 108.932351ms 2026/10/07 04:44:05 DEBUG : pacer: Reducing sleep to 128.62957ms 2026/10/07 04:44:05 DEBUG : pacer: Reducing sleep to 197.173133ms 2026/10/07 04:44:05 DEBUG : pacer: Reducing sleep to 227.228528ms 2026/10/07 04:44:09 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/10/07 04:44:14 DEBUG : pacer: Reducing sleep to 88.172108ms 2026/10/07 04:44:14 DEBUG : pacer: Reducing sleep to 207.852051ms 2026/10/07 04:44:14 DEBUG : pacer: Reducing sleep to 194.147449ms 2026/10/07 04:44:14 DEBUG : pacer: Reducing sleep to 237.940086ms 2026/10/07 04:44:19 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:44:19 DEBUG : pacer: Reducing sleep to 105.005629ms 2026/10/07 04:44:19 DEBUG : pacer: Reducing sleep to 90.644766ms 2026/10/07 04:44:19 DEBUG : pacer: Reducing sleep to 156.721473ms 2026/10/07 04:44:19 DEBUG : pacer: Reducing sleep to 227.972406ms 2026/10/07 04:44:23 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/10/07 04:44:28 DEBUG : pacer: Reducing sleep to 92.058333ms 2026/10/07 04:44:28 DEBUG : pacer: Reducing sleep to 208.778527ms 2026/10/07 04:44:28 DEBUG : pacer: Reducing sleep to 190.942934ms 2026/10/07 04:44:28 DEBUG : pacer: Reducing sleep to 233.947382ms 2026/10/07 04:44:32 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:44:32 DEBUG : pacer: Reducing sleep to 107.469639ms 2026/10/07 04:44:33 DEBUG : pacer: Reducing sleep to 88.839105ms 2026/10/07 04:44:33 DEBUG : pacer: Reducing sleep to 160.947516ms 2026/10/07 04:44:33 DEBUG : pacer: Reducing sleep to 228.175333ms 2026/10/07 04:44:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/10/07 04:44:42 DEBUG : pacer: Reducing sleep to 88.06836ms 2026/10/07 04:44:42 DEBUG : pacer: Reducing sleep to 204.004583ms 2026/10/07 04:44:42 DEBUG : pacer: Reducing sleep to 143.42879ms 2026/10/07 04:44:42 DEBUG : pacer: Reducing sleep to 240.855058ms 2026/10/07 04:44:46 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:44:46 DEBUG : pacer: Reducing sleep to 103.766126ms 2026/10/07 04:44:47 DEBUG : pacer: Reducing sleep to 87.519741ms 2026/10/07 04:44:47 DEBUG : pacer: Reducing sleep to 159.309771ms 2026/10/07 04:44:47 DEBUG : pacer: Reducing sleep to 230.726575ms 2026/10/07 04:44:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/10/07 04:44:51 DEBUG : zoho root 'rclone-test-levewas7hiho': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/10/07 04:44:51 DEBUG : pacer: Reducing sleep to 116.826742ms 2026/10/07 04:44:51 DEBUG : pacer: Reducing sleep to 76.276173ms 2026/10/07 04:44:51 DEBUG : pacer: Reducing sleep to 152.459717ms 2026/10/07 04:44:51 DEBUG : pacer: Reducing sleep to 148.480942ms 2026/10/07 04:44:51 DEBUG : pacer: Reducing sleep to 248.941637ms 2026/10/07 04:44:53 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:44:53 DEBUG : pacer: Reducing sleep to 112.91188ms 2026/10/07 04:44:53 DEBUG : pacer: Reducing sleep to 73.478871ms 2026/10/07 04:44:53 DEBUG : pacer: Reducing sleep to 138.299993ms 2026/10/07 04:44:53 DEBUG : pacer: Reducing sleep to 217.446419ms 2026/10/07 04:44:53 DEBUG : pacer: Reducing sleep to 265.88939ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/10/07 04:44:54 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:44:54 DEBUG : pacer: Reducing sleep to 91.618553ms 2026/10/07 04:44:54 DEBUG : pacer: Reducing sleep to 67.989217ms 2026/10/07 04:44:55 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:44:55 DEBUG : pacer: Reducing sleep to 110.518613ms 2026/10/07 04:44:55 DEBUG : pacer: Reducing sleep to 221.424117ms 2026/10/07 04:44:55 DEBUG : pacer: Reducing sleep to 278.966548ms 2026/10/07 04:44:55 DEBUG : pacer: Reducing sleep to 208.531738ms 2026/10/07 04:44:55 DEBUG : pacer: Reducing sleep to 110.43133ms 2026/10/07 04:44:56 DEBUG : pacer: Reducing sleep to 4.651914ms 2026/10/07 04:44:59 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/10/07 04:45:04 DEBUG : pacer: Reducing sleep to 89.242926ms 2026/10/07 04:45:04 DEBUG : pacer: Reducing sleep to 203.956209ms 2026/10/07 04:45:04 DEBUG : pacer: Reducing sleep to 185.160117ms 2026/10/07 04:45:04 DEBUG : pacer: Reducing sleep to 239.491806ms 2026/10/07 04:45:09 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:45:09 DEBUG : pacer: Reducing sleep to 104.16078ms 2026/10/07 04:45:09 DEBUG : pacer: Reducing sleep to 95.13776ms 2026/10/07 04:45:09 DEBUG : pacer: Reducing sleep to 163.286411ms 2026/10/07 04:45:09 DEBUG : pacer: Reducing sleep to 228.799768ms 2026/10/07 04:45:13 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/10/07 04:45:18 DEBUG : pacer: Reducing sleep to 83.968087ms 2026/10/07 04:45:18 DEBUG : pacer: Reducing sleep to 202.456065ms 2026/10/07 04:45:18 DEBUG : pacer: Reducing sleep to 117.825631ms 2026/10/07 04:45:18 DEBUG : pacer: Reducing sleep to 227.788216ms 2026/10/07 04:45:22 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:45:22 DEBUG : pacer: Reducing sleep to 109.070929ms 2026/10/07 04:45:23 DEBUG : pacer: Reducing sleep to 103.039754ms 2026/10/07 04:45:23 DEBUG : pacer: Reducing sleep to 171.22312ms 2026/10/07 04:45:23 DEBUG : pacer: Reducing sleep to 228.045689ms 2026/10/07 04:45:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/10/07 04:45:32 DEBUG : pacer: Reducing sleep to 88.19185ms 2026/10/07 04:45:32 DEBUG : pacer: Reducing sleep to 205.047998ms 2026/10/07 04:45:32 DEBUG : pacer: Reducing sleep to 204.308761ms 2026/10/07 04:45:32 DEBUG : pacer: Reducing sleep to 237.774863ms 2026/10/07 04:45:36 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:45:36 DEBUG : pacer: Reducing sleep to 106.35511ms 2026/10/07 04:45:37 DEBUG : pacer: Reducing sleep to 78.859911ms 2026/10/07 04:45:37 DEBUG : pacer: Reducing sleep to 155.84812ms 2026/10/07 04:45:37 DEBUG : pacer: Reducing sleep to 233.668219ms 2026/10/07 04:45:41 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/10/07 04:45:46 DEBUG : pacer: Reducing sleep to 64.948596ms 2026/10/07 04:45:46 DEBUG : pacer: Reducing sleep to 182.036952ms 2026/10/07 04:45:46 DEBUG : pacer: Reducing sleep to 111.77095ms 2026/10/07 04:45:46 DEBUG : pacer: Reducing sleep to 219.001716ms 2026/10/07 04:45:50 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:45:50 DEBUG : pacer: Reducing sleep to 102.318069ms 2026/10/07 04:45:50 DEBUG : pacer: Reducing sleep to 105.062447ms 2026/10/07 04:45:50 DEBUG : pacer: Reducing sleep to 176.362024ms 2026/10/07 04:45:51 DEBUG : pacer: Reducing sleep to 229.523347ms 2026/10/07 04:45:51 DEBUG : pacer: Reducing sleep to 229.995766ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/10/07 04:45:51 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:45:51 DEBUG : pacer: Reducing sleep to 84.38439ms 2026/10/07 04:45:51 DEBUG : zoho root 'rclone-test-levewas7hiho': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/10/07 04:45:52 DEBUG : pacer: Reducing sleep to 97.507604ms 2026/10/07 04:45:52 DEBUG : pacer: Reducing sleep to 105.756665ms 2026/10/07 04:45:52 DEBUG : pacer: Reducing sleep to 216.583688ms 2026/10/07 04:45:52 DEBUG : pacer: Reducing sleep to 277.358305ms 2026/10/07 04:45:52 DEBUG : pacer: Reducing sleep to 222.386999ms 2026/10/07 04:45:52 DEBUG : pacer: Reducing sleep to 99.539297ms 2026/10/07 04:45:53 DEBUG : pacer: Reducing sleep to 58.529098ms 2026/10/07 04:45:53 DEBUG : pacer: Reducing sleep to 110.85267ms 2026/10/07 04:45:53 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/10/07 04:45:54 DEBUG : pacer: Reducing sleep to 67.487599ms 2026/10/07 04:45:54 DEBUG : pacer: Reducing sleep to 188.27066ms 2026/10/07 04:45:54 DEBUG : pacer: Reducing sleep to 93.4561ms 2026/10/07 04:45:54 DEBUG : pacer: Reducing sleep to 204.321435ms 2026/10/07 04:45:58 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:45:58 DEBUG : pacer: Reducing sleep to 80.255455ms 2026/10/07 04:45:58 DEBUG : pacer: Reducing sleep to 116.88682ms 2026/10/07 04:45:59 DEBUG : pacer: Reducing sleep to 207.967874ms 2026/10/07 04:45:59 DEBUG : pacer: Reducing sleep to 253.040986ms 2026/10/07 04:46:03 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/10/07 04:46:08 DEBUG : pacer: Reducing sleep to 36.425023ms 2026/10/07 04:46:08 DEBUG : pacer: Reducing sleep to 153.700611ms 2026/10/07 04:46:08 DEBUG : pacer: Reducing sleep to 192.54295ms 2026/10/07 04:46:08 DEBUG : pacer: Reducing sleep to 278.447359ms 2026/10/07 04:46:12 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:46:12 DEBUG : pacer: Reducing sleep to 106.11258ms 2026/10/07 04:46:12 DEBUG : pacer: Reducing sleep to 32.292907ms 2026/10/07 04:46:12 DEBUG : pacer: Reducing sleep to 114.456083ms 2026/10/07 04:46:13 DEBUG : pacer: Reducing sleep to 218.920935ms 2026/10/07 04:46:17 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/10/07 04:46:35 DEBUG : pacer: Reducing sleep to 86.859532ms 2026/10/07 04:46:35 DEBUG : pacer: Reducing sleep to 206.259489ms 2026/10/07 04:46:35 DEBUG : pacer: Reducing sleep to 253.294632ms 2026/10/07 04:46:36 DEBUG : pacer: Reducing sleep to 238.776764ms 2026/10/07 04:46:36 DEBUG : pacer: Reducing sleep to 121.285133ms 2026/10/07 04:46:36 DEBUG : pacer: Reducing sleep to 85.072465ms 2026/10/07 04:46:36 DEBUG : pacer: Reducing sleep to 99.076817ms 2026/10/07 04:46:36 DEBUG : pacer: Reducing sleep to 258.115982ms 2026/10/07 04:46:36 DEBUG : pacer: Reducing sleep to 277.93381ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/10/07 04:46:40 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:46:40 DEBUG : pacer: Reducing sleep to 21.286219ms 2026/10/07 04:46:44 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:46:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:46:45 UTC": parsing time "Wed, 07 Oct 2026 04:46:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:46:45 UTC" as " " 2026/10/07 04:46:45 DEBUG : pacer: Reducing sleep to 59.686799ms === 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/10/07 04:46:49 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:46:49 DEBUG : pacer: Reducing sleep to 701.173µs 2026/10/07 04:46:54 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:46:54 DEBUG : zoho root 'rclone-test-levewas7hiho': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/10/07 04:46:54 DEBUG : pacer: Reducing sleep to 119.610634ms 2026/10/07 04:46:54 DEBUG : pacer: Reducing sleep to 31.275677ms 2026/10/07 04:46:54 DEBUG : pacer: Reducing sleep to 141.783565ms 2026/10/07 04:46:54 DEBUG : pacer: Reducing sleep to 252.10961ms 2026/10/07 04:46:54 DEBUG : pacer: Reducing sleep to 207.835249ms 2026/10/07 04:46:54 DEBUG : pacer: Reducing sleep to 187.971254ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/10/07 04:46:55 DEBUG : pacer: Reducing sleep to 146.015272ms 2026/10/07 04:46:55 DEBUG : pacer: Reducing sleep to 120.541105ms 2026/10/07 04:46:55 DEBUG : pacer: Reducing sleep to 143.954728ms 2026/10/07 04:46:55 DEBUG : pacer: Reducing sleep to 188.938688ms 2026/10/07 04:46:55 DEBUG : pacer: Reducing sleep to 212.377238ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/10/07 04:46:55 DEBUG : pacer: Reducing sleep to 183.027345ms 2026/10/07 04:46:56 DEBUG : pacer: Reducing sleep to 141.700039ms 2026/10/07 04:46:56 DEBUG : pacer: Reducing sleep to 125.630568ms 2026/10/07 04:46:56 DEBUG : pacer: Reducing sleep to 151.135963ms 2026/10/07 04:46:56 DEBUG : pacer: Reducing sleep to 184.751467ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/10/07 04:46:56 DEBUG : pacer: Reducing sleep to 205.120363ms 2026/10/07 04:46:56 DEBUG : pacer: Reducing sleep to 184.561056ms 2026/10/07 04:46:57 DEBUG : pacer: Reducing sleep to 149.863576ms 2026/10/07 04:46:57 DEBUG : pacer: Reducing sleep to 125.554458ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/10/07 04:46:57 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/10/07 04:47:01 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/10/07 04:47:06 DEBUG : pacer: Reducing sleep to 113.567002ms 2026/10/07 04:47:06 DEBUG : pacer: Reducing sleep to 224.084208ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/10/07 04:47:11 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:11 DEBUG : pacer: Reducing sleep to 112.360465ms 2026/10/07 04:47:11 DEBUG : pacer: Reducing sleep to 108.515673ms 2026/10/07 04:47:11 DEBUG : pacer: Reducing sleep to 160.310389ms 2026/10/07 04:47:11 DEBUG : pacer: Reducing sleep to 218.41048ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/10/07 04:47:15 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/10/07 04:47:20 DEBUG : pacer: Reducing sleep to 114.0369ms 2026/10/07 04:47:20 DEBUG : pacer: Reducing sleep to 223.987816ms 2026/10/07 04:47:20 DEBUG : pacer: Reducing sleep to 277.989458ms 2026/10/07 04:47:20 DEBUG : pacer: Reducing sleep to 216.634494ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/10/07 04:47:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/10/07 04:47:25 DEBUG : pacer: Reducing sleep to 111.299311ms 2026/10/07 04:47:29 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:29 DEBUG : pacer: Reducing sleep to 85.540107ms 2026/10/07 04:47:30 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:30 DEBUG : pacer: Reducing sleep to 110.41508ms 2026/10/07 04:47:34 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:34 DEBUG : pacer: Reducing sleep to 107.766591ms 2026/10/07 04:47:34 DEBUG : pacer: Reducing sleep to 274.298432ms 2026/10/07 04:47:34 DEBUG : pacer: Reducing sleep to 385.226311ms 2026/10/07 04:47:34 DEBUG : pacer: Reducing sleep to 277.786777ms 2026/10/07 04:47:35 DEBUG : pacer: Reducing sleep to 53.523196ms 2026/10/07 04:47:35 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:35 DEBUG : pacer: Reducing sleep to 100.961282ms 2026/10/07 04:47:39 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:39 DEBUG : pacer: Reducing sleep to 113.755839ms 2026/10/07 04:47:39 DEBUG : pacer: Reducing sleep to 224.024714ms 2026/10/07 04:47:39 DEBUG : pacer: Reducing sleep to 281.017613ms 2026/10/07 04:47:39 DEBUG : pacer: Reducing sleep to 211.826412ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/10/07 04:47:43 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:48 DEBUG : pacer: Reducing sleep to 93.395416ms 2026/10/07 04:47:48 DEBUG : pacer: Reducing sleep to 95.490404ms 2026/10/07 04:47:48 DEBUG : pacer: Reducing sleep to 206.142995ms 2026/10/07 04:47:52 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:52 DEBUG : pacer: Reducing sleep to 111.201316ms 2026/10/07 04:47:52 DEBUG : pacer: Reducing sleep to 277.513321ms 2026/10/07 04:47:53 DEBUG : pacer: Reducing sleep to 292.237575ms 2026/10/07 04:47:53 DEBUG : pacer: Reducing sleep to 181.199681ms 2026/10/07 04:47:53 DEBUG : pacer: Reducing sleep to 52.570827ms 2026/10/07 04:47:53 DEBUG : Creating backend with remote "TestZoho:rclone-test-levewas7hiho/dirToPurgeFromRoot" 2026/10/07 04:47:53 DEBUG : Config file has changed externally - reloading 2026/10/07 04:47:54 DEBUG : pacer: Reducing sleep to 93.680957ms 2026/10/07 04:47:54 DEBUG : pacer: Reducing sleep to 206.132651ms 2026/10/07 04:47:54 DEBUG : pacer: Reducing sleep to 262.674788ms 2026/10/07 04:47:54 DEBUG : zoho root 'rclone-test-levewas7hiho': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/10/07 04:47:54 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:54 DEBUG : pacer: Reducing sleep to 107.970545ms 2026/10/07 04:47:54 DEBUG : pacer: Reducing sleep to 220.340346ms 2026/10/07 04:47:54 DEBUG : pacer: Reducing sleep to 274.293635ms 2026/10/07 04:47:54 DEBUG : pacer: Reducing sleep to 223.96212ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/10/07 04:47:54 DEBUG : Creating backend with remote "TestZoho:rclone-test-levewas7hiho/hello? sausage/êé" 2026/10/07 04:47:55 DEBUG : pacer: Reducing sleep to 110.755564ms 2026/10/07 04:47:55 DEBUG : pacer: Reducing sleep to 225.867171ms 2026/10/07 04:47:55 DEBUG : pacer: Reducing sleep to 275.213801ms 2026/10/07 04:47:55 DEBUG : pacer: Reducing sleep to 218.419013ms 2026/10/07 04:47:55 DEBUG : pacer: Reducing sleep to 80.544241ms 2026/10/07 04:47:55 DEBUG : pacer: Reducing sleep to 64.910106ms 2026/10/07 04:47:56 DEBUG : pacer: Reducing sleep to 84.402852ms 2026/10/07 04:47:56 DEBUG : pacer: Reducing sleep to 201.887559ms 2026/10/07 04:47:56 DEBUG : pacer: Reducing sleep to 248.747235ms 2026/10/07 04:47:56 DEBUG : pacer: Reducing sleep to 249.13532ms 2026/10/07 04:47:56 DEBUG : pacer: Reducing sleep to 126.663319ms 2026/10/07 04:47:57 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:57 DEBUG : pacer: Reducing sleep to 110.713605ms 2026/10/07 04:47:57 DEBUG : pacer: Reducing sleep to 220.673745ms 2026/10/07 04:47:57 DEBUG : pacer: Reducing sleep to 269.209083ms 2026/10/07 04:47:57 DEBUG : pacer: Reducing sleep to 220.301315ms 2026/10/07 04:47:57 DEBUG : pacer: Reducing sleep to 113.001801ms 2026/10/07 04:47:57 DEBUG : pacer: Reducing sleep to 65.596221ms 2026/10/07 04:47:58 DEBUG : pacer: Reducing sleep to 103.184731ms 2026/10/07 04:47:58 DEBUG : pacer: Reducing sleep to 207.919737ms 2026/10/07 04:47:58 DEBUG : pacer: Reducing sleep to 277.25617ms 2026/10/07 04:47:58 DEBUG : pacer: Reducing sleep to 224.271234ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/10/07 04:47:58 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:47:58 DEBUG : pacer: Reducing sleep to 77.234975ms 2026/10/07 04:47:58 DEBUG : pacer: Reducing sleep to 191.272394ms 2026/10/07 04:47:58 DEBUG : pacer: Reducing sleep to 272.626878ms 2026/10/07 04:48:01 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:01 DEBUG : pacer: Reducing sleep to 108.738545ms 2026/10/07 04:48:02 DEBUG : pacer: Reducing sleep to 51.2574ms 2026/10/07 04:48:06 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/10/07 04:48:11 DEBUG : pacer: Reducing sleep to 93.055555ms 2026/10/07 04:48:11 DEBUG : pacer: Reducing sleep to 168.627339ms 2026/10/07 04:48:11 DEBUG : pacer: Reducing sleep to 267.84152ms 2026/10/07 04:48:11 DEBUG : pacer: Reducing sleep to 275.476878ms 2026/10/07 04:48:11 DEBUG : pacer: Reducing sleep to 171.06354ms 2026/10/07 04:48:12 DEBUG : pacer: Reducing sleep to 32.861288ms 2026/10/07 04:48:12 DEBUG : pacer: Reducing sleep to 49.447244ms 2026/10/07 04:48:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:25 DEBUG : pacer: Reducing sleep to 91.420085ms 2026/10/07 04:48:25 DEBUG : pacer: Reducing sleep to 191.692274ms 2026/10/07 04:48:25 DEBUG : pacer: Reducing sleep to 227.092461ms 2026/10/07 04:48:25 DEBUG : pacer: Reducing sleep to 242.701911ms 2026/10/07 04:48:29 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:29 DEBUG : pacer: Reducing sleep to 114.703426ms 2026/10/07 04:48:29 DEBUG : pacer: Reducing sleep to 276.540422ms 2026/10/07 04:48:30 DEBUG : pacer: Reducing sleep to 57.701279ms 2026/10/07 04:48:30 DEBUG : pacer: Reducing sleep to 48.24278ms 2026/10/07 04:48:30 DEBUG : pacer: Reducing sleep to 159.316443ms 2026/10/07 04:48:30 DEBUG : pacer: Reducing sleep to 246.027747ms 2026/10/07 04:48:30 DEBUG : pacer: Reducing sleep to 270.743722ms 2026/10/07 04:48:31 DEBUG : pacer: Reducing sleep to 175.999076ms 2026/10/07 04:48:31 DEBUG : pacer: Reducing sleep to 85.079824ms 2026/10/07 04:48:34 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:34 DEBUG : pacer: Reducing sleep to 118.612244ms 2026/10/07 04:48:34 DEBUG : pacer: Reducing sleep to 278.160181ms 2026/10/07 04:48:34 DEBUG : pacer: Reducing sleep to 390.136657ms 2026/10/07 04:48:34 DEBUG : pacer: Reducing sleep to 170.15736ms 2026/10/07 04:48:35 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:39 DEBUG : pacer: Reducing sleep to 103.024344ms 2026/10/07 04:48:39 DEBUG : pacer: Reducing sleep to 185.798487ms 2026/10/07 04:48:39 DEBUG : pacer: Reducing sleep to 263.930112ms 2026/10/07 04:48:43 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:43 DEBUG : pacer: Reducing sleep to 118.30693ms 2026/10/07 04:48:43 DEBUG : pacer: Reducing sleep to 280.986919ms 2026/10/07 04:48:43 DEBUG : pacer: Reducing sleep to 231.387829ms 2026/10/07 04:48:44 DEBUG : pacer: Reducing sleep to 118.128114ms 2026/10/07 04:48:44 DEBUG : pacer: Reducing sleep to 51.112201ms 2026/10/07 04:48:44 DEBUG : pacer: Reducing sleep to 105.591729ms 2026/10/07 04:48:44 DEBUG : pacer: Reducing sleep to 206.764226ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/10/07 04:48:44 DEBUG : Creating backend with remote "TestZoho:rclone-test-piyegek1kuyu" 2026/10/07 04:48:45 DEBUG : pacer: Reducing sleep to 17.956571ms 2026/10/07 04:48:45 DEBUG : pacer: Reducing sleep to 45.123491ms 2026/10/07 04:48:45 DEBUG : pacer: Reducing sleep to 62.468216ms 2026/10/07 04:48:45 DEBUG : pacer: Reducing sleep to 177.927659ms 2026/10/07 04:48:45 DEBUG : pacer: Reducing sleep to 251.350745ms 2026/10/07 04:48:46 DEBUG : pacer: Reducing sleep to 267.594612ms 2026/10/07 04:48:46 DEBUG : pacer: Reducing sleep to 161.379838ms 2026/10/07 04:48:46 DEBUG : pacer: Reducing sleep to 65.419976ms 2026/10/07 04:48:46 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:46 DEBUG : pacer: Reducing sleep to 40.981421ms 2026/10/07 04:48:46 DEBUG : pacer: Reducing sleep to 148.491455ms 2026/10/07 04:48:48 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:48 DEBUG : pacer: Reducing sleep to 112.344962ms 2026/10/07 04:48:48 DEBUG : pacer: Reducing sleep to 185.152816ms 2026/10/07 04:48:48 DEBUG : pacer: Reducing sleep to 242.943816ms 2026/10/07 04:48:48 DEBUG : pacer: Reducing sleep to 216.624421ms 2026/10/07 04:48:50 DEBUG : pacer: Reducing sleep to 19.838253ms 2026/10/07 04:48:50 DEBUG : pacer: Reducing sleep to 125.439693ms 2026/10/07 04:48:50 DEBUG : pacer: Reducing sleep to 237.344424ms 2026/10/07 04:48:50 DEBUG : pacer: Reducing sleep to 275.051215ms 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 205.544927ms 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 92.457146ms 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 109.40347ms 2026/10/07 04:48:51 DEBUG : zoho root 'rclone-test-piyegek1kuyu': Purge remote 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 116.419253ms 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 153.90254ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 107.334431ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/10/07 04:48:51 DEBUG : pacer: Reducing sleep to 217.240159ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/10/07 04:48:52 DEBUG : pacer: Reducing sleep to 240.539227ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/10/07 04:48:52 DEBUG : pacer: Reducing sleep to 225.087959ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/10/07 04:48:53 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/10/07 04:48:58 DEBUG : zoho root 'rclone-test-levewas7hiho': 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/10/07 04:49:21 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:21 UTC": parsing time "Wed, 07 Oct 2026 04:49:21 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:21 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/10/07 04:49:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:25 UTC": parsing time "Wed, 07 Oct 2026 04:49:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:25 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/10/07 04:49:30 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:30 UTC": parsing time "Wed, 07 Oct 2026 04:49:30 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:30 UTC" as " " 2026/10/07 04:49:30 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:30 UTC": parsing time "Wed, 07 Oct 2026 04:49:30 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:30 UTC" as " " 2026/10/07 04:49:31 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:31 UTC": parsing time "Wed, 07 Oct 2026 04:49:31 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:31 UTC" as " " 2026/10/07 04:49:31 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:31 UTC": parsing time "Wed, 07 Oct 2026 04:49:31 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:31 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/10/07 04:49:35 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:35 UTC": parsing time "Wed, 07 Oct 2026 04:49:35 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:35 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/10/07 04:49:39 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:39 UTC": parsing time "Wed, 07 Oct 2026 04:49:39 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:39 UTC" as " " 2026/10/07 04:49:44 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:44 UTC": parsing time "Wed, 07 Oct 2026 04:49:44 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:44 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/10/07 04:49:44 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:44 UTC": parsing time "Wed, 07 Oct 2026 04:49:44 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:44 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/10/07 04:49:44 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:44 UTC": parsing time "Wed, 07 Oct 2026 04:49:44 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:44 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/10/07 04:49:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:45 UTC": parsing time "Wed, 07 Oct 2026 04:49:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:45 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/10/07 04:49:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:45 UTC": parsing time "Wed, 07 Oct 2026 04:49:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:45 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/10/07 04:49:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:48 UTC": parsing time "Wed, 07 Oct 2026 04:49:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:48 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/10/07 04:49:53 DEBUG : pacer: Reducing sleep to 113.342133ms 2026/10/07 04:49:53 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 07 Oct 2026 04:49:53 UTC": parsing time "Wed, 07 Oct 2026 04:49:53 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Oct 2026 04:49:53 UTC" as " " 2026/10/07 04:49:53 DEBUG : pacer: Reducing sleep to 100.22124ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/10/07 04:49:53 DEBUG : pacer: Reducing sleep to 207.82532ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/10/07 04:49:53 DEBUG : Creating backend with remote "TestZoho:rclone-test-levewas7hiho/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/10/07 04:49:53 DEBUG : Config file has changed externally - reloading 2026/10/07 04:49:54 DEBUG : pacer: Reducing sleep to 109.446398ms 2026/10/07 04:49:54 DEBUG : pacer: Reducing sleep to 216.69206ms 2026/10/07 04:49:54 DEBUG : pacer: Reducing sleep to 275.608552ms 2026/10/07 04:49:54 DEBUG : pacer: Reducing sleep to 221.281877ms 2026/10/07 04:49:54 DEBUG : pacer: Reducing sleep to 112.305681ms 2026/10/07 04:49:55 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:49:55 DEBUG : pacer: Reducing sleep to 109.202666ms 2026/10/07 04:49:55 DEBUG : pacer: Reducing sleep to 215.591101ms 2026/10/07 04:49:55 DEBUG : pacer: Reducing sleep to 275.113085ms 2026/10/07 04:49:55 DEBUG : pacer: Reducing sleep to 221.307305ms 2026/10/07 04:49:55 DEBUG : pacer: Reducing sleep to 117.03582ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/10/07 04:49:56 DEBUG : pacer: Reducing sleep to 52.268963ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/10/07 04:49:56 DEBUG : Creating backend with remote "TestZoho:rclone-test-levewas7hiho/not found.txt" 2026/10/07 04:49:56 DEBUG : pacer: Reducing sleep to 113.194413ms 2026/10/07 04:49:56 DEBUG : pacer: Reducing sleep to 139.885611ms 2026/10/07 04:50:01 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:50:01 DEBUG : zoho root 'rclone-test-levewas7hiho/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/10/07 04:50:05 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-levewas7hiho" from "TestZoho:rclone-test-levewas7hiho" 2026/10/07 04:50:05 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/10/07 04:50:10 DEBUG : pacer: Reducing sleep to 108.956162ms 2026/10/07 04:50:10 DEBUG : pacer: Reducing sleep to 220.17251ms 2026/10/07 04:50:10 DEBUG : pacer: Reducing sleep to 276.508991ms 2026/10/07 04:50:10 DEBUG : pacer: Reducing sleep to 219.513897ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/10/07 04:50:14 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/10/07 04:50:28 DEBUG : pacer: Reducing sleep to 104.650771ms 2026/10/07 04:50:28 DEBUG : pacer: Reducing sleep to 218.611863ms 2026/10/07 04:50:28 DEBUG : pacer: Reducing sleep to 274.815562ms 2026/10/07 04:50:29 DEBUG : pacer: Reducing sleep to 217.555494ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/10/07 04:50:29 DEBUG : pacer: Reducing sleep to 92.553504ms 2026/10/07 04:50:33 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:50:33 DEBUG : pacer: Reducing sleep to 111.037812ms 2026/10/07 04:50:33 DEBUG : pacer: Reducing sleep to 219.862405ms 2026/10/07 04:50:33 DEBUG : pacer: Reducing sleep to 276.675834ms 2026/10/07 04:50:33 DEBUG : pacer: Reducing sleep to 220.676588ms === 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/10/07 04:50:38 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:50:42 DEBUG : pacer: Reducing sleep to 108.042044ms 2026/10/07 04:50:42 DEBUG : pacer: Reducing sleep to 214.583075ms 2026/10/07 04:50:42 DEBUG : pacer: Reducing sleep to 269.500984ms 2026/10/07 04:50:43 DEBUG : pacer: Reducing sleep to 222.450994ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/10/07 04:50:43 DEBUG : pacer: Reducing sleep to 114.970374ms === 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/10/07 04:50:47 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:50:47 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/10/07 04:50:47 DEBUG : pacer: Rate limited, increasing sleep to 1.65266405s fstests.go:2660: Put returned an error after the injected failure (acceptable): upload error: HTTP error 500 (500 Internal Server Error) returned body: "" 2026/10/07 04:50:51 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:50:51 DEBUG : pacer: Reducing sleep to 109.829909ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/10/07 04:50:53 DEBUG : pacer: Reducing sleep to 0s 2026/10/07 04:50:53 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=e31vn73a09770cfa4433886ec779d8f97a53b": write tcp: unexpected EOF) 2026/10/07 04:50:53 DEBUG : pacer: Rate limited, increasing sleep to 1.585830731s 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=e31vn73a09770cfa4433886ec779d8f97a53b": write tcp: unexpected EOF 2026/10/07 04:50:54 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/10/07 04:50:54 DEBUG : pacer: Reducing sleep to 128.835836ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n