"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/30 03:54:48 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/30 03:54:48 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/30 03:54:48 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/30 03:54:48 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/30 03:54:48 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/30 03:54:48 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/30 03:54:48 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/30 03:54:48 DEBUG : Creating backend with remote "TestZoho:rclone-test-qikirom9keno" 2026/09/30 03:54:48 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/30 03:54:48 DEBUG : zoho root 'rclone-test-qikirom9keno': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/09/30 03:54:48 DEBUG : TestZoho: Token expired 2026/09/30 03:54:48 DEBUG : TestZoho: No updated token found in the config file 2026/09/30 03:54:48 DEBUG : TestZoho: Token refresh successful 2026/09/30 03:54:48 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/30 03:54:48 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/30 03:54:49 DEBUG : pacer: Reducing sleep to 2.20544ms 2026/09/30 03:54:50 DEBUG : pacer: Reducing sleep to 4.194021ms 2026/09/30 03:54:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:54:50 DEBUG : pacer: Reducing sleep to 89.191545ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/30 03:54:50 DEBUG : pacer: Reducing sleep to 176.584176ms 2026/09/30 03:54:50 DEBUG : pacer: Reducing sleep to 242.374625ms === RUN TestIntegration/FsMkdir 2026/09/30 03:54:50 DEBUG : pacer: Reducing sleep to 165.360544ms 2026/09/30 03:54:51 DEBUG : pacer: Reducing sleep to 66.395663ms 2026/09/30 03:54:51 DEBUG : pacer: Reducing sleep to 158.470172ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/30 03:54:51 INFO : dir/subdir: Making directory 2026/09/30 03:54:51 DEBUG : pacer: Reducing sleep to 251.462142ms 2026/09/30 03:54:51 DEBUG : pacer: Reducing sleep to 224.64812ms 2026/09/30 03:54:51 DEBUG : pacer: Reducing sleep to 172.306897ms 2026/09/30 03:54:51 DEBUG : pacer: Reducing sleep to 88.131916ms 2026/09/30 03:54:52 DEBUG : pacer: Reducing sleep to 78.980518ms 2026/09/30 03:54:52 DEBUG : pacer: Reducing sleep to 148.651082ms 2026/09/30 03:54:52 DEBUG : pacer: Reducing sleep to 242.012481ms 2026/09/30 03:54:52 INFO : dir/subdir: Removing directory 2026/09/30 03:54:52 DEBUG : pacer: Reducing sleep to 254.23914ms 2026/09/30 03:54:52 DEBUG : pacer: Reducing sleep to 160.613779ms 2026/09/30 03:54:52 DEBUG : pacer: Reducing sleep to 85.398043ms 2026/09/30 03:54:53 DEBUG : pacer: Reducing sleep to 98.346676ms 2026/09/30 03:54:53 INFO : dir: Removing directory 2026/09/30 03:54:53 DEBUG : pacer: Reducing sleep to 174.582334ms 2026/09/30 03:54:53 DEBUG : pacer: Reducing sleep to 234.786066ms 2026/09/30 03:54:53 DEBUG : pacer: Reducing sleep to 240.541108ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/30 03:54:53 DEBUG : pacer: Reducing sleep to 173.077216ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/30 03:54:58 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/30 03:55:02 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/30 03:55:12 DEBUG : pacer: Reducing sleep to 42.980096ms 2026/09/30 03:55:12 DEBUG : pacer: Reducing sleep to 136.373685ms 2026/09/30 03:55:12 DEBUG : pacer: Reducing sleep to 15.732873ms 2026/09/30 03:55:12 DEBUG : pacer: Reducing sleep to 99.510659ms 2026/09/30 03:55:16 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:55:16 DEBUG : pacer: Reducing sleep to 84.717118ms 2026/09/30 03:55:16 DEBUG : pacer: Reducing sleep to 161.874586ms 2026/09/30 03:55:16 DEBUG : pacer: Reducing sleep to 251.568881ms 2026/09/30 03:55:17 DEBUG : pacer: Reducing sleep to 242.043136ms 2026/09/30 03:55:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/30 03:55:25 DEBUG : pacer: Reducing sleep to 62.466513ms 2026/09/30 03:55:25 DEBUG : pacer: Reducing sleep to 157.986335ms 2026/09/30 03:55:26 DEBUG : pacer: Reducing sleep to 127.196336ms 2026/09/30 03:55:26 DEBUG : pacer: Reducing sleep to 214.930683ms 2026/09/30 03:55:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:55:30 DEBUG : pacer: Reducing sleep to 72.035588ms 2026/09/30 03:55:30 DEBUG : pacer: Reducing sleep to 128.914302ms 2026/09/30 03:55:30 DEBUG : pacer: Reducing sleep to 216.544892ms 2026/09/30 03:55:30 DEBUG : pacer: Reducing sleep to 243.295891ms 2026/09/30 03:55:35 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/30 03:55:39 DEBUG : pacer: Reducing sleep to 61.129289ms 2026/09/30 03:55:39 DEBUG : pacer: Reducing sleep to 155.104976ms 2026/09/30 03:55:40 DEBUG : pacer: Reducing sleep to 154.582546ms 2026/09/30 03:55:40 DEBUG : pacer: Reducing sleep to 233.709012ms 2026/09/30 03:55:44 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:55:44 DEBUG : pacer: Reducing sleep to 76.647804ms 2026/09/30 03:55:44 DEBUG : pacer: Reducing sleep to 96.16694ms 2026/09/30 03:55:44 DEBUG : pacer: Reducing sleep to 182.805476ms 2026/09/30 03:55:44 DEBUG : pacer: Reducing sleep to 247.985632ms 2026/09/30 03:55:48 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/30 03:55:48 DEBUG : zoho root 'rclone-test-qikirom9keno': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/09/30 03:55:53 DEBUG : pacer: Reducing sleep to 64.788425ms 2026/09/30 03:55:53 DEBUG : pacer: Reducing sleep to 156.046784ms 2026/09/30 03:55:53 DEBUG : pacer: Reducing sleep to 84.365667ms 2026/09/30 03:55:53 DEBUG : pacer: Reducing sleep to 173.849775ms 2026/09/30 03:55:54 DEBUG : pacer: Reducing sleep to 239.815894ms 2026/09/30 03:55:54 DEBUG : pacer: Reducing sleep to 243.826161ms 2026/09/30 03:55:54 DEBUG : pacer: Reducing sleep to 164.35535ms 2026/09/30 03:55:54 DEBUG : pacer: Reducing sleep to 94.908793ms 2026/09/30 03:55:54 DEBUG : pacer: Reducing sleep to 86.788108ms 2026/09/30 03:55:54 DEBUG : pacer: Reducing sleep to 174.300283ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/30 03:55:55 DEBUG : pacer: Reducing sleep to 252.666192ms 2026/09/30 03:55:55 DEBUG : pacer: Reducing sleep to 216.390976ms 2026/09/30 03:55:55 DEBUG : pacer: Reducing sleep to 156.473769ms 2026/09/30 03:55:55 DEBUG : pacer: Reducing sleep to 9.377411ms 2026/09/30 03:55:55 DEBUG : pacer: Reducing sleep to 95.828002ms 2026/09/30 03:55:55 DEBUG : pacer: Reducing sleep to 182.071343ms 2026/09/30 03:55:56 DEBUG : pacer: Reducing sleep to 188.685375ms 2026/09/30 03:55:56 DEBUG : pacer: Reducing sleep to 230.258765ms 2026/09/30 03:55:56 DEBUG : pacer: Reducing sleep to 216.740712ms 2026/09/30 03:55:56 DEBUG : pacer: Reducing sleep to 145.645613ms 2026/09/30 03:55:56 DEBUG : pacer: Reducing sleep to 106.201054ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/30 03:56:01 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:56:01 DEBUG : pacer: Reducing sleep to 68.616683ms 2026/09/30 03:56:01 DEBUG : pacer: Reducing sleep to 163.706674ms 2026/09/30 03:56:01 DEBUG : pacer: Reducing sleep to 91.686698ms 2026/09/30 03:56:01 DEBUG : pacer: Reducing sleep to 180.425628ms 2026/09/30 03:56:06 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:56:06 DEBUG : pacer: Reducing sleep to 50.86419ms 2026/09/30 03:56:06 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:56:06 DEBUG : pacer: Reducing sleep to 87.619298ms 2026/09/30 03:56:06 DEBUG : pacer: Reducing sleep to 168.663571ms 2026/09/30 03:56:10 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/30 03:56:15 DEBUG : pacer: Reducing sleep to 8.040183ms 2026/09/30 03:56:15 DEBUG : pacer: Reducing sleep to 97.721964ms 2026/09/30 03:56:15 DEBUG : pacer: Reducing sleep to 103.143094ms 2026/09/30 03:56:15 DEBUG : pacer: Reducing sleep to 182.690844ms 2026/09/30 03:56:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:56:19 DEBUG : pacer: Reducing sleep to 84.043238ms 2026/09/30 03:56:20 DEBUG : pacer: Reducing sleep to 139.354963ms 2026/09/30 03:56:20 DEBUG : pacer: Reducing sleep to 204.942737ms 2026/09/30 03:56:20 DEBUG : pacer: Reducing sleep to 245.579189ms 2026/09/30 03:56:24 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/30 03:56:29 DEBUG : pacer: Reducing sleep to 65.351219ms 2026/09/30 03:56:29 DEBUG : pacer: Reducing sleep to 159.993414ms 2026/09/30 03:56:29 DEBUG : pacer: Reducing sleep to 106.471128ms 2026/09/30 03:56:29 DEBUG : pacer: Reducing sleep to 181.790565ms 2026/09/30 03:56:33 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:56:33 DEBUG : pacer: Reducing sleep to 81.319915ms 2026/09/30 03:56:33 DEBUG : pacer: Reducing sleep to 142.960097ms 2026/09/30 03:56:33 DEBUG : pacer: Reducing sleep to 233.186887ms 2026/09/30 03:56:34 DEBUG : pacer: Reducing sleep to 237.107344ms 2026/09/30 03:56:38 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/30 03:56:43 DEBUG : pacer: Reducing sleep to 67.508571ms 2026/09/30 03:56:43 DEBUG : pacer: Reducing sleep to 160.740443ms 2026/09/30 03:56:43 DEBUG : pacer: Reducing sleep to 176.458111ms 2026/09/30 03:56:43 DEBUG : pacer: Reducing sleep to 253.475052ms 2026/09/30 03:56:47 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:56:47 DEBUG : pacer: Reducing sleep to 81.206759ms 2026/09/30 03:56:47 DEBUG : pacer: Reducing sleep to 65.144323ms 2026/09/30 03:56:47 DEBUG : pacer: Reducing sleep to 154.260365ms 2026/09/30 03:56:47 DEBUG : pacer: Reducing sleep to 236.693641ms 2026/09/30 03:56:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/30 03:56:52 DEBUG : zoho root 'rclone-test-qikirom9keno': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/30 03:56:52 DEBUG : pacer: Reducing sleep to 88.376195ms 2026/09/30 03:56:52 DEBUG : pacer: Reducing sleep to 80.770812ms 2026/09/30 03:56:52 DEBUG : pacer: Reducing sleep to 173.61258ms 2026/09/30 03:56:52 DEBUG : pacer: Reducing sleep to 97.78898ms 2026/09/30 03:56:52 DEBUG : pacer: Reducing sleep to 185.849131ms 2026/09/30 03:56:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:56:54 DEBUG : pacer: Reducing sleep to 74.427653ms 2026/09/30 03:56:54 DEBUG : pacer: Reducing sleep to 134.896504ms 2026/09/30 03:56:54 DEBUG : pacer: Reducing sleep to 224.28495ms 2026/09/30 03:56:54 DEBUG : pacer: Reducing sleep to 248.172552ms 2026/09/30 03:56:54 DEBUG : pacer: Reducing sleep to 201.177818ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/30 03:56:55 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:56:55 DEBUG : pacer: Reducing sleep to 65.872372ms 2026/09/30 03:56:55 DEBUG : pacer: Reducing sleep to 131.95951ms 2026/09/30 03:56:55 DEBUG : pacer: Reducing sleep to 139.768477ms 2026/09/30 03:56:55 DEBUG : pacer: Reducing sleep to 228.979959ms 2026/09/30 03:56:56 DEBUG : pacer: Reducing sleep to 232.412545ms 2026/09/30 03:56:56 DEBUG : pacer: Reducing sleep to 190.165253ms 2026/09/30 03:56:56 DEBUG : pacer: Reducing sleep to 115.849753ms 2026/09/30 03:56:56 DEBUG : pacer: Reducing sleep to 103.332116ms 2026/09/30 03:56:56 DEBUG : pacer: Reducing sleep to 143.224193ms 2026/09/30 03:57:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/30 03:57:05 DEBUG : pacer: Reducing sleep to 67.009518ms 2026/09/30 03:57:05 DEBUG : pacer: Reducing sleep to 161.722146ms 2026/09/30 03:57:05 DEBUG : pacer: Reducing sleep to 164.610273ms 2026/09/30 03:57:05 DEBUG : pacer: Reducing sleep to 252.067794ms 2026/09/30 03:57:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:57:09 DEBUG : pacer: Reducing sleep to 86.197772ms 2026/09/30 03:57:10 DEBUG : pacer: Reducing sleep to 37.883727ms 2026/09/30 03:57:10 DEBUG : pacer: Reducing sleep to 122.652193ms 2026/09/30 03:57:10 DEBUG : pacer: Reducing sleep to 204.770875ms 2026/09/30 03:57:14 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/30 03:57:19 DEBUG : pacer: Reducing sleep to 70.111284ms 2026/09/30 03:57:19 DEBUG : pacer: Reducing sleep to 165.555393ms 2026/09/30 03:57:19 DEBUG : pacer: Reducing sleep to 100.081252ms 2026/09/30 03:57:19 DEBUG : pacer: Reducing sleep to 183.1141ms 2026/09/30 03:57:23 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:57:23 DEBUG : pacer: Reducing sleep to 72.72115ms 2026/09/30 03:57:23 DEBUG : pacer: Reducing sleep to 142.188568ms 2026/09/30 03:57:23 DEBUG : pacer: Reducing sleep to 233.452145ms 2026/09/30 03:57:24 DEBUG : pacer: Reducing sleep to 231.844214ms 2026/09/30 03:57:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/30 03:57:33 DEBUG : pacer: Reducing sleep to 69.553922ms 2026/09/30 03:57:33 DEBUG : pacer: Reducing sleep to 162.205037ms 2026/09/30 03:57:33 DEBUG : pacer: Reducing sleep to 177.875516ms 2026/09/30 03:57:33 DEBUG : pacer: Reducing sleep to 251.874239ms 2026/09/30 03:57:37 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:57:37 DEBUG : pacer: Reducing sleep to 83.958634ms 2026/09/30 03:57:37 DEBUG : pacer: Reducing sleep to 78.512497ms 2026/09/30 03:57:37 DEBUG : pacer: Reducing sleep to 167.633608ms 2026/09/30 03:57:37 DEBUG : pacer: Reducing sleep to 248.403995ms 2026/09/30 03:57:42 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/30 03:57:46 DEBUG : pacer: Reducing sleep to 65.661933ms 2026/09/30 03:57:46 DEBUG : pacer: Reducing sleep to 159.655784ms 2026/09/30 03:57:47 DEBUG : pacer: Reducing sleep to 133.385384ms 2026/09/30 03:57:47 DEBUG : pacer: Reducing sleep to 220.987547ms 2026/09/30 03:57:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:57:51 DEBUG : pacer: Reducing sleep to 82.012613ms 2026/09/30 03:57:51 DEBUG : pacer: Reducing sleep to 93.217399ms 2026/09/30 03:57:51 DEBUG : pacer: Reducing sleep to 182.642905ms 2026/09/30 03:57:51 DEBUG : pacer: Reducing sleep to 246.706843ms 2026/09/30 03:57:51 DEBUG : pacer: Reducing sleep to 243.147317ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/30 03:57:52 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:57:52 DEBUG : pacer: Reducing sleep to 64.026496ms 2026/09/30 03:57:52 DEBUG : zoho root 'rclone-test-qikirom9keno': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/30 03:57:52 DEBUG : pacer: Reducing sleep to 87.038533ms 2026/09/30 03:57:53 DEBUG : pacer: Reducing sleep to 33.749966ms 2026/09/30 03:57:53 DEBUG : pacer: Reducing sleep to 124.476329ms 2026/09/30 03:57:53 DEBUG : pacer: Reducing sleep to 213.141831ms 2026/09/30 03:57:53 DEBUG : pacer: Reducing sleep to 256.553981ms 2026/09/30 03:57:53 DEBUG : pacer: Reducing sleep to 197.330039ms 2026/09/30 03:57:53 DEBUG : pacer: Reducing sleep to 119.275056ms 2026/09/30 03:57:54 DEBUG : pacer: Reducing sleep to 70.499769ms 2026/09/30 03:57:54 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/30 03:57:55 DEBUG : pacer: Reducing sleep to 67.283705ms 2026/09/30 03:57:55 DEBUG : pacer: Reducing sleep to 158.834283ms 2026/09/30 03:57:55 DEBUG : pacer: Reducing sleep to 196.969942ms 2026/09/30 03:57:55 DEBUG : pacer: Reducing sleep to 250.855965ms 2026/09/30 03:57:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:57:59 DEBUG : pacer: Reducing sleep to 86.538125ms 2026/09/30 03:57:59 DEBUG : pacer: Reducing sleep to 71.793263ms 2026/09/30 03:58:00 DEBUG : pacer: Reducing sleep to 162.259874ms 2026/09/30 03:58:00 DEBUG : pacer: Reducing sleep to 100.713557ms 2026/09/30 03:58:04 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/30 03:58:09 DEBUG : pacer: Reducing sleep to 63.515151ms 2026/09/30 03:58:09 DEBUG : pacer: Reducing sleep to 158.525341ms 2026/09/30 03:58:09 DEBUG : pacer: Reducing sleep to 104.113602ms 2026/09/30 03:58:09 DEBUG : pacer: Reducing sleep to 192.079413ms 2026/09/30 03:58:13 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:58:13 DEBUG : pacer: Reducing sleep to 81.861096ms 2026/09/30 03:58:13 DEBUG : pacer: Reducing sleep to 114.047964ms 2026/09/30 03:58:13 DEBUG : pacer: Reducing sleep to 202.978394ms 2026/09/30 03:58:13 DEBUG : pacer: Reducing sleep to 246.671365ms 2026/09/30 03:58:18 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/30 03:58:36 DEBUG : pacer: Reducing sleep to 59.682145ms 2026/09/30 03:58:36 DEBUG : pacer: Reducing sleep to 154.844291ms 2026/09/30 03:58:36 DEBUG : pacer: Reducing sleep to 214.28593ms 2026/09/30 03:58:37 DEBUG : pacer: Reducing sleep to 253.188266ms 2026/09/30 03:58:37 DEBUG : pacer: Reducing sleep to 175.38056ms 2026/09/30 03:58:37 DEBUG : pacer: Reducing sleep to 122.629757ms 2026/09/30 03:58:37 DEBUG : pacer: Reducing sleep to 83.280555ms 2026/09/30 03:58:37 DEBUG : pacer: Reducing sleep to 229.603856ms 2026/09/30 03:58:37 DEBUG : pacer: Reducing sleep to 237.95235ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/30 03:58:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:58:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 03:58:46 UTC": parsing time "Wed, 30 Sep 2026 03:58:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 03:58:46 UTC" as " " 2026/09/30 03:58:46 DEBUG : pacer: Reducing sleep to 68.670152ms === 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/30 03:58:50 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:58:55 DEBUG : zoho root 'rclone-test-qikirom9keno': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/30 03:58:55 DEBUG : pacer: Reducing sleep to 93.645613ms 2026/09/30 03:58:55 DEBUG : pacer: Reducing sleep to 76.301343ms 2026/09/30 03:58:55 DEBUG : pacer: Reducing sleep to 161.211735ms 2026/09/30 03:58:55 DEBUG : pacer: Reducing sleep to 247.905182ms 2026/09/30 03:58:55 DEBUG : pacer: Reducing sleep to 104.926041ms 2026/09/30 03:58:55 DEBUG : pacer: Reducing sleep to 170.321642ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/30 03:58:56 DEBUG : pacer: Reducing sleep to 231.725958ms 2026/09/30 03:58:56 DEBUG : pacer: Reducing sleep to 219.501473ms 2026/09/30 03:58:56 DEBUG : pacer: Reducing sleep to 157.160465ms 2026/09/30 03:58:56 DEBUG : pacer: Reducing sleep to 101.388048ms 2026/09/30 03:58:56 DEBUG : pacer: Reducing sleep to 116.279364ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/30 03:58:56 DEBUG : pacer: Reducing sleep to 176.816532ms 2026/09/30 03:58:57 DEBUG : pacer: Reducing sleep to 229.91613ms 2026/09/30 03:58:57 DEBUG : pacer: Reducing sleep to 216.764415ms 2026/09/30 03:58:57 DEBUG : pacer: Reducing sleep to 155.34147ms 2026/09/30 03:58:57 DEBUG : pacer: Reducing sleep to 96.716898ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/30 03:58:57 DEBUG : pacer: Reducing sleep to 111.171169ms 2026/09/30 03:58:57 DEBUG : pacer: Reducing sleep to 184.422309ms 2026/09/30 03:58:58 DEBUG : pacer: Reducing sleep to 236.447438ms 2026/09/30 03:58:58 DEBUG : pacer: Reducing sleep to 217.141444ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/30 03:58:58 DEBUG : Creating backend with remote "TestZoho:" 2026/09/30 03:58:58 DEBUG : Config file has changed externally - reloading === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/09/30 03:59:02 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/30 03:59:07 DEBUG : pacer: Reducing sleep to 87.290354ms 2026/09/30 03:59:07 DEBUG : pacer: Reducing sleep to 173.719793ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/30 03:59:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:12 DEBUG : pacer: Reducing sleep to 66.071247ms 2026/09/30 03:59:12 DEBUG : pacer: Reducing sleep to 139.918853ms 2026/09/30 03:59:12 DEBUG : pacer: Reducing sleep to 225.662407ms 2026/09/30 03:59:12 DEBUG : pacer: Reducing sleep to 252.321409ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/30 03:59:16 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/30 03:59:21 DEBUG : pacer: Reducing sleep to 87.118601ms 2026/09/30 03:59:21 DEBUG : pacer: Reducing sleep to 174.58164ms 2026/09/30 03:59:21 DEBUG : pacer: Reducing sleep to 249.41132ms 2026/09/30 03:59:21 DEBUG : pacer: Reducing sleep to 245.808983ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/30 03:59:26 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/30 03:59:26 DEBUG : pacer: Reducing sleep to 87.703755ms 2026/09/30 03:59:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:30 DEBUG : pacer: Reducing sleep to 66.934746ms 2026/09/30 03:59:31 DEBUG : pacer: Reducing sleep to 88.146162ms 2026/09/30 03:59:31 DEBUG : pacer: Reducing sleep to 172.949202ms 2026/09/30 03:59:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:35 DEBUG : pacer: Reducing sleep to 89.155424ms 2026/09/30 03:59:35 DEBUG : pacer: Reducing sleep to 250.721581ms 2026/09/30 03:59:35 DEBUG : pacer: Reducing sleep to 331.662199ms 2026/09/30 03:59:35 DEBUG : pacer: Reducing sleep to 242.361524ms 2026/09/30 03:59:36 DEBUG : pacer: Reducing sleep to 79.07814ms 2026/09/30 03:59:36 DEBUG : pacer: Reducing sleep to 4.04981ms 2026/09/30 03:59:36 DEBUG : pacer: Reducing sleep to 83.8058ms 2026/09/30 03:59:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:40 DEBUG : pacer: Reducing sleep to 74.377636ms 2026/09/30 03:59:40 DEBUG : pacer: Reducing sleep to 163.901067ms 2026/09/30 03:59:40 DEBUG : pacer: Reducing sleep to 251.910018ms 2026/09/30 03:59:40 DEBUG : pacer: Reducing sleep to 253.155763ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/30 03:59:44 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:49 DEBUG : pacer: Reducing sleep to 67.019565ms 2026/09/30 03:59:49 DEBUG : pacer: Reducing sleep to 12.09073ms 2026/09/30 03:59:49 DEBUG : pacer: Reducing sleep to 100.811756ms 2026/09/30 03:59:53 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:53 DEBUG : pacer: Reducing sleep to 87.696602ms 2026/09/30 03:59:53 DEBUG : pacer: Reducing sleep to 249.321579ms 2026/09/30 03:59:54 DEBUG : pacer: Reducing sleep to 338.03564ms 2026/09/30 03:59:54 DEBUG : pacer: Reducing sleep to 404.29466ms 2026/09/30 03:59:54 DEBUG : pacer: Reducing sleep to 247.949425ms 2026/09/30 03:59:54 DEBUG : Creating backend with remote "TestZoho:rclone-test-qikirom9keno/dirToPurgeFromRoot" 2026/09/30 03:59:54 DEBUG : pacer: Reducing sleep to 115.676243ms 2026/09/30 03:59:54 DEBUG : pacer: Reducing sleep to 236.355992ms 2026/09/30 03:59:54 DEBUG : pacer: Reducing sleep to 274.488984ms 2026/09/30 03:59:55 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:55 DEBUG : pacer: Reducing sleep to 90.37139ms 2026/09/30 03:59:55 DEBUG : zoho root 'rclone-test-qikirom9keno': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/30 03:59:55 DEBUG : pacer: Reducing sleep to 82.78972ms 2026/09/30 03:59:55 DEBUG : pacer: Reducing sleep to 157.711944ms 2026/09/30 03:59:55 DEBUG : pacer: Reducing sleep to 243.35094ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/30 03:59:55 DEBUG : Creating backend with remote "TestZoho:rclone-test-qikirom9keno/hello? sausage/êé" 2026/09/30 03:59:55 DEBUG : pacer: Reducing sleep to 116.537579ms 2026/09/30 03:59:55 DEBUG : pacer: Reducing sleep to 234.052282ms 2026/09/30 03:59:55 DEBUG : pacer: Reducing sleep to 281.726336ms 2026/09/30 03:59:56 DEBUG : pacer: Reducing sleep to 204.483525ms 2026/09/30 03:59:56 DEBUG : pacer: Reducing sleep to 77.270326ms 2026/09/30 03:59:56 DEBUG : pacer: Reducing sleep to 67.92748ms 2026/09/30 03:59:56 DEBUG : pacer: Reducing sleep to 124.044904ms 2026/09/30 03:59:56 DEBUG : pacer: Reducing sleep to 246.850246ms 2026/09/30 03:59:56 DEBUG : pacer: Reducing sleep to 259.327594ms 2026/09/30 03:59:57 DEBUG : pacer: Reducing sleep to 208.344838ms 2026/09/30 03:59:57 DEBUG : pacer: Reducing sleep to 86.283425ms 2026/09/30 03:59:57 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:57 DEBUG : pacer: Reducing sleep to 116.074775ms 2026/09/30 03:59:57 DEBUG : pacer: Reducing sleep to 235.260203ms 2026/09/30 03:59:57 DEBUG : pacer: Reducing sleep to 272.406803ms 2026/09/30 03:59:58 DEBUG : pacer: Reducing sleep to 214.228582ms 2026/09/30 03:59:58 DEBUG : pacer: Reducing sleep to 105.080678ms 2026/09/30 03:59:58 DEBUG : pacer: Reducing sleep to 59.020886ms 2026/09/30 03:59:58 DEBUG : pacer: Reducing sleep to 118.122885ms 2026/09/30 03:59:58 DEBUG : pacer: Reducing sleep to 224.497568ms 2026/09/30 03:59:58 DEBUG : pacer: Reducing sleep to 274.171267ms 2026/09/30 03:59:59 DEBUG : pacer: Reducing sleep to 208.122467ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/30 03:59:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 03:59:59 DEBUG : pacer: Reducing sleep to 66.750497ms 2026/09/30 03:59:59 DEBUG : pacer: Reducing sleep to 154.88748ms 2026/09/30 03:59:59 DEBUG : pacer: Reducing sleep to 241.839106ms 2026/09/30 04:00:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:02 DEBUG : pacer: Reducing sleep to 85.311039ms 2026/09/30 04:00:02 DEBUG : pacer: Reducing sleep to 87.554737ms 2026/09/30 04:00:07 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/30 04:00:11 DEBUG : pacer: Reducing sleep to 67.417347ms 2026/09/30 04:00:12 DEBUG : pacer: Reducing sleep to 122.960346ms 2026/09/30 04:00:12 DEBUG : pacer: Reducing sleep to 173.486034ms 2026/09/30 04:00:12 DEBUG : pacer: Reducing sleep to 251.416838ms 2026/09/30 04:00:12 DEBUG : pacer: Reducing sleep to 244.043231ms 2026/09/30 04:00:12 DEBUG : pacer: Reducing sleep to 160.395456ms 2026/09/30 04:00:12 DEBUG : pacer: Reducing sleep to 79.326501ms 2026/09/30 04:00:16 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:26 DEBUG : pacer: Reducing sleep to 54.661275ms 2026/09/30 04:00:26 DEBUG : pacer: Reducing sleep to 130.196507ms 2026/09/30 04:00:26 DEBUG : pacer: Reducing sleep to 179.447539ms 2026/09/30 04:00:26 DEBUG : pacer: Reducing sleep to 239.559994ms 2026/09/30 04:00:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:30 DEBUG : pacer: Reducing sleep to 72.364396ms 2026/09/30 04:00:30 DEBUG : pacer: Reducing sleep to 202.088989ms 2026/09/30 04:00:30 DEBUG : pacer: Reducing sleep to 274.974319ms 2026/09/30 04:00:31 DEBUG : pacer: Reducing sleep to 361.421081ms 2026/09/30 04:00:31 DEBUG : pacer: Reducing sleep to 258.577927ms 2026/09/30 04:00:31 DEBUG : pacer: Reducing sleep to 51.614941ms 2026/09/30 04:00:31 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:32 DEBUG : pacer: Reducing sleep to 26.511953ms 2026/09/30 04:00:32 DEBUG : pacer: Reducing sleep to 102.785679ms 2026/09/30 04:00:35 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:35 DEBUG : pacer: Reducing sleep to 89.950615ms 2026/09/30 04:00:35 DEBUG : pacer: Reducing sleep to 230.377646ms 2026/09/30 04:00:35 DEBUG : pacer: Reducing sleep to 296.335136ms 2026/09/30 04:00:35 DEBUG : pacer: Reducing sleep to 236.442027ms 2026/09/30 04:00:36 DEBUG : pacer: Reducing sleep to 106.266545ms 2026/09/30 04:00:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:40 DEBUG : pacer: Reducing sleep to 71.156516ms 2026/09/30 04:00:40 DEBUG : pacer: Reducing sleep to 104.635211ms 2026/09/30 04:00:40 DEBUG : pacer: Reducing sleep to 167.571427ms 2026/09/30 04:00:44 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:44 DEBUG : pacer: Reducing sleep to 93.10983ms 2026/09/30 04:00:44 DEBUG : pacer: Reducing sleep to 252.17705ms 2026/09/30 04:00:44 DEBUG : pacer: Reducing sleep to 335.183115ms 2026/09/30 04:00:44 DEBUG : pacer: Reducing sleep to 244.172711ms 2026/09/30 04:00:45 DEBUG : pacer: Reducing sleep to 54.809852ms 2026/09/30 04:00:45 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:45 DEBUG : pacer: Reducing sleep to 74.556994ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/30 04:00:45 DEBUG : Creating backend with remote "TestZoho:rclone-test-ticutew4wevi" 2026/09/30 04:00:46 DEBUG : pacer: Reducing sleep to 26.915513ms 2026/09/30 04:00:46 DEBUG : pacer: Reducing sleep to 41.359322ms 2026/09/30 04:00:46 DEBUG : pacer: Reducing sleep to 62.770104ms 2026/09/30 04:00:46 DEBUG : pacer: Reducing sleep to 185.663162ms 2026/09/30 04:00:46 DEBUG : pacer: Reducing sleep to 255.515095ms 2026/09/30 04:00:46 DEBUG : pacer: Reducing sleep to 272.643884ms 2026/09/30 04:00:46 DEBUG : pacer: Reducing sleep to 138.400811ms 2026/09/30 04:00:47 DEBUG : pacer: Reducing sleep to 49.969076ms 2026/09/30 04:00:47 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:47 DEBUG : pacer: Reducing sleep to 3.342053ms 2026/09/30 04:00:47 DEBUG : pacer: Reducing sleep to 119.188116ms 2026/09/30 04:00:49 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:49 DEBUG : pacer: Reducing sleep to 119.475394ms 2026/09/30 04:00:49 DEBUG : pacer: Reducing sleep to 216.99975ms 2026/09/30 04:00:49 DEBUG : pacer: Reducing sleep to 268.447917ms 2026/09/30 04:00:49 DEBUG : pacer: Reducing sleep to 203.967509ms 2026/09/30 04:00:51 DEBUG : pacer: Reducing sleep to 9.511499ms 2026/09/30 04:00:51 DEBUG : pacer: Reducing sleep to 92.200727ms 2026/09/30 04:00:51 DEBUG : pacer: Reducing sleep to 180.946726ms 2026/09/30 04:00:51 DEBUG : pacer: Reducing sleep to 249.283901ms 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 234.1641ms 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 146.472718ms 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 123.630928ms 2026/09/30 04:00:52 DEBUG : zoho root 'rclone-test-ticutew4wevi': Purge remote 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 125.631339ms 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 142.683764ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 78.276481ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/30 04:00:52 DEBUG : pacer: Reducing sleep to 166.787757ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/30 04:00:53 DEBUG : pacer: Reducing sleep to 239.482634ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/30 04:00:53 DEBUG : pacer: Reducing sleep to 249.230951ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/30 04:00:54 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/30 04:00:59 DEBUG : zoho root 'rclone-test-qikirom9keno': folder listing limiter sweep: evicted 6 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/30 04:01:22 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:22 UTC": parsing time "Wed, 30 Sep 2026 04:01:22 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:22 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/30 04:01:26 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:26 UTC": parsing time "Wed, 30 Sep 2026 04:01:26 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:26 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/30 04:01:31 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:31 UTC": parsing time "Wed, 30 Sep 2026 04:01:31 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:31 UTC" as " " 2026/09/30 04:01:31 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:31 UTC": parsing time "Wed, 30 Sep 2026 04:01:31 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:31 UTC" as " " 2026/09/30 04:01:31 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:31 UTC": parsing time "Wed, 30 Sep 2026 04:01:31 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:31 UTC" as " " 2026/09/30 04:01:32 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:32 UTC": parsing time "Wed, 30 Sep 2026 04:01:32 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:32 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/30 04:01:36 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:36 UTC": parsing time "Wed, 30 Sep 2026 04:01:36 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:36 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/30 04:01:40 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:40 UTC": parsing time "Wed, 30 Sep 2026 04:01:40 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:40 UTC" as " " 2026/09/30 04:01:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:45 UTC": parsing time "Wed, 30 Sep 2026 04:01:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:45 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/30 04:01:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:45 UTC": parsing time "Wed, 30 Sep 2026 04:01:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:45 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/30 04:01:45 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:45 UTC": parsing time "Wed, 30 Sep 2026 04:01:45 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:45 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/30 04:01:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:46 UTC": parsing time "Wed, 30 Sep 2026 04:01:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:46 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/30 04:01:46 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:46 UTC": parsing time "Wed, 30 Sep 2026 04:01:46 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:46 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/30 04:01:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:49 UTC": parsing time "Wed, 30 Sep 2026 04:01:49 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:49 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/30 04:01:54 DEBUG : pacer: Reducing sleep to 84.160937ms 2026/09/30 04:01:55 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Wed, 30 Sep 2026 04:01:54 UTC": parsing time "Wed, 30 Sep 2026 04:01:54 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 30 Sep 2026 04:01:54 UTC" as " " 2026/09/30 04:01:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/30 04:01:55 DEBUG : pacer: Reducing sleep to 84.893401ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/30 04:01:55 DEBUG : Creating backend with remote "TestZoho:rclone-test-qikirom9keno/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/30 04:01:55 DEBUG : pacer: Reducing sleep to 116.070693ms 2026/09/30 04:01:55 DEBUG : pacer: Reducing sleep to 217.172351ms 2026/09/30 04:01:55 DEBUG : pacer: Reducing sleep to 282.532914ms 2026/09/30 04:01:55 DEBUG : pacer: Reducing sleep to 228.917259ms 2026/09/30 04:01:56 DEBUG : pacer: Reducing sleep to 93.826332ms 2026/09/30 04:01:56 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:01:56 DEBUG : pacer: Reducing sleep to 116.88897ms 2026/09/30 04:01:56 DEBUG : pacer: Reducing sleep to 226.257613ms 2026/09/30 04:01:56 DEBUG : pacer: Reducing sleep to 284.442062ms 2026/09/30 04:01:56 DEBUG : pacer: Reducing sleep to 218.434019ms 2026/09/30 04:01:57 DEBUG : pacer: Reducing sleep to 102.503826ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/30 04:01:57 DEBUG : pacer: Reducing sleep to 46.20859ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/30 04:01:57 DEBUG : Creating backend with remote "TestZoho:rclone-test-qikirom9keno/not found.txt" 2026/09/30 04:01:57 DEBUG : pacer: Reducing sleep to 111.630275ms 2026/09/30 04:01:57 DEBUG : pacer: Reducing sleep to 147.590004ms 2026/09/30 04:02:02 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:02:02 DEBUG : zoho root 'rclone-test-qikirom9keno/not found.txt': folder listing limiter sweep: evicted 3 of 9 idle limiters 2026/09/30 04:02:06 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-qikirom9keno" from "TestZoho:rclone-test-qikirom9keno" 2026/09/30 04:02:06 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/30 04:02:11 DEBUG : pacer: Reducing sleep to 116.10515ms 2026/09/30 04:02:11 DEBUG : pacer: Reducing sleep to 234.843323ms 2026/09/30 04:02:11 DEBUG : pacer: Reducing sleep to 283.269482ms 2026/09/30 04:02:11 DEBUG : pacer: Reducing sleep to 210.439239ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/30 04:02:16 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/30 04:02:29 DEBUG : pacer: Reducing sleep to 118.78327ms 2026/09/30 04:02:29 DEBUG : pacer: Reducing sleep to 235.724409ms 2026/09/30 04:02:30 DEBUG : pacer: Reducing sleep to 163.518704ms 2026/09/30 04:02:30 DEBUG : pacer: Reducing sleep to 139.021343ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/30 04:02:30 DEBUG : pacer: Reducing sleep to 172.292601ms 2026/09/30 04:02:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:02:34 DEBUG : pacer: Reducing sleep to 115.446718ms 2026/09/30 04:02:34 DEBUG : pacer: Reducing sleep to 158.899056ms 2026/09/30 04:02:34 DEBUG : pacer: Reducing sleep to 209.419345ms 2026/09/30 04:02:34 DEBUG : pacer: Reducing sleep to 213.816274ms === 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/30 04:02:39 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:02:43 DEBUG : pacer: Reducing sleep to 113.662578ms 2026/09/30 04:02:43 DEBUG : pacer: Reducing sleep to 228.871138ms 2026/09/30 04:02:43 DEBUG : pacer: Reducing sleep to 278.754426ms 2026/09/30 04:02:44 DEBUG : pacer: Reducing sleep to 212.244203ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/30 04:02:44 DEBUG : pacer: Reducing sleep to 101.953355ms === 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/30 04:02:48 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:02:48 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/30 04:02:48 DEBUG : pacer: Rate limited, increasing sleep to 1.015170415s 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/30 04:02:52 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:02:53 DEBUG : pacer: Reducing sleep to 116.68535ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/30 04:02:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/30 04:02:54 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=ij0uu16aa3bd304d54f279866f9dd279fd376": write tcp: unexpected EOF) 2026/09/30 04:02:54 DEBUG : pacer: Rate limited, increasing sleep to 1.389835734s 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=ij0uu16aa3bd304d54f279866f9dd279fd376": write tcp: unexpected EOF 2026/09/30 04:02:55 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/30 04:02:55 DEBUG : pacer: Reducing sleep to 136.601226ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n