"go test -v -timeout 2h0m0s -remote TestZoho: -verbose" - Starting (try 1/5) === RUN TestFolderListLimiter --- PASS: TestFolderListLimiter (0.00s) === RUN TestFolderWindowLimiterShape --- PASS: TestFolderWindowLimiterShape (0.00s) === RUN TestFolderWindowLimiterIdleResume --- PASS: TestFolderWindowLimiterIdleResume (0.00s) === RUN TestFolderWindowLimiterClamps --- PASS: TestFolderWindowLimiterClamps (0.00s) === RUN TestFolderListLimiterConcurrent --- PASS: TestFolderListLimiterConcurrent (0.00s) === RUN TestTPSMinSleep --- PASS: TestTPSMinSleep (0.00s) === RUN TestShouldRetry === RUN TestShouldRetry/429_honours_Retry-After 2026/09/09 03:41:51 NOTICE: zoho root '': Too many requests: Trying again in 6 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_without_Retry-After_falls_back_to_60s 2026/09/09 03:41:51 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_with_unparseable_Retry-After_falls_back 2026/09/09 03:41:51 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/09 03:41:51 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/09/09 03:41:51 ERROR : zoho: missing OAuth Scope. Run rclone config reconnect to fix this issue. === RUN TestShouldRetry/401_expired_token_retries === RUN TestShouldRetry/401_R008_deleted_folder_is_not_retried === RUN TestShouldRetry/cancelled_context_aborts --- PASS: TestShouldRetry (0.00s) --- PASS: TestShouldRetry/429_honours_Retry-After (0.00s) --- PASS: TestShouldRetry/429_without_Retry-After_falls_back_to_60s (0.00s) --- PASS: TestShouldRetry/429_with_unparseable_Retry-After_falls_back (0.00s) --- PASS: TestShouldRetry/401_missing_scope_aborts (0.00s) --- PASS: TestShouldRetry/401_expired_token_retries (0.00s) --- PASS: TestShouldRetry/401_R008_deleted_folder_is_not_retried (0.00s) --- PASS: TestShouldRetry/cancelled_context_aborts (0.00s) === RUN TestIsMissingResourceErr --- PASS: TestIsMissingResourceErr (0.00s) === RUN TestThrottleEpisode 2026/09/09 03:41:51 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/09/09 03:41:51 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleStateConcurrent (0.00s) === RUN TestConfigRootFolderID === RUN TestConfigRootFolderID/SetRootAsksBeforeChanging === RUN TestConfigRootFolderID/EmptyRootGoesToEdition === RUN TestConfigRootFolderID/KeepRootOnNo === RUN TestConfigRootFolderID/ChangeRootOnYes --- PASS: TestConfigRootFolderID (0.00s) --- PASS: TestConfigRootFolderID/SetRootAsksBeforeChanging (0.00s) --- PASS: TestConfigRootFolderID/EmptyRootGoesToEdition (0.00s) --- PASS: TestConfigRootFolderID/KeepRootOnNo (0.00s) --- PASS: TestConfigRootFolderID/ChangeRootOnYes (0.00s) === RUN TestIntegration fstests.go:464: Using remote "TestZoho:" 2026/09/09 03:41:51 DEBUG : Creating backend with remote "TestZoho:rclone-test-vicideg2zeci" 2026/09/09 03:41:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/09 03:41:51 DEBUG : zoho root 'rclone-test-vicideg2zeci': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/09 03:41:51 DEBUG : TestZoho: Token expired 2026/09/09 03:41:51 DEBUG : TestZoho: No updated token found in the config file 2026/09/09 03:41:51 DEBUG : TestZoho: Token refresh successful 2026/09/09 03:41:51 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/09 03:41:51 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:505: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:533: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/09/09 03:41:52 DEBUG : pacer: Reducing sleep to 32.911051ms 2026/09/09 03:41:52 DEBUG : pacer: Reducing sleep to 59.56239ms 2026/09/09 03:41:53 DEBUG : pacer: Reducing sleep to 55.033885ms 2026/09/09 03:41:53 DEBUG : pacer: Reducing sleep to 170.563207ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/09 03:41:53 DEBUG : pacer: Reducing sleep to 281.126678ms 2026/09/09 03:41:53 DEBUG : pacer: Reducing sleep to 252.136869ms === RUN TestIntegration/FsMkdir 2026/09/09 03:41:53 DEBUG : pacer: Reducing sleep to 83.317752ms 2026/09/09 03:41:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:41:54 DEBUG : pacer: Reducing sleep to 115.141928ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/09 03:41:54 INFO : dir/subdir: Making directory 2026/09/09 03:41:54 DEBUG : pacer: Reducing sleep to 230.192274ms 2026/09/09 03:41:54 DEBUG : pacer: Reducing sleep to 243.695013ms 2026/09/09 03:41:54 DEBUG : pacer: Reducing sleep to 222.072987ms 2026/09/09 03:41:54 DEBUG : pacer: Reducing sleep to 104.619509ms 2026/09/09 03:41:54 DEBUG : pacer: Reducing sleep to 65.008828ms 2026/09/09 03:41:55 DEBUG : pacer: Reducing sleep to 143.939001ms 2026/09/09 03:41:55 DEBUG : pacer: Reducing sleep to 240.17765ms 2026/09/09 03:41:55 INFO : dir/subdir: Removing directory 2026/09/09 03:41:55 DEBUG : pacer: Reducing sleep to 268.707308ms 2026/09/09 03:41:55 DEBUG : pacer: Reducing sleep to 174.514488ms 2026/09/09 03:41:55 DEBUG : pacer: Reducing sleep to 91.269884ms 2026/09/09 03:41:55 DEBUG : pacer: Reducing sleep to 83.163222ms 2026/09/09 03:41:55 INFO : dir: Removing directory 2026/09/09 03:41:56 DEBUG : pacer: Reducing sleep to 153.558775ms 2026/09/09 03:41:56 DEBUG : pacer: Reducing sleep to 199.396359ms 2026/09/09 03:41:56 DEBUG : pacer: Reducing sleep to 256.540616ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/09 03:41:56 DEBUG : pacer: Reducing sleep to 218.445099ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/09 03:42:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/09 03:42:05 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:734: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/09/09 03:42:14 DEBUG : pacer: Reducing sleep to 97.379674ms 2026/09/09 03:42:14 DEBUG : pacer: Reducing sleep to 215.655702ms 2026/09/09 03:42:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:42:15 DEBUG : pacer: Reducing sleep to 111.227426ms 2026/09/09 03:42:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:42:19 DEBUG : pacer: Reducing sleep to 100.756723ms 2026/09/09 03:42:19 DEBUG : pacer: Reducing sleep to 206.973466ms 2026/09/09 03:42:19 DEBUG : pacer: Reducing sleep to 279.388153ms 2026/09/09 03:42:19 DEBUG : pacer: Reducing sleep to 228.727444ms 2026/09/09 03:42:23 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/09 03:42:28 DEBUG : pacer: Reducing sleep to 79.268578ms 2026/09/09 03:42:28 DEBUG : pacer: Reducing sleep to 200.039369ms 2026/09/09 03:42:28 DEBUG : pacer: Reducing sleep to 147.988777ms 2026/09/09 03:42:29 DEBUG : pacer: Reducing sleep to 248.02088ms 2026/09/09 03:42:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:42:33 DEBUG : pacer: Reducing sleep to 111.975349ms 2026/09/09 03:42:33 DEBUG : pacer: Reducing sleep to 74.428468ms 2026/09/09 03:42:33 DEBUG : pacer: Reducing sleep to 151.010486ms 2026/09/09 03:42:33 DEBUG : pacer: Reducing sleep to 221.074253ms 2026/09/09 03:42:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/09 03:42:42 DEBUG : pacer: Reducing sleep to 77.789278ms 2026/09/09 03:42:42 DEBUG : pacer: Reducing sleep to 198.060342ms 2026/09/09 03:42:42 DEBUG : pacer: Reducing sleep to 151.263493ms 2026/09/09 03:42:42 DEBUG : pacer: Reducing sleep to 241.333453ms 2026/09/09 03:42:47 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:42:47 DEBUG : pacer: Reducing sleep to 103.93316ms 2026/09/09 03:42:47 DEBUG : pacer: Reducing sleep to 82.769331ms 2026/09/09 03:42:47 DEBUG : pacer: Reducing sleep to 151.731147ms 2026/09/09 03:42:47 DEBUG : pacer: Reducing sleep to 231.656157ms 2026/09/09 03:42:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/09 03:42:51 DEBUG : zoho root 'rclone-test-vicideg2zeci': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/09 03:42:56 DEBUG : pacer: Reducing sleep to 77.321903ms 2026/09/09 03:42:56 DEBUG : pacer: Reducing sleep to 198.515872ms 2026/09/09 03:42:56 DEBUG : pacer: Reducing sleep to 166.718341ms 2026/09/09 03:42:56 DEBUG : pacer: Reducing sleep to 243.281181ms 2026/09/09 03:42:56 DEBUG : pacer: Reducing sleep to 247.828268ms 2026/09/09 03:42:57 DEBUG : pacer: Reducing sleep to 171.260685ms 2026/09/09 03:42:57 DEBUG : pacer: Reducing sleep to 64.948447ms 2026/09/09 03:42:57 DEBUG : pacer: Reducing sleep to 84.136784ms 2026/09/09 03:42:57 DEBUG : pacer: Reducing sleep to 160.010042ms 2026/09/09 03:42:57 DEBUG : pacer: Reducing sleep to 270.364701ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/09 03:42:57 DEBUG : pacer: Reducing sleep to 271.364036ms 2026/09/09 03:42:58 DEBUG : pacer: Reducing sleep to 143.276485ms 2026/09/09 03:42:58 DEBUG : pacer: Reducing sleep to 59.093431ms 2026/09/09 03:42:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:42:58 DEBUG : pacer: Reducing sleep to 113.394308ms 2026/09/09 03:42:58 DEBUG : pacer: Reducing sleep to 220.773528ms 2026/09/09 03:42:58 DEBUG : pacer: Reducing sleep to 280.54671ms 2026/09/09 03:42:59 DEBUG : pacer: Reducing sleep to 206.05602ms 2026/09/09 03:42:59 DEBUG : pacer: Reducing sleep to 113.019316ms 2026/09/09 03:42:59 DEBUG : pacer: Reducing sleep to 53.661083ms 2026/09/09 03:42:59 DEBUG : pacer: Reducing sleep to 130.41222ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/09 03:43:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:43:04 DEBUG : pacer: Reducing sleep to 82.110108ms 2026/09/09 03:43:04 DEBUG : pacer: Reducing sleep to 204.092455ms 2026/09/09 03:43:04 DEBUG : pacer: Reducing sleep to 158.873176ms 2026/09/09 03:43:04 DEBUG : pacer: Reducing sleep to 232.279856ms 2026/09/09 03:43:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:43:08 DEBUG : pacer: Reducing sleep to 111.394953ms 2026/09/09 03:43:09 DEBUG : pacer: Reducing sleep to 76.591233ms 2026/09/09 03:43:09 DEBUG : pacer: Reducing sleep to 169.580055ms 2026/09/09 03:43:09 DEBUG : pacer: Reducing sleep to 240.601431ms 2026/09/09 03:43:13 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/09 03:43:18 DEBUG : pacer: Reducing sleep to 33.790272ms 2026/09/09 03:43:18 DEBUG : pacer: Reducing sleep to 155.435093ms 2026/09/09 03:43:18 DEBUG : pacer: Reducing sleep to 215.262668ms 2026/09/09 03:43:18 DEBUG : pacer: Reducing sleep to 262.116151ms 2026/09/09 03:43:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:43:22 DEBUG : pacer: Reducing sleep to 110.348576ms 2026/09/09 03:43:23 DEBUG : pacer: Reducing sleep to 38.474018ms 2026/09/09 03:43:23 DEBUG : pacer: Reducing sleep to 134.343679ms 2026/09/09 03:43:23 DEBUG : pacer: Reducing sleep to 228.477343ms 2026/09/09 03:43:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/09 03:43:32 DEBUG : pacer: Reducing sleep to 91.321773ms 2026/09/09 03:43:32 DEBUG : pacer: Reducing sleep to 164.291289ms 2026/09/09 03:43:32 DEBUG : pacer: Reducing sleep to 154.231217ms 2026/09/09 03:43:32 DEBUG : pacer: Reducing sleep to 268.70676ms 2026/09/09 03:43:36 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:43:36 DEBUG : pacer: Reducing sleep to 100.91474ms 2026/09/09 03:43:36 DEBUG : pacer: Reducing sleep to 48.709126ms 2026/09/09 03:43:36 DEBUG : pacer: Reducing sleep to 131.986478ms 2026/09/09 03:43:37 DEBUG : pacer: Reducing sleep to 224.427185ms 2026/09/09 03:43:41 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/09 03:43:45 DEBUG : pacer: Reducing sleep to 84.679028ms 2026/09/09 03:43:45 DEBUG : pacer: Reducing sleep to 205.554346ms 2026/09/09 03:43:46 DEBUG : pacer: Reducing sleep to 209.679785ms 2026/09/09 03:43:46 DEBUG : pacer: Reducing sleep to 228.640704ms 2026/09/09 03:43:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:43:50 DEBUG : pacer: Reducing sleep to 105.098051ms 2026/09/09 03:43:50 DEBUG : pacer: Reducing sleep to 95.363005ms 2026/09/09 03:43:50 DEBUG : pacer: Reducing sleep to 164.644275ms 2026/09/09 03:43:50 DEBUG : pacer: Reducing sleep to 228.361305ms 2026/09/09 03:43:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/09 03:43:55 DEBUG : zoho root 'rclone-test-vicideg2zeci': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/09 03:43:55 DEBUG : pacer: Reducing sleep to 120.547047ms 2026/09/09 03:43:55 DEBUG : pacer: Reducing sleep to 78.546286ms 2026/09/09 03:43:55 DEBUG : pacer: Reducing sleep to 156.23904ms 2026/09/09 03:43:55 DEBUG : pacer: Reducing sleep to 118.070357ms 2026/09/09 03:43:55 DEBUG : pacer: Reducing sleep to 232.185247ms 2026/09/09 03:43:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:43:56 DEBUG : pacer: Reducing sleep to 114.917376ms 2026/09/09 03:43:57 DEBUG : pacer: Reducing sleep to 92.84482ms 2026/09/09 03:43:57 DEBUG : pacer: Reducing sleep to 163.946697ms 2026/09/09 03:43:57 DEBUG : pacer: Reducing sleep to 219.57818ms 2026/09/09 03:43:57 DEBUG : pacer: Reducing sleep to 242.673214ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/09 03:43:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:43:58 DEBUG : pacer: Reducing sleep to 94.388687ms 2026/09/09 03:43:58 DEBUG : pacer: Reducing sleep to 90.967803ms 2026/09/09 03:43:58 DEBUG : pacer: Reducing sleep to 99.559328ms 2026/09/09 03:43:58 DEBUG : pacer: Reducing sleep to 214.656404ms 2026/09/09 03:43:58 DEBUG : pacer: Reducing sleep to 272.692046ms 2026/09/09 03:43:58 DEBUG : pacer: Reducing sleep to 232.112358ms 2026/09/09 03:43:59 DEBUG : pacer: Reducing sleep to 106.682319ms 2026/09/09 03:43:59 DEBUG : pacer: Reducing sleep to 62.869916ms 2026/09/09 03:43:59 DEBUG : pacer: Reducing sleep to 101.487756ms 2026/09/09 03:44:03 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/09 03:44:08 DEBUG : pacer: Reducing sleep to 78.841475ms 2026/09/09 03:44:08 DEBUG : pacer: Reducing sleep to 192.92224ms 2026/09/09 03:44:08 DEBUG : pacer: Reducing sleep to 125.417604ms 2026/09/09 03:44:08 DEBUG : pacer: Reducing sleep to 222.66537ms 2026/09/09 03:44:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:44:12 DEBUG : pacer: Reducing sleep to 80.698842ms 2026/09/09 03:44:12 DEBUG : pacer: Reducing sleep to 97.898114ms 2026/09/09 03:44:12 DEBUG : pacer: Reducing sleep to 188.518754ms 2026/09/09 03:44:12 DEBUG : pacer: Reducing sleep to 244.212453ms 2026/09/09 03:44:17 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/09 03:44:21 DEBUG : pacer: Reducing sleep to 72.465994ms 2026/09/09 03:44:21 DEBUG : pacer: Reducing sleep to 193.895878ms 2026/09/09 03:44:22 DEBUG : pacer: Reducing sleep to 209.960116ms 2026/09/09 03:44:22 DEBUG : pacer: Reducing sleep to 241.417002ms 2026/09/09 03:44:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:44:26 DEBUG : pacer: Reducing sleep to 112.313127ms 2026/09/09 03:44:26 DEBUG : pacer: Reducing sleep to 87.868152ms 2026/09/09 03:44:26 DEBUG : pacer: Reducing sleep to 162.585298ms 2026/09/09 03:44:26 DEBUG : pacer: Reducing sleep to 219.373233ms 2026/09/09 03:44:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/09 03:44:35 DEBUG : pacer: Reducing sleep to 61.429782ms 2026/09/09 03:44:35 DEBUG : pacer: Reducing sleep to 181.306985ms 2026/09/09 03:44:35 DEBUG : pacer: Reducing sleep to 172.553953ms 2026/09/09 03:44:36 DEBUG : pacer: Reducing sleep to 232.219663ms 2026/09/09 03:44:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:44:40 DEBUG : pacer: Reducing sleep to 109.679264ms 2026/09/09 03:44:40 DEBUG : pacer: Reducing sleep to 96.013994ms 2026/09/09 03:44:40 DEBUG : pacer: Reducing sleep to 171.220785ms 2026/09/09 03:44:40 DEBUG : pacer: Reducing sleep to 228.10833ms 2026/09/09 03:44:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/09 03:44:49 DEBUG : pacer: Reducing sleep to 82.94832ms 2026/09/09 03:44:49 DEBUG : pacer: Reducing sleep to 204.130046ms 2026/09/09 03:44:49 DEBUG : pacer: Reducing sleep to 200.046164ms 2026/09/09 03:44:49 DEBUG : pacer: Reducing sleep to 238.038893ms 2026/09/09 03:44:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:44:54 DEBUG : pacer: Reducing sleep to 108.313154ms 2026/09/09 03:44:54 DEBUG : pacer: Reducing sleep to 112.194383ms 2026/09/09 03:44:54 DEBUG : pacer: Reducing sleep to 192.076903ms 2026/09/09 03:44:54 DEBUG : pacer: Reducing sleep to 230.164032ms 2026/09/09 03:44:54 DEBUG : pacer: Reducing sleep to 224.183037ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/09 03:44:55 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:44:55 DEBUG : pacer: Reducing sleep to 77.509189ms 2026/09/09 03:44:55 DEBUG : zoho root 'rclone-test-vicideg2zeci': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/09 03:44:55 DEBUG : pacer: Reducing sleep to 105.199132ms 2026/09/09 03:44:55 DEBUG : pacer: Reducing sleep to 66.397464ms 2026/09/09 03:44:56 DEBUG : pacer: Reducing sleep to 180.642278ms 2026/09/09 03:44:56 DEBUG : pacer: Reducing sleep to 261.711089ms 2026/09/09 03:44:56 DEBUG : pacer: Reducing sleep to 265.952058ms 2026/09/09 03:44:56 DEBUG : pacer: Reducing sleep to 152.561496ms 2026/09/09 03:44:56 DEBUG : pacer: Reducing sleep to 72.714152ms 2026/09/09 03:44:56 DEBUG : pacer: Reducing sleep to 68.475326ms 2026/09/09 03:44:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/09 03:44:57 DEBUG : pacer: Reducing sleep to 97.348646ms 2026/09/09 03:44:57 DEBUG : pacer: Reducing sleep to 213.264888ms 2026/09/09 03:44:58 DEBUG : pacer: Reducing sleep to 216.629011ms 2026/09/09 03:44:58 DEBUG : pacer: Reducing sleep to 232.182814ms 2026/09/09 03:45:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:45:02 DEBUG : pacer: Reducing sleep to 111.997932ms 2026/09/09 03:45:02 DEBUG : pacer: Reducing sleep to 81.57163ms 2026/09/09 03:45:02 DEBUG : pacer: Reducing sleep to 163.241876ms 2026/09/09 03:45:02 DEBUG : pacer: Reducing sleep to 229.827076ms 2026/09/09 03:45:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:725: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:734: testing "test%46.txt" 2026/09/09 03:45:11 DEBUG : pacer: Reducing sleep to 97.43631ms 2026/09/09 03:45:11 DEBUG : pacer: Reducing sleep to 214.546258ms 2026/09/09 03:45:11 DEBUG : pacer: Reducing sleep to 194.067302ms 2026/09/09 03:45:12 DEBUG : pacer: Reducing sleep to 226.634707ms 2026/09/09 03:45:16 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:45:16 DEBUG : pacer: Reducing sleep to 100.519153ms 2026/09/09 03:45:16 DEBUG : pacer: Reducing sleep to 82.208232ms 2026/09/09 03:45:16 DEBUG : pacer: Reducing sleep to 175.503362ms 2026/09/09 03:45:16 DEBUG : pacer: Reducing sleep to 241.02334ms 2026/09/09 03:45:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/09 03:45:39 DEBUG : pacer: Reducing sleep to 92.333985ms 2026/09/09 03:45:39 DEBUG : pacer: Reducing sleep to 205.650037ms 2026/09/09 03:45:39 DEBUG : pacer: Reducing sleep to 257.361125ms 2026/09/09 03:45:39 DEBUG : pacer: Reducing sleep to 241.940321ms 2026/09/09 03:45:40 DEBUG : pacer: Reducing sleep to 111.372741ms 2026/09/09 03:45:40 DEBUG : pacer: Reducing sleep to 75.523107ms 2026/09/09 03:45:40 DEBUG : pacer: Reducing sleep to 100.306393ms 2026/09/09 03:45:40 DEBUG : pacer: Reducing sleep to 266.5664ms 2026/09/09 03:45:40 DEBUG : pacer: Reducing sleep to 278.545774ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/09 03:45:43 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:45:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:45:48 UTC": parsing time "Wed, 09 Sep 2026 03:45:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:45:48 UTC" as " " 2026/09/09 03:45:49 DEBUG : pacer: Reducing sleep to 105.335601ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:813: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:846: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:899: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/09/09 03:45:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:45:53 DEBUG : pacer: Reducing sleep to 51.55883ms 2026/09/09 03:45:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:45:57 DEBUG : zoho root 'rclone-test-vicideg2zeci': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/09 03:45:57 DEBUG : pacer: Reducing sleep to 120.435187ms 2026/09/09 03:45:58 DEBUG : pacer: Reducing sleep to 53.780526ms 2026/09/09 03:45:58 DEBUG : pacer: Reducing sleep to 155.44814ms 2026/09/09 03:45:58 DEBUG : pacer: Reducing sleep to 269.908724ms 2026/09/09 03:45:58 DEBUG : pacer: Reducing sleep to 216.368582ms 2026/09/09 03:45:58 DEBUG : pacer: Reducing sleep to 170.698143ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/09 03:45:58 DEBUG : pacer: Reducing sleep to 121.684979ms 2026/09/09 03:45:59 DEBUG : pacer: Reducing sleep to 112.224332ms 2026/09/09 03:45:59 DEBUG : pacer: Reducing sleep to 162.671845ms 2026/09/09 03:45:59 DEBUG : pacer: Reducing sleep to 211.179153ms 2026/09/09 03:45:59 DEBUG : pacer: Reducing sleep to 223.236092ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/09 03:45:59 DEBUG : pacer: Reducing sleep to 169.055041ms 2026/09/09 03:45:59 DEBUG : pacer: Reducing sleep to 121.438344ms 2026/09/09 03:46:00 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:46:00 DEBUG : pacer: Reducing sleep to 32.858504ms 2026/09/09 03:46:00 DEBUG : pacer: Reducing sleep to 43.364395ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/09 03:46:00 DEBUG : pacer: Reducing sleep to 126.899996ms 2026/09/09 03:46:00 DEBUG : pacer: Reducing sleep to 241.623705ms 2026/09/09 03:46:00 DEBUG : pacer: Reducing sleep to 268.727772ms 2026/09/09 03:46:01 DEBUG : pacer: Reducing sleep to 203.649941ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/09 03:46:01 DEBUG : Creating backend with remote "TestZoho:" 2026/09/09 03:46:01 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/09/09 03:46:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/09/09 03:46:10 DEBUG : pacer: Reducing sleep to 101.551253ms 2026/09/09 03:46:10 DEBUG : pacer: Reducing sleep to 215.75979ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/09 03:46:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:46:14 DEBUG : pacer: Reducing sleep to 115.686828ms 2026/09/09 03:46:14 DEBUG : pacer: Reducing sleep to 123.437708ms 2026/09/09 03:46:15 DEBUG : pacer: Reducing sleep to 140.62707ms 2026/09/09 03:46:15 DEBUG : pacer: Reducing sleep to 205.259943ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/09 03:46:19 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/09 03:46:24 DEBUG : pacer: Reducing sleep to 44.198582ms 2026/09/09 03:46:24 DEBUG : pacer: Reducing sleep to 159.161623ms 2026/09/09 03:46:24 DEBUG : pacer: Reducing sleep to 267.276521ms 2026/09/09 03:46:24 DEBUG : pacer: Reducing sleep to 279.590058ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/09 03:46:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/09 03:46:28 DEBUG : pacer: Reducing sleep to 107.740342ms 2026/09/09 03:46:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:46:33 DEBUG : pacer: Reducing sleep to 94.568646ms 2026/09/09 03:46:33 DEBUG : pacer: Reducing sleep to 102.369318ms 2026/09/09 03:46:33 DEBUG : pacer: Reducing sleep to 206.698888ms 2026/09/09 03:46:38 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:46:38 DEBUG : pacer: Reducing sleep to 115.189238ms 2026/09/09 03:46:38 DEBUG : pacer: Reducing sleep to 268.298792ms 2026/09/09 03:46:38 DEBUG : pacer: Reducing sleep to 310.485091ms 2026/09/09 03:46:38 DEBUG : pacer: Reducing sleep to 202.899213ms 2026/09/09 03:46:38 DEBUG : pacer: Reducing sleep to 63.62038ms 2026/09/09 03:46:39 DEBUG : pacer: Reducing sleep to 23.190088ms 2026/09/09 03:46:39 DEBUG : pacer: Reducing sleep to 121.445837ms 2026/09/09 03:46:42 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:46:42 DEBUG : pacer: Reducing sleep to 116.696546ms 2026/09/09 03:46:42 DEBUG : pacer: Reducing sleep to 202.989763ms 2026/09/09 03:46:42 DEBUG : pacer: Reducing sleep to 260.266955ms 2026/09/09 03:46:43 DEBUG : pacer: Reducing sleep to 216.274183ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/09 03:46:47 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:46:52 DEBUG : pacer: Reducing sleep to 85.276287ms 2026/09/09 03:46:52 DEBUG : pacer: Reducing sleep to 40.714301ms 2026/09/09 03:46:52 DEBUG : pacer: Reducing sleep to 147.78081ms 2026/09/09 03:46:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:46:56 DEBUG : pacer: Reducing sleep to 114.891577ms 2026/09/09 03:46:56 DEBUG : pacer: Reducing sleep to 263.730324ms 2026/09/09 03:46:56 DEBUG : pacer: Reducing sleep to 366.95625ms 2026/09/09 03:46:57 DEBUG : pacer: Reducing sleep to 262.014446ms 2026/09/09 03:46:57 DEBUG : pacer: Reducing sleep to 69.45932ms 2026/09/09 03:46:57 DEBUG : Creating backend with remote "TestZoho:rclone-test-vicideg2zeci/dirToPurgeFromRoot" 2026/09/09 03:46:57 DEBUG : Config file has changed externally - reloading 2026/09/09 03:46:57 DEBUG : pacer: Reducing sleep to 104.931267ms 2026/09/09 03:46:57 DEBUG : pacer: Reducing sleep to 228.252813ms 2026/09/09 03:46:57 DEBUG : pacer: Reducing sleep to 252.812184ms 2026/09/09 03:46:57 DEBUG : zoho root 'rclone-test-vicideg2zeci': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/09 03:46:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:46:58 DEBUG : pacer: Reducing sleep to 109.444188ms 2026/09/09 03:46:58 DEBUG : pacer: Reducing sleep to 196.781798ms 2026/09/09 03:46:58 DEBUG : pacer: Reducing sleep to 281.310035ms 2026/09/09 03:46:58 DEBUG : pacer: Reducing sleep to 241.926057ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/09 03:46:58 DEBUG : Creating backend with remote "TestZoho:rclone-test-vicideg2zeci/hello? sausage/êé" 2026/09/09 03:46:58 DEBUG : pacer: Reducing sleep to 117.737932ms 2026/09/09 03:46:58 DEBUG : pacer: Reducing sleep to 220.341964ms 2026/09/09 03:46:59 DEBUG : pacer: Reducing sleep to 286.493813ms 2026/09/09 03:46:59 DEBUG : pacer: Reducing sleep to 221.723896ms 2026/09/09 03:46:59 DEBUG : pacer: Reducing sleep to 88.911234ms 2026/09/09 03:46:59 DEBUG : pacer: Reducing sleep to 52.135694ms 2026/09/09 03:46:59 DEBUG : pacer: Reducing sleep to 108.290736ms 2026/09/09 03:46:59 DEBUG : pacer: Reducing sleep to 227.150135ms 2026/09/09 03:47:00 DEBUG : pacer: Reducing sleep to 36.105954ms 2026/09/09 03:47:00 DEBUG : pacer: Reducing sleep to 52.252668ms 2026/09/09 03:47:00 DEBUG : pacer: Reducing sleep to 126.534904ms 2026/09/09 03:47:00 DEBUG : pacer: Reducing sleep to 73.866389ms 2026/09/09 03:47:00 DEBUG : pacer: Reducing sleep to 181.995113ms 2026/09/09 03:47:00 DEBUG : pacer: Reducing sleep to 284.839588ms 2026/09/09 03:47:01 DEBUG : pacer: Reducing sleep to 249.486915ms 2026/09/09 03:47:01 DEBUG : pacer: Reducing sleep to 148.803141ms 2026/09/09 03:47:01 DEBUG : pacer: Reducing sleep to 51.733714ms 2026/09/09 03:47:01 DEBUG : pacer: Reducing sleep to 79.658932ms 2026/09/09 03:47:01 DEBUG : pacer: Reducing sleep to 178.335517ms 2026/09/09 03:47:01 DEBUG : pacer: Reducing sleep to 283.281181ms 2026/09/09 03:47:02 DEBUG : pacer: Reducing sleep to 252.330982ms 2026/09/09 03:47:02 DEBUG : pacer: Reducing sleep to 145.935912ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/09 03:47:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:02 DEBUG : pacer: Reducing sleep to 98.29313ms 2026/09/09 03:47:02 DEBUG : pacer: Reducing sleep to 197.66125ms 2026/09/09 03:47:02 DEBUG : pacer: Reducing sleep to 280.398915ms 2026/09/09 03:47:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:05 DEBUG : pacer: Reducing sleep to 114.435456ms 2026/09/09 03:47:05 DEBUG : pacer: Reducing sleep to 44.538374ms 2026/09/09 03:47:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:10 DEBUG : pacer: Reducing sleep to 91.242014ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/09 03:47:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:14 DEBUG : pacer: Reducing sleep to 103.874461ms 2026/09/09 03:47:14 DEBUG : pacer: Reducing sleep to 176.011495ms 2026/09/09 03:47:15 DEBUG : pacer: Reducing sleep to 232.265784ms 2026/09/09 03:47:15 DEBUG : pacer: Reducing sleep to 199.704472ms 2026/09/09 03:47:15 DEBUG : pacer: Reducing sleep to 203.388489ms 2026/09/09 03:47:15 DEBUG : pacer: Reducing sleep to 163.900657ms 2026/09/09 03:47:15 DEBUG : pacer: Reducing sleep to 132.47017ms 2026/09/09 03:47:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:29 DEBUG : pacer: Reducing sleep to 83.912491ms 2026/09/09 03:47:29 DEBUG : pacer: Reducing sleep to 189.672123ms 2026/09/09 03:47:29 DEBUG : pacer: Reducing sleep to 244.407694ms 2026/09/09 03:47:29 DEBUG : pacer: Reducing sleep to 245.930961ms 2026/09/09 03:47:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:33 DEBUG : pacer: Reducing sleep to 112.942255ms 2026/09/09 03:47:33 DEBUG : pacer: Reducing sleep to 276.529947ms 2026/09/09 03:47:33 DEBUG : pacer: Reducing sleep to 269.588537ms 2026/09/09 03:47:34 DEBUG : pacer: Reducing sleep to 123.482923ms 2026/09/09 03:47:34 DEBUG : pacer: Reducing sleep to 63.207713ms 2026/09/09 03:47:34 DEBUG : pacer: Reducing sleep to 90.765408ms 2026/09/09 03:47:34 DEBUG : pacer: Reducing sleep to 193.751381ms 2026/09/09 03:47:34 DEBUG : pacer: Reducing sleep to 237.511274ms 2026/09/09 03:47:34 DEBUG : pacer: Reducing sleep to 237.460409ms 2026/09/09 03:47:38 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:38 DEBUG : pacer: Reducing sleep to 121.648128ms 2026/09/09 03:47:38 DEBUG : pacer: Reducing sleep to 267.912702ms 2026/09/09 03:47:38 DEBUG : pacer: Reducing sleep to 264.375734ms 2026/09/09 03:47:38 DEBUG : pacer: Reducing sleep to 168.882284ms 2026/09/09 03:47:38 DEBUG : pacer: Reducing sleep to 74.627331ms 2026/09/09 03:47:42 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:42 DEBUG : pacer: Reducing sleep to 95.805072ms 2026/09/09 03:47:42 DEBUG : pacer: Reducing sleep to 183.388607ms 2026/09/09 03:47:43 DEBUG : pacer: Reducing sleep to 260.345943ms 2026/09/09 03:47:47 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:47 DEBUG : pacer: Reducing sleep to 116.634399ms 2026/09/09 03:47:47 DEBUG : pacer: Reducing sleep to 282.651828ms 2026/09/09 03:47:47 DEBUG : pacer: Reducing sleep to 236.190625ms 2026/09/09 03:47:47 DEBUG : pacer: Reducing sleep to 122.200084ms 2026/09/09 03:47:48 DEBUG : pacer: Reducing sleep to 41.831307ms 2026/09/09 03:47:48 DEBUG : pacer: Reducing sleep to 99.516497ms 2026/09/09 03:47:48 DEBUG : pacer: Reducing sleep to 196.705684ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/09 03:47:48 DEBUG : Creating backend with remote "TestZoho:rclone-test-kelizaz3qape" 2026/09/09 03:47:48 DEBUG : Config file has changed externally - reloading 2026/09/09 03:47:48 DEBUG : pacer: Reducing sleep to 33.102545ms 2026/09/09 03:47:48 DEBUG : pacer: Reducing sleep to 76.777748ms 2026/09/09 03:47:49 DEBUG : pacer: Reducing sleep to 93.5634ms 2026/09/09 03:47:49 DEBUG : pacer: Reducing sleep to 214.931808ms 2026/09/09 03:47:49 DEBUG : pacer: Reducing sleep to 246.663427ms 2026/09/09 03:47:49 DEBUG : pacer: Reducing sleep to 237.748371ms 2026/09/09 03:47:49 DEBUG : pacer: Reducing sleep to 108.558583ms 2026/09/09 03:47:49 DEBUG : pacer: Reducing sleep to 66.547766ms 2026/09/09 03:47:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:50 DEBUG : pacer: Reducing sleep to 21.69984ms 2026/09/09 03:47:50 DEBUG : pacer: Reducing sleep to 135.863983ms 2026/09/09 03:47:52 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:52 DEBUG : pacer: Reducing sleep to 113.751483ms 2026/09/09 03:47:52 DEBUG : pacer: Reducing sleep to 200.145492ms 2026/09/09 03:47:52 DEBUG : pacer: Reducing sleep to 265.558518ms 2026/09/09 03:47:52 DEBUG : pacer: Reducing sleep to 216.434767ms 2026/09/09 03:47:54 DEBUG : pacer: Reducing sleep to 37.424056ms 2026/09/09 03:47:54 DEBUG : pacer: Reducing sleep to 136.98431ms 2026/09/09 03:47:54 DEBUG : pacer: Reducing sleep to 254.65719ms 2026/09/09 03:47:54 DEBUG : pacer: Reducing sleep to 272.011345ms 2026/09/09 03:47:54 DEBUG : pacer: Reducing sleep to 192.74965ms 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 67.080971ms 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 114.927495ms 2026/09/09 03:47:55 DEBUG : zoho root 'rclone-test-kelizaz3qape': Purge remote 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 115.116403ms 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 146.310585ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 107.300621ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 219.131519ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/09 03:47:55 DEBUG : pacer: Reducing sleep to 274.231347ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/09 03:47:56 DEBUG : pacer: Reducing sleep to 218.075277ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/09 03:47:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/09/09 03:48:01 DEBUG : zoho root 'rclone-test-vicideg2zeci': folder listing limiter sweep: evicted 5 of 15 idle limiters fstests.go:1664: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1735: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1792: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/09/09 03:48:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:25 UTC": parsing time "Wed, 09 Sep 2026 03:48:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:25 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/09 03:48:29 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:29 UTC": parsing time "Wed, 09 Sep 2026 03:48:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:29 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/09 03:48:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:34 UTC": parsing time "Wed, 09 Sep 2026 03:48:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:34 UTC" as " " 2026/09/09 03:48:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:34 UTC": parsing time "Wed, 09 Sep 2026 03:48:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:34 UTC" as " " 2026/09/09 03:48:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:34 UTC": parsing time "Wed, 09 Sep 2026 03:48:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:34 UTC" as " " 2026/09/09 03:48:35 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:35 UTC": parsing time "Wed, 09 Sep 2026 03:48:35 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:35 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/09 03:48:38 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:38 UTC": parsing time "Wed, 09 Sep 2026 03:48:38 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:38 UTC" as " " 2026/09/09 03:48:38 DEBUG : pacer: Reducing sleep to 12.616048ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/09 03:48:43 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:48:43 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:43 UTC": parsing time "Wed, 09 Sep 2026 03:48:43 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:43 UTC" as " " 2026/09/09 03:48:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:48 UTC": parsing time "Wed, 09 Sep 2026 03:48:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:48 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/09 03:48:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:48 UTC": parsing time "Wed, 09 Sep 2026 03:48:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:48 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/09 03:48:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:48 UTC": parsing time "Wed, 09 Sep 2026 03:48:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:48 UTC" as " " 2026/09/09 03:48:48 DEBUG : pacer: Reducing sleep to 5.501289ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/09 03:48:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:48 UTC": parsing time "Wed, 09 Sep 2026 03:48:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:48 UTC" as " " 2026/09/09 03:48:48 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/09 03:48:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:48 UTC": parsing time "Wed, 09 Sep 2026 03:48:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:48 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/09 03:48:52 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:52 UTC": parsing time "Wed, 09 Sep 2026 03:48:52 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:52 UTC" as " " 2026/09/09 03:48:52 DEBUG : pacer: Reducing sleep to 9.389131ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/09 03:48:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:48:57 DEBUG : pacer: Reducing sleep to 52.58988ms 2026/09/09 03:48:57 DEBUG : pacer: Reducing sleep to 166.441032ms 2026/09/09 03:48:57 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 09 Sep 2026 03:48:57 UTC": parsing time "Wed, 09 Sep 2026 03:48:57 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 09 Sep 2026 03:48:57 UTC" as " " 2026/09/09 03:48:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/09 03:48:57 DEBUG : pacer: Reducing sleep to 111.667788ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/09 03:48:57 DEBUG : Creating backend with remote "TestZoho:rclone-test-vicideg2zeci/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/09 03:48:57 DEBUG : Config file has changed externally - reloading 2026/09/09 03:48:58 DEBUG : pacer: Reducing sleep to 117.712225ms 2026/09/09 03:48:58 DEBUG : pacer: Reducing sleep to 221.801803ms 2026/09/09 03:48:58 DEBUG : pacer: Reducing sleep to 282.338819ms 2026/09/09 03:48:58 DEBUG : pacer: Reducing sleep to 215.155092ms 2026/09/09 03:48:58 DEBUG : pacer: Reducing sleep to 109.822911ms 2026/09/09 03:48:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:48:59 DEBUG : pacer: Reducing sleep to 116.418471ms 2026/09/09 03:48:59 DEBUG : pacer: Reducing sleep to 227.879199ms 2026/09/09 03:48:59 DEBUG : pacer: Reducing sleep to 284.69031ms 2026/09/09 03:48:59 DEBUG : pacer: Reducing sleep to 216.752569ms 2026/09/09 03:48:59 DEBUG : pacer: Reducing sleep to 103.684128ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/09 03:48:59 DEBUG : pacer: Reducing sleep to 46.055074ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/09 03:48:59 DEBUG : Creating backend with remote "TestZoho:rclone-test-vicideg2zeci/not found.txt" 2026/09/09 03:48:59 DEBUG : Config file has changed externally - reloading 2026/09/09 03:49:00 DEBUG : pacer: Reducing sleep to 108.996744ms 2026/09/09 03:49:00 DEBUG : pacer: Reducing sleep to 114.247703ms 2026/09/09 03:49:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:49:05 DEBUG : zoho root 'rclone-test-vicideg2zeci/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/09/09 03:49:09 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-vicideg2zeci" from "TestZoho:rclone-test-vicideg2zeci" 2026/09/09 03:49:09 DEBUG : Creating backend with remote "TestZoho:" 2026/09/09 03:49:09 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/09 03:49:14 DEBUG : pacer: Reducing sleep to 112.347774ms 2026/09/09 03:49:14 DEBUG : pacer: Reducing sleep to 231.390701ms 2026/09/09 03:49:14 DEBUG : pacer: Reducing sleep to 275.20162ms 2026/09/09 03:49:14 DEBUG : pacer: Reducing sleep to 216.64696ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/09 03:49:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2031: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/09/09 03:49:23 DEBUG : pacer: Reducing sleep to 18.479561ms 2026/09/09 03:49:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:49:32 DEBUG : pacer: Reducing sleep to 117.663393ms 2026/09/09 03:49:32 DEBUG : pacer: Reducing sleep to 228.117088ms 2026/09/09 03:49:33 DEBUG : pacer: Reducing sleep to 283.582227ms 2026/09/09 03:49:33 DEBUG : pacer: Reducing sleep to 202.926338ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/09 03:49:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:49:37 DEBUG : pacer: Reducing sleep to 117.932711ms 2026/09/09 03:49:37 DEBUG : pacer: Reducing sleep to 222.550917ms 2026/09/09 03:49:37 DEBUG : pacer: Reducing sleep to 281.866669ms 2026/09/09 03:49:37 DEBUG : pacer: Reducing sleep to 214.38438ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2090: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:438: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2195: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2211: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/09/09 03:49:42 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:49:46 DEBUG : pacer: Reducing sleep to 108.99448ms 2026/09/09 03:49:46 DEBUG : pacer: Reducing sleep to 230.022426ms 2026/09/09 03:49:46 DEBUG : pacer: Reducing sleep to 275.597849ms 2026/09/09 03:49:47 DEBUG : pacer: Reducing sleep to 220.551404ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/09 03:49:47 DEBUG : pacer: Reducing sleep to 99.435775ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2385: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2431: *zoho.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2452: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutRetry === RUN TestIntegration/FsMkdir/FsPutRetry/Simple === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/Status500 2026/09/09 03:49:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:49:51 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/09 03:49:51 DEBUG : pacer: Rate limited, increasing sleep to 1.329160641s fstests.go:2660: Put returned an error after the injected failure (acceptable): upload error: HTTP error 500 (500 Internal Server Error) returned body: "" 2026/09/09 03:49:55 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:49:55 DEBUG : pacer: Reducing sleep to 115.162379ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/09 03:49:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/09 03:49:57 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=9bu8ucf601d6419974c6c8414ba45e8433a80": write tcp: unexpected EOF) 2026/09/09 03:49:57 DEBUG : pacer: Rate limited, increasing sleep to 1.172643959s 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=9bu8ucf601d6419974c6c8414ba45e8433a80": write tcp: unexpected EOF 2026/09/09 03:49:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutRetry/Chunked fstests.go:2697: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/09/09 03:49:58 DEBUG : pacer: Reducing sleep to 139.394111ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n