"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/27 01:05:13 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/27 01:05:13 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/27 01:05:13 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/08/27 01:05:13 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/27 01:05:13 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/27 01:05:13 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/27 01:05:13 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/27 01:05:13 DEBUG : Creating backend with remote "TestZoho:rclone-test-qehatox1jepo" 2026/08/27 01:05:13 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/08/27 01:05:13 DEBUG : zoho root 'rclone-test-qehatox1jepo': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/08/27 01:05:13 DEBUG : pacer: Reducing sleep to 1.49326ms === 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/27 01:05:13 DEBUG : pacer: Reducing sleep to 32.416151ms 2026/08/27 01:05:13 DEBUG : pacer: Reducing sleep to 72.800096ms 2026/08/27 01:05:13 DEBUG : pacer: Reducing sleep to 51.688701ms 2026/08/27 01:05:14 DEBUG : pacer: Reducing sleep to 179.788807ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/08/27 01:05:14 DEBUG : pacer: Reducing sleep to 248.4162ms 2026/08/27 01:05:14 DEBUG : pacer: Reducing sleep to 263.799506ms === RUN TestIntegration/FsMkdir 2026/08/27 01:05:14 DEBUG : pacer: Reducing sleep to 107.629539ms 2026/08/27 01:05:14 DEBUG : pacer: Reducing sleep to 2.002255ms 2026/08/27 01:05:14 DEBUG : pacer: Reducing sleep to 126.371176ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/08/27 01:05:14 INFO : dir/subdir: Making directory 2026/08/27 01:05:14 DEBUG : pacer: Reducing sleep to 252.13506ms 2026/08/27 01:05:15 DEBUG : pacer: Reducing sleep to 95.800563ms 2026/08/27 01:05:15 DEBUG : pacer: Reducing sleep to 204.466809ms 2026/08/27 01:05:15 DEBUG : pacer: Reducing sleep to 185.320274ms 2026/08/27 01:05:15 DEBUG : pacer: Reducing sleep to 226.131844ms 2026/08/27 01:05:15 DEBUG : pacer: Reducing sleep to 207.753378ms 2026/08/27 01:05:16 DEBUG : pacer: Reducing sleep to 156.10982ms 2026/08/27 01:05:16 INFO : dir/subdir: Removing directory 2026/08/27 01:05:16 DEBUG : pacer: Reducing sleep to 107.299762ms 2026/08/27 01:05:16 DEBUG : pacer: Reducing sleep to 111.133061ms 2026/08/27 01:05:16 DEBUG : pacer: Reducing sleep to 176.340099ms 2026/08/27 01:05:16 DEBUG : pacer: Reducing sleep to 241.149666ms 2026/08/27 01:05:16 INFO : dir: Removing directory 2026/08/27 01:05:16 DEBUG : pacer: Reducing sleep to 218.846872ms 2026/08/27 01:05:17 DEBUG : pacer: Reducing sleep to 137.942652ms 2026/08/27 01:05:17 DEBUG : pacer: Reducing sleep to 96.638926ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/08/27 01:05:17 DEBUG : pacer: Reducing sleep to 128.202256ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/08/27 01:05:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/08/27 01:05:26 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/27 01:05:35 DEBUG : pacer: Reducing sleep to 98.125485ms 2026/08/27 01:05:35 DEBUG : pacer: Reducing sleep to 224.057647ms 2026/08/27 01:05:36 DEBUG : pacer: Reducing sleep to 156.188206ms 2026/08/27 01:05:36 DEBUG : pacer: Reducing sleep to 228.868733ms 2026/08/27 01:05:40 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:05:40 DEBUG : pacer: Reducing sleep to 112.614148ms 2026/08/27 01:05:40 DEBUG : pacer: Reducing sleep to 94.141405ms 2026/08/27 01:05:40 DEBUG : pacer: Reducing sleep to 154.486574ms 2026/08/27 01:05:40 DEBUG : pacer: Reducing sleep to 219.840118ms 2026/08/27 01:05:45 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:727: testing "." 2026/08/27 01:05:49 DEBUG : pacer: Reducing sleep to 91.905261ms 2026/08/27 01:05:49 DEBUG : pacer: Reducing sleep to 219.598319ms 2026/08/27 01:05:49 DEBUG : pacer: Reducing sleep to 127.649858ms 2026/08/27 01:05:50 DEBUG : pacer: Reducing sleep to 222.211134ms 2026/08/27 01:05:54 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:05:54 DEBUG : pacer: Reducing sleep to 110.323984ms 2026/08/27 01:05:54 DEBUG : pacer: Reducing sleep to 97.731943ms 2026/08/27 01:05:54 DEBUG : pacer: Reducing sleep to 164.984342ms 2026/08/27 01:05:54 DEBUG : pacer: Reducing sleep to 227.643099ms 2026/08/27 01:05:58 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:727: testing ".." 2026/08/27 01:06:03 DEBUG : pacer: Reducing sleep to 102.218964ms 2026/08/27 01:06:03 DEBUG : pacer: Reducing sleep to 222.97862ms 2026/08/27 01:06:03 DEBUG : pacer: Reducing sleep to 192.825625ms 2026/08/27 01:06:03 DEBUG : pacer: Reducing sleep to 230.216889ms 2026/08/27 01:06:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:06:08 DEBUG : pacer: Reducing sleep to 45.491764ms 2026/08/27 01:06:08 DEBUG : pacer: Reducing sleep to 94.842647ms 2026/08/27 01:06:08 DEBUG : pacer: Reducing sleep to 213.906853ms 2026/08/27 01:06:08 DEBUG : pacer: Reducing sleep to 280.67585ms 2026/08/27 01:06:12 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:727: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/08/27 01:06:17 DEBUG : pacer: Reducing sleep to 94.673187ms 2026/08/27 01:06:17 DEBUG : zoho root 'rclone-test-qehatox1jepo': folder listing limiter sweep: evicted 4 of 8 idle limiters 2026/08/27 01:06:17 DEBUG : pacer: Reducing sleep to 222.17881ms 2026/08/27 01:06:17 DEBUG : pacer: Reducing sleep to 146.763414ms 2026/08/27 01:06:17 DEBUG : pacer: Reducing sleep to 203.408796ms 2026/08/27 01:06:17 DEBUG : pacer: Reducing sleep to 250.303391ms 2026/08/27 01:06:18 DEBUG : pacer: Reducing sleep to 178.805399ms 2026/08/27 01:06:18 DEBUG : pacer: Reducing sleep to 119.747935ms 2026/08/27 01:06:18 DEBUG : pacer: Reducing sleep to 115.626766ms 2026/08/27 01:06:18 DEBUG : pacer: Reducing sleep to 155.676929ms 2026/08/27 01:06:18 DEBUG : pacer: Reducing sleep to 213.795444ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:727: testing " leading space" 2026/08/27 01:06:18 DEBUG : pacer: Reducing sleep to 228.916312ms 2026/08/27 01:06:19 DEBUG : pacer: Reducing sleep to 150.587455ms 2026/08/27 01:06:19 DEBUG : pacer: Reducing sleep to 117.651144ms 2026/08/27 01:06:19 DEBUG : pacer: Reducing sleep to 44.178685ms 2026/08/27 01:06:19 DEBUG : pacer: Reducing sleep to 158.269531ms 2026/08/27 01:06:19 DEBUG : pacer: Reducing sleep to 281.546862ms 2026/08/27 01:06:19 DEBUG : pacer: Reducing sleep to 275.52858ms 2026/08/27 01:06:20 DEBUG : pacer: Reducing sleep to 163.242704ms 2026/08/27 01:06:20 DEBUG : pacer: Reducing sleep to 60.669014ms 2026/08/27 01:06:20 DEBUG : pacer: Reducing sleep to 56.417368ms 2026/08/27 01:06:20 DEBUG : pacer: Reducing sleep to 173.353026ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:727: testing "~leading tilde" 2026/08/27 01:06:25 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:06:25 DEBUG : pacer: Reducing sleep to 93.634516ms 2026/08/27 01:06:25 DEBUG : pacer: Reducing sleep to 161.578952ms 2026/08/27 01:06:25 DEBUG : pacer: Reducing sleep to 93.742262ms 2026/08/27 01:06:25 DEBUG : pacer: Reducing sleep to 211.305795ms 2026/08/27 01:06:29 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:06:29 DEBUG : pacer: Reducing sleep to 109.291495ms 2026/08/27 01:06:30 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:06:30 DEBUG : pacer: Reducing sleep to 106.677701ms 2026/08/27 01:06:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:727: testing "␍leading CR" 2026/08/27 01:06:39 DEBUG : pacer: Reducing sleep to 92.002756ms 2026/08/27 01:06:39 DEBUG : pacer: Reducing sleep to 219.252765ms 2026/08/27 01:06:39 DEBUG : pacer: Reducing sleep to 200.045254ms 2026/08/27 01:06:39 DEBUG : pacer: Reducing sleep to 228.834679ms 2026/08/27 01:06:43 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:06:43 DEBUG : pacer: Reducing sleep to 110.67229ms 2026/08/27 01:06:43 DEBUG : pacer: Reducing sleep to 93.511735ms 2026/08/27 01:06:44 DEBUG : pacer: Reducing sleep to 152.002522ms 2026/08/27 01:06:44 DEBUG : pacer: Reducing sleep to 221.237545ms 2026/08/27 01:06:48 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:727: testing "␊leading LF" 2026/08/27 01:06:53 DEBUG : pacer: Reducing sleep to 93.294885ms 2026/08/27 01:06:53 DEBUG : pacer: Reducing sleep to 219.476559ms 2026/08/27 01:06:53 DEBUG : pacer: Reducing sleep to 155.988288ms 2026/08/27 01:06:53 DEBUG : pacer: Reducing sleep to 227.831985ms 2026/08/27 01:06:57 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:06:57 DEBUG : pacer: Reducing sleep to 108.177151ms 2026/08/27 01:06:57 DEBUG : pacer: Reducing sleep to 93.430843ms 2026/08/27 01:06:57 DEBUG : pacer: Reducing sleep to 159.333023ms 2026/08/27 01:06:57 DEBUG : pacer: Reducing sleep to 226.528655ms 2026/08/27 01:07:02 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:727: testing "␉leading HT" 2026/08/27 01:07:06 DEBUG : pacer: Reducing sleep to 100.582644ms 2026/08/27 01:07:06 DEBUG : pacer: Reducing sleep to 222.437518ms 2026/08/27 01:07:07 DEBUG : pacer: Reducing sleep to 196.364645ms 2026/08/27 01:07:07 DEBUG : pacer: Reducing sleep to 230.63146ms 2026/08/27 01:07:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:07:11 DEBUG : pacer: Reducing sleep to 116.150825ms 2026/08/27 01:07:11 DEBUG : pacer: Reducing sleep to 90.067151ms 2026/08/27 01:07:11 DEBUG : pacer: Reducing sleep to 155.988318ms 2026/08/27 01:07:11 DEBUG : pacer: Reducing sleep to 216.508827ms 2026/08/27 01:07:16 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:727: testing "␋leading VT" 2026/08/27 01:07:16 DEBUG : pacer: Reducing sleep to 106.638126ms 2026/08/27 01:07:16 DEBUG : pacer: Reducing sleep to 89.913242ms 2026/08/27 01:07:16 DEBUG : pacer: Reducing sleep to 170.099288ms 2026/08/27 01:07:16 DEBUG : pacer: Reducing sleep to 112.065415ms 2026/08/27 01:07:16 DEBUG : pacer: Reducing sleep to 234.777858ms 2026/08/27 01:07:17 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:07:17 DEBUG : zoho root 'rclone-test-qehatox1jepo': folder listing limiter sweep: evicted 3 of 11 idle limiters 2026/08/27 01:07:17 DEBUG : pacer: Reducing sleep to 121.604109ms 2026/08/27 01:07:18 DEBUG : pacer: Reducing sleep to 52.414675ms 2026/08/27 01:07:18 DEBUG : pacer: Reducing sleep to 150.388243ms 2026/08/27 01:07:18 DEBUG : pacer: Reducing sleep to 243.277585ms 2026/08/27 01:07:18 DEBUG : pacer: Reducing sleep to 283.348508ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:727: testing ".leading dot" 2026/08/27 01:07:19 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:07:19 DEBUG : pacer: Reducing sleep to 98.548052ms 2026/08/27 01:07:19 DEBUG : pacer: Reducing sleep to 48.213916ms 2026/08/27 01:07:19 DEBUG : pacer: Reducing sleep to 33.917076ms 2026/08/27 01:07:19 DEBUG : pacer: Reducing sleep to 154.906667ms 2026/08/27 01:07:19 DEBUG : pacer: Reducing sleep to 272.329313ms 2026/08/27 01:07:19 DEBUG : pacer: Reducing sleep to 286.946761ms 2026/08/27 01:07:20 DEBUG : pacer: Reducing sleep to 147.952682ms 2026/08/27 01:07:20 DEBUG : pacer: Reducing sleep to 61.79414ms 2026/08/27 01:07:20 DEBUG : pacer: Reducing sleep to 65.600768ms 2026/08/27 01:07:24 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:727: testing "trailing space " 2026/08/27 01:07:29 DEBUG : pacer: Reducing sleep to 105.022075ms 2026/08/27 01:07:29 DEBUG : pacer: Reducing sleep to 226.332961ms 2026/08/27 01:07:29 DEBUG : pacer: Reducing sleep to 146.770684ms 2026/08/27 01:07:29 DEBUG : pacer: Reducing sleep to 226.312745ms 2026/08/27 01:07:33 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:07:33 DEBUG : pacer: Reducing sleep to 117.892153ms 2026/08/27 01:07:33 DEBUG : pacer: Reducing sleep to 93.93723ms 2026/08/27 01:07:33 DEBUG : pacer: Reducing sleep to 158.633182ms 2026/08/27 01:07:34 DEBUG : pacer: Reducing sleep to 170.710493ms 2026/08/27 01:07:38 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:727: testing "trailing CR␍" 2026/08/27 01:07:42 DEBUG : pacer: Reducing sleep to 98.580993ms 2026/08/27 01:07:42 DEBUG : pacer: Reducing sleep to 220.741417ms 2026/08/27 01:07:43 DEBUG : pacer: Reducing sleep to 185.140816ms 2026/08/27 01:07:43 DEBUG : pacer: Reducing sleep to 230.8862ms 2026/08/27 01:07:47 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:07:47 DEBUG : pacer: Reducing sleep to 116.544128ms 2026/08/27 01:07:47 DEBUG : pacer: Reducing sleep to 86.174002ms 2026/08/27 01:07:47 DEBUG : pacer: Reducing sleep to 159.227692ms 2026/08/27 01:07:47 DEBUG : pacer: Reducing sleep to 220.146924ms 2026/08/27 01:07:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:727: testing "trailing LF␊" 2026/08/27 01:07:56 DEBUG : pacer: Reducing sleep to 103.355078ms 2026/08/27 01:07:56 DEBUG : pacer: Reducing sleep to 225.587796ms 2026/08/27 01:07:57 DEBUG : pacer: Reducing sleep to 164.577423ms 2026/08/27 01:07:57 DEBUG : pacer: Reducing sleep to 226.950801ms 2026/08/27 01:08:01 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:08:01 DEBUG : pacer: Reducing sleep to 116.210017ms 2026/08/27 01:08:01 DEBUG : pacer: Reducing sleep to 85.577004ms 2026/08/27 01:08:01 DEBUG : pacer: Reducing sleep to 157.892943ms 2026/08/27 01:08:01 DEBUG : pacer: Reducing sleep to 223.325025ms 2026/08/27 01:08:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:727: testing "trailing HT␉" 2026/08/27 01:08:10 DEBUG : pacer: Reducing sleep to 102.984239ms 2026/08/27 01:08:10 DEBUG : pacer: Reducing sleep to 226.968974ms 2026/08/27 01:08:10 DEBUG : pacer: Reducing sleep to 216.63227ms 2026/08/27 01:08:10 DEBUG : pacer: Reducing sleep to 225.883286ms 2026/08/27 01:08:15 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:08:15 DEBUG : pacer: Reducing sleep to 105.494948ms 2026/08/27 01:08:15 DEBUG : pacer: Reducing sleep to 111.905452ms 2026/08/27 01:08:15 DEBUG : pacer: Reducing sleep to 223.94206ms 2026/08/27 01:08:15 DEBUG : pacer: Reducing sleep to 257.519312ms 2026/08/27 01:08:15 DEBUG : pacer: Reducing sleep to 223.271376ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:727: testing "trailing VT␋" 2026/08/27 01:08:16 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:08:16 DEBUG : pacer: Reducing sleep to 99.95098ms 2026/08/27 01:08:16 DEBUG : pacer: Reducing sleep to 112.14359ms 2026/08/27 01:08:16 DEBUG : pacer: Reducing sleep to 35.757088ms 2026/08/27 01:08:17 DEBUG : pacer: Reducing sleep to 155.606296ms 2026/08/27 01:08:17 DEBUG : pacer: Reducing sleep to 272.371733ms 2026/08/27 01:08:17 DEBUG : pacer: Reducing sleep to 287.081133ms 2026/08/27 01:08:17 DEBUG : pacer: Reducing sleep to 166.892817ms 2026/08/27 01:08:17 DEBUG : pacer: Reducing sleep to 61.444328ms 2026/08/27 01:08:17 DEBUG : pacer: Reducing sleep to 43.806871ms 2026/08/27 01:08:17 DEBUG : zoho root 'rclone-test-qehatox1jepo': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/08/27 01:08:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:727: testing "trailing dot." 2026/08/27 01:08:18 DEBUG : pacer: Reducing sleep to 103.143238ms 2026/08/27 01:08:18 DEBUG : pacer: Reducing sleep to 226.177548ms 2026/08/27 01:08:19 DEBUG : pacer: Reducing sleep to 220.275898ms 2026/08/27 01:08:19 DEBUG : pacer: Reducing sleep to 226.195203ms 2026/08/27 01:08:23 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:08:23 DEBUG : pacer: Reducing sleep to 116.470287ms 2026/08/27 01:08:23 DEBUG : pacer: Reducing sleep to 95.310209ms 2026/08/27 01:08:23 DEBUG : pacer: Reducing sleep to 159.858654ms 2026/08/27 01:08:23 DEBUG : pacer: Reducing sleep to 212.162531ms 2026/08/27 01:08:28 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/27 01:08:32 DEBUG : pacer: Reducing sleep to 99.747317ms 2026/08/27 01:08:32 DEBUG : pacer: Reducing sleep to 224.511714ms 2026/08/27 01:08:32 DEBUG : pacer: Reducing sleep to 161.159019ms 2026/08/27 01:08:33 DEBUG : pacer: Reducing sleep to 227.081267ms 2026/08/27 01:08:37 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:08:37 DEBUG : pacer: Reducing sleep to 117.126116ms 2026/08/27 01:08:37 DEBUG : pacer: Reducing sleep to 76.95659ms 2026/08/27 01:08:37 DEBUG : pacer: Reducing sleep to 158.239628ms 2026/08/27 01:08:37 DEBUG : pacer: Reducing sleep to 235.472389ms 2026/08/27 01:08:41 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/08/27 01:09:00 DEBUG : pacer: Reducing sleep to 123.55782ms 2026/08/27 01:09:00 DEBUG : pacer: Reducing sleep to 194.941157ms 2026/08/27 01:09:00 DEBUG : pacer: Reducing sleep to 291.857579ms 2026/08/27 01:09:01 DEBUG : pacer: Reducing sleep to 223.389758ms 2026/08/27 01:09:01 DEBUG : pacer: Reducing sleep to 137.059693ms 2026/08/27 01:09:01 DEBUG : pacer: Reducing sleep to 40.293094ms 2026/08/27 01:09:01 DEBUG : pacer: Reducing sleep to 147.648494ms 2026/08/27 01:09:01 DEBUG : pacer: Reducing sleep to 234.142631ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/08/27 01:09:04 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:09:05 DEBUG : pacer: Reducing sleep to 57.506467ms 2026/08/27 01:09:09 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:09:10 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:09:10 UTC": parsing time "Thu, 27 Aug 2026 01:09:10 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:09:10 UTC" as " " 2026/08/27 01:09:10 DEBUG : pacer: Reducing sleep to 66.361777ms === 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/27 01:09:14 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:09:14 DEBUG : pacer: Reducing sleep to 3.909341ms 2026/08/27 01:09:18 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:09:18 DEBUG : zoho root 'rclone-test-qehatox1jepo': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/08/27 01:09:18 DEBUG : pacer: Reducing sleep to 126.217218ms 2026/08/27 01:09:18 DEBUG : pacer: Reducing sleep to 152.987921ms 2026/08/27 01:09:19 DEBUG : pacer: Reducing sleep to 266.791224ms 2026/08/27 01:09:19 DEBUG : pacer: Reducing sleep to 288.513415ms 2026/08/27 01:09:19 DEBUG : pacer: Reducing sleep to 77.326439ms 2026/08/27 01:09:19 DEBUG : pacer: Reducing sleep to 61.043999ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/08/27 01:09:19 DEBUG : pacer: Reducing sleep to 144.983281ms 2026/08/27 01:09:19 DEBUG : pacer: Reducing sleep to 248.703878ms 2026/08/27 01:09:20 DEBUG : pacer: Reducing sleep to 264.640006ms 2026/08/27 01:09:20 DEBUG : pacer: Reducing sleep to 185.972598ms 2026/08/27 01:09:20 DEBUG : pacer: Reducing sleep to 97.201043ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/08/27 01:09:20 DEBUG : pacer: Reducing sleep to 69.51252ms 2026/08/27 01:09:20 DEBUG : pacer: Reducing sleep to 142.551704ms 2026/08/27 01:09:20 DEBUG : pacer: Reducing sleep to 235.065794ms 2026/08/27 01:09:21 DEBUG : pacer: Reducing sleep to 263.942432ms 2026/08/27 01:09:21 DEBUG : pacer: Reducing sleep to 190.169715ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/08/27 01:09:21 DEBUG : pacer: Reducing sleep to 96.384175ms 2026/08/27 01:09:21 DEBUG : pacer: Reducing sleep to 69.64815ms 2026/08/27 01:09:21 DEBUG : pacer: Reducing sleep to 141.983995ms 2026/08/27 01:09:21 DEBUG : pacer: Reducing sleep to 234.794065ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/08/27 01:09:21 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/08/27 01:09:26 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/27 01:09:31 DEBUG : pacer: Reducing sleep to 120.969753ms 2026/08/27 01:09:31 DEBUG : pacer: Reducing sleep to 236.494527ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:418: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/08/27 01:09:35 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:09:35 DEBUG : pacer: Reducing sleep to 116.443127ms 2026/08/27 01:09:36 DEBUG : pacer: Reducing sleep to 91.937512ms 2026/08/27 01:09:36 DEBUG : pacer: Reducing sleep to 134.640113ms 2026/08/27 01:09:36 DEBUG : pacer: Reducing sleep to 218.785695ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/08/27 01:09:40 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1145: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/08/27 01:09:45 DEBUG : pacer: Reducing sleep to 74.425111ms 2026/08/27 01:09:45 DEBUG : pacer: Reducing sleep to 165.150104ms 2026/08/27 01:09:45 DEBUG : pacer: Reducing sleep to 282.570617ms 2026/08/27 01:09:45 DEBUG : pacer: Reducing sleep to 278.552339ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/08/27 01:09:49 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/08/27 01:09:49 DEBUG : pacer: Reducing sleep to 117.768962ms 2026/08/27 01:09:54 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:09:54 DEBUG : pacer: Reducing sleep to 83.908493ms 2026/08/27 01:09:54 DEBUG : pacer: Reducing sleep to 124.795927ms 2026/08/27 01:09:54 DEBUG : pacer: Reducing sleep to 245.227364ms 2026/08/27 01:09:59 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:09:59 DEBUG : pacer: Reducing sleep to 119.72876ms 2026/08/27 01:09:59 DEBUG : pacer: Reducing sleep to 284.155567ms 2026/08/27 01:09:59 DEBUG : pacer: Reducing sleep to 250.948374ms 2026/08/27 01:09:59 DEBUG : pacer: Reducing sleep to 135.697049ms 2026/08/27 01:09:59 DEBUG : pacer: Reducing sleep to 47.430277ms 2026/08/27 01:10:00 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:03 DEBUG : pacer: Reducing sleep to 113.990677ms 2026/08/27 01:10:03 DEBUG : pacer: Reducing sleep to 233.729106ms 2026/08/27 01:10:03 DEBUG : pacer: Reducing sleep to 277.857607ms 2026/08/27 01:10:04 DEBUG : pacer: Reducing sleep to 218.234082ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/08/27 01:10:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:13 DEBUG : pacer: Reducing sleep to 94.868094ms 2026/08/27 01:10:13 DEBUG : pacer: Reducing sleep to 92.659926ms 2026/08/27 01:10:13 DEBUG : pacer: Reducing sleep to 213.564577ms 2026/08/27 01:10:17 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:17 DEBUG : pacer: Reducing sleep to 119.397355ms 2026/08/27 01:10:17 DEBUG : pacer: Reducing sleep to 278.211385ms 2026/08/27 01:10:17 DEBUG : pacer: Reducing sleep to 292.516373ms 2026/08/27 01:10:18 DEBUG : pacer: Reducing sleep to 119.874676ms 2026/08/27 01:10:18 DEBUG : pacer: Reducing sleep to 55.210705ms 2026/08/27 01:10:18 DEBUG : Creating backend with remote "TestZoho:rclone-test-qehatox1jepo/dirToPurgeFromRoot" 2026/08/27 01:10:18 DEBUG : pacer: Reducing sleep to 80.503545ms 2026/08/27 01:10:18 DEBUG : pacer: Reducing sleep to 172.370955ms 2026/08/27 01:10:18 DEBUG : pacer: Reducing sleep to 241.304769ms 2026/08/27 01:10:18 DEBUG : zoho root 'rclone-test-qehatox1jepo': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/08/27 01:10:19 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:19 DEBUG : pacer: Reducing sleep to 120.889082ms 2026/08/27 01:10:19 DEBUG : pacer: Reducing sleep to 238.300095ms 2026/08/27 01:10:19 DEBUG : pacer: Reducing sleep to 284.988139ms 2026/08/27 01:10:19 DEBUG : pacer: Reducing sleep to 210.043179ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/08/27 01:10:19 DEBUG : Creating backend with remote "TestZoho:rclone-test-qehatox1jepo/hello? sausage/êé" 2026/08/27 01:10:19 DEBUG : pacer: Reducing sleep to 116.165763ms 2026/08/27 01:10:19 DEBUG : pacer: Reducing sleep to 238.150402ms 2026/08/27 01:10:19 DEBUG : pacer: Reducing sleep to 281.684573ms 2026/08/27 01:10:20 DEBUG : pacer: Reducing sleep to 209.732573ms 2026/08/27 01:10:20 DEBUG : pacer: Reducing sleep to 61.870881ms 2026/08/27 01:10:20 DEBUG : pacer: Reducing sleep to 59.609742ms 2026/08/27 01:10:20 DEBUG : pacer: Reducing sleep to 120.250819ms 2026/08/27 01:10:20 DEBUG : pacer: Reducing sleep to 244.834996ms 2026/08/27 01:10:20 DEBUG : pacer: Reducing sleep to 253.625542ms 2026/08/27 01:10:21 DEBUG : pacer: Reducing sleep to 211.600201ms 2026/08/27 01:10:21 DEBUG : pacer: Reducing sleep to 82.357339ms 2026/08/27 01:10:21 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:21 DEBUG : pacer: Reducing sleep to 118.033179ms 2026/08/27 01:10:21 DEBUG : pacer: Reducing sleep to 231.675528ms 2026/08/27 01:10:21 DEBUG : pacer: Reducing sleep to 277.952597ms 2026/08/27 01:10:22 DEBUG : pacer: Reducing sleep to 219.193423ms 2026/08/27 01:10:22 DEBUG : pacer: Reducing sleep to 100.497312ms 2026/08/27 01:10:22 DEBUG : pacer: Reducing sleep to 48.837042ms 2026/08/27 01:10:22 DEBUG : pacer: Reducing sleep to 119.332403ms 2026/08/27 01:10:22 DEBUG : pacer: Reducing sleep to 230.865481ms 2026/08/27 01:10:22 DEBUG : pacer: Reducing sleep to 273.43013ms 2026/08/27 01:10:23 DEBUG : pacer: Reducing sleep to 209.991032ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/08/27 01:10:23 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:23 DEBUG : pacer: Reducing sleep to 107.613016ms 2026/08/27 01:10:23 DEBUG : pacer: Reducing sleep to 224.028502ms 2026/08/27 01:10:23 DEBUG : pacer: Reducing sleep to 287.042639ms 2026/08/27 01:10:26 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:26 DEBUG : pacer: Reducing sleep to 122.531381ms 2026/08/27 01:10:26 DEBUG : pacer: Reducing sleep to 41.432012ms 2026/08/27 01:10:31 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:31 DEBUG : pacer: Reducing sleep to 86.648587ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1318: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/08/27 01:10:35 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:35 DEBUG : pacer: Reducing sleep to 98.234539ms 2026/08/27 01:10:35 DEBUG : pacer: Reducing sleep to 181.238686ms 2026/08/27 01:10:35 DEBUG : pacer: Reducing sleep to 272.427564ms 2026/08/27 01:10:36 DEBUG : pacer: Reducing sleep to 267.469032ms 2026/08/27 01:10:36 DEBUG : pacer: Reducing sleep to 155.971443ms 2026/08/27 01:10:36 DEBUG : pacer: Reducing sleep to 64.47869ms 2026/08/27 01:10:36 DEBUG : pacer: Reducing sleep to 64.219471ms 2026/08/27 01:10:40 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:49 DEBUG : pacer: Reducing sleep to 100.601589ms 2026/08/27 01:10:49 DEBUG : pacer: Reducing sleep to 204.013634ms 2026/08/27 01:10:50 DEBUG : pacer: Reducing sleep to 242.491177ms 2026/08/27 01:10:50 DEBUG : pacer: Reducing sleep to 232.621204ms 2026/08/27 01:10:54 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:54 DEBUG : pacer: Reducing sleep to 119.248965ms 2026/08/27 01:10:54 DEBUG : pacer: Reducing sleep to 279.845081ms 2026/08/27 01:10:54 DEBUG : pacer: Reducing sleep to 266.786377ms 2026/08/27 01:10:54 DEBUG : pacer: Reducing sleep to 156.961775ms 2026/08/27 01:10:55 DEBUG : pacer: Reducing sleep to 60.489711ms 2026/08/27 01:10:55 DEBUG : pacer: Reducing sleep to 64.411676ms 2026/08/27 01:10:55 DEBUG : pacer: Reducing sleep to 155.267978ms 2026/08/27 01:10:55 DEBUG : pacer: Reducing sleep to 251.372237ms 2026/08/27 01:10:55 DEBUG : pacer: Reducing sleep to 266.41674ms 2026/08/27 01:10:59 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:10:59 DEBUG : pacer: Reducing sleep to 122.526732ms 2026/08/27 01:10:59 DEBUG : pacer: Reducing sleep to 284.117215ms 2026/08/27 01:10:59 DEBUG : pacer: Reducing sleep to 228.373173ms 2026/08/27 01:10:59 DEBUG : pacer: Reducing sleep to 116.306178ms 2026/08/27 01:10:59 DEBUG : pacer: Reducing sleep to 58.51898ms 2026/08/27 01:11:03 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:11:03 DEBUG : pacer: Reducing sleep to 103.855061ms 2026/08/27 01:11:03 DEBUG : pacer: Reducing sleep to 203.454038ms 2026/08/27 01:11:03 DEBUG : pacer: Reducing sleep to 271.086053ms 2026/08/27 01:11:08 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:11:08 DEBUG : pacer: Reducing sleep to 124.619482ms 2026/08/27 01:11:08 DEBUG : pacer: Reducing sleep to 289.396883ms 2026/08/27 01:11:08 DEBUG : pacer: Reducing sleep to 225.845061ms 2026/08/27 01:11:08 DEBUG : pacer: Reducing sleep to 105.244706ms 2026/08/27 01:11:09 DEBUG : pacer: Reducing sleep to 40.972043ms 2026/08/27 01:11:09 DEBUG : pacer: Reducing sleep to 106.685347ms 2026/08/27 01:11:09 DEBUG : pacer: Reducing sleep to 211.859329ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1430: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/08/27 01:11:09 DEBUG : Creating backend with remote "TestZoho:rclone-test-cayohaj5cuta" 2026/08/27 01:11:09 DEBUG : pacer: Reducing sleep to 16.32445ms 2026/08/27 01:11:09 DEBUG : pacer: Reducing sleep to 25.979849ms 2026/08/27 01:11:10 DEBUG : pacer: Reducing sleep to 27.463461ms 2026/08/27 01:11:10 DEBUG : pacer: Reducing sleep to 95.042615ms 2026/08/27 01:11:10 DEBUG : pacer: Reducing sleep to 165.557772ms 2026/08/27 01:11:10 DEBUG : pacer: Reducing sleep to 261.067138ms 2026/08/27 01:11:10 DEBUG : pacer: Reducing sleep to 228.232398ms 2026/08/27 01:11:10 DEBUG : pacer: Reducing sleep to 145.360803ms 2026/08/27 01:11:10 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:11:10 DEBUG : pacer: Reducing sleep to 100.020734ms 2026/08/27 01:11:11 DEBUG : pacer: Reducing sleep to 114.938177ms 2026/08/27 01:11:12 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:11:13 DEBUG : pacer: Reducing sleep to 86.24307ms 2026/08/27 01:11:13 DEBUG : pacer: Reducing sleep to 179.121579ms 2026/08/27 01:11:13 DEBUG : pacer: Reducing sleep to 252.71115ms 2026/08/27 01:11:13 DEBUG : pacer: Reducing sleep to 235.120332ms 2026/08/27 01:11:15 DEBUG : pacer: Reducing sleep to 21.021506ms 2026/08/27 01:11:15 DEBUG : pacer: Reducing sleep to 72.729388ms 2026/08/27 01:11:15 DEBUG : pacer: Reducing sleep to 192.108349ms 2026/08/27 01:11:15 DEBUG : pacer: Reducing sleep to 247.389506ms 2026/08/27 01:11:15 DEBUG : pacer: Reducing sleep to 259.591924ms 2026/08/27 01:11:15 DEBUG : pacer: Reducing sleep to 164.672492ms 2026/08/27 01:11:15 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:11:16 DEBUG : pacer: Reducing sleep to 97.679081ms 2026/08/27 01:11:16 DEBUG : zoho root 'rclone-test-cayohaj5cuta': Purge remote 2026/08/27 01:11:16 DEBUG : pacer: Reducing sleep to 96.002326ms 2026/08/27 01:11:16 DEBUG : pacer: Reducing sleep to 159.505716ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/08/27 01:11:16 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/08/27 01:11:16 DEBUG : pacer: Reducing sleep to 115.934766ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/08/27 01:11:16 DEBUG : pacer: Reducing sleep to 165.34494ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/08/27 01:11:16 DEBUG : pacer: Reducing sleep to 209.77476ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/08/27 01:11:16 DEBUG : pacer: Reducing sleep to 215.65902ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/08/27 01:11:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1626: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/08/27 01:11:22 DEBUG : zoho root 'rclone-test-qehatox1jepo': folder listing limiter sweep: evicted 5 of 15 idle limiters fstests.go:1657: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1728: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1785: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/08/27 01:11:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:11:46 UTC": parsing time "Thu, 27 Aug 2026 01:11:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:11:46 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/08/27 01:11:50 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:11:50 UTC": parsing time "Thu, 27 Aug 2026 01:11:50 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:11:50 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/08/27 01:11:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:11:55 UTC": parsing time "Thu, 27 Aug 2026 01:11:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:11:55 UTC" as " " 2026/08/27 01:11:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:11:55 UTC": parsing time "Thu, 27 Aug 2026 01:11:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:11:55 UTC" as " " 2026/08/27 01:11:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:11:55 UTC": parsing time "Thu, 27 Aug 2026 01:11:55 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:11:55 UTC" as " " 2026/08/27 01:11:56 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:11:56 UTC": parsing time "Thu, 27 Aug 2026 01:11:56 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:11:56 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/08/27 01:11:59 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:11:59 UTC": parsing time "Thu, 27 Aug 2026 01:11:59 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:11:59 UTC" as " " 2026/08/27 01:11:59 DEBUG : pacer: Reducing sleep to 1.158227ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/08/27 01:12:04 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:12:04 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:12:04 UTC": parsing time "Thu, 27 Aug 2026 01:12:04 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:12:04 UTC" as " " 2026/08/27 01:12:09 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:12:09 UTC": parsing time "Thu, 27 Aug 2026 01:12:09 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:12:09 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/08/27 01:12:09 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:12:09 UTC": parsing time "Thu, 27 Aug 2026 01:12:09 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:12:09 UTC" as " " 2026/08/27 01:12:09 DEBUG : pacer: Reducing sleep to 4.975071ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/08/27 01:12:09 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:12:09 UTC": parsing time "Thu, 27 Aug 2026 01:12:09 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:12:09 UTC" as " " 2026/08/27 01:12:09 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/08/27 01:12:09 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:12:09 UTC": parsing time "Thu, 27 Aug 2026 01:12:09 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:12:09 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/08/27 01:12:09 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:12:09 UTC": parsing time "Thu, 27 Aug 2026 01:12:09 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:12:09 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/08/27 01:12:13 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:12:13 UTC": parsing time "Thu, 27 Aug 2026 01:12:13 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:12:13 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/08/27 01:12:18 DEBUG : pacer: Reducing sleep to 34.926577ms 2026/08/27 01:12:18 DEBUG : pacer: Reducing sleep to 155.32262ms 2026/08/27 01:12:18 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Thu, 27 Aug 2026 01:12:18 UTC": parsing time "Thu, 27 Aug 2026 01:12:18 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 27 Aug 2026 01:12:18 UTC" as " " 2026/08/27 01:12:18 DEBUG : pacer: Reducing sleep to 143.020613ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/08/27 01:12:18 DEBUG : pacer: Reducing sleep to 257.783207ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/08/27 01:12:18 DEBUG : Creating backend with remote "TestZoho:rclone-test-qehatox1jepo/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/08/27 01:12:18 DEBUG : pacer: Reducing sleep to 114.927676ms 2026/08/27 01:12:18 DEBUG : pacer: Reducing sleep to 235.984734ms 2026/08/27 01:12:18 DEBUG : pacer: Reducing sleep to 284.334225ms 2026/08/27 01:12:19 DEBUG : pacer: Reducing sleep to 215.868817ms 2026/08/27 01:12:19 DEBUG : pacer: Reducing sleep to 89.893253ms 2026/08/27 01:12:19 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:12:19 DEBUG : pacer: Reducing sleep to 114.93963ms 2026/08/27 01:12:19 DEBUG : pacer: Reducing sleep to 236.447157ms 2026/08/27 01:12:19 DEBUG : pacer: Reducing sleep to 282.762618ms 2026/08/27 01:12:20 DEBUG : pacer: Reducing sleep to 214.094927ms 2026/08/27 01:12:20 DEBUG : pacer: Reducing sleep to 91.15241ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/08/27 01:12:20 DEBUG : pacer: Reducing sleep to 50.42623ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/08/27 01:12:20 DEBUG : Creating backend with remote "TestZoho:rclone-test-qehatox1jepo/not found.txt" 2026/08/27 01:12:21 DEBUG : pacer: Reducing sleep to 115.391783ms 2026/08/27 01:12:21 DEBUG : pacer: Reducing sleep to 157.220923ms 2026/08/27 01:12:25 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:12:25 DEBUG : zoho root 'rclone-test-qehatox1jepo/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/08/27 01:12:30 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1987: Opening root remote "TestZoho:" path "rclone-test-qehatox1jepo" from "TestZoho:rclone-test-qehatox1jepo" 2026/08/27 01:12:30 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/08/27 01:12:35 DEBUG : pacer: Reducing sleep to 118.602855ms 2026/08/27 01:12:35 DEBUG : pacer: Reducing sleep to 235.209271ms 2026/08/27 01:12:35 DEBUG : pacer: Reducing sleep to 282.374295ms 2026/08/27 01:12:35 DEBUG : pacer: Reducing sleep to 206.486634ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/08/27 01:12:39 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/27 01:12:44 DEBUG : pacer: Reducing sleep to 6.695478ms 2026/08/27 01:12:48 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:12:53 DEBUG : pacer: Reducing sleep to 117.816389ms 2026/08/27 01:12:53 DEBUG : pacer: Reducing sleep to 235.413456ms 2026/08/27 01:12:53 DEBUG : pacer: Reducing sleep to 277.480956ms 2026/08/27 01:12:53 DEBUG : pacer: Reducing sleep to 214.786243ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/08/27 01:12:54 DEBUG : pacer: Reducing sleep to 55.147445ms 2026/08/27 01:12:58 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:12:58 DEBUG : pacer: Reducing sleep to 99.630205ms 2026/08/27 01:12:58 DEBUG : pacer: Reducing sleep to 217.856238ms 2026/08/27 01:12:58 DEBUG : pacer: Reducing sleep to 277.136725ms 2026/08/27 01:12:58 DEBUG : pacer: Reducing sleep to 230.423124ms === 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/27 01:13:02 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:13:07 DEBUG : pacer: Reducing sleep to 117.221527ms 2026/08/27 01:13:07 DEBUG : pacer: Reducing sleep to 238.630779ms 2026/08/27 01:13:07 DEBUG : pacer: Reducing sleep to 282.171442ms 2026/08/27 01:13:07 DEBUG : pacer: Reducing sleep to 215.6482ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/08/27 01:13:08 DEBUG : pacer: Reducing sleep to 91.001725ms === 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/27 01:13:11 DEBUG : pacer: Reducing sleep to 0s 2026/08/27 01:13:11 DEBUG : pacer: Reducing sleep to 139.397222ms fstests.go:2596: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n