"go test -v -timeout 2h0m0s -remote TestZoho: -verbose" - Starting (try 1/5) === RUN TestFolderListLimiter --- PASS: TestFolderListLimiter (0.00s) === RUN TestFolderWindowLimiterShape --- PASS: TestFolderWindowLimiterShape (0.00s) === RUN TestFolderWindowLimiterIdleResume --- PASS: TestFolderWindowLimiterIdleResume (0.00s) === RUN TestFolderWindowLimiterClamps --- PASS: TestFolderWindowLimiterClamps (0.00s) === RUN TestFolderListLimiterConcurrent --- PASS: TestFolderListLimiterConcurrent (0.00s) === RUN TestTPSMinSleep --- PASS: TestTPSMinSleep (0.00s) === RUN TestShouldRetry === RUN TestShouldRetry/429_honours_Retry-After 2026/09/13 02:51:28 NOTICE: zoho root '': Too many requests: Trying again in 6 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_without_Retry-After_falls_back_to_60s 2026/09/13 02:51:28 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/429_with_unparseable_Retry-After_falls_back 2026/09/13 02:51:28 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/13 02:51:28 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/09/13 02:51:28 ERROR : zoho: missing OAuth Scope. Run rclone config reconnect to fix this issue. === RUN TestShouldRetry/401_expired_token_retries === RUN TestShouldRetry/401_R008_deleted_folder_is_not_retried === RUN TestShouldRetry/cancelled_context_aborts --- PASS: TestShouldRetry (0.00s) --- PASS: TestShouldRetry/429_honours_Retry-After (0.00s) --- PASS: TestShouldRetry/429_without_Retry-After_falls_back_to_60s (0.00s) --- PASS: TestShouldRetry/429_with_unparseable_Retry-After_falls_back (0.00s) --- PASS: TestShouldRetry/401_missing_scope_aborts (0.00s) --- PASS: TestShouldRetry/401_expired_token_retries (0.00s) --- PASS: TestShouldRetry/401_R008_deleted_folder_is_not_retried (0.00s) --- PASS: TestShouldRetry/cancelled_context_aborts (0.00s) === RUN TestIsMissingResourceErr --- PASS: TestIsMissingResourceErr (0.00s) === RUN TestThrottleEpisode 2026/09/13 02:51:28 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/09/13 02:51:28 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleStateConcurrent (0.00s) === RUN TestConfigRootFolderID === RUN TestConfigRootFolderID/SetRootAsksBeforeChanging === RUN TestConfigRootFolderID/EmptyRootGoesToEdition === RUN TestConfigRootFolderID/KeepRootOnNo === RUN TestConfigRootFolderID/ChangeRootOnYes --- PASS: TestConfigRootFolderID (0.00s) --- PASS: TestConfigRootFolderID/SetRootAsksBeforeChanging (0.00s) --- PASS: TestConfigRootFolderID/EmptyRootGoesToEdition (0.00s) --- PASS: TestConfigRootFolderID/KeepRootOnNo (0.00s) --- PASS: TestConfigRootFolderID/ChangeRootOnYes (0.00s) === RUN TestIntegration fstests.go:464: Using remote "TestZoho:" 2026/09/13 02:51:28 DEBUG : Creating backend with remote "TestZoho:rclone-test-wadodox4ramu" 2026/09/13 02:51:28 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/13 02:51:28 DEBUG : zoho root 'rclone-test-wadodox4ramu': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/13 02:51:28 DEBUG : TestZoho: Token expired 2026/09/13 02:51:28 DEBUG : TestZoho: No updated token found in the config file 2026/09/13 02:51:28 DEBUG : TestZoho: Token refresh successful 2026/09/13 02:51:28 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/13 02:51:28 DEBUG : TestZoho: Saved new token in config file === RUN TestIntegration/FsCheckWrap fstests.go:505: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:533: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2026/09/13 02:51:29 DEBUG : pacer: Reducing sleep to 19.840208ms 2026/09/13 02:51:29 DEBUG : pacer: Reducing sleep to 56.554237ms 2026/09/13 02:51:29 DEBUG : pacer: Reducing sleep to 33.284597ms 2026/09/13 02:51:29 DEBUG : pacer: Reducing sleep to 157.04475ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/13 02:51:29 DEBUG : pacer: Reducing sleep to 270.571139ms 2026/09/13 02:51:30 DEBUG : pacer: Reducing sleep to 157.503801ms === RUN TestIntegration/FsMkdir 2026/09/13 02:51:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:51:30 DEBUG : pacer: Reducing sleep to 29.294789ms 2026/09/13 02:51:30 DEBUG : pacer: Reducing sleep to 142.103143ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/13 02:51:30 INFO : dir/subdir: Making directory 2026/09/13 02:51:30 DEBUG : pacer: Reducing sleep to 267.461033ms 2026/09/13 02:51:30 DEBUG : pacer: Reducing sleep to 247.090136ms 2026/09/13 02:51:31 DEBUG : pacer: Reducing sleep to 188.29476ms 2026/09/13 02:51:31 DEBUG : pacer: Reducing sleep to 68.576363ms 2026/09/13 02:51:31 DEBUG : pacer: Reducing sleep to 73.906042ms 2026/09/13 02:51:31 DEBUG : pacer: Reducing sleep to 171.420881ms 2026/09/13 02:51:31 DEBUG : pacer: Reducing sleep to 275.926467ms 2026/09/13 02:51:31 INFO : dir/subdir: Removing directory 2026/09/13 02:51:31 DEBUG : pacer: Reducing sleep to 256.071004ms 2026/09/13 02:51:32 DEBUG : pacer: Reducing sleep to 145.248453ms 2026/09/13 02:51:32 DEBUG : pacer: Reducing sleep to 56.28183ms 2026/09/13 02:51:32 DEBUG : pacer: Reducing sleep to 91.36055ms 2026/09/13 02:51:32 INFO : dir: Removing directory 2026/09/13 02:51:32 DEBUG : pacer: Reducing sleep to 186.280438ms 2026/09/13 02:51:32 DEBUG : pacer: Reducing sleep to 260.58405ms 2026/09/13 02:51:32 DEBUG : pacer: Reducing sleep to 254.183131ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/13 02:51:33 DEBUG : pacer: Reducing sleep to 151.396192ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/13 02:51:37 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/13 02:51:42 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:734: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2026/09/13 02:51:51 DEBUG : pacer: Reducing sleep to 87.835051ms 2026/09/13 02:51:51 DEBUG : pacer: Reducing sleep to 213.16114ms 2026/09/13 02:51:51 DEBUG : pacer: Reducing sleep to 125.800617ms 2026/09/13 02:51:51 DEBUG : pacer: Reducing sleep to 228.924947ms 2026/09/13 02:51:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:51:56 DEBUG : pacer: Reducing sleep to 70.306324ms 2026/09/13 02:51:56 DEBUG : pacer: Reducing sleep to 102.974081ms 2026/09/13 02:51:56 DEBUG : pacer: Reducing sleep to 206.07212ms 2026/09/13 02:51:56 DEBUG : pacer: Reducing sleep to 265.665296ms 2026/09/13 02:52:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/13 02:52:05 DEBUG : pacer: Reducing sleep to 102.853058ms 2026/09/13 02:52:05 DEBUG : pacer: Reducing sleep to 223.509729ms 2026/09/13 02:52:05 DEBUG : pacer: Reducing sleep to 99.092886ms 2026/09/13 02:52:05 DEBUG : pacer: Reducing sleep to 219.71354ms 2026/09/13 02:52:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:52:09 DEBUG : pacer: Reducing sleep to 107.030935ms 2026/09/13 02:52:10 DEBUG : pacer: Reducing sleep to 87.830746ms 2026/09/13 02:52:10 DEBUG : pacer: Reducing sleep to 169.297984ms 2026/09/13 02:52:10 DEBUG : pacer: Reducing sleep to 244.933028ms 2026/09/13 02:52:14 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/13 02:52:19 DEBUG : pacer: Reducing sleep to 101.052407ms 2026/09/13 02:52:19 DEBUG : pacer: Reducing sleep to 225.395949ms 2026/09/13 02:52:19 DEBUG : pacer: Reducing sleep to 193.576684ms 2026/09/13 02:52:19 DEBUG : pacer: Reducing sleep to 222.194023ms 2026/09/13 02:52:23 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:52:23 DEBUG : pacer: Reducing sleep to 111.842718ms 2026/09/13 02:52:23 DEBUG : pacer: Reducing sleep to 96.500324ms 2026/09/13 02:52:24 DEBUG : pacer: Reducing sleep to 166.687422ms 2026/09/13 02:52:24 DEBUG : pacer: Reducing sleep to 221.676495ms 2026/09/13 02:52:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/13 02:52:33 DEBUG : pacer: Reducing sleep to 97.912868ms 2026/09/13 02:52:33 DEBUG : zoho root 'rclone-test-wadodox4ramu': folder listing limiter sweep: evicted 4 of 8 idle limiters 2026/09/13 02:52:33 DEBUG : pacer: Reducing sleep to 219.193267ms 2026/09/13 02:52:33 DEBUG : pacer: Reducing sleep to 123.67042ms 2026/09/13 02:52:33 DEBUG : pacer: Reducing sleep to 230.065414ms 2026/09/13 02:52:33 DEBUG : pacer: Reducing sleep to 272.508637ms 2026/09/13 02:52:33 DEBUG : pacer: Reducing sleep to 209.446619ms 2026/09/13 02:52:34 DEBUG : pacer: Reducing sleep to 79.893928ms 2026/09/13 02:52:34 DEBUG : pacer: Reducing sleep to 63.005603ms 2026/09/13 02:52:34 DEBUG : pacer: Reducing sleep to 131.589654ms 2026/09/13 02:52:34 DEBUG : pacer: Reducing sleep to 256.994561ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/13 02:52:34 DEBUG : pacer: Reducing sleep to 286.410646ms 2026/09/13 02:52:34 DEBUG : pacer: Reducing sleep to 171.995647ms 2026/09/13 02:52:35 DEBUG : pacer: Reducing sleep to 69.727271ms 2026/09/13 02:52:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:52:35 DEBUG : pacer: Reducing sleep to 114.089791ms 2026/09/13 02:52:35 DEBUG : pacer: Reducing sleep to 228.863776ms 2026/09/13 02:52:35 DEBUG : pacer: Reducing sleep to 284.994084ms 2026/09/13 02:52:35 DEBUG : pacer: Reducing sleep to 207.22261ms 2026/09/13 02:52:36 DEBUG : pacer: Reducing sleep to 97.60007ms 2026/09/13 02:52:36 DEBUG : pacer: Reducing sleep to 49.644916ms 2026/09/13 02:52:36 DEBUG : pacer: Reducing sleep to 135.165045ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/13 02:52:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:52:41 DEBUG : pacer: Reducing sleep to 98.848644ms 2026/09/13 02:52:41 DEBUG : pacer: Reducing sleep to 195.622833ms 2026/09/13 02:52:41 DEBUG : pacer: Reducing sleep to 96.68192ms 2026/09/13 02:52:41 DEBUG : pacer: Reducing sleep to 217.019088ms 2026/09/13 02:52:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:52:45 DEBUG : pacer: Reducing sleep to 116.324903ms 2026/09/13 02:52:45 DEBUG : pacer: Reducing sleep to 115.482335ms 2026/09/13 02:52:45 DEBUG : pacer: Reducing sleep to 183.291042ms 2026/09/13 02:52:46 DEBUG : pacer: Reducing sleep to 207.263598ms 2026/09/13 02:52:50 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/13 02:52:54 DEBUG : pacer: Reducing sleep to 101.853068ms 2026/09/13 02:52:54 DEBUG : pacer: Reducing sleep to 221.379886ms 2026/09/13 02:52:55 DEBUG : pacer: Reducing sleep to 209.722867ms 2026/09/13 02:52:55 DEBUG : pacer: Reducing sleep to 231.914612ms 2026/09/13 02:52:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:52:59 DEBUG : pacer: Reducing sleep to 114.170934ms 2026/09/13 02:52:59 DEBUG : pacer: Reducing sleep to 87.224187ms 2026/09/13 02:52:59 DEBUG : pacer: Reducing sleep to 156.968157ms 2026/09/13 02:52:59 DEBUG : pacer: Reducing sleep to 218.55803ms 2026/09/13 02:53:04 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/13 02:53:08 DEBUG : pacer: Reducing sleep to 100.615ms 2026/09/13 02:53:08 DEBUG : pacer: Reducing sleep to 223.392034ms 2026/09/13 02:53:09 DEBUG : pacer: Reducing sleep to 111.157849ms 2026/09/13 02:53:09 DEBUG : pacer: Reducing sleep to 222.377221ms 2026/09/13 02:53:13 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:53:13 DEBUG : pacer: Reducing sleep to 112.379242ms 2026/09/13 02:53:13 DEBUG : pacer: Reducing sleep to 99.487753ms 2026/09/13 02:53:13 DEBUG : pacer: Reducing sleep to 174.425298ms 2026/09/13 02:53:13 DEBUG : pacer: Reducing sleep to 221.941028ms 2026/09/13 02:53:17 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/13 02:53:22 DEBUG : pacer: Reducing sleep to 98.88876ms 2026/09/13 02:53:22 DEBUG : pacer: Reducing sleep to 220.983477ms 2026/09/13 02:53:22 DEBUG : pacer: Reducing sleep to 124.117188ms 2026/09/13 02:53:22 DEBUG : pacer: Reducing sleep to 230.185794ms 2026/09/13 02:53:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:53:27 DEBUG : pacer: Reducing sleep to 116.571789ms 2026/09/13 02:53:27 DEBUG : pacer: Reducing sleep to 83.266012ms 2026/09/13 02:53:27 DEBUG : pacer: Reducing sleep to 162.698125ms 2026/09/13 02:53:27 DEBUG : pacer: Reducing sleep to 222.880699ms 2026/09/13 02:53:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/13 02:53:31 DEBUG : pacer: Reducing sleep to 120.242861ms 2026/09/13 02:53:31 DEBUG : pacer: Reducing sleep to 83.780835ms 2026/09/13 02:53:32 DEBUG : pacer: Reducing sleep to 136.886657ms 2026/09/13 02:53:32 DEBUG : pacer: Reducing sleep to 144.530311ms 2026/09/13 02:53:32 DEBUG : pacer: Reducing sleep to 254.715891ms 2026/09/13 02:53:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:53:33 DEBUG : zoho root 'rclone-test-wadodox4ramu': folder listing limiter sweep: evicted 3 of 11 idle limiters 2026/09/13 02:53:33 DEBUG : pacer: Reducing sleep to 113.050379ms 2026/09/13 02:53:33 DEBUG : pacer: Reducing sleep to 68.810308ms 2026/09/13 02:53:33 DEBUG : pacer: Reducing sleep to 130.205523ms 2026/09/13 02:53:33 DEBUG : pacer: Reducing sleep to 225.094802ms 2026/09/13 02:53:34 DEBUG : pacer: Reducing sleep to 263.285382ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/13 02:53:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:53:34 DEBUG : pacer: Reducing sleep to 85.006346ms 2026/09/13 02:53:35 DEBUG : pacer: Reducing sleep to 69.375694ms 2026/09/13 02:53:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:53:35 DEBUG : pacer: Reducing sleep to 104.786544ms 2026/09/13 02:53:35 DEBUG : pacer: Reducing sleep to 217.377206ms 2026/09/13 02:53:35 DEBUG : pacer: Reducing sleep to 277.318466ms 2026/09/13 02:53:35 DEBUG : pacer: Reducing sleep to 219.762941ms 2026/09/13 02:53:36 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:53:36 DEBUG : pacer: Reducing sleep to 110.994957ms 2026/09/13 02:53:40 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/13 02:53:44 DEBUG : pacer: Reducing sleep to 100.768378ms 2026/09/13 02:53:44 DEBUG : pacer: Reducing sleep to 226.4567ms 2026/09/13 02:53:45 DEBUG : pacer: Reducing sleep to 193.982022ms 2026/09/13 02:53:45 DEBUG : pacer: Reducing sleep to 139.130744ms 2026/09/13 02:53:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:53:49 DEBUG : pacer: Reducing sleep to 106.393069ms 2026/09/13 02:53:49 DEBUG : pacer: Reducing sleep to 186.685926ms 2026/09/13 02:53:49 DEBUG : pacer: Reducing sleep to 245.620114ms 2026/09/13 02:53:49 DEBUG : pacer: Reducing sleep to 222.467838ms 2026/09/13 02:53:53 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/13 02:53:58 DEBUG : pacer: Reducing sleep to 85.812907ms 2026/09/13 02:53:58 DEBUG : pacer: Reducing sleep to 209.725468ms 2026/09/13 02:53:58 DEBUG : pacer: Reducing sleep to 126.313279ms 2026/09/13 02:53:59 DEBUG : pacer: Reducing sleep to 234.754997ms 2026/09/13 02:54:03 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:54:03 DEBUG : pacer: Reducing sleep to 102.142303ms 2026/09/13 02:54:03 DEBUG : pacer: Reducing sleep to 88.216628ms 2026/09/13 02:54:03 DEBUG : pacer: Reducing sleep to 161.448062ms 2026/09/13 02:54:03 DEBUG : pacer: Reducing sleep to 235.557403ms 2026/09/13 02:54:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/13 02:54:12 DEBUG : pacer: Reducing sleep to 86.610266ms 2026/09/13 02:54:12 DEBUG : pacer: Reducing sleep to 212.047141ms 2026/09/13 02:54:12 DEBUG : pacer: Reducing sleep to 183.969806ms 2026/09/13 02:54:12 DEBUG : pacer: Reducing sleep to 231.749902ms 2026/09/13 02:54:17 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:54:17 DEBUG : pacer: Reducing sleep to 100.188833ms 2026/09/13 02:54:17 DEBUG : pacer: Reducing sleep to 92.118223ms 2026/09/13 02:54:17 DEBUG : pacer: Reducing sleep to 164.887945ms 2026/09/13 02:54:17 DEBUG : pacer: Reducing sleep to 233.731353ms 2026/09/13 02:54:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/13 02:54:26 DEBUG : pacer: Reducing sleep to 96.967921ms 2026/09/13 02:54:26 DEBUG : pacer: Reducing sleep to 214.160411ms 2026/09/13 02:54:26 DEBUG : pacer: Reducing sleep to 111.719371ms 2026/09/13 02:54:26 DEBUG : pacer: Reducing sleep to 230.588376ms 2026/09/13 02:54:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:54:30 DEBUG : pacer: Reducing sleep to 113.404197ms 2026/09/13 02:54:31 DEBUG : pacer: Reducing sleep to 96.13639ms 2026/09/13 02:54:31 DEBUG : pacer: Reducing sleep to 168.330785ms 2026/09/13 02:54:31 DEBUG : pacer: Reducing sleep to 221.010613ms 2026/09/13 02:54:31 DEBUG : pacer: Reducing sleep to 241.496522ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/13 02:54:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:54:32 DEBUG : pacer: Reducing sleep to 98.395166ms 2026/09/13 02:54:32 DEBUG : pacer: Reducing sleep to 92.393613ms 2026/09/13 02:54:32 DEBUG : pacer: Reducing sleep to 4.105325ms 2026/09/13 02:54:32 DEBUG : pacer: Reducing sleep to 124.374665ms 2026/09/13 02:54:32 DEBUG : pacer: Reducing sleep to 236.397994ms 2026/09/13 02:54:32 DEBUG : pacer: Reducing sleep to 283.676343ms 2026/09/13 02:54:33 DEBUG : pacer: Reducing sleep to 193.930503ms 2026/09/13 02:54:33 DEBUG : pacer: Reducing sleep to 97.302399ms 2026/09/13 02:54:33 DEBUG : pacer: Reducing sleep to 48.469882ms 2026/09/13 02:54:33 DEBUG : zoho root 'rclone-test-wadodox4ramu': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/13 02:54:33 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/13 02:54:34 DEBUG : pacer: Reducing sleep to 95.308206ms 2026/09/13 02:54:34 DEBUG : pacer: Reducing sleep to 214.81793ms 2026/09/13 02:54:34 DEBUG : pacer: Reducing sleep to 213.471356ms 2026/09/13 02:54:34 DEBUG : pacer: Reducing sleep to 235.321562ms 2026/09/13 02:54:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:54:39 DEBUG : pacer: Reducing sleep to 111.597686ms 2026/09/13 02:54:39 DEBUG : pacer: Reducing sleep to 84.892698ms 2026/09/13 02:54:39 DEBUG : pacer: Reducing sleep to 160.960237ms 2026/09/13 02:54:39 DEBUG : pacer: Reducing sleep to 225.919039ms 2026/09/13 02:54:43 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:725: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:734: testing "test%46.txt" 2026/09/13 02:54:48 DEBUG : pacer: Reducing sleep to 99.926829ms 2026/09/13 02:54:48 DEBUG : pacer: Reducing sleep to 220.651078ms 2026/09/13 02:54:48 DEBUG : pacer: Reducing sleep to 196.394754ms 2026/09/13 02:54:48 DEBUG : pacer: Reducing sleep to 233.051887ms 2026/09/13 02:54:52 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:54:52 DEBUG : pacer: Reducing sleep to 107.841051ms 2026/09/13 02:54:53 DEBUG : pacer: Reducing sleep to 70.181951ms 2026/09/13 02:54:53 DEBUG : pacer: Reducing sleep to 149.096634ms 2026/09/13 02:54:53 DEBUG : pacer: Reducing sleep to 245.237344ms 2026/09/13 02:54:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/13 02:55:16 DEBUG : pacer: Reducing sleep to 76.20758ms 2026/09/13 02:55:16 DEBUG : pacer: Reducing sleep to 201.735682ms 2026/09/13 02:55:16 DEBUG : pacer: Reducing sleep to 254.662371ms 2026/09/13 02:55:16 DEBUG : pacer: Reducing sleep to 255.986596ms 2026/09/13 02:55:16 DEBUG : pacer: Reducing sleep to 124.180028ms 2026/09/13 02:55:16 DEBUG : pacer: Reducing sleep to 75.389488ms 2026/09/13 02:55:17 DEBUG : pacer: Reducing sleep to 82.329968ms 2026/09/13 02:55:17 DEBUG : pacer: Reducing sleep to 248.569364ms 2026/09/13 02:55:17 DEBUG : pacer: Reducing sleep to 290.103044ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/13 02:55:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:55:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:55:25 UTC": parsing time "Sun, 13 Sep 2026 02:55:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:55:25 UTC" as " " 2026/09/13 02:55:25 DEBUG : pacer: Reducing sleep to 112.464301ms === RUN TestIntegration/FsMkdir/FsOpenWriterAt fstests.go:813: FS has no OpenWriterAt interface === RUN TestIntegration/FsMkdir/FsOpenChunkWriter fstests.go:846: FS has no OpenChunkWriter interface === RUN TestIntegration/FsMkdir/FsChangeNotify fstests.go:899: FS has no ChangeNotify interface === RUN TestIntegration/FsMkdir/FsPutFiles 2026/09/13 02:55:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:55:29 DEBUG : pacer: Reducing sleep to 31.397288ms 2026/09/13 02:55:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:55:34 DEBUG : zoho root 'rclone-test-wadodox4ramu': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/13 02:55:34 DEBUG : pacer: Reducing sleep to 114.717963ms 2026/09/13 02:55:34 DEBUG : pacer: Reducing sleep to 13.18032ms 2026/09/13 02:55:34 DEBUG : pacer: Reducing sleep to 133.529793ms 2026/09/13 02:55:34 DEBUG : pacer: Reducing sleep to 254.107007ms 2026/09/13 02:55:35 DEBUG : pacer: Reducing sleep to 226.238047ms 2026/09/13 02:55:35 DEBUG : pacer: Reducing sleep to 194.84727ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/13 02:55:35 DEBUG : pacer: Reducing sleep to 120.868513ms 2026/09/13 02:55:35 DEBUG : pacer: Reducing sleep to 104.284123ms 2026/09/13 02:55:35 DEBUG : pacer: Reducing sleep to 144.514795ms 2026/09/13 02:55:35 DEBUG : pacer: Reducing sleep to 212.656014ms 2026/09/13 02:55:36 DEBUG : pacer: Reducing sleep to 225.280837ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/13 02:55:36 DEBUG : pacer: Reducing sleep to 174.387389ms 2026/09/13 02:55:36 DEBUG : pacer: Reducing sleep to 120.060732ms 2026/09/13 02:55:36 DEBUG : pacer: Reducing sleep to 119.258283ms 2026/09/13 02:55:36 DEBUG : pacer: Reducing sleep to 164.707883ms 2026/09/13 02:55:36 DEBUG : pacer: Reducing sleep to 209.762825ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/13 02:55:37 DEBUG : pacer: Reducing sleep to 205.865778ms 2026/09/13 02:55:37 DEBUG : pacer: Reducing sleep to 168.58052ms 2026/09/13 02:55:37 DEBUG : pacer: Reducing sleep to 118.158268ms 2026/09/13 02:55:37 DEBUG : pacer: Reducing sleep to 120.406848ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/13 02:55:37 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/09/13 02:55:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir#01 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListLevel2 2026/09/13 02:55:46 DEBUG : pacer: Reducing sleep to 116.717754ms 2026/09/13 02:55:46 DEBUG : pacer: Reducing sleep to 227.047304ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/13 02:55:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:55:51 DEBUG : pacer: Reducing sleep to 108.34935ms 2026/09/13 02:55:51 DEBUG : pacer: Reducing sleep to 105.04965ms 2026/09/13 02:55:51 DEBUG : pacer: Reducing sleep to 158.689766ms 2026/09/13 02:55:51 DEBUG : pacer: Reducing sleep to 226.778616ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/13 02:55:56 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/13 02:56:00 DEBUG : pacer: Reducing sleep to 86.093518ms 2026/09/13 02:56:00 DEBUG : pacer: Reducing sleep to 204.505545ms 2026/09/13 02:56:00 DEBUG : pacer: Reducing sleep to 276.543806ms 2026/09/13 02:56:01 DEBUG : pacer: Reducing sleep to 246.549083ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/13 02:56:05 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/13 02:56:05 DEBUG : pacer: Reducing sleep to 118.47156ms 2026/09/13 02:56:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:10 DEBUG : pacer: Reducing sleep to 99.520064ms 2026/09/13 02:56:10 DEBUG : pacer: Reducing sleep to 13.322655ms 2026/09/13 02:56:10 DEBUG : pacer: Reducing sleep to 126.813818ms 2026/09/13 02:56:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:14 DEBUG : pacer: Reducing sleep to 115.592339ms 2026/09/13 02:56:14 DEBUG : pacer: Reducing sleep to 279.181902ms 2026/09/13 02:56:14 DEBUG : pacer: Reducing sleep to 365.566919ms 2026/09/13 02:56:15 DEBUG : pacer: Reducing sleep to 216.06443ms 2026/09/13 02:56:15 DEBUG : pacer: Reducing sleep to 49.426989ms 2026/09/13 02:56:15 DEBUG : pacer: Reducing sleep to 11.314144ms 2026/09/13 02:56:15 DEBUG : pacer: Reducing sleep to 109.067794ms 2026/09/13 02:56:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:19 DEBUG : pacer: Reducing sleep to 114.197006ms 2026/09/13 02:56:19 DEBUG : pacer: Reducing sleep to 224.054537ms 2026/09/13 02:56:19 DEBUG : pacer: Reducing sleep to 265.087852ms 2026/09/13 02:56:19 DEBUG : pacer: Reducing sleep to 215.904773ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/13 02:56:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:28 DEBUG : pacer: Reducing sleep to 91.936645ms 2026/09/13 02:56:28 DEBUG : pacer: Reducing sleep to 139.502079ms 2026/09/13 02:56:28 DEBUG : pacer: Reducing sleep to 260.776548ms 2026/09/13 02:56:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:33 DEBUG : pacer: Reducing sleep to 119.266356ms 2026/09/13 02:56:33 DEBUG : pacer: Reducing sleep to 284.131536ms 2026/09/13 02:56:33 DEBUG : pacer: Reducing sleep to 237.686574ms 2026/09/13 02:56:33 DEBUG : pacer: Reducing sleep to 119.872205ms 2026/09/13 02:56:34 DEBUG : pacer: Reducing sleep to 43.712567ms 2026/09/13 02:56:34 DEBUG : Creating backend with remote "TestZoho:rclone-test-wadodox4ramu/dirToPurgeFromRoot" 2026/09/13 02:56:34 DEBUG : pacer: Reducing sleep to 111.819951ms 2026/09/13 02:56:34 DEBUG : pacer: Reducing sleep to 236.891451ms 2026/09/13 02:56:34 DEBUG : pacer: Reducing sleep to 270.545093ms 2026/09/13 02:56:34 DEBUG : zoho root 'rclone-test-wadodox4ramu': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/13 02:56:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:34 DEBUG : pacer: Reducing sleep to 118.691355ms 2026/09/13 02:56:34 DEBUG : pacer: Reducing sleep to 233.76823ms 2026/09/13 02:56:34 DEBUG : pacer: Reducing sleep to 285.967747ms 2026/09/13 02:56:35 DEBUG : pacer: Reducing sleep to 210.052846ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/13 02:56:35 DEBUG : Creating backend with remote "TestZoho:rclone-test-wadodox4ramu/hello? sausage/êé" 2026/09/13 02:56:35 DEBUG : pacer: Reducing sleep to 114.825257ms 2026/09/13 02:56:35 DEBUG : pacer: Reducing sleep to 237.71914ms 2026/09/13 02:56:35 DEBUG : pacer: Reducing sleep to 279.384329ms 2026/09/13 02:56:35 DEBUG : pacer: Reducing sleep to 215.504733ms 2026/09/13 02:56:36 DEBUG : pacer: Reducing sleep to 60.635216ms 2026/09/13 02:56:36 DEBUG : pacer: Reducing sleep to 58.401876ms 2026/09/13 02:56:36 DEBUG : pacer: Reducing sleep to 125.825492ms 2026/09/13 02:56:36 DEBUG : pacer: Reducing sleep to 252.797506ms 2026/09/13 02:56:36 DEBUG : pacer: Reducing sleep to 253.694892ms 2026/09/13 02:56:36 DEBUG : pacer: Reducing sleep to 206.052507ms 2026/09/13 02:56:37 DEBUG : pacer: Reducing sleep to 81.896381ms 2026/09/13 02:56:37 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:37 DEBUG : pacer: Reducing sleep to 123.783379ms 2026/09/13 02:56:37 DEBUG : pacer: Reducing sleep to 246.150284ms 2026/09/13 02:56:37 DEBUG : pacer: Reducing sleep to 280.906482ms 2026/09/13 02:56:37 DEBUG : pacer: Reducing sleep to 198.504622ms 2026/09/13 02:56:38 DEBUG : pacer: Reducing sleep to 85.003996ms 2026/09/13 02:56:38 DEBUG : pacer: Reducing sleep to 56.148007ms 2026/09/13 02:56:38 DEBUG : pacer: Reducing sleep to 141.682957ms 2026/09/13 02:56:38 DEBUG : pacer: Reducing sleep to 246.579288ms 2026/09/13 02:56:38 DEBUG : pacer: Reducing sleep to 274.799467ms 2026/09/13 02:56:38 DEBUG : pacer: Reducing sleep to 180.298055ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/13 02:56:38 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:39 DEBUG : pacer: Reducing sleep to 102.233412ms 2026/09/13 02:56:39 DEBUG : pacer: Reducing sleep to 216.941842ms 2026/09/13 02:56:39 DEBUG : pacer: Reducing sleep to 284.148972ms 2026/09/13 02:56:42 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:42 DEBUG : pacer: Reducing sleep to 120.255396ms 2026/09/13 02:56:42 DEBUG : pacer: Reducing sleep to 62.366025ms 2026/09/13 02:56:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:46 DEBUG : pacer: Reducing sleep to 84.709333ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/13 02:56:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:56:51 DEBUG : pacer: Reducing sleep to 100.209946ms 2026/09/13 02:56:51 DEBUG : pacer: Reducing sleep to 186.452971ms 2026/09/13 02:56:51 DEBUG : pacer: Reducing sleep to 271.277187ms 2026/09/13 02:56:51 DEBUG : pacer: Reducing sleep to 264.457515ms 2026/09/13 02:56:52 DEBUG : pacer: Reducing sleep to 151.341255ms 2026/09/13 02:56:52 DEBUG : pacer: Reducing sleep to 59.745967ms 2026/09/13 02:56:52 DEBUG : pacer: Reducing sleep to 72.509664ms 2026/09/13 02:56:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:57:05 DEBUG : pacer: Reducing sleep to 101.035903ms 2026/09/13 02:57:05 DEBUG : pacer: Reducing sleep to 203.6033ms 2026/09/13 02:57:05 DEBUG : pacer: Reducing sleep to 247.666859ms 2026/09/13 02:57:06 DEBUG : pacer: Reducing sleep to 207.418162ms 2026/09/13 02:57:10 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:57:10 DEBUG : pacer: Reducing sleep to 116.981736ms 2026/09/13 02:57:10 DEBUG : pacer: Reducing sleep to 279.058995ms 2026/09/13 02:57:10 DEBUG : pacer: Reducing sleep to 303.673974ms 2026/09/13 02:57:10 DEBUG : pacer: Reducing sleep to 182.475717ms 2026/09/13 02:57:11 DEBUG : pacer: Reducing sleep to 57.409743ms 2026/09/13 02:57:11 DEBUG : pacer: Reducing sleep to 29.775836ms 2026/09/13 02:57:11 DEBUG : pacer: Reducing sleep to 130.738916ms 2026/09/13 02:57:11 DEBUG : pacer: Reducing sleep to 210.640042ms 2026/09/13 02:57:11 DEBUG : pacer: Reducing sleep to 279.484513ms 2026/09/13 02:57:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:57:14 DEBUG : pacer: Reducing sleep to 123.403349ms 2026/09/13 02:57:14 DEBUG : pacer: Reducing sleep to 275.984531ms 2026/09/13 02:57:15 DEBUG : pacer: Reducing sleep to 190.808024ms 2026/09/13 02:57:15 DEBUG : pacer: Reducing sleep to 78.2076ms 2026/09/13 02:57:15 DEBUG : pacer: Reducing sleep to 97.414268ms 2026/09/13 02:57:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:57:19 DEBUG : pacer: Reducing sleep to 111.246314ms 2026/09/13 02:57:19 DEBUG : pacer: Reducing sleep to 198.094422ms 2026/09/13 02:57:19 DEBUG : pacer: Reducing sleep to 274.287498ms 2026/09/13 02:57:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:57:24 DEBUG : pacer: Reducing sleep to 125.160073ms 2026/09/13 02:57:24 DEBUG : pacer: Reducing sleep to 271.087017ms 2026/09/13 02:57:24 DEBUG : pacer: Reducing sleep to 260.934154ms 2026/09/13 02:57:24 DEBUG : pacer: Reducing sleep to 150.950496ms 2026/09/13 02:57:24 DEBUG : pacer: Reducing sleep to 61.667926ms 2026/09/13 02:57:25 DEBUG : pacer: Reducing sleep to 75.282933ms 2026/09/13 02:57:25 DEBUG : pacer: Reducing sleep to 172.385658ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/13 02:57:25 DEBUG : Creating backend with remote "TestZoho:rclone-test-futitoh0kono" 2026/09/13 02:57:25 DEBUG : Config file has changed externally - reloading 2026/09/13 02:57:25 DEBUG : pacer: Reducing sleep to 41.576926ms 2026/09/13 02:57:25 DEBUG : pacer: Reducing sleep to 87.512251ms 2026/09/13 02:57:25 DEBUG : pacer: Reducing sleep to 106.491014ms 2026/09/13 02:57:25 DEBUG : pacer: Reducing sleep to 224.67078ms 2026/09/13 02:57:25 DEBUG : pacer: Reducing sleep to 249.312695ms 2026/09/13 02:57:26 DEBUG : pacer: Reducing sleep to 227.169404ms 2026/09/13 02:57:26 DEBUG : pacer: Reducing sleep to 95.145387ms 2026/09/13 02:57:26 DEBUG : pacer: Reducing sleep to 63.990011ms 2026/09/13 02:57:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:57:26 DEBUG : pacer: Reducing sleep to 42.708454ms 2026/09/13 02:57:26 DEBUG : pacer: Reducing sleep to 159.176067ms 2026/09/13 02:57:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:57:28 DEBUG : pacer: Reducing sleep to 120.442725ms 2026/09/13 02:57:28 DEBUG : pacer: Reducing sleep to 175.203207ms 2026/09/13 02:57:28 DEBUG : pacer: Reducing sleep to 227.355976ms 2026/09/13 02:57:29 DEBUG : pacer: Reducing sleep to 206.313147ms 2026/09/13 02:57:31 DEBUG : pacer: Reducing sleep to 40.496046ms 2026/09/13 02:57:31 DEBUG : pacer: Reducing sleep to 158.710811ms 2026/09/13 02:57:31 DEBUG : pacer: Reducing sleep to 274.989608ms 2026/09/13 02:57:31 DEBUG : pacer: Reducing sleep to 284.671094ms 2026/09/13 02:57:31 DEBUG : pacer: Reducing sleep to 167.37961ms 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 48.822236ms 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 109.159165ms 2026/09/13 02:57:32 DEBUG : zoho root 'rclone-test-futitoh0kono': Purge remote 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 134.563285ms 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 170.920399ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 118.407792ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 230.685095ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 280.521362ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/13 02:57:32 DEBUG : pacer: Reducing sleep to 215.651042ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/13 02:57:33 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/09/13 02:57:38 DEBUG : zoho root 'rclone-test-wadodox4ramu': folder listing limiter sweep: evicted 5 of 15 idle limiters fstests.go:1664: Metadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetMetadata fstests.go:1735: SetMetadata method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSetModTime fstests.go:1792: can't set modified time === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectSize === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpen 2026/09/13 02:58:01 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:01 UTC": parsing time "Sun, 13 Sep 2026 02:58:01 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:01 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/13 02:58:06 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:06 UTC": parsing time "Sun, 13 Sep 2026 02:58:06 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:06 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/13 02:58:10 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:10 UTC": parsing time "Sun, 13 Sep 2026 02:58:10 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:10 UTC" as " " 2026/09/13 02:58:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:11 UTC": parsing time "Sun, 13 Sep 2026 02:58:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:11 UTC" as " " 2026/09/13 02:58:11 DEBUG : pacer: Reducing sleep to 3.092809ms 2026/09/13 02:58:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:11 UTC": parsing time "Sun, 13 Sep 2026 02:58:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:11 UTC" as " " 2026/09/13 02:58:11 DEBUG : pacer: Reducing sleep to 2.039183ms 2026/09/13 02:58:11 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:11 UTC": parsing time "Sun, 13 Sep 2026 02:58:11 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:11 UTC" as " " 2026/09/13 02:58:11 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/13 02:58:15 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:15 UTC": parsing time "Sun, 13 Sep 2026 02:58:15 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:15 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/13 02:58:20 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:20 UTC": parsing time "Sun, 13 Sep 2026 02:58:20 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:20 UTC" as " " 2026/09/13 02:58:24 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:24 UTC": parsing time "Sun, 13 Sep 2026 02:58:24 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:24 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/13 02:58:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:25 UTC": parsing time "Sun, 13 Sep 2026 02:58:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:25 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/13 02:58:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:25 UTC": parsing time "Sun, 13 Sep 2026 02:58:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:25 UTC" as " " 2026/09/13 02:58:25 DEBUG : pacer: Reducing sleep to 13.108875ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/13 02:58:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:25 UTC": parsing time "Sun, 13 Sep 2026 02:58:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:25 UTC" as " " 2026/09/13 02:58:25 DEBUG : pacer: Reducing sleep to 11.395344ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/13 02:58:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:25 UTC": parsing time "Sun, 13 Sep 2026 02:58:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:25 UTC" as " " 2026/09/13 02:58:25 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/13 02:58:29 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:29 UTC": parsing time "Sun, 13 Sep 2026 02:58:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:29 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/13 02:58:33 DEBUG : pacer: Reducing sleep to 32.575972ms 2026/09/13 02:58:33 DEBUG : pacer: Reducing sleep to 146.292037ms 2026/09/13 02:58:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sun, 13 Sep 2026 02:58:34 UTC": parsing time "Sun, 13 Sep 2026 02:58:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 13 Sep 2026 02:58:34 UTC" as " " 2026/09/13 02:58:34 DEBUG : pacer: Reducing sleep to 126.580258ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/13 02:58:34 DEBUG : pacer: Reducing sleep to 240.974538ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/13 02:58:34 DEBUG : Creating backend with remote "TestZoho:rclone-test-wadodox4ramu/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/13 02:58:34 DEBUG : pacer: Reducing sleep to 114.806159ms 2026/09/13 02:58:34 DEBUG : pacer: Reducing sleep to 232.118145ms 2026/09/13 02:58:34 DEBUG : pacer: Reducing sleep to 285.046666ms 2026/09/13 02:58:34 DEBUG : pacer: Reducing sleep to 219.247877ms 2026/09/13 02:58:35 DEBUG : pacer: Reducing sleep to 86.294473ms 2026/09/13 02:58:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:58:35 DEBUG : pacer: Reducing sleep to 108.871047ms 2026/09/13 02:58:35 DEBUG : pacer: Reducing sleep to 229.004097ms 2026/09/13 02:58:35 DEBUG : pacer: Reducing sleep to 276.190401ms 2026/09/13 02:58:35 DEBUG : pacer: Reducing sleep to 208.934914ms 2026/09/13 02:58:36 DEBUG : pacer: Reducing sleep to 105.819373ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/13 02:58:36 DEBUG : pacer: Reducing sleep to 51.242706ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/13 02:58:36 DEBUG : Creating backend with remote "TestZoho:rclone-test-wadodox4ramu/not found.txt" 2026/09/13 02:58:36 DEBUG : pacer: Reducing sleep to 105.910812ms 2026/09/13 02:58:36 DEBUG : pacer: Reducing sleep to 143.456965ms 2026/09/13 02:58:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:58:41 DEBUG : zoho root 'rclone-test-wadodox4ramu/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/09/13 02:58:45 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-wadodox4ramu" from "TestZoho:rclone-test-wadodox4ramu" 2026/09/13 02:58:45 DEBUG : Creating backend with remote "TestZoho:" 2026/09/13 02:58:45 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/13 02:58:50 DEBUG : pacer: Reducing sleep to 120.153958ms 2026/09/13 02:58:50 DEBUG : pacer: Reducing sleep to 237.690888ms 2026/09/13 02:58:50 DEBUG : pacer: Reducing sleep to 286.180096ms 2026/09/13 02:58:50 DEBUG : pacer: Reducing sleep to 201.682916ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/13 02:58:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListR fstests.go:2031: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put 2026/09/13 02:59:09 DEBUG : pacer: Reducing sleep to 117.96608ms 2026/09/13 02:59:09 DEBUG : pacer: Reducing sleep to 228.302543ms 2026/09/13 02:59:09 DEBUG : pacer: Reducing sleep to 283.092345ms 2026/09/13 02:59:09 DEBUG : pacer: Reducing sleep to 216.297586ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/13 02:59:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:59:13 DEBUG : pacer: Reducing sleep to 117.972085ms 2026/09/13 02:59:13 DEBUG : pacer: Reducing sleep to 232.010839ms 2026/09/13 02:59:13 DEBUG : pacer: Reducing sleep to 285.780284ms 2026/09/13 02:59:14 DEBUG : pacer: Reducing sleep to 206.752826ms === RUN TestIntegration/FsMkdir/FsPutFiles/PublicLink fstests.go:2090: FS has no PublicLinker interface === RUN TestIntegration/FsMkdir/FsPutFiles/SetTier fstests.go:438: FS has no SetTier & GetTier interfaces === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectCheckWrap fstests.go:2195: Not a wrapping Fs === RUN TestIntegration/FsMkdir/FsPutFiles/Bucket fstests.go:2211: Not a bucket based backend === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemove 2026/09/13 02:59:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:59:22 DEBUG : pacer: Reducing sleep to 112.725464ms 2026/09/13 02:59:22 DEBUG : pacer: Reducing sleep to 228.66857ms 2026/09/13 02:59:23 DEBUG : pacer: Reducing sleep to 269.010564ms 2026/09/13 02:59:23 DEBUG : pacer: Reducing sleep to 216.091233ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/13 02:59:23 DEBUG : pacer: Reducing sleep to 107.245188ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPutStream fstests.go:2385: FS has no PutStream interface === RUN TestIntegration/FsMkdir/FsPutFiles/Internal fstests.go:2431: *zoho.Fs does not implement InternalTester === RUN TestIntegration/FsMkdir/FsPutChunked fstests.go:2452: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutRetry === RUN TestIntegration/FsMkdir/FsPutRetry/Simple === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/Status500 2026/09/13 02:59:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:59:27 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/13 02:59:27 DEBUG : pacer: Rate limited, increasing sleep to 1.909642384s fstests.go:2660: Put returned an error after the injected failure (acceptable): upload error: HTTP error 500 (500 Internal Server Error) returned body: "" 2026/09/13 02:59:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:59:32 DEBUG : pacer: Reducing sleep to 113.824518ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/13 02:59:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/13 02:59:34 DEBUG : pacer: low level retry 1/1 (error Post "https://workdrive.zoho.eu/api/v1/upload?filename=retry-NetError.bin&override-name-exist=true&parent_id=8l3pke0ae02faf5234b2f9fbdedbc9dcd0171": write tcp: unexpected EOF) 2026/09/13 02:59:34 DEBUG : pacer: Rate limited, increasing sleep to 1.041892555s fstests.go:2660: Put returned an error after the injected failure (acceptable): upload error: Post "https://workdrive.zoho.eu/api/v1/upload?filename=retry-NetError.bin&override-name-exist=true&parent_id=8l3pke0ae02faf5234b2f9fbdedbc9dcd0171": write tcp: unexpected EOF 2026/09/13 02:59:34 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutRetry/Chunked fstests.go:2697: *zoho.Fs does not implement SetUploadChunkSizer === RUN TestIntegration/FsMkdir/FsPutShortEOF === RUN TestIntegration/FsMkdir/FsPutShortEOF/Simple 2026/09/13 02:59:34 DEBUG : pacer: Reducing sleep to 135.611748ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n