"go test -v -timeout 2h0m0s -remote TestZoho: -verbose" - Starting (try 1/5) === RUN TestFolderListLimiter --- PASS: TestFolderListLimiter (0.00s) === RUN TestFolderWindowLimiterShape --- PASS: TestFolderWindowLimiterShape (0.00s) === RUN TestFolderWindowLimiterIdleResume --- PASS: TestFolderWindowLimiterIdleResume (0.00s) === RUN TestFolderWindowLimiterClamps --- PASS: TestFolderWindowLimiterClamps (0.00s) === RUN TestFolderListLimiterConcurrent --- PASS: TestFolderListLimiterConcurrent (0.00s) === RUN TestTPSMinSleep --- PASS: TestTPSMinSleep (0.00s) === RUN TestShouldRetry === RUN TestShouldRetry/429_honours_Retry-After 2026/08/29 04:26:05 NOTICE: zoho root '': Too many requests: Trying again in 6 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_without_Retry-After_falls_back_to_60s 2026/08/29 04:26:05 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_with_unparseable_Retry-After_falls_back 2026/08/29 04:26:05 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/08/29 04:26:05 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/08/29 04:26:05 ERROR : zoho: missing OAuth Scope. Run rclone config reconnect to fix this issue. === RUN TestShouldRetry/401_expired_token_retries === RUN TestShouldRetry/401_R008_deleted_folder_is_not_retried === RUN TestShouldRetry/cancelled_context_aborts --- PASS: TestShouldRetry (0.00s) --- PASS: TestShouldRetry/429_honours_Retry-After (0.00s) --- PASS: TestShouldRetry/429_without_Retry-After_falls_back_to_60s (0.00s) --- PASS: TestShouldRetry/429_with_unparseable_Retry-After_falls_back (0.00s) --- PASS: TestShouldRetry/401_missing_scope_aborts (0.00s) --- PASS: TestShouldRetry/401_expired_token_retries (0.00s) --- PASS: TestShouldRetry/401_R008_deleted_folder_is_not_retried (0.00s) --- PASS: TestShouldRetry/cancelled_context_aborts (0.00s) === RUN TestIsMissingResourceErr --- PASS: TestIsMissingResourceErr (0.00s) === RUN TestThrottleEpisode 2026/08/29 04:26:05 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/08/29 04:26:05 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleStateConcurrent (0.00s) === RUN TestConfigRootFolderID === RUN TestConfigRootFolderID/SetRootAsksBeforeChanging === RUN TestConfigRootFolderID/EmptyRootGoesToEdition === RUN TestConfigRootFolderID/KeepRootOnNo === RUN TestConfigRootFolderID/ChangeRootOnYes --- PASS: TestConfigRootFolderID (0.00s) --- PASS: TestConfigRootFolderID/SetRootAsksBeforeChanging (0.00s) --- PASS: TestConfigRootFolderID/EmptyRootGoesToEdition (0.00s) --- PASS: TestConfigRootFolderID/KeepRootOnNo (0.00s) --- PASS: TestConfigRootFolderID/ChangeRootOnYes (0.00s) === RUN TestIntegration fstests.go:457: Using remote "TestZoho:" 2026/08/29 04:26:05 DEBUG : Creating backend with remote "TestZoho:rclone-test-ciyutoy9sago" 2026/08/29 04:26:05 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/29 04:26:05 DEBUG : zoho root 'rclone-test-ciyutoy9sago': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/08/29 04:26:05 DEBUG : TestZoho: Token expired 2026/08/29 04:26:05 DEBUG : TestZoho: No updated token found in the config file 2026/08/29 04:26:05 DEBUG : TestZoho: Token refresh successful 2026/08/29 04:26:05 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/08/29 04:26:05 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:498: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:526: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/08/29 04:26:06 DEBUG : pacer: Reducing sleep to 10.619883ms 2026/08/29 04:26:06 DEBUG : pacer: Reducing sleep to 41.929356ms 2026/08/29 04:26:06 DEBUG : pacer: Reducing sleep to 21.332339ms 2026/08/29 04:26:06 DEBUG : pacer: Reducing sleep to 144.570333ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/08/29 04:26:06 DEBUG : pacer: Reducing sleep to 258.101146ms 2026/08/29 04:26:06 DEBUG : pacer: Reducing sleep to 260.827331ms === RUN TestIntegration/FsMkdir 2026/08/29 04:26:07 DEBUG : pacer: Reducing sleep to 108.442573ms 2026/08/29 04:26:07 DEBUG : pacer: Reducing sleep to 5.522034ms 2026/08/29 04:26:07 DEBUG : pacer: Reducing sleep to 123.99537ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/08/29 04:26:07 INFO : dir/subdir: Making directory 2026/08/29 04:26:07 DEBUG : pacer: Reducing sleep to 247.335476ms 2026/08/29 04:26:07 DEBUG : pacer: Reducing sleep to 246.154896ms 2026/08/29 04:26:07 DEBUG : pacer: Reducing sleep to 208.207303ms 2026/08/29 04:26:08 DEBUG : pacer: Reducing sleep to 65.804063ms 2026/08/29 04:26:08 DEBUG : pacer: Reducing sleep to 73.263217ms 2026/08/29 04:26:08 DEBUG : pacer: Reducing sleep to 176.433536ms 2026/08/29 04:26:08 DEBUG : pacer: Reducing sleep to 277.962966ms 2026/08/29 04:26:08 INFO : dir/subdir: Removing directory 2026/08/29 04:26:08 DEBUG : pacer: Reducing sleep to 259.034891ms 2026/08/29 04:26:08 DEBUG : pacer: Reducing sleep to 139.417951ms 2026/08/29 04:26:09 DEBUG : pacer: Reducing sleep to 50.981192ms 2026/08/29 04:26:09 DEBUG : pacer: Reducing sleep to 90.123558ms 2026/08/29 04:26:09 INFO : dir: Removing directory 2026/08/29 04:26:09 DEBUG : pacer: Reducing sleep to 195.735607ms 2026/08/29 04:26:09 DEBUG : pacer: Reducing sleep to 268.151887ms 2026/08/29 04:26:09 DEBUG : pacer: Reducing sleep to 249.887436ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/08/29 04:26:09 DEBUG : pacer: Reducing sleep to 149.128639ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/08/29 04:26:14 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/08/29 04:26:18 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:727: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/08/29 04:26:28 DEBUG : pacer: Reducing sleep to 88.213484ms 2026/08/29 04:26:28 DEBUG : pacer: Reducing sleep to 210.858118ms 2026/08/29 04:26:28 DEBUG : pacer: Reducing sleep to 148.617068ms 2026/08/29 04:26:28 DEBUG : pacer: Reducing sleep to 232.462823ms 2026/08/29 04:26:32 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:26:32 DEBUG : pacer: Reducing sleep to 106.028575ms 2026/08/29 04:26:33 DEBUG : pacer: Reducing sleep to 90.760282ms 2026/08/29 04:26:33 DEBUG : pacer: Reducing sleep to 159.474512ms 2026/08/29 04:26:33 DEBUG : pacer: Reducing sleep to 226.140965ms 2026/08/29 04:26:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:727: testing "." 2026/08/29 04:26:42 DEBUG : pacer: Reducing sleep to 67.605577ms 2026/08/29 04:26:42 DEBUG : pacer: Reducing sleep to 193.11958ms 2026/08/29 04:26:42 DEBUG : pacer: Reducing sleep to 190.732641ms 2026/08/29 04:26:42 DEBUG : pacer: Reducing sleep to 250.40777ms 2026/08/29 04:26:46 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:26:46 DEBUG : pacer: Reducing sleep to 106.938754ms 2026/08/29 04:26:46 DEBUG : pacer: Reducing sleep to 73.941011ms 2026/08/29 04:26:47 DEBUG : pacer: Reducing sleep to 141.413306ms 2026/08/29 04:26:47 DEBUG : pacer: Reducing sleep to 224.680038ms 2026/08/29 04:26:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:727: testing ".." 2026/08/29 04:26:55 DEBUG : pacer: Reducing sleep to 89.489544ms 2026/08/29 04:26:56 DEBUG : pacer: Reducing sleep to 213.488455ms 2026/08/29 04:26:56 DEBUG : pacer: Reducing sleep to 125.625652ms 2026/08/29 04:26:56 DEBUG : pacer: Reducing sleep to 231.359401ms 2026/08/29 04:27:00 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:27:00 DEBUG : pacer: Reducing sleep to 96.067225ms 2026/08/29 04:27:00 DEBUG : pacer: Reducing sleep to 96.246472ms 2026/08/29 04:27:00 DEBUG : pacer: Reducing sleep to 173.624525ms 2026/08/29 04:27:00 DEBUG : pacer: Reducing sleep to 240.492638ms 2026/08/29 04:27:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:727: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/08/29 04:27:09 DEBUG : pacer: Reducing sleep to 98.163903ms 2026/08/29 04:27:09 DEBUG : zoho root 'rclone-test-ciyutoy9sago': folder listing limiter sweep: evicted 4 of 8 idle limiters 2026/08/29 04:27:09 DEBUG : pacer: Reducing sleep to 218.879494ms 2026/08/29 04:27:10 DEBUG : pacer: Reducing sleep to 191.811196ms 2026/08/29 04:27:10 DEBUG : pacer: Reducing sleep to 229.854858ms 2026/08/29 04:27:10 DEBUG : pacer: Reducing sleep to 197.097428ms 2026/08/29 04:27:10 DEBUG : pacer: Reducing sleep to 141.175553ms 2026/08/29 04:27:10 DEBUG : pacer: Reducing sleep to 95.05097ms 2026/08/29 04:27:10 DEBUG : pacer: Reducing sleep to 137.810278ms 2026/08/29 04:27:11 DEBUG : pacer: Reducing sleep to 194.380325ms 2026/08/29 04:27:11 DEBUG : pacer: Reducing sleep to 238.81214ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:727: testing " leading space" 2026/08/29 04:27:11 DEBUG : pacer: Reducing sleep to 207.463962ms 2026/08/29 04:27:11 DEBUG : pacer: Reducing sleep to 110.217119ms 2026/08/29 04:27:11 DEBUG : pacer: Reducing sleep to 93.03414ms 2026/08/29 04:27:12 DEBUG : pacer: Reducing sleep to 10.78575ms 2026/08/29 04:27:12 DEBUG : pacer: Reducing sleep to 121.082085ms 2026/08/29 04:27:12 DEBUG : pacer: Reducing sleep to 226.56945ms 2026/08/29 04:27:12 DEBUG : pacer: Reducing sleep to 284.049514ms 2026/08/29 04:27:12 DEBUG : pacer: Reducing sleep to 208.179931ms 2026/08/29 04:27:12 DEBUG : pacer: Reducing sleep to 109.529637ms 2026/08/29 04:27:13 DEBUG : pacer: Reducing sleep to 52.368022ms 2026/08/29 04:27:13 DEBUG : pacer: Reducing sleep to 126.538426ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:727: testing "~leading tilde" 2026/08/29 04:27:17 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:27:17 DEBUG : pacer: Reducing sleep to 98.172661ms 2026/08/29 04:27:17 DEBUG : pacer: Reducing sleep to 206.153051ms 2026/08/29 04:27:17 DEBUG : pacer: Reducing sleep to 194.465049ms 2026/08/29 04:27:18 DEBUG : pacer: Reducing sleep to 225.168851ms 2026/08/29 04:27:22 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:27:22 DEBUG : pacer: Reducing sleep to 114.63529ms 2026/08/29 04:27:22 DEBUG : pacer: Reducing sleep to 97.949829ms 2026/08/29 04:27:22 DEBUG : pacer: Reducing sleep to 163.309205ms 2026/08/29 04:27:22 DEBUG : pacer: Reducing sleep to 218.671471ms 2026/08/29 04:27:26 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:727: testing "␍leading CR" 2026/08/29 04:27:31 DEBUG : pacer: Reducing sleep to 85.647488ms 2026/08/29 04:27:31 DEBUG : pacer: Reducing sleep to 209.989216ms 2026/08/29 04:27:31 DEBUG : pacer: Reducing sleep to 173.888151ms 2026/08/29 04:27:31 DEBUG : pacer: Reducing sleep to 233.734526ms 2026/08/29 04:27:36 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:27:36 DEBUG : pacer: Reducing sleep to 103.256831ms 2026/08/29 04:27:36 DEBUG : pacer: Reducing sleep to 89.307028ms 2026/08/29 04:27:36 DEBUG : pacer: Reducing sleep to 161.078202ms 2026/08/29 04:27:36 DEBUG : pacer: Reducing sleep to 232.97872ms 2026/08/29 04:27:40 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:727: testing "␊leading LF" 2026/08/29 04:27:45 DEBUG : pacer: Reducing sleep to 45.369276ms 2026/08/29 04:27:45 DEBUG : pacer: Reducing sleep to 166.978164ms 2026/08/29 04:27:45 DEBUG : pacer: Reducing sleep to 133.806662ms 2026/08/29 04:27:45 DEBUG : pacer: Reducing sleep to 242.95999ms 2026/08/29 04:27:49 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:27:50 DEBUG : pacer: Reducing sleep to 76.640578ms 2026/08/29 04:27:50 DEBUG : pacer: Reducing sleep to 79.839448ms 2026/08/29 04:27:50 DEBUG : pacer: Reducing sleep to 176.841047ms 2026/08/29 04:27:50 DEBUG : pacer: Reducing sleep to 258.97946ms 2026/08/29 04:27:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:727: testing "␉leading HT" 2026/08/29 04:27:59 DEBUG : pacer: Reducing sleep to 98.06095ms 2026/08/29 04:27:59 DEBUG : pacer: Reducing sleep to 219.352679ms 2026/08/29 04:27:59 DEBUG : pacer: Reducing sleep to 198.067614ms 2026/08/29 04:27:59 DEBUG : pacer: Reducing sleep to 229.178144ms 2026/08/29 04:28:03 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:28:03 DEBUG : pacer: Reducing sleep to 115.109584ms 2026/08/29 04:28:04 DEBUG : pacer: Reducing sleep to 60.258123ms 2026/08/29 04:28:04 DEBUG : pacer: Reducing sleep to 154.068285ms 2026/08/29 04:28:04 DEBUG : pacer: Reducing sleep to 245.275723ms 2026/08/29 04:28:08 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:727: testing "␋leading VT" 2026/08/29 04:28:08 DEBUG : pacer: Reducing sleep to 118.555091ms 2026/08/29 04:28:08 DEBUG : pacer: Reducing sleep to 64.871144ms 2026/08/29 04:28:08 DEBUG : pacer: Reducing sleep to 130.837758ms 2026/08/29 04:28:08 DEBUG : pacer: Reducing sleep to 133.242548ms 2026/08/29 04:28:09 DEBUG : pacer: Reducing sleep to 250.035742ms 2026/08/29 04:28:10 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:28:10 DEBUG : zoho root 'rclone-test-ciyutoy9sago': folder listing limiter sweep: evicted 3 of 11 idle limiters 2026/08/29 04:28:10 DEBUG : pacer: Reducing sleep to 111.376274ms 2026/08/29 04:28:10 DEBUG : pacer: Reducing sleep to 72.469152ms 2026/08/29 04:28:10 DEBUG : pacer: Reducing sleep to 128.902242ms 2026/08/29 04:28:10 DEBUG : pacer: Reducing sleep to 224.358141ms 2026/08/29 04:28:10 DEBUG : pacer: Reducing sleep to 268.74337ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:727: testing ".leading dot" 2026/08/29 04:28:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:28:11 DEBUG : pacer: Reducing sleep to 85.74945ms 2026/08/29 04:28:11 DEBUG : pacer: Reducing sleep to 64.929643ms 2026/08/29 04:28:12 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:28:12 DEBUG : pacer: Reducing sleep to 102.48847ms 2026/08/29 04:28:12 DEBUG : pacer: Reducing sleep to 220.463767ms 2026/08/29 04:28:12 DEBUG : pacer: Reducing sleep to 278.53991ms 2026/08/29 04:28:12 DEBUG : pacer: Reducing sleep to 218.382017ms 2026/08/29 04:28:12 DEBUG : pacer: Reducing sleep to 112.957032ms 2026/08/29 04:28:13 DEBUG : pacer: Reducing sleep to 56.556464ms 2026/08/29 04:28:16 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:727: testing "trailing space " 2026/08/29 04:28:21 DEBUG : pacer: Reducing sleep to 82.22195ms 2026/08/29 04:28:21 DEBUG : pacer: Reducing sleep to 207.153372ms 2026/08/29 04:28:21 DEBUG : pacer: Reducing sleep to 213.276666ms 2026/08/29 04:28:21 DEBUG : pacer: Reducing sleep to 236.496556ms 2026/08/29 04:28:26 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:28:26 DEBUG : pacer: Reducing sleep to 88.04659ms 2026/08/29 04:28:26 DEBUG : pacer: Reducing sleep to 91.369696ms 2026/08/29 04:28:26 DEBUG : pacer: Reducing sleep to 177.422259ms 2026/08/29 04:28:26 DEBUG : pacer: Reducing sleep to 246.761591ms 2026/08/29 04:28:30 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:727: testing "trailing CR␍" 2026/08/29 04:28:35 DEBUG : pacer: Reducing sleep to 89.287417ms 2026/08/29 04:28:35 DEBUG : pacer: Reducing sleep to 212.934412ms 2026/08/29 04:28:35 DEBUG : pacer: Reducing sleep to 196.444327ms 2026/08/29 04:28:35 DEBUG : pacer: Reducing sleep to 229.397738ms 2026/08/29 04:28:39 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:28:39 DEBUG : pacer: Reducing sleep to 106.50515ms 2026/08/29 04:28:40 DEBUG : pacer: Reducing sleep to 94.468132ms 2026/08/29 04:28:40 DEBUG : pacer: Reducing sleep to 161.053544ms 2026/08/29 04:28:40 DEBUG : pacer: Reducing sleep to 225.838935ms 2026/08/29 04:28:44 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:727: testing "trailing LF␊" 2026/08/29 04:28:49 DEBUG : pacer: Reducing sleep to 95.882724ms 2026/08/29 04:28:49 DEBUG : pacer: Reducing sleep to 219.51561ms 2026/08/29 04:28:49 DEBUG : pacer: Reducing sleep to 137.232538ms 2026/08/29 04:28:49 DEBUG : pacer: Reducing sleep to 219.660829ms 2026/08/29 04:28:53 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:28:53 DEBUG : pacer: Reducing sleep to 103.641802ms 2026/08/29 04:28:53 DEBUG : pacer: Reducing sleep to 106.165162ms 2026/08/29 04:28:54 DEBUG : pacer: Reducing sleep to 163.903122ms 2026/08/29 04:28:54 DEBUG : pacer: Reducing sleep to 229.556013ms 2026/08/29 04:28:58 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:727: testing "trailing HT␉" 2026/08/29 04:29:03 DEBUG : pacer: Reducing sleep to 81.537458ms 2026/08/29 04:29:03 DEBUG : pacer: Reducing sleep to 206.946018ms 2026/08/29 04:29:03 DEBUG : pacer: Reducing sleep to 197.787793ms 2026/08/29 04:29:03 DEBUG : pacer: Reducing sleep to 235.420245ms 2026/08/29 04:29:07 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:29:07 DEBUG : pacer: Reducing sleep to 102.698209ms 2026/08/29 04:29:07 DEBUG : pacer: Reducing sleep to 86.05727ms 2026/08/29 04:29:07 DEBUG : pacer: Reducing sleep to 159.817457ms 2026/08/29 04:29:08 DEBUG : pacer: Reducing sleep to 231.199432ms 2026/08/29 04:29:08 DEBUG : pacer: Reducing sleep to 247.667546ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:727: testing "trailing VT␋" 2026/08/29 04:29:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:29:09 DEBUG : pacer: Reducing sleep to 82.197373ms 2026/08/29 04:29:09 DEBUG : pacer: Reducing sleep to 86.392735ms 2026/08/29 04:29:09 DEBUG : pacer: Reducing sleep to 101.218245ms 2026/08/29 04:29:09 DEBUG : pacer: Reducing sleep to 213.495272ms 2026/08/29 04:29:09 DEBUG : pacer: Reducing sleep to 282.656099ms 2026/08/29 04:29:09 DEBUG : pacer: Reducing sleep to 229.102693ms 2026/08/29 04:29:10 DEBUG : pacer: Reducing sleep to 108.569896ms 2026/08/29 04:29:10 DEBUG : pacer: Reducing sleep to 49.674699ms 2026/08/29 04:29:10 DEBUG : pacer: Reducing sleep to 105.481824ms 2026/08/29 04:29:10 DEBUG : zoho root 'rclone-test-ciyutoy9sago': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/08/29 04:29:10 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:727: testing "trailing dot." 2026/08/29 04:29:11 DEBUG : pacer: Reducing sleep to 78.747549ms 2026/08/29 04:29:11 DEBUG : pacer: Reducing sleep to 203.36296ms 2026/08/29 04:29:11 DEBUG : pacer: Reducing sleep to 169.841225ms 2026/08/29 04:29:11 DEBUG : pacer: Reducing sleep to 231.413319ms 2026/08/29 04:29:15 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:29:15 DEBUG : pacer: Reducing sleep to 102.924881ms 2026/08/29 04:29:16 DEBUG : pacer: Reducing sleep to 83.277643ms 2026/08/29 04:29:16 DEBUG : pacer: Reducing sleep to 158.336023ms 2026/08/29 04:29:16 DEBUG : pacer: Reducing sleep to 239.661402ms 2026/08/29 04:29:20 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:718: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:727: testing "test%46.txt" 2026/08/29 04:29:25 DEBUG : pacer: Reducing sleep to 85.572492ms 2026/08/29 04:29:25 DEBUG : pacer: Reducing sleep to 210.284898ms 2026/08/29 04:29:25 DEBUG : pacer: Reducing sleep to 128.358671ms 2026/08/29 04:29:25 DEBUG : pacer: Reducing sleep to 232.990787ms 2026/08/29 04:29:29 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:29:29 DEBUG : pacer: Reducing sleep to 101.792012ms 2026/08/29 04:29:29 DEBUG : pacer: Reducing sleep to 73.062901ms 2026/08/29 04:29:30 DEBUG : pacer: Reducing sleep to 12.010394ms 2026/08/29 04:29:30 DEBUG : pacer: Reducing sleep to 13.650642ms 2026/08/29 04:29:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/08/29 04:29:52 DEBUG : pacer: Reducing sleep to 84.389389ms 2026/08/29 04:29:52 DEBUG : pacer: Reducing sleep to 207.241091ms 2026/08/29 04:29:53 DEBUG : pacer: Reducing sleep to 253.513139ms 2026/08/29 04:29:53 DEBUG : pacer: Reducing sleep to 247.654456ms 2026/08/29 04:29:53 DEBUG : pacer: Reducing sleep to 113.085265ms 2026/08/29 04:29:53 DEBUG : pacer: Reducing sleep to 78.640736ms 2026/08/29 04:29:53 DEBUG : pacer: Reducing sleep to 86.37093ms 2026/08/29 04:29:53 DEBUG : pacer: Reducing sleep to 252.474963ms 2026/08/29 04:29:53 DEBUG : pacer: Reducing sleep to 289.464118ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/08/29 04:29:57 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:29:57 DEBUG : pacer: Reducing sleep to 62.341162ms 2026/08/29 04:30:02 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:30:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:30:02 UTC": parsing time "Sat, 29 Aug 2026 04:30:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:30:02 UTC" as " " 2026/08/29 04:30:02 DEBUG : pacer: Reducing sleep to 111.412985ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:806: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:839: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:892: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/08/29 04:30:06 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:30:11 DEBUG : zoho root 'rclone-test-ciyutoy9sago': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/08/29 04:30:11 DEBUG : pacer: Reducing sleep to 116.221429ms 2026/08/29 04:30:11 DEBUG : pacer: Reducing sleep to 149.535613ms 2026/08/29 04:30:11 DEBUG : pacer: Reducing sleep to 268.726411ms 2026/08/29 04:30:11 DEBUG : pacer: Reducing sleep to 277.278831ms 2026/08/29 04:30:12 DEBUG : pacer: Reducing sleep to 57.746879ms 2026/08/29 04:30:12 DEBUG : pacer: Reducing sleep to 67.344004ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/08/29 04:30:12 DEBUG : pacer: Reducing sleep to 168.510566ms 2026/08/29 04:30:12 DEBUG : pacer: Reducing sleep to 271.450377ms 2026/08/29 04:30:12 DEBUG : pacer: Reducing sleep to 259.846104ms 2026/08/29 04:30:12 DEBUG : pacer: Reducing sleep to 166.259703ms 2026/08/29 04:30:13 DEBUG : pacer: Reducing sleep to 69.747495ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/08/29 04:30:13 DEBUG : pacer: Reducing sleep to 71.659904ms 2026/08/29 04:30:13 DEBUG : pacer: Reducing sleep to 163.844509ms 2026/08/29 04:30:13 DEBUG : pacer: Reducing sleep to 263.703841ms 2026/08/29 04:30:13 DEBUG : pacer: Reducing sleep to 259.137102ms 2026/08/29 04:30:13 DEBUG : pacer: Reducing sleep to 163.027594ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/08/29 04:30:14 DEBUG : pacer: Reducing sleep to 69.021651ms 2026/08/29 04:30:14 DEBUG : pacer: Reducing sleep to 76.666649ms 2026/08/29 04:30:14 DEBUG : pacer: Reducing sleep to 161.458469ms 2026/08/29 04:30:14 DEBUG : pacer: Reducing sleep to 262.744446ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/08/29 04:30:14 DEBUG : Creating backend with remote "TestZoho:" 2026/08/29 04:30:14 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/08/29 04:30:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/08/29 04:30:23 DEBUG : pacer: Reducing sleep to 113.185124ms 2026/08/29 04:30:23 DEBUG : pacer: Reducing sleep to 221.065568ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/08/29 04:30:28 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:30:28 DEBUG : pacer: Reducing sleep to 107.663123ms 2026/08/29 04:30:28 DEBUG : pacer: Reducing sleep to 110.993523ms 2026/08/29 04:30:28 DEBUG : pacer: Reducing sleep to 165.861632ms 2026/08/29 04:30:28 DEBUG : pacer: Reducing sleep to 222.935949ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/08/29 04:30:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1145: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/08/29 04:30:37 DEBUG : pacer: Reducing sleep to 114.318285ms 2026/08/29 04:30:37 DEBUG : pacer: Reducing sleep to 232.418806ms 2026/08/29 04:30:37 DEBUG : pacer: Reducing sleep to 278.511539ms 2026/08/29 04:30:37 DEBUG : pacer: Reducing sleep to 214.001786ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/08/29 04:30:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/08/29 04:30:42 DEBUG : pacer: Reducing sleep to 116.058222ms 2026/08/29 04:30:46 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:30:46 DEBUG : pacer: Reducing sleep to 91.135826ms 2026/08/29 04:30:47 DEBUG : pacer: Reducing sleep to 123.767616ms 2026/08/29 04:30:47 DEBUG : pacer: Reducing sleep to 241.17185ms 2026/08/29 04:30:51 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:30:51 DEBUG : pacer: Reducing sleep to 115.70244ms 2026/08/29 04:30:51 DEBUG : pacer: Reducing sleep to 280.304376ms 2026/08/29 04:30:51 DEBUG : pacer: Reducing sleep to 259.198321ms 2026/08/29 04:30:52 DEBUG : pacer: Reducing sleep to 138.788522ms 2026/08/29 04:30:52 DEBUG : pacer: Reducing sleep to 54.21817ms 2026/08/29 04:30:52 DEBUG : pacer: Reducing sleep to 77.428745ms 2026/08/29 04:30:52 DEBUG : pacer: Reducing sleep to 185.190316ms 2026/08/29 04:30:56 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:30:56 DEBUG : pacer: Reducing sleep to 116.841693ms 2026/08/29 04:30:56 DEBUG : pacer: Reducing sleep to 162.722238ms 2026/08/29 04:30:56 DEBUG : pacer: Reducing sleep to 219.975394ms 2026/08/29 04:30:56 DEBUG : pacer: Reducing sleep to 213.376822ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/08/29 04:31:00 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:05 DEBUG : pacer: Reducing sleep to 89.872291ms 2026/08/29 04:31:05 DEBUG : pacer: Reducing sleep to 126.587144ms 2026/08/29 04:31:05 DEBUG : pacer: Reducing sleep to 243.834032ms 2026/08/29 04:31:09 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:10 DEBUG : pacer: Reducing sleep to 116.688212ms 2026/08/29 04:31:10 DEBUG : pacer: Reducing sleep to 282.085972ms 2026/08/29 04:31:10 DEBUG : pacer: Reducing sleep to 263.392499ms 2026/08/29 04:31:10 DEBUG : pacer: Reducing sleep to 133.474335ms 2026/08/29 04:31:10 DEBUG : pacer: Reducing sleep to 48.023123ms 2026/08/29 04:31:10 DEBUG : Creating backend with remote "TestZoho:rclone-test-ciyutoy9sago/dirToPurgeFromRoot" 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 114.743902ms 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 238.82546ms 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 275.801156ms 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 116.640632ms 2026/08/29 04:31:11 DEBUG : zoho root 'rclone-test-ciyutoy9sago': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 221.406817ms 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 282.697141ms 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 219.889802ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/08/29 04:31:11 DEBUG : Creating backend with remote "TestZoho:rclone-test-ciyutoy9sago/hello? sausage/êé" 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 116.714202ms 2026/08/29 04:31:11 DEBUG : pacer: Reducing sleep to 236.04636ms 2026/08/29 04:31:12 DEBUG : pacer: Reducing sleep to 256.880968ms 2026/08/29 04:31:12 DEBUG : pacer: Reducing sleep to 214.822761ms 2026/08/29 04:31:12 DEBUG : pacer: Reducing sleep to 96.79588ms 2026/08/29 04:31:12 DEBUG : pacer: Reducing sleep to 81.294735ms 2026/08/29 04:31:12 DEBUG : pacer: Reducing sleep to 111.14914ms 2026/08/29 04:31:12 DEBUG : pacer: Reducing sleep to 233.958976ms 2026/08/29 04:31:13 DEBUG : pacer: Reducing sleep to 235.898325ms 2026/08/29 04:31:13 DEBUG : pacer: Reducing sleep to 221.060715ms 2026/08/29 04:31:13 DEBUG : pacer: Reducing sleep to 106.729113ms 2026/08/29 04:31:13 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:13 DEBUG : pacer: Reducing sleep to 117.806855ms 2026/08/29 04:31:13 DEBUG : pacer: Reducing sleep to 222.846016ms 2026/08/29 04:31:14 DEBUG : pacer: Reducing sleep to 260.056989ms 2026/08/29 04:31:14 DEBUG : pacer: Reducing sleep to 217.097019ms 2026/08/29 04:31:14 DEBUG : pacer: Reducing sleep to 129.032932ms 2026/08/29 04:31:14 DEBUG : pacer: Reducing sleep to 76.63803ms 2026/08/29 04:31:14 DEBUG : pacer: Reducing sleep to 118.069184ms 2026/08/29 04:31:15 DEBUG : pacer: Reducing sleep to 201.250808ms 2026/08/29 04:31:15 DEBUG : pacer: Reducing sleep to 217.785454ms 2026/08/29 04:31:15 DEBUG : pacer: Reducing sleep to 186.631897ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/08/29 04:31:15 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:15 DEBUG : pacer: Reducing sleep to 100.124015ms 2026/08/29 04:31:15 DEBUG : pacer: Reducing sleep to 212.623577ms 2026/08/29 04:31:15 DEBUG : pacer: Reducing sleep to 283.367468ms 2026/08/29 04:31:18 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:18 DEBUG : pacer: Reducing sleep to 116.22596ms 2026/08/29 04:31:18 DEBUG : pacer: Reducing sleep to 51.799443ms 2026/08/29 04:31:23 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:23 DEBUG : pacer: Reducing sleep to 94.788107ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1318: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/08/29 04:31:27 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:27 DEBUG : pacer: Reducing sleep to 104.868659ms 2026/08/29 04:31:28 DEBUG : pacer: Reducing sleep to 145.82745ms 2026/08/29 04:31:28 DEBUG : pacer: Reducing sleep to 253.548197ms 2026/08/29 04:31:28 DEBUG : pacer: Reducing sleep to 276.402453ms 2026/08/29 04:31:28 DEBUG : pacer: Reducing sleep to 196.162638ms 2026/08/29 04:31:28 DEBUG : pacer: Reducing sleep to 82.686925ms 2026/08/29 04:31:29 DEBUG : pacer: Reducing sleep to 56.588597ms 2026/08/29 04:31:32 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:42 DEBUG : pacer: Reducing sleep to 98.615469ms 2026/08/29 04:31:42 DEBUG : pacer: Reducing sleep to 200.934032ms 2026/08/29 04:31:42 DEBUG : pacer: Reducing sleep to 230.964914ms 2026/08/29 04:31:42 DEBUG : pacer: Reducing sleep to 230.829888ms 2026/08/29 04:31:46 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:46 DEBUG : pacer: Reducing sleep to 118.560432ms 2026/08/29 04:31:46 DEBUG : pacer: Reducing sleep to 282.435174ms 2026/08/29 04:31:46 DEBUG : pacer: Reducing sleep to 263.162371ms 2026/08/29 04:31:47 DEBUG : pacer: Reducing sleep to 150.052845ms 2026/08/29 04:31:47 DEBUG : pacer: Reducing sleep to 59.44446ms 2026/08/29 04:31:47 DEBUG : pacer: Reducing sleep to 69.786243ms 2026/08/29 04:31:47 DEBUG : pacer: Reducing sleep to 164.509491ms 2026/08/29 04:31:47 DEBUG : pacer: Reducing sleep to 247.530402ms 2026/08/29 04:31:47 DEBUG : pacer: Reducing sleep to 261.52776ms 2026/08/29 04:31:51 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:51 DEBUG : pacer: Reducing sleep to 124.456279ms 2026/08/29 04:31:51 DEBUG : pacer: Reducing sleep to 282.950388ms 2026/08/29 04:31:51 DEBUG : pacer: Reducing sleep to 243.352283ms 2026/08/29 04:31:51 DEBUG : pacer: Reducing sleep to 123.582098ms 2026/08/29 04:31:52 DEBUG : pacer: Reducing sleep to 49.259685ms 2026/08/29 04:31:55 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:31:55 DEBUG : pacer: Reducing sleep to 111.050335ms 2026/08/29 04:31:56 DEBUG : pacer: Reducing sleep to 136.160896ms 2026/08/29 04:31:56 DEBUG : pacer: Reducing sleep to 241.283928ms 2026/08/29 04:32:00 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:32:00 DEBUG : pacer: Reducing sleep to 120.445221ms 2026/08/29 04:32:00 DEBUG : pacer: Reducing sleep to 264.013017ms 2026/08/29 04:32:00 DEBUG : pacer: Reducing sleep to 279.502406ms 2026/08/29 04:32:01 DEBUG : pacer: Reducing sleep to 173.251868ms 2026/08/29 04:32:01 DEBUG : pacer: Reducing sleep to 67.289846ms 2026/08/29 04:32:01 DEBUG : pacer: Reducing sleep to 57.674475ms 2026/08/29 04:32:01 DEBUG : pacer: Reducing sleep to 151.881457ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1430: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/08/29 04:32:01 DEBUG : Creating backend with remote "TestZoho:rclone-test-lijequv4nuda" 2026/08/29 04:32:01 DEBUG : pacer: Reducing sleep to 41.885705ms 2026/08/29 04:32:02 DEBUG : pacer: Reducing sleep to 60.389146ms 2026/08/29 04:32:02 DEBUG : pacer: Reducing sleep to 87.059143ms 2026/08/29 04:32:02 DEBUG : pacer: Reducing sleep to 210.013223ms 2026/08/29 04:32:02 DEBUG : pacer: Reducing sleep to 266.140509ms 2026/08/29 04:32:02 DEBUG : pacer: Reducing sleep to 246.608138ms 2026/08/29 04:32:02 DEBUG : pacer: Reducing sleep to 112.632263ms 2026/08/29 04:32:03 DEBUG : pacer: Reducing sleep to 43.346074ms 2026/08/29 04:32:03 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:32:03 DEBUG : pacer: Reducing sleep to 33.981018ms 2026/08/29 04:32:03 DEBUG : pacer: Reducing sleep to 152.636879ms 2026/08/29 04:32:05 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:32:05 DEBUG : pacer: Reducing sleep to 121.74167ms 2026/08/29 04:32:05 DEBUG : pacer: Reducing sleep to 182.173026ms 2026/08/29 04:32:05 DEBUG : pacer: Reducing sleep to 230.591271ms 2026/08/29 04:32:05 DEBUG : pacer: Reducing sleep to 199.34822ms 2026/08/29 04:32:07 DEBUG : pacer: Reducing sleep to 37.274927ms 2026/08/29 04:32:09 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:32:09 DEBUG : pacer: Reducing sleep to 115.300595ms 2026/08/29 04:32:09 DEBUG : pacer: Reducing sleep to 235.16516ms 2026/08/29 04:32:09 DEBUG : pacer: Reducing sleep to 280.150162ms 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 210.982308ms 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 124.347662ms 2026/08/29 04:32:10 DEBUG : zoho root 'rclone-test-lijequv4nuda': Purge remote 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 144.430497ms 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 162.72235ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 116.839341ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 118.243044ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/08/29 04:32:10 DEBUG : pacer: Reducing sleep to 170.7168ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/08/29 04:32:11 DEBUG : pacer: Reducing sleep to 214.728064ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/08/29 04:32:11 DEBUG : pacer: Reducing sleep to 212.746703ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType 2026/08/29 04:32:11 DEBUG : zoho root 'rclone-test-ciyutoy9sago': folder listing limiter sweep: evicted 2 of 15 idle limiters 2026/08/29 04:32:15 DEBUG : pacer: Reducing sleep to 0s fstests.go:1626: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata fstests.go:1657: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1728: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1785: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/08/29 04:32:39 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:32:39 UTC": parsing time "Sat, 29 Aug 2026 04:32:39 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:32:39 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/08/29 04:32:43 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:32:43 UTC": parsing time "Sat, 29 Aug 2026 04:32:43 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:32:43 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/08/29 04:32:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:32:48 UTC": parsing time "Sat, 29 Aug 2026 04:32:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:32:48 UTC" as " " 2026/08/29 04:32:48 DEBUG : pacer: Reducing sleep to 2.142923ms 2026/08/29 04:32:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:32:48 UTC": parsing time "Sat, 29 Aug 2026 04:32:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:32:48 UTC" as " " 2026/08/29 04:32:48 DEBUG : pacer: Reducing sleep to 560.982µs 2026/08/29 04:32:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:32:48 UTC": parsing time "Sat, 29 Aug 2026 04:32:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:32:48 UTC" as " " 2026/08/29 04:32:48 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:32:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:32:49 UTC": parsing time "Sat, 29 Aug 2026 04:32:49 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:32:49 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/08/29 04:32:52 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:32:52 UTC": parsing time "Sat, 29 Aug 2026 04:32:52 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:32:52 UTC" as " " 2026/08/29 04:32:52 DEBUG : pacer: Reducing sleep to 12.972469ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/08/29 04:32:57 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:32:57 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:32:57 UTC": parsing time "Sat, 29 Aug 2026 04:32:57 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:32:57 UTC" as " " 2026/08/29 04:33:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:33:02 UTC": parsing time "Sat, 29 Aug 2026 04:33:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:33:02 UTC" as " " 2026/08/29 04:33:02 DEBUG : pacer: Reducing sleep to 17.459555ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/08/29 04:33:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:33:02 UTC": parsing time "Sat, 29 Aug 2026 04:33:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:33:02 UTC" as " " 2026/08/29 04:33:02 DEBUG : pacer: Reducing sleep to 39.413691ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/08/29 04:33:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:33:02 UTC": parsing time "Sat, 29 Aug 2026 04:33:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:33:02 UTC" as " " 2026/08/29 04:33:02 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/08/29 04:33:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:33:02 UTC": parsing time "Sat, 29 Aug 2026 04:33:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:33:02 UTC" as " " 2026/08/29 04:33:02 DEBUG : pacer: Reducing sleep to 1.140131ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/08/29 04:33:02 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:33:02 UTC": parsing time "Sat, 29 Aug 2026 04:33:02 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:33:02 UTC" as " " 2026/08/29 04:33:02 DEBUG : pacer: Reducing sleep to 2.591311ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/08/29 04:33:06 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:33:06 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:33:06 UTC": parsing time "Sat, 29 Aug 2026 04:33:06 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:33:06 UTC" as " " 2026/08/29 04:33:06 DEBUG : pacer: Reducing sleep to 14.261116ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/08/29 04:33:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:33:11 DEBUG : pacer: Reducing sleep to 12.472496ms 2026/08/29 04:33:11 DEBUG : zoho root 'rclone-test-ciyutoy9sago': folder listing limiter sweep: evicted 12 of 13 idle limiters 2026/08/29 04:33:11 DEBUG : pacer: Reducing sleep to 129.427748ms 2026/08/29 04:33:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 29 Aug 2026 04:33:11 UTC": parsing time "Sat, 29 Aug 2026 04:33:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 29 Aug 2026 04:33:11 UTC" as " " 2026/08/29 04:33:11 DEBUG : pacer: Reducing sleep to 116.519658ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/08/29 04:33:11 DEBUG : pacer: Reducing sleep to 225.467386ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/08/29 04:33:11 DEBUG : Creating backend with remote "TestZoho:rclone-test-ciyutoy9sago/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/08/29 04:33:12 DEBUG : pacer: Reducing sleep to 112.858444ms 2026/08/29 04:33:12 DEBUG : pacer: Reducing sleep to 200.879886ms 2026/08/29 04:33:12 DEBUG : pacer: Reducing sleep to 284.156487ms 2026/08/29 04:33:12 DEBUG : pacer: Reducing sleep to 243.762129ms 2026/08/29 04:33:12 DEBUG : pacer: Reducing sleep to 129.501517ms 2026/08/29 04:33:13 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:33:13 DEBUG : pacer: Reducing sleep to 117.839392ms 2026/08/29 04:33:13 DEBUG : pacer: Reducing sleep to 231.164277ms 2026/08/29 04:33:13 DEBUG : pacer: Reducing sleep to 285.721874ms 2026/08/29 04:33:13 DEBUG : pacer: Reducing sleep to 213.518498ms 2026/08/29 04:33:13 DEBUG : pacer: Reducing sleep to 99.083274ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/08/29 04:33:13 DEBUG : pacer: Reducing sleep to 45.857474ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/08/29 04:33:13 DEBUG : Creating backend with remote "TestZoho:rclone-test-ciyutoy9sago/not found.txt" 2026/08/29 04:33:14 DEBUG : pacer: Reducing sleep to 110.133197ms 2026/08/29 04:33:14 DEBUG : pacer: Reducing sleep to 151.928489ms 2026/08/29 04:33:18 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:33:23 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1987: Opening root remote "TestZoho:" path "rclone-test-ciyutoy9sago" from "TestZoho:rclone-test-ciyutoy9sago" 2026/08/29 04:33:23 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/08/29 04:33:28 DEBUG : pacer: Reducing sleep to 118.53717ms 2026/08/29 04:33:28 DEBUG : pacer: Reducing sleep to 231.522104ms 2026/08/29 04:33:28 DEBUG : pacer: Reducing sleep to 284.232985ms 2026/08/29 04:33:28 DEBUG : pacer: Reducing sleep to 206.871268ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/08/29 04:33:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2024: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/08/29 04:33:37 DEBUG : pacer: Reducing sleep to 38.300002ms 2026/08/29 04:33:41 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:33:46 DEBUG : pacer: Reducing sleep to 116.289985ms 2026/08/29 04:33:46 DEBUG : pacer: Reducing sleep to 232.201127ms 2026/08/29 04:33:46 DEBUG : pacer: Reducing sleep to 280.477383ms 2026/08/29 04:33:46 DEBUG : pacer: Reducing sleep to 218.212994ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/08/29 04:33:47 DEBUG : pacer: Reducing sleep to 71.366219ms 2026/08/29 04:33:51 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:33:51 DEBUG : pacer: Reducing sleep to 121.390871ms 2026/08/29 04:33:51 DEBUG : pacer: Reducing sleep to 237.237599ms 2026/08/29 04:33:51 DEBUG : pacer: Reducing sleep to 285.984224ms 2026/08/29 04:33:51 DEBUG : pacer: Reducing sleep to 207.870151ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2083: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:431: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2188: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2204: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/08/29 04:33:55 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:34:00 DEBUG : pacer: Reducing sleep to 119.000888ms 2026/08/29 04:34:00 DEBUG : pacer: Reducing sleep to 218.068876ms 2026/08/29 04:34:00 DEBUG : pacer: Reducing sleep to 285.908494ms 2026/08/29 04:34:00 DEBUG : pacer: Reducing sleep to 227.081197ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/08/29 04:34:01 DEBUG : pacer: Reducing sleep to 111.42588ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2378: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2424: *zoho.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2445: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/08/29 04:34:04 DEBUG : pacer: Reducing sleep to 0s 2026/08/29 04:34:05 DEBUG : pacer: Reducing sleep to 139.453726ms fstests.go:2596: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n