"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/07 01:31:23 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/07 01:31:23 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/07 01:31:23 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/09/07 01:31:23 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/07 01:31:23 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/07 01:31:23 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/07 01:31:23 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/07 01:31:23 DEBUG : Creating backend with remote "TestZoho:rclone-test-doficis7bevo" 2026/09/07 01:31:23 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/09/07 01:31:23 DEBUG : zoho root 'rclone-test-doficis7bevo': folder listing limiter sweep: evicted 0 of 0 idle limiters === 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/07 01:31:24 DEBUG : pacer: Reducing sleep to 33.660296ms 2026/09/07 01:31:24 DEBUG : pacer: Reducing sleep to 47.451474ms 2026/09/07 01:31:24 DEBUG : pacer: Reducing sleep to 43.386818ms 2026/09/07 01:31:24 DEBUG : pacer: Reducing sleep to 159.228396ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/09/07 01:31:24 DEBUG : pacer: Reducing sleep to 277.886023ms 2026/09/07 01:31:25 DEBUG : pacer: Reducing sleep to 258.745832ms === RUN TestIntegration/FsMkdir 2026/09/07 01:31:25 DEBUG : pacer: Reducing sleep to 96.595662ms 2026/09/07 01:31:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:31:25 DEBUG : pacer: Reducing sleep to 125.553847ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/09/07 01:31:25 INFO : dir/subdir: Making directory 2026/09/07 01:31:25 DEBUG : pacer: Reducing sleep to 242.383903ms 2026/09/07 01:31:25 DEBUG : pacer: Reducing sleep to 260.371976ms 2026/09/07 01:31:26 DEBUG : pacer: Reducing sleep to 205.201743ms 2026/09/07 01:31:26 DEBUG : pacer: Reducing sleep to 89.395609ms 2026/09/07 01:31:26 DEBUG : pacer: Reducing sleep to 52.957467ms 2026/09/07 01:31:26 DEBUG : pacer: Reducing sleep to 151.062085ms 2026/09/07 01:31:26 DEBUG : pacer: Reducing sleep to 262.183943ms 2026/09/07 01:31:26 INFO : dir/subdir: Removing directory 2026/09/07 01:31:26 DEBUG : pacer: Reducing sleep to 278.260797ms 2026/09/07 01:31:27 DEBUG : pacer: Reducing sleep to 158.113937ms 2026/09/07 01:31:27 DEBUG : pacer: Reducing sleep to 70.499113ms 2026/09/07 01:31:27 DEBUG : pacer: Reducing sleep to 64.097784ms 2026/09/07 01:31:27 INFO : dir: Removing directory 2026/09/07 01:31:27 DEBUG : pacer: Reducing sleep to 174.529569ms 2026/09/07 01:31:27 DEBUG : pacer: Reducing sleep to 243.707799ms 2026/09/07 01:31:27 DEBUG : pacer: Reducing sleep to 270.929278ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/09/07 01:31:28 DEBUG : pacer: Reducing sleep to 178.669072ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/09/07 01:31:32 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/09/07 01:31:37 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/07 01:31:46 DEBUG : pacer: Reducing sleep to 84.585542ms 2026/09/07 01:31:46 DEBUG : pacer: Reducing sleep to 208.274488ms 2026/09/07 01:31:46 DEBUG : pacer: Reducing sleep to 142.576779ms 2026/09/07 01:31:46 DEBUG : pacer: Reducing sleep to 238.429696ms 2026/09/07 01:31:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:31:51 DEBUG : pacer: Reducing sleep to 114.088469ms 2026/09/07 01:31:51 DEBUG : pacer: Reducing sleep to 70.795755ms 2026/09/07 01:31:51 DEBUG : pacer: Reducing sleep to 161.291276ms 2026/09/07 01:31:51 DEBUG : pacer: Reducing sleep to 229.678263ms 2026/09/07 01:31:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/09/07 01:32:00 DEBUG : pacer: Reducing sleep to 124.125451ms 2026/09/07 01:32:00 DEBUG : pacer: Reducing sleep to 108.502593ms 2026/09/07 01:32:00 DEBUG : pacer: Reducing sleep to 216.259025ms 2026/09/07 01:32:04 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:32:04 DEBUG : pacer: Reducing sleep to 90.850701ms 2026/09/07 01:32:05 DEBUG : pacer: Reducing sleep to 105.945334ms 2026/09/07 01:32:05 DEBUG : pacer: Reducing sleep to 190.13578ms 2026/09/07 01:32:05 DEBUG : pacer: Reducing sleep to 247.802675ms 2026/09/07 01:32:09 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/09/07 01:32:14 DEBUG : pacer: Reducing sleep to 73.372591ms 2026/09/07 01:32:14 DEBUG : pacer: Reducing sleep to 196.940118ms 2026/09/07 01:32:14 DEBUG : pacer: Reducing sleep to 181.261153ms 2026/09/07 01:32:14 DEBUG : pacer: Reducing sleep to 244.720265ms 2026/09/07 01:32:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:32:18 DEBUG : pacer: Reducing sleep to 101.76538ms 2026/09/07 01:32:18 DEBUG : pacer: Reducing sleep to 74.525898ms 2026/09/07 01:32:19 DEBUG : pacer: Reducing sleep to 146.912557ms 2026/09/07 01:32:19 DEBUG : pacer: Reducing sleep to 234.294901ms 2026/09/07 01:32:23 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/09/07 01:32:28 DEBUG : pacer: Reducing sleep to 83.624869ms 2026/09/07 01:32:28 DEBUG : zoho root 'rclone-test-doficis7bevo': folder listing limiter sweep: evicted 4 of 8 idle limiters 2026/09/07 01:32:28 DEBUG : pacer: Reducing sleep to 206.773326ms 2026/09/07 01:32:28 DEBUG : pacer: Reducing sleep to 137.698732ms 2026/09/07 01:32:28 DEBUG : pacer: Reducing sleep to 236.055518ms 2026/09/07 01:32:28 DEBUG : pacer: Reducing sleep to 272.394022ms 2026/09/07 01:32:28 DEBUG : pacer: Reducing sleep to 194.457136ms 2026/09/07 01:32:29 DEBUG : pacer: Reducing sleep to 84.605077ms 2026/09/07 01:32:29 DEBUG : pacer: Reducing sleep to 61.162335ms 2026/09/07 01:32:29 DEBUG : pacer: Reducing sleep to 136.710814ms 2026/09/07 01:32:29 DEBUG : pacer: Reducing sleep to 252.562273ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/09/07 01:32:29 DEBUG : pacer: Reducing sleep to 286.3126ms 2026/09/07 01:32:29 DEBUG : pacer: Reducing sleep to 149.919268ms 2026/09/07 01:32:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:32:30 DEBUG : pacer: Reducing sleep to 15.451185ms 2026/09/07 01:32:30 DEBUG : pacer: Reducing sleep to 40.490916ms 2026/09/07 01:32:30 DEBUG : pacer: Reducing sleep to 155.430913ms 2026/09/07 01:32:30 DEBUG : pacer: Reducing sleep to 212.433736ms 2026/09/07 01:32:30 DEBUG : pacer: Reducing sleep to 273.229309ms 2026/09/07 01:32:30 DEBUG : pacer: Reducing sleep to 232.1892ms 2026/09/07 01:32:31 DEBUG : pacer: Reducing sleep to 122.329227ms 2026/09/07 01:32:31 DEBUG : pacer: Reducing sleep to 59.714591ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/09/07 01:32:35 DEBUG : TestZoho: Token expired 2026/09/07 01:32:35 DEBUG : Config file has changed externally - reloading 2026/09/07 01:32:35 DEBUG : TestZoho: No updated token found in the config file 2026/09/07 01:32:35 DEBUG : TestZoho: Token refresh successful 2026/09/07 01:32:35 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/09/07 01:32:35 DEBUG : TestZoho: Saved new token in config file 2026/09/07 01:32:36 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:32:36 DEBUG : pacer: Reducing sleep to 19.896919ms 2026/09/07 01:32:36 DEBUG : pacer: Reducing sleep to 143.414012ms 2026/09/07 01:32:36 DEBUG : pacer: Reducing sleep to 134.97552ms 2026/09/07 01:32:36 DEBUG : pacer: Reducing sleep to 242.122334ms 2026/09/07 01:32:40 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:32:40 DEBUG : pacer: Reducing sleep to 103.288658ms 2026/09/07 01:32:40 DEBUG : pacer: Reducing sleep to 80.427012ms 2026/09/07 01:32:40 DEBUG : pacer: Reducing sleep to 150.443176ms 2026/09/07 01:32:40 DEBUG : pacer: Reducing sleep to 230.682454ms 2026/09/07 01:32:45 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/09/07 01:32:49 DEBUG : pacer: Reducing sleep to 87.725983ms 2026/09/07 01:32:49 DEBUG : pacer: Reducing sleep to 211.536149ms 2026/09/07 01:32:50 DEBUG : pacer: Reducing sleep to 193.187274ms 2026/09/07 01:32:50 DEBUG : pacer: Reducing sleep to 227.623697ms 2026/09/07 01:32:54 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:32:54 DEBUG : pacer: Reducing sleep to 113.090375ms 2026/09/07 01:32:54 DEBUG : pacer: Reducing sleep to 95.648796ms 2026/09/07 01:32:54 DEBUG : pacer: Reducing sleep to 167.967307ms 2026/09/07 01:32:54 DEBUG : pacer: Reducing sleep to 216.928074ms 2026/09/07 01:32:58 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/09/07 01:33:03 DEBUG : pacer: Reducing sleep to 95.040124ms 2026/09/07 01:33:03 DEBUG : pacer: Reducing sleep to 205.168887ms 2026/09/07 01:33:03 DEBUG : pacer: Reducing sleep to 180.571586ms 2026/09/07 01:33:04 DEBUG : pacer: Reducing sleep to 243.787149ms 2026/09/07 01:33:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:33:08 DEBUG : pacer: Reducing sleep to 99.148712ms 2026/09/07 01:33:08 DEBUG : pacer: Reducing sleep to 77.246098ms 2026/09/07 01:33:08 DEBUG : pacer: Reducing sleep to 150.243181ms 2026/09/07 01:33:08 DEBUG : pacer: Reducing sleep to 236.273273ms 2026/09/07 01:33:12 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/09/07 01:33:17 DEBUG : pacer: Reducing sleep to 80.530627ms 2026/09/07 01:33:17 DEBUG : pacer: Reducing sleep to 204.871005ms 2026/09/07 01:33:17 DEBUG : pacer: Reducing sleep to 208.866732ms 2026/09/07 01:33:17 DEBUG : pacer: Reducing sleep to 236.495823ms 2026/09/07 01:33:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:33:22 DEBUG : pacer: Reducing sleep to 91.766102ms 2026/09/07 01:33:22 DEBUG : pacer: Reducing sleep to 91.681255ms 2026/09/07 01:33:22 DEBUG : pacer: Reducing sleep to 169.457701ms 2026/09/07 01:33:22 DEBUG : pacer: Reducing sleep to 241.813404ms 2026/09/07 01:33:26 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/09/07 01:33:26 DEBUG : pacer: Reducing sleep to 122.436752ms 2026/09/07 01:33:26 DEBUG : pacer: Reducing sleep to 56.452947ms 2026/09/07 01:33:27 DEBUG : pacer: Reducing sleep to 138.708812ms 2026/09/07 01:33:27 DEBUG : pacer: Reducing sleep to 127.900348ms 2026/09/07 01:33:27 DEBUG : pacer: Reducing sleep to 240.666231ms 2026/09/07 01:33:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:33:28 DEBUG : zoho root 'rclone-test-doficis7bevo': folder listing limiter sweep: evicted 3 of 11 idle limiters 2026/09/07 01:33:28 DEBUG : pacer: Reducing sleep to 112.251201ms 2026/09/07 01:33:28 DEBUG : pacer: Reducing sleep to 84.785415ms 2026/09/07 01:33:28 DEBUG : pacer: Reducing sleep to 145.666732ms 2026/09/07 01:33:28 DEBUG : pacer: Reducing sleep to 222.362027ms 2026/09/07 01:33:29 DEBUG : pacer: Reducing sleep to 248.937008ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/09/07 01:33:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:33:29 DEBUG : pacer: Reducing sleep to 89.912742ms 2026/09/07 01:33:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:33:30 DEBUG : pacer: Reducing sleep to 27.100082ms 2026/09/07 01:33:30 DEBUG : pacer: Reducing sleep to 111.61887ms 2026/09/07 01:33:30 DEBUG : pacer: Reducing sleep to 223.079579ms 2026/09/07 01:33:30 DEBUG : pacer: Reducing sleep to 240.283274ms 2026/09/07 01:33:30 DEBUG : pacer: Reducing sleep to 219.673242ms 2026/09/07 01:33:31 DEBUG : pacer: Reducing sleep to 143.259656ms 2026/09/07 01:33:31 DEBUG : pacer: Reducing sleep to 91.271277ms 2026/09/07 01:33:35 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/09/07 01:33:39 DEBUG : pacer: Reducing sleep to 87.187818ms 2026/09/07 01:33:39 DEBUG : pacer: Reducing sleep to 210.446693ms 2026/09/07 01:33:40 DEBUG : pacer: Reducing sleep to 114.311288ms 2026/09/07 01:33:40 DEBUG : pacer: Reducing sleep to 226.83314ms 2026/09/07 01:33:44 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:33:44 DEBUG : pacer: Reducing sleep to 99.761029ms 2026/09/07 01:33:44 DEBUG : pacer: Reducing sleep to 71.458122ms 2026/09/07 01:33:44 DEBUG : pacer: Reducing sleep to 165.926948ms 2026/09/07 01:33:44 DEBUG : pacer: Reducing sleep to 257.316658ms 2026/09/07 01:33:49 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/09/07 01:33:53 DEBUG : pacer: Reducing sleep to 98.357208ms 2026/09/07 01:33:53 DEBUG : pacer: Reducing sleep to 223.406955ms 2026/09/07 01:33:53 DEBUG : pacer: Reducing sleep to 215.313067ms 2026/09/07 01:33:54 DEBUG : pacer: Reducing sleep to 219.494605ms 2026/09/07 01:33:58 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:33:58 DEBUG : pacer: Reducing sleep to 114.124608ms 2026/09/07 01:33:58 DEBUG : pacer: Reducing sleep to 99.7601ms 2026/09/07 01:33:58 DEBUG : pacer: Reducing sleep to 166.54265ms 2026/09/07 01:33:58 DEBUG : pacer: Reducing sleep to 222.966612ms 2026/09/07 01:34:02 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/09/07 01:34:07 DEBUG : pacer: Reducing sleep to 98.570952ms 2026/09/07 01:34:07 DEBUG : pacer: Reducing sleep to 223.145882ms 2026/09/07 01:34:07 DEBUG : pacer: Reducing sleep to 147.610783ms 2026/09/07 01:34:07 DEBUG : pacer: Reducing sleep to 212.553629ms 2026/09/07 01:34:12 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:34:12 DEBUG : pacer: Reducing sleep to 115.59195ms 2026/09/07 01:34:12 DEBUG : pacer: Reducing sleep to 175.441708ms 2026/09/07 01:34:12 DEBUG : pacer: Reducing sleep to 237.929579ms 2026/09/07 01:34:12 DEBUG : pacer: Reducing sleep to 210.998283ms 2026/09/07 01:34:16 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/09/07 01:34:21 DEBUG : pacer: Reducing sleep to 88.568024ms 2026/09/07 01:34:21 DEBUG : pacer: Reducing sleep to 208.751254ms 2026/09/07 01:34:21 DEBUG : pacer: Reducing sleep to 219.714317ms 2026/09/07 01:34:21 DEBUG : pacer: Reducing sleep to 241.908549ms 2026/09/07 01:34:25 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:34:26 DEBUG : pacer: Reducing sleep to 104.671842ms 2026/09/07 01:34:26 DEBUG : pacer: Reducing sleep to 80.47568ms 2026/09/07 01:34:26 DEBUG : pacer: Reducing sleep to 142.604744ms 2026/09/07 01:34:26 DEBUG : pacer: Reducing sleep to 228.031267ms 2026/09/07 01:34:26 DEBUG : pacer: Reducing sleep to 257.372372ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/09/07 01:34:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:34:27 DEBUG : pacer: Reducing sleep to 88.270142ms 2026/09/07 01:34:27 DEBUG : pacer: Reducing sleep to 76.088957ms 2026/09/07 01:34:27 DEBUG : pacer: Reducing sleep to 77.571741ms 2026/09/07 01:34:27 DEBUG : pacer: Reducing sleep to 184.62035ms 2026/09/07 01:34:27 DEBUG : pacer: Reducing sleep to 285.951203ms 2026/09/07 01:34:27 DEBUG : pacer: Reducing sleep to 259.854254ms 2026/09/07 01:34:28 DEBUG : pacer: Reducing sleep to 136.597221ms 2026/09/07 01:34:28 DEBUG : pacer: Reducing sleep to 47.874524ms 2026/09/07 01:34:28 DEBUG : pacer: Reducing sleep to 74.20214ms 2026/09/07 01:34:28 DEBUG : zoho root 'rclone-test-doficis7bevo': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/09/07 01:34:28 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/09/07 01:34:29 DEBUG : pacer: Reducing sleep to 85.757627ms 2026/09/07 01:34:29 DEBUG : pacer: Reducing sleep to 211.061946ms 2026/09/07 01:34:29 DEBUG : pacer: Reducing sleep to 169.1957ms 2026/09/07 01:34:29 DEBUG : pacer: Reducing sleep to 232.436693ms 2026/09/07 01:34:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:34:34 DEBUG : pacer: Reducing sleep to 95.663331ms 2026/09/07 01:34:34 DEBUG : pacer: Reducing sleep to 91.315004ms 2026/09/07 01:34:34 DEBUG : pacer: Reducing sleep to 165.930986ms 2026/09/07 01:34:34 DEBUG : pacer: Reducing sleep to 239.332023ms 2026/09/07 01:34:38 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/07 01:34:43 DEBUG : pacer: Reducing sleep to 73.215165ms 2026/09/07 01:34:43 DEBUG : pacer: Reducing sleep to 197.283259ms 2026/09/07 01:34:43 DEBUG : pacer: Reducing sleep to 226.887102ms 2026/09/07 01:34:43 DEBUG : pacer: Reducing sleep to 236.443902ms 2026/09/07 01:34:47 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:34:47 DEBUG : pacer: Reducing sleep to 96.207199ms 2026/09/07 01:34:48 DEBUG : pacer: Reducing sleep to 64.356565ms 2026/09/07 01:34:48 DEBUG : pacer: Reducing sleep to 158.567615ms 2026/09/07 01:34:48 DEBUG : pacer: Reducing sleep to 258.654509ms 2026/09/07 01:34:52 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/09/07 01:35:11 DEBUG : pacer: Reducing sleep to 100.632053ms 2026/09/07 01:35:11 DEBUG : pacer: Reducing sleep to 215.805193ms 2026/09/07 01:35:11 DEBUG : pacer: Reducing sleep to 261.085645ms 2026/09/07 01:35:11 DEBUG : pacer: Reducing sleep to 235.085298ms 2026/09/07 01:35:11 DEBUG : pacer: Reducing sleep to 116.425959ms 2026/09/07 01:35:11 DEBUG : pacer: Reducing sleep to 68.215397ms 2026/09/07 01:35:12 DEBUG : pacer: Reducing sleep to 88.198955ms 2026/09/07 01:35:12 DEBUG : pacer: Reducing sleep to 254.296505ms 2026/09/07 01:35:12 DEBUG : pacer: Reducing sleep to 277.01094ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/09/07 01:35:15 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:35:15 DEBUG : pacer: Reducing sleep to 7.930631ms 2026/09/07 01:35:20 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:35:20 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:35:20 UTC": parsing time "Mon, 07 Sep 2026 01:35:20 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:35:20 UTC" as " " 2026/09/07 01:35:20 DEBUG : pacer: Reducing sleep to 93.585017ms === 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/07 01:35:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:35:24 DEBUG : pacer: Reducing sleep to 29.662144ms 2026/09/07 01:35:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:35:29 DEBUG : zoho root 'rclone-test-doficis7bevo': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/09/07 01:35:29 DEBUG : pacer: Reducing sleep to 111.494453ms 2026/09/07 01:35:29 DEBUG : pacer: Reducing sleep to 103.114136ms 2026/09/07 01:35:29 DEBUG : pacer: Reducing sleep to 221.293031ms 2026/09/07 01:35:29 DEBUG : pacer: Reducing sleep to 271.348208ms 2026/09/07 01:35:30 DEBUG : pacer: Reducing sleep to 38.846531ms 2026/09/07 01:35:30 DEBUG : pacer: Reducing sleep to 86.564215ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/09/07 01:35:30 DEBUG : pacer: Reducing sleep to 156.756954ms 2026/09/07 01:35:30 DEBUG : pacer: Reducing sleep to 261.93992ms 2026/09/07 01:35:30 DEBUG : pacer: Reducing sleep to 254.917727ms 2026/09/07 01:35:30 DEBUG : pacer: Reducing sleep to 187.770571ms 2026/09/07 01:35:31 DEBUG : pacer: Reducing sleep to 90.902097ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/09/07 01:35:31 DEBUG : pacer: Reducing sleep to 77.215752ms 2026/09/07 01:35:31 DEBUG : pacer: Reducing sleep to 146.999169ms 2026/09/07 01:35:31 DEBUG : pacer: Reducing sleep to 240.91436ms 2026/09/07 01:35:31 DEBUG : pacer: Reducing sleep to 250.32735ms 2026/09/07 01:35:31 DEBUG : pacer: Reducing sleep to 177.792771ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/09/07 01:35:32 DEBUG : pacer: Reducing sleep to 85.3848ms 2026/09/07 01:35:32 DEBUG : pacer: Reducing sleep to 86.39315ms 2026/09/07 01:35:32 DEBUG : pacer: Reducing sleep to 161.852345ms 2026/09/07 01:35:32 DEBUG : pacer: Reducing sleep to 245.95555ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/09/07 01:35:32 DEBUG : Creating backend with remote "TestZoho:" 2026/09/07 01:35:32 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/07 01:35:37 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/07 01:35:41 DEBUG : pacer: Reducing sleep to 107.40412ms 2026/09/07 01:35:41 DEBUG : pacer: Reducing sleep to 224.347481ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/09/07 01:35:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:35:46 DEBUG : pacer: Reducing sleep to 111.687559ms 2026/09/07 01:35:46 DEBUG : pacer: Reducing sleep to 109.000091ms 2026/09/07 01:35:46 DEBUG : pacer: Reducing sleep to 158.289784ms 2026/09/07 01:35:46 DEBUG : pacer: Reducing sleep to 219.82668ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/09/07 01:35:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/09/07 01:35:55 DEBUG : pacer: Reducing sleep to 117.939584ms 2026/09/07 01:35:55 DEBUG : pacer: Reducing sleep to 230.856413ms 2026/09/07 01:35:55 DEBUG : pacer: Reducing sleep to 283.168591ms 2026/09/07 01:35:56 DEBUG : pacer: Reducing sleep to 174.652365ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/09/07 01:36:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/09/07 01:36:00 DEBUG : pacer: Reducing sleep to 107.769389ms 2026/09/07 01:36:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:05 DEBUG : pacer: Reducing sleep to 83.915391ms 2026/09/07 01:36:05 DEBUG : pacer: Reducing sleep to 96.093101ms 2026/09/07 01:36:05 DEBUG : pacer: Reducing sleep to 210.154342ms 2026/09/07 01:36:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:09 DEBUG : pacer: Reducing sleep to 118.561928ms 2026/09/07 01:36:09 DEBUG : pacer: Reducing sleep to 270.028685ms 2026/09/07 01:36:09 DEBUG : pacer: Reducing sleep to 297.129261ms 2026/09/07 01:36:10 DEBUG : pacer: Reducing sleep to 183.830837ms 2026/09/07 01:36:10 DEBUG : pacer: Reducing sleep to 61.303778ms 2026/09/07 01:36:10 DEBUG : pacer: Reducing sleep to 39.392936ms 2026/09/07 01:36:10 DEBUG : pacer: Reducing sleep to 126.73168ms 2026/09/07 01:36:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:14 DEBUG : pacer: Reducing sleep to 106.867457ms 2026/09/07 01:36:14 DEBUG : pacer: Reducing sleep to 206.210306ms 2026/09/07 01:36:14 DEBUG : pacer: Reducing sleep to 257.827514ms 2026/09/07 01:36:14 DEBUG : pacer: Reducing sleep to 224.352947ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/09/07 01:36:18 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:23 DEBUG : pacer: Reducing sleep to 102.413827ms 2026/09/07 01:36:23 DEBUG : pacer: Reducing sleep to 85.445947ms 2026/09/07 01:36:23 DEBUG : pacer: Reducing sleep to 195.260991ms 2026/09/07 01:36:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:28 DEBUG : pacer: Reducing sleep to 117.513138ms 2026/09/07 01:36:28 DEBUG : pacer: Reducing sleep to 278.984453ms 2026/09/07 01:36:28 DEBUG : pacer: Reducing sleep to 315.149457ms 2026/09/07 01:36:28 DEBUG : pacer: Reducing sleep to 198.823225ms 2026/09/07 01:36:29 DEBUG : pacer: Reducing sleep to 54.58886ms 2026/09/07 01:36:29 DEBUG : Creating backend with remote "TestZoho:rclone-test-doficis7bevo/dirToPurgeFromRoot" 2026/09/07 01:36:29 DEBUG : pacer: Reducing sleep to 105.253138ms 2026/09/07 01:36:29 DEBUG : pacer: Reducing sleep to 226.704782ms 2026/09/07 01:36:29 DEBUG : pacer: Reducing sleep to 257.760687ms 2026/09/07 01:36:29 DEBUG : zoho root 'rclone-test-doficis7bevo': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/09/07 01:36:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:29 DEBUG : pacer: Reducing sleep to 118.397787ms 2026/09/07 01:36:29 DEBUG : pacer: Reducing sleep to 228.574142ms 2026/09/07 01:36:29 DEBUG : pacer: Reducing sleep to 282.260048ms 2026/09/07 01:36:30 DEBUG : pacer: Reducing sleep to 65.03192ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/09/07 01:36:30 DEBUG : Creating backend with remote "TestZoho:rclone-test-doficis7bevo/hello? sausage/êé" 2026/09/07 01:36:30 DEBUG : pacer: Reducing sleep to 102.300715ms 2026/09/07 01:36:30 DEBUG : pacer: Reducing sleep to 225.782213ms 2026/09/07 01:36:30 DEBUG : pacer: Reducing sleep to 253.626073ms 2026/09/07 01:36:30 DEBUG : pacer: Reducing sleep to 228.937486ms 2026/09/07 01:36:31 DEBUG : pacer: Reducing sleep to 100.573625ms 2026/09/07 01:36:31 DEBUG : pacer: Reducing sleep to 83.129196ms 2026/09/07 01:36:31 DEBUG : pacer: Reducing sleep to 78.204947ms 2026/09/07 01:36:31 DEBUG : pacer: Reducing sleep to 206.373182ms 2026/09/07 01:36:31 DEBUG : pacer: Reducing sleep to 245.621949ms 2026/09/07 01:36:31 DEBUG : pacer: Reducing sleep to 253.850793ms 2026/09/07 01:36:32 DEBUG : pacer: Reducing sleep to 125.568708ms 2026/09/07 01:36:32 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:32 DEBUG : pacer: Reducing sleep to 123.027079ms 2026/09/07 01:36:32 DEBUG : pacer: Reducing sleep to 231.323755ms 2026/09/07 01:36:32 DEBUG : pacer: Reducing sleep to 280.104969ms 2026/09/07 01:36:32 DEBUG : pacer: Reducing sleep to 202.262419ms 2026/09/07 01:36:33 DEBUG : pacer: Reducing sleep to 103.901469ms 2026/09/07 01:36:33 DEBUG : pacer: Reducing sleep to 65.136254ms 2026/09/07 01:36:33 DEBUG : pacer: Reducing sleep to 132.781225ms 2026/09/07 01:36:33 DEBUG : pacer: Reducing sleep to 227.352465ms 2026/09/07 01:36:33 DEBUG : pacer: Reducing sleep to 269.172264ms 2026/09/07 01:36:34 DEBUG : pacer: Reducing sleep to 176.927663ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/09/07 01:36:34 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:34 DEBUG : pacer: Reducing sleep to 101.248327ms 2026/09/07 01:36:34 DEBUG : pacer: Reducing sleep to 212.640077ms 2026/09/07 01:36:34 DEBUG : pacer: Reducing sleep to 283.974934ms 2026/09/07 01:36:37 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:37 DEBUG : pacer: Reducing sleep to 117.209624ms 2026/09/07 01:36:37 DEBUG : pacer: Reducing sleep to 40.543178ms 2026/09/07 01:36:41 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:42 DEBUG : pacer: Reducing sleep to 82.287237ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy/Metadata fstests.go:1325: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove 2026/09/07 01:36:46 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:36:46 DEBUG : pacer: Reducing sleep to 104.360403ms 2026/09/07 01:36:46 DEBUG : pacer: Reducing sleep to 174.220978ms 2026/09/07 01:36:46 DEBUG : pacer: Reducing sleep to 273.933415ms 2026/09/07 01:36:46 DEBUG : pacer: Reducing sleep to 267.917835ms 2026/09/07 01:36:47 DEBUG : pacer: Reducing sleep to 168.252113ms 2026/09/07 01:36:47 DEBUG : pacer: Reducing sleep to 43.226306ms 2026/09/07 01:36:47 DEBUG : pacer: Reducing sleep to 64.055674ms 2026/09/07 01:36:51 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:37:00 DEBUG : pacer: Reducing sleep to 98.858475ms 2026/09/07 01:37:00 DEBUG : pacer: Reducing sleep to 197.075159ms 2026/09/07 01:37:00 DEBUG : pacer: Reducing sleep to 252.895533ms 2026/09/07 01:37:01 DEBUG : pacer: Reducing sleep to 239.199212ms 2026/09/07 01:37:05 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:37:05 DEBUG : pacer: Reducing sleep to 117.761107ms 2026/09/07 01:37:05 DEBUG : pacer: Reducing sleep to 279.250236ms 2026/09/07 01:37:05 DEBUG : pacer: Reducing sleep to 256.810345ms 2026/09/07 01:37:05 DEBUG : pacer: Reducing sleep to 149.881281ms 2026/09/07 01:37:06 DEBUG : pacer: Reducing sleep to 59.720002ms 2026/09/07 01:37:06 DEBUG : pacer: Reducing sleep to 74.051863ms 2026/09/07 01:37:06 DEBUG : pacer: Reducing sleep to 163.378391ms 2026/09/07 01:37:06 DEBUG : pacer: Reducing sleep to 245.94209ms 2026/09/07 01:37:06 DEBUG : pacer: Reducing sleep to 253.681261ms 2026/09/07 01:37:09 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:37:09 DEBUG : pacer: Reducing sleep to 116.57222ms 2026/09/07 01:37:09 DEBUG : pacer: Reducing sleep to 282.807392ms 2026/09/07 01:37:10 DEBUG : pacer: Reducing sleep to 236.323914ms 2026/09/07 01:37:10 DEBUG : pacer: Reducing sleep to 129.161358ms 2026/09/07 01:37:10 DEBUG : pacer: Reducing sleep to 59.791877ms 2026/09/07 01:37:14 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:37:14 DEBUG : pacer: Reducing sleep to 98.835012ms 2026/09/07 01:37:14 DEBUG : pacer: Reducing sleep to 183.736576ms 2026/09/07 01:37:14 DEBUG : pacer: Reducing sleep to 254.249332ms 2026/09/07 01:37:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:37:19 DEBUG : pacer: Reducing sleep to 119.497947ms 2026/09/07 01:37:19 DEBUG : pacer: Reducing sleep to 283.374202ms 2026/09/07 01:37:19 DEBUG : pacer: Reducing sleep to 235.858466ms 2026/09/07 01:37:19 DEBUG : pacer: Reducing sleep to 128.900848ms 2026/09/07 01:37:19 DEBUG : pacer: Reducing sleep to 48.790658ms 2026/09/07 01:37:20 DEBUG : pacer: Reducing sleep to 92.798479ms 2026/09/07 01:37:20 DEBUG : pacer: Reducing sleep to 166.506348ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/09/07 01:37:20 DEBUG : Creating backend with remote "TestZoho:rclone-test-foxasom3jili" 2026/09/07 01:37:20 DEBUG : pacer: Reducing sleep to 46.534272ms 2026/09/07 01:37:20 DEBUG : pacer: Reducing sleep to 85.234632ms 2026/09/07 01:37:20 DEBUG : pacer: Reducing sleep to 109.697109ms 2026/09/07 01:37:20 DEBUG : pacer: Reducing sleep to 233.359659ms 2026/09/07 01:37:20 DEBUG : pacer: Reducing sleep to 266.821277ms 2026/09/07 01:37:21 DEBUG : pacer: Reducing sleep to 220.658995ms 2026/09/07 01:37:21 DEBUG : pacer: Reducing sleep to 93.729055ms 2026/09/07 01:37:21 DEBUG : pacer: Reducing sleep to 38.809921ms 2026/09/07 01:37:21 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:37:21 DEBUG : pacer: Reducing sleep to 31.844178ms 2026/09/07 01:37:21 DEBUG : pacer: Reducing sleep to 147.453692ms 2026/09/07 01:37:23 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:37:23 DEBUG : pacer: Reducing sleep to 115.923998ms 2026/09/07 01:37:23 DEBUG : pacer: Reducing sleep to 185.077298ms 2026/09/07 01:37:24 DEBUG : pacer: Reducing sleep to 214.607241ms 2026/09/07 01:37:24 DEBUG : pacer: Reducing sleep to 208.368131ms 2026/09/07 01:37:26 DEBUG : pacer: Reducing sleep to 36.305959ms 2026/09/07 01:37:26 DEBUG : pacer: Reducing sleep to 123.62156ms 2026/09/07 01:37:26 DEBUG : pacer: Reducing sleep to 242.453539ms 2026/09/07 01:37:26 DEBUG : pacer: Reducing sleep to 263.811024ms 2026/09/07 01:37:26 DEBUG : pacer: Reducing sleep to 206.318359ms 2026/09/07 01:37:26 DEBUG : pacer: Reducing sleep to 86.838856ms 2026/09/07 01:37:26 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:37:26 DEBUG : pacer: Reducing sleep to 127.908396ms 2026/09/07 01:37:26 DEBUG : zoho root 'rclone-test-foxasom3jili': Purge remote 2026/09/07 01:37:27 DEBUG : pacer: Reducing sleep to 121.51423ms 2026/09/07 01:37:27 DEBUG : pacer: Reducing sleep to 159.923302ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/09/07 01:37:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/09/07 01:37:27 DEBUG : pacer: Reducing sleep to 108.774848ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/09/07 01:37:27 DEBUG : pacer: Reducing sleep to 224.620538ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/09/07 01:37:27 DEBUG : pacer: Reducing sleep to 275.110139ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/09/07 01:37:27 DEBUG : pacer: Reducing sleep to 224.041012ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/09/07 01:37:28 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/07 01:37:33 DEBUG : zoho root 'rclone-test-doficis7bevo': 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/07 01:37:56 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:37:56 UTC": parsing time "Mon, 07 Sep 2026 01:37:56 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:37:56 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/09/07 01:38:01 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:01 UTC": parsing time "Mon, 07 Sep 2026 01:38:01 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:01 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/09/07 01:38:06 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:06 UTC": parsing time "Mon, 07 Sep 2026 01:38:06 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:06 UTC" as " " 2026/09/07 01:38:06 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:06 UTC": parsing time "Mon, 07 Sep 2026 01:38:06 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:06 UTC" as " " 2026/09/07 01:38:06 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:06 UTC": parsing time "Mon, 07 Sep 2026 01:38:06 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:06 UTC" as " " 2026/09/07 01:38:07 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:07 UTC": parsing time "Mon, 07 Sep 2026 01:38:07 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:07 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/09/07 01:38:10 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:10 UTC": parsing time "Mon, 07 Sep 2026 01:38:10 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:10 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/09/07 01:38:15 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:15 UTC": parsing time "Mon, 07 Sep 2026 01:38:15 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:15 UTC" as " " 2026/09/07 01:38:15 DEBUG : pacer: Reducing sleep to 4.160626ms 2026/09/07 01:38:19 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:38:19 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:19 UTC": parsing time "Mon, 07 Sep 2026 01:38:19 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:19 UTC" as " " 2026/09/07 01:38:19 DEBUG : pacer: Reducing sleep to 3.181086ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/09/07 01:38:19 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:19 UTC": parsing time "Mon, 07 Sep 2026 01:38:19 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:19 UTC" as " " 2026/09/07 01:38:19 DEBUG : pacer: Reducing sleep to 1.771103ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/09/07 01:38:19 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:19 UTC": parsing time "Mon, 07 Sep 2026 01:38:19 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:19 UTC" as " " 2026/09/07 01:38:19 DEBUG : pacer: Reducing sleep to 10.080825ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/09/07 01:38:20 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:20 UTC": parsing time "Mon, 07 Sep 2026 01:38:20 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:20 UTC" as " " 2026/09/07 01:38:20 DEBUG : pacer: Reducing sleep to 26.009931ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/09/07 01:38:20 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:20 UTC": parsing time "Mon, 07 Sep 2026 01:38:20 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:20 UTC" as " " 2026/09/07 01:38:20 DEBUG : pacer: Reducing sleep to 17.921769ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/09/07 01:38:24 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:38:24 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:24 UTC": parsing time "Mon, 07 Sep 2026 01:38:24 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:24 UTC" as " " 2026/09/07 01:38:24 DEBUG : pacer: Reducing sleep to 5.882245ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/09/07 01:38:28 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:38:28 DEBUG : pacer: Reducing sleep to 12.156883ms 2026/09/07 01:38:28 DEBUG : pacer: Reducing sleep to 122.803838ms 2026/09/07 01:38:29 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Mon, 07 Sep 2026 01:38:29 UTC": parsing time "Mon, 07 Sep 2026 01:38:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 07 Sep 2026 01:38:29 UTC" as " " 2026/09/07 01:38:29 DEBUG : pacer: Reducing sleep to 126.200201ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/09/07 01:38:29 DEBUG : pacer: Reducing sleep to 240.732872ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/09/07 01:38:29 DEBUG : Creating backend with remote "TestZoho:rclone-test-doficis7bevo/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/09/07 01:38:29 DEBUG : pacer: Reducing sleep to 121.284149ms 2026/09/07 01:38:29 DEBUG : pacer: Reducing sleep to 234.885232ms 2026/09/07 01:38:29 DEBUG : pacer: Reducing sleep to 287.53493ms 2026/09/07 01:38:29 DEBUG : pacer: Reducing sleep to 210.609236ms 2026/09/07 01:38:30 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:38:30 DEBUG : pacer: Reducing sleep to 119.476356ms 2026/09/07 01:38:30 DEBUG : pacer: Reducing sleep to 209.443294ms 2026/09/07 01:38:30 DEBUG : pacer: Reducing sleep to 289.613806ms 2026/09/07 01:38:30 DEBUG : pacer: Reducing sleep to 232.735402ms 2026/09/07 01:38:31 DEBUG : pacer: Reducing sleep to 116.830581ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/09/07 01:38:31 DEBUG : pacer: Reducing sleep to 43.828004ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/09/07 01:38:31 DEBUG : Creating backend with remote "TestZoho:rclone-test-doficis7bevo/not found.txt" 2026/09/07 01:38:31 DEBUG : pacer: Reducing sleep to 110.03031ms 2026/09/07 01:38:31 DEBUG : pacer: Reducing sleep to 159.336798ms 2026/09/07 01:38:36 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:38:36 DEBUG : zoho root 'rclone-test-doficis7bevo/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/09/07 01:38:40 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-doficis7bevo" from "TestZoho:rclone-test-doficis7bevo" 2026/09/07 01:38:40 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/09/07 01:38:45 DEBUG : pacer: Reducing sleep to 117.745788ms 2026/09/07 01:38:45 DEBUG : pacer: Reducing sleep to 230.543122ms 2026/09/07 01:38:45 DEBUG : pacer: Reducing sleep to 283.167935ms 2026/09/07 01:38:45 DEBUG : pacer: Reducing sleep to 211.125231ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/09/07 01:38:50 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/07 01:38:54 DEBUG : pacer: Reducing sleep to 44.015758ms 2026/09/07 01:38:59 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:39:04 DEBUG : pacer: Reducing sleep to 111.465681ms 2026/09/07 01:39:04 DEBUG : pacer: Reducing sleep to 209.617595ms 2026/09/07 01:39:04 DEBUG : pacer: Reducing sleep to 283.612526ms 2026/09/07 01:39:04 DEBUG : pacer: Reducing sleep to 230.256765ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/09/07 01:39:04 DEBUG : pacer: Reducing sleep to 96.790627ms 2026/09/07 01:39:08 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:39:08 DEBUG : pacer: Reducing sleep to 120.985114ms 2026/09/07 01:39:08 DEBUG : pacer: Reducing sleep to 232.889313ms 2026/09/07 01:39:08 DEBUG : pacer: Reducing sleep to 283.850333ms 2026/09/07 01:39:09 DEBUG : pacer: Reducing sleep to 209.533557ms === 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/07 01:39:13 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:39:17 DEBUG : pacer: Reducing sleep to 119.502905ms 2026/09/07 01:39:17 DEBUG : pacer: Reducing sleep to 231.32565ms 2026/09/07 01:39:18 DEBUG : pacer: Reducing sleep to 281.173669ms 2026/09/07 01:39:18 DEBUG : pacer: Reducing sleep to 215.023241ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/09/07 01:39:18 DEBUG : pacer: Reducing sleep to 102.294879ms === 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/07 01:39:22 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:39:22 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/09/07 01:39:22 DEBUG : pacer: Rate limited, increasing sleep to 1.211502916s 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/07 01:39:27 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:39:27 DEBUG : pacer: Reducing sleep to 112.241504ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/09/07 01:39:29 DEBUG : pacer: Reducing sleep to 0s 2026/09/07 01:39:29 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=cgyo2badc36d528834fbfa17357bd1a11f194": write tcp: unexpected EOF) 2026/09/07 01:39:29 DEBUG : pacer: Rate limited, increasing sleep to 1.931287006s 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=cgyo2badc36d528834fbfa17357bd1a11f194": write tcp: unexpected EOF 2026/09/07 01:39:29 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/07 01:39:29 DEBUG : pacer: Reducing sleep to 137.195371ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n