"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/10/03 03:08:51 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/10/03 03:08:51 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/10/03 03:08:51 NOTICE: zoho root '': Failed to parse Retry-After: "soon": strconv.Atoi: parsing "soon": invalid syntax 2026/10/03 03:08:51 NOTICE: zoho root '': Too many requests: Trying again in 61 seconds. assert.AnError general error for testing === RUN TestShouldRetry/401_missing_scope_aborts 2026/10/03 03:08:51 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/10/03 03:08:51 NOTICE: zoho root '': Too many requests: Trying again in 2 seconds. assert.AnError general error for testing --- PASS: TestThrottleEpisode (0.00s) === RUN TestThrottleStateConcurrent 2026/10/03 03:08:51 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/10/03 03:08:51 DEBUG : Creating backend with remote "TestZoho:rclone-test-vusuzup7gago" 2026/10/03 03:08:51 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2026/10/03 03:08:51 DEBUG : zoho root 'rclone-test-vusuzup7gago': folder listing limiter sweep: evicted 0 of 0 idle limiters 2026/10/03 03:08:51 DEBUG : TestZoho: Token expired 2026/10/03 03:08:51 DEBUG : TestZoho: No updated token found in the config file 2026/10/03 03:08:51 DEBUG : TestZoho: Token refresh successful 2026/10/03 03:08:51 DEBUG : Saving config "token" in section "TestZoho" of the config file 2026/10/03 03:08:51 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/10/03 03:08:53 DEBUG : pacer: Reducing sleep to 26.887759ms 2026/10/03 03:08:53 DEBUG : pacer: Reducing sleep to 14.893192ms 2026/10/03 03:08:53 DEBUG : pacer: Reducing sleep to 139.220511ms === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2026/10/03 03:08:53 DEBUG : pacer: Reducing sleep to 249.362636ms 2026/10/03 03:08:53 DEBUG : pacer: Reducing sleep to 268.245935ms === RUN TestIntegration/FsMkdir 2026/10/03 03:08:53 DEBUG : pacer: Reducing sleep to 80.338856ms 2026/10/03 03:08:54 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:08:54 DEBUG : pacer: Reducing sleep to 118.817172ms === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2026/10/03 03:08:54 INFO : dir/subdir: Making directory 2026/10/03 03:08:54 DEBUG : pacer: Reducing sleep to 238.905422ms 2026/10/03 03:08:54 DEBUG : pacer: Reducing sleep to 250.563524ms 2026/10/03 03:08:54 DEBUG : pacer: Reducing sleep to 213.55108ms 2026/10/03 03:08:54 DEBUG : pacer: Reducing sleep to 89.076813ms 2026/10/03 03:08:55 DEBUG : pacer: Reducing sleep to 63.894466ms 2026/10/03 03:08:55 DEBUG : pacer: Reducing sleep to 146.208423ms 2026/10/03 03:08:55 DEBUG : pacer: Reducing sleep to 258.884202ms 2026/10/03 03:08:55 INFO : dir/subdir: Removing directory 2026/10/03 03:08:55 DEBUG : pacer: Reducing sleep to 265.902629ms 2026/10/03 03:08:55 DEBUG : pacer: Reducing sleep to 171.831345ms 2026/10/03 03:08:55 DEBUG : pacer: Reducing sleep to 75.130691ms 2026/10/03 03:08:56 DEBUG : pacer: Reducing sleep to 73.96548ms 2026/10/03 03:08:56 INFO : dir: Removing directory 2026/10/03 03:08:56 DEBUG : pacer: Reducing sleep to 158.747139ms 2026/10/03 03:08:56 DEBUG : pacer: Reducing sleep to 246.98671ms 2026/10/03 03:08:56 DEBUG : pacer: Reducing sleep to 264.576628ms === RUN TestIntegration/FsMkdir/FsListEmpty 2026/10/03 03:08:56 DEBUG : pacer: Reducing sleep to 182.710067ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2026/10/03 03:09:01 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2026/10/03 03:09:05 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/10/03 03:09:15 DEBUG : pacer: Reducing sleep to 88.586065ms 2026/10/03 03:09:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:09:15 DEBUG : pacer: Reducing sleep to 110.527793ms 2026/10/03 03:09:19 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:09:19 DEBUG : pacer: Reducing sleep to 112.487014ms 2026/10/03 03:09:19 DEBUG : pacer: Reducing sleep to 211.561883ms 2026/10/03 03:09:19 DEBUG : pacer: Reducing sleep to 280.974143ms 2026/10/03 03:09:20 DEBUG : pacer: Reducing sleep to 185.648524ms 2026/10/03 03:09:24 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:734: testing "." 2026/10/03 03:09:28 DEBUG : pacer: Reducing sleep to 88.772861ms 2026/10/03 03:09:28 DEBUG : pacer: Reducing sleep to 212.591881ms 2026/10/03 03:09:29 DEBUG : pacer: Reducing sleep to 146.16412ms 2026/10/03 03:09:29 DEBUG : pacer: Reducing sleep to 232.005644ms 2026/10/03 03:09:33 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:09:33 DEBUG : pacer: Reducing sleep to 104.459641ms 2026/10/03 03:09:33 DEBUG : pacer: Reducing sleep to 87.153098ms 2026/10/03 03:09:33 DEBUG : pacer: Reducing sleep to 159.276439ms 2026/10/03 03:09:33 DEBUG : pacer: Reducing sleep to 228.566419ms 2026/10/03 03:09:38 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:734: testing ".." 2026/10/03 03:09:42 DEBUG : pacer: Reducing sleep to 87.82944ms 2026/10/03 03:09:42 DEBUG : pacer: Reducing sleep to 209.850583ms 2026/10/03 03:09:43 DEBUG : pacer: Reducing sleep to 108.873894ms 2026/10/03 03:09:43 DEBUG : pacer: Reducing sleep to 226.493355ms 2026/10/03 03:09:47 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:09:47 DEBUG : pacer: Reducing sleep to 114.334606ms 2026/10/03 03:09:47 DEBUG : pacer: Reducing sleep to 101.891038ms 2026/10/03 03:09:47 DEBUG : pacer: Reducing sleep to 165.26778ms 2026/10/03 03:09:47 DEBUG : pacer: Reducing sleep to 217.016535ms 2026/10/03 03:09:51 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:734: testing "!\"#$%&'()*+,-./:;<=>?@[\\]^_`{|}~" 2026/10/03 03:09:51 DEBUG : zoho root 'rclone-test-vusuzup7gago': folder listing limiter sweep: evicted 0 of 8 idle limiters 2026/10/03 03:09:56 DEBUG : pacer: Reducing sleep to 95.995494ms 2026/10/03 03:09:56 DEBUG : pacer: Reducing sleep to 217.057639ms 2026/10/03 03:09:56 DEBUG : pacer: Reducing sleep to 194.98208ms 2026/10/03 03:09:56 DEBUG : pacer: Reducing sleep to 231.090825ms 2026/10/03 03:09:57 DEBUG : pacer: Reducing sleep to 201.541318ms 2026/10/03 03:09:57 DEBUG : pacer: Reducing sleep to 135.390837ms 2026/10/03 03:09:57 DEBUG : pacer: Reducing sleep to 93.433172ms 2026/10/03 03:09:57 DEBUG : pacer: Reducing sleep to 137.898843ms 2026/10/03 03:09:57 DEBUG : pacer: Reducing sleep to 189.481207ms 2026/10/03 03:09:57 DEBUG : pacer: Reducing sleep to 237.840232ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:734: testing " leading space" 2026/10/03 03:09:58 DEBUG : pacer: Reducing sleep to 203.365222ms 2026/10/03 03:09:58 DEBUG : pacer: Reducing sleep to 113.120849ms 2026/10/03 03:09:58 DEBUG : pacer: Reducing sleep to 105.121329ms 2026/10/03 03:09:58 DEBUG : pacer: Reducing sleep to 714.043µs 2026/10/03 03:09:58 DEBUG : pacer: Reducing sleep to 120.757563ms 2026/10/03 03:09:58 DEBUG : pacer: Reducing sleep to 236.907848ms 2026/10/03 03:09:59 DEBUG : pacer: Reducing sleep to 277.468533ms 2026/10/03 03:09:59 DEBUG : pacer: Reducing sleep to 202.050867ms 2026/10/03 03:09:59 DEBUG : pacer: Reducing sleep to 102.792489ms 2026/10/03 03:09:59 DEBUG : pacer: Reducing sleep to 54.248093ms 2026/10/03 03:09:59 DEBUG : pacer: Reducing sleep to 132.867506ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:734: testing "~leading tilde" 2026/10/03 03:10:04 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:10:04 DEBUG : pacer: Reducing sleep to 96.121885ms 2026/10/03 03:10:04 DEBUG : pacer: Reducing sleep to 203.099374ms 2026/10/03 03:10:04 DEBUG : pacer: Reducing sleep to 192.733535ms 2026/10/03 03:10:04 DEBUG : pacer: Reducing sleep to 225.183259ms 2026/10/03 03:10:09 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:10:09 DEBUG : pacer: Reducing sleep to 111.685493ms 2026/10/03 03:10:09 DEBUG : pacer: Reducing sleep to 97.565211ms 2026/10/03 03:10:09 DEBUG : pacer: Reducing sleep to 165.779694ms 2026/10/03 03:10:09 DEBUG : pacer: Reducing sleep to 223.754572ms 2026/10/03 03:10:13 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:734: testing "␍leading CR" 2026/10/03 03:10:18 DEBUG : pacer: Reducing sleep to 82.293455ms 2026/10/03 03:10:18 DEBUG : pacer: Reducing sleep to 204.95521ms 2026/10/03 03:10:18 DEBUG : pacer: Reducing sleep to 83.492283ms 2026/10/03 03:10:18 DEBUG : pacer: Reducing sleep to 194.127011ms 2026/10/03 03:10:22 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:10:23 DEBUG : pacer: Reducing sleep to 81.90947ms 2026/10/03 03:10:23 DEBUG : pacer: Reducing sleep to 130.520534ms 2026/10/03 03:10:23 DEBUG : pacer: Reducing sleep to 219.195061ms 2026/10/03 03:10:23 DEBUG : pacer: Reducing sleep to 249.889843ms 2026/10/03 03:10:27 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:734: testing "␊leading LF" 2026/10/03 03:10:32 DEBUG : pacer: Reducing sleep to 75.165872ms 2026/10/03 03:10:32 DEBUG : pacer: Reducing sleep to 197.964768ms 2026/10/03 03:10:32 DEBUG : pacer: Reducing sleep to 185.710587ms 2026/10/03 03:10:32 DEBUG : pacer: Reducing sleep to 244.228602ms 2026/10/03 03:10:36 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:10:36 DEBUG : pacer: Reducing sleep to 103.991621ms 2026/10/03 03:10:37 DEBUG : pacer: Reducing sleep to 78.803419ms 2026/10/03 03:10:37 DEBUG : pacer: Reducing sleep to 147.54074ms 2026/10/03 03:10:37 DEBUG : pacer: Reducing sleep to 229.346906ms 2026/10/03 03:10:41 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:734: testing "␉leading HT" 2026/10/03 03:10:46 DEBUG : pacer: Reducing sleep to 30.961647ms 2026/10/03 03:10:46 DEBUG : pacer: Reducing sleep to 152.960664ms 2026/10/03 03:10:46 DEBUG : pacer: Reducing sleep to 36.976683ms 2026/10/03 03:10:46 DEBUG : pacer: Reducing sleep to 149.92423ms 2026/10/03 03:10:50 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:10:50 DEBUG : pacer: Reducing sleep to 112.132719ms 2026/10/03 03:10:50 DEBUG : pacer: Reducing sleep to 171.174858ms 2026/10/03 03:10:50 DEBUG : pacer: Reducing sleep to 243.569527ms 2026/10/03 03:10:51 DEBUG : pacer: Reducing sleep to 225.914737ms 2026/10/03 03:10:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:734: testing "␋leading VT" 2026/10/03 03:10:55 DEBUG : zoho root 'rclone-test-vusuzup7gago': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/10/03 03:10:55 DEBUG : pacer: Reducing sleep to 122.808044ms 2026/10/03 03:10:55 DEBUG : pacer: Reducing sleep to 76.211387ms 2026/10/03 03:10:55 DEBUG : pacer: Reducing sleep to 152.395887ms 2026/10/03 03:10:55 DEBUG : pacer: Reducing sleep to 152.763899ms 2026/10/03 03:10:55 DEBUG : pacer: Reducing sleep to 246.387406ms 2026/10/03 03:10:57 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:10:57 DEBUG : pacer: Reducing sleep to 113.174367ms 2026/10/03 03:10:57 DEBUG : pacer: Reducing sleep to 78.80681ms 2026/10/03 03:10:57 DEBUG : pacer: Reducing sleep to 140.531817ms 2026/10/03 03:10:57 DEBUG : pacer: Reducing sleep to 215.955797ms 2026/10/03 03:10:57 DEBUG : pacer: Reducing sleep to 260.360409ms === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:734: testing ".leading dot" 2026/10/03 03:10:58 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:10:58 DEBUG : pacer: Reducing sleep to 81.762074ms 2026/10/03 03:10:58 DEBUG : pacer: Reducing sleep to 71.358481ms 2026/10/03 03:10:58 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:10:58 DEBUG : pacer: Reducing sleep to 109.026012ms 2026/10/03 03:10:59 DEBUG : pacer: Reducing sleep to 221.476671ms 2026/10/03 03:10:59 DEBUG : pacer: Reducing sleep to 279.709492ms 2026/10/03 03:10:59 DEBUG : pacer: Reducing sleep to 214.920122ms 2026/10/03 03:10:59 DEBUG : pacer: Reducing sleep to 111.873131ms 2026/10/03 03:10:59 DEBUG : pacer: Reducing sleep to 52.389113ms 2026/10/03 03:11:03 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:734: testing "trailing space " 2026/10/03 03:11:08 DEBUG : pacer: Reducing sleep to 81.909453ms 2026/10/03 03:11:08 DEBUG : pacer: Reducing sleep to 205.822184ms 2026/10/03 03:11:08 DEBUG : pacer: Reducing sleep to 211.921503ms 2026/10/03 03:11:08 DEBUG : pacer: Reducing sleep to 237.494566ms 2026/10/03 03:11:12 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:11:12 DEBUG : pacer: Reducing sleep to 105.874209ms 2026/10/03 03:11:13 DEBUG : pacer: Reducing sleep to 88.094224ms 2026/10/03 03:11:13 DEBUG : pacer: Reducing sleep to 154.265802ms 2026/10/03 03:11:13 DEBUG : pacer: Reducing sleep to 226.443483ms 2026/10/03 03:11:17 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:734: testing "trailing CR␍" 2026/10/03 03:11:22 DEBUG : pacer: Reducing sleep to 93.979051ms 2026/10/03 03:11:22 DEBUG : pacer: Reducing sleep to 202.985005ms 2026/10/03 03:11:22 DEBUG : pacer: Reducing sleep to 115.913632ms 2026/10/03 03:11:22 DEBUG : pacer: Reducing sleep to 232.136698ms 2026/10/03 03:11:26 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:11:26 DEBUG : pacer: Reducing sleep to 112.532016ms 2026/10/03 03:11:26 DEBUG : pacer: Reducing sleep to 95.248391ms 2026/10/03 03:11:27 DEBUG : pacer: Reducing sleep to 165.255923ms 2026/10/03 03:11:27 DEBUG : pacer: Reducing sleep to 219.146695ms 2026/10/03 03:11:31 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:734: testing "trailing LF␊" 2026/10/03 03:11:35 DEBUG : pacer: Reducing sleep to 91.628602ms 2026/10/03 03:11:36 DEBUG : pacer: Reducing sleep to 191.427665ms 2026/10/03 03:11:36 DEBUG : pacer: Reducing sleep to 203.96476ms 2026/10/03 03:11:36 DEBUG : pacer: Reducing sleep to 258.272128ms 2026/10/03 03:11:40 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:11:40 DEBUG : pacer: Reducing sleep to 111.671139ms 2026/10/03 03:11:40 DEBUG : pacer: Reducing sleep to 58.099885ms 2026/10/03 03:11:40 DEBUG : pacer: Reducing sleep to 132.664077ms 2026/10/03 03:11:40 DEBUG : pacer: Reducing sleep to 227.471183ms 2026/10/03 03:11:45 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:734: testing "trailing HT␉" 2026/10/03 03:11:49 DEBUG : pacer: Reducing sleep to 101.402486ms 2026/10/03 03:11:49 DEBUG : pacer: Reducing sleep to 221.418807ms 2026/10/03 03:11:50 DEBUG : pacer: Reducing sleep to 205.981592ms 2026/10/03 03:11:50 DEBUG : pacer: Reducing sleep to 227.255499ms 2026/10/03 03:11:54 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:11:54 DEBUG : pacer: Reducing sleep to 111.526176ms 2026/10/03 03:11:54 DEBUG : pacer: Reducing sleep to 97.440094ms 2026/10/03 03:11:54 DEBUG : pacer: Reducing sleep to 164.940524ms 2026/10/03 03:11:54 DEBUG : pacer: Reducing sleep to 222.100963ms 2026/10/03 03:11:54 DEBUG : pacer: Reducing sleep to 236.77283ms === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:734: testing "trailing VT␋" 2026/10/03 03:11:55 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:11:55 DEBUG : pacer: Reducing sleep to 91.257883ms 2026/10/03 03:11:55 DEBUG : zoho root 'rclone-test-vusuzup7gago': folder listing limiter sweep: evicted 6 of 13 idle limiters 2026/10/03 03:11:55 DEBUG : pacer: Reducing sleep to 98.011984ms 2026/10/03 03:11:56 DEBUG : pacer: Reducing sleep to 90.228548ms 2026/10/03 03:11:56 DEBUG : pacer: Reducing sleep to 203.326182ms 2026/10/03 03:11:56 DEBUG : pacer: Reducing sleep to 275.0504ms 2026/10/03 03:11:56 DEBUG : pacer: Reducing sleep to 240.295069ms 2026/10/03 03:11:56 DEBUG : pacer: Reducing sleep to 117.65044ms 2026/10/03 03:11:56 DEBUG : pacer: Reducing sleep to 63.556661ms 2026/10/03 03:11:57 DEBUG : pacer: Reducing sleep to 96.683205ms 2026/10/03 03:11:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:734: testing "trailing dot." 2026/10/03 03:11:58 DEBUG : pacer: Reducing sleep to 44.479917ms 2026/10/03 03:11:58 DEBUG : pacer: Reducing sleep to 162.649162ms 2026/10/03 03:11:58 DEBUG : pacer: Reducing sleep to 212.1376ms 2026/10/03 03:11:58 DEBUG : pacer: Reducing sleep to 281.614934ms 2026/10/03 03:12:02 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:12:02 DEBUG : pacer: Reducing sleep to 110.412932ms 2026/10/03 03:12:02 DEBUG : pacer: Reducing sleep to 37.348262ms 2026/10/03 03:12:03 DEBUG : pacer: Reducing sleep to 112.430404ms 2026/10/03 03:12:03 DEBUG : pacer: Reducing sleep to 218.157747ms 2026/10/03 03:12:07 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/10/03 03:12:11 DEBUG : pacer: Reducing sleep to 90.970863ms 2026/10/03 03:12:11 DEBUG : pacer: Reducing sleep to 209.445924ms 2026/10/03 03:12:12 DEBUG : pacer: Reducing sleep to 42.685232ms 2026/10/03 03:12:12 DEBUG : pacer: Reducing sleep to 158.576684ms 2026/10/03 03:12:16 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:12:16 DEBUG : pacer: Reducing sleep to 116.769608ms 2026/10/03 03:12:16 DEBUG : pacer: Reducing sleep to 148.24749ms 2026/10/03 03:12:16 DEBUG : pacer: Reducing sleep to 227.530306ms 2026/10/03 03:12:16 DEBUG : pacer: Reducing sleep to 233.250247ms 2026/10/03 03:12:21 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError 2026/10/03 03:12:39 DEBUG : pacer: Reducing sleep to 90.449788ms 2026/10/03 03:12:39 DEBUG : pacer: Reducing sleep to 212.519702ms 2026/10/03 03:12:39 DEBUG : pacer: Reducing sleep to 249.798542ms 2026/10/03 03:12:39 DEBUG : pacer: Reducing sleep to 242.867049ms 2026/10/03 03:12:40 DEBUG : pacer: Reducing sleep to 115.684047ms 2026/10/03 03:12:40 DEBUG : pacer: Reducing sleep to 80.824173ms 2026/10/03 03:12:40 DEBUG : pacer: Reducing sleep to 93.457885ms 2026/10/03 03:12:40 DEBUG : pacer: Reducing sleep to 259.633763ms 2026/10/03 03:12:40 DEBUG : pacer: Reducing sleep to 288.944674ms === RUN TestIntegration/FsMkdir/FsPutZeroLength 2026/10/03 03:12:44 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:12:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:12:48 UTC": parsing time "Sat, 03 Oct 2026 03:12:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:12:48 UTC" as " " 2026/10/03 03:12:49 DEBUG : pacer: Reducing sleep to 104.457237ms === 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/10/03 03:12:53 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:12:58 DEBUG : zoho root 'rclone-test-vusuzup7gago': folder listing limiter sweep: evicted 7 of 14 idle limiters 2026/10/03 03:12:58 DEBUG : pacer: Reducing sleep to 118.461167ms 2026/10/03 03:12:58 DEBUG : pacer: Reducing sleep to 138.646504ms 2026/10/03 03:12:58 DEBUG : pacer: Reducing sleep to 249.93918ms 2026/10/03 03:12:58 DEBUG : pacer: Reducing sleep to 281.087413ms 2026/10/03 03:12:58 DEBUG : pacer: Reducing sleep to 43.805032ms 2026/10/03 03:12:58 DEBUG : pacer: Reducing sleep to 78.231928ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirFile2 2026/10/03 03:12:59 DEBUG : pacer: Reducing sleep to 192.200739ms 2026/10/03 03:12:59 DEBUG : pacer: Reducing sleep to 277.074006ms 2026/10/03 03:12:59 DEBUG : pacer: Reducing sleep to 245.994116ms 2026/10/03 03:12:59 DEBUG : pacer: Reducing sleep to 142.85425ms 2026/10/03 03:12:59 DEBUG : pacer: Reducing sleep to 53.312456ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirFile2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListR 2026/10/03 03:12:59 DEBUG : pacer: Reducing sleep to 86.051213ms 2026/10/03 03:13:00 DEBUG : pacer: Reducing sleep to 181.849225ms 2026/10/03 03:13:00 DEBUG : pacer: Reducing sleep to 159.5086ms 2026/10/03 03:13:00 DEBUG : pacer: Reducing sleep to 208.459728ms 2026/10/03 03:13:00 DEBUG : pacer: Reducing sleep to 227.951825ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRSubdir 2026/10/03 03:13:00 DEBUG : pacer: Reducing sleep to 208.656542ms 2026/10/03 03:13:00 DEBUG : pacer: Reducing sleep to 160.245847ms 2026/10/03 03:13:01 DEBUG : pacer: Reducing sleep to 115.996643ms 2026/10/03 03:13:01 DEBUG : pacer: Reducing sleep to 124.837962ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListDirRoot 2026/10/03 03:13:01 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRDirRoot fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListSubdir 2026/10/03 03:13:05 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/10/03 03:13:10 DEBUG : pacer: Reducing sleep to 116.524728ms 2026/10/03 03:13:10 DEBUG : pacer: Reducing sleep to 228.845699ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRLevel2 fstests.go:425: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1 2026/10/03 03:13:15 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:13:15 DEBUG : pacer: Reducing sleep to 30.2727ms 2026/10/03 03:13:15 DEBUG : pacer: Reducing sleep to 113.342692ms 2026/10/03 03:13:15 DEBUG : pacer: Reducing sleep to 170.055241ms 2026/10/03 03:13:15 DEBUG : pacer: Reducing sleep to 283.120336ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObject 2026/10/03 03:13:19 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectCaseInsensitive fstests.go:1152: Not Case Insensitive === RUN TestIntegration/FsMkdir/FsPutFiles/FsListFile1and2 2026/10/03 03:13:24 DEBUG : pacer: Reducing sleep to 109.089768ms 2026/10/03 03:13:24 DEBUG : pacer: Reducing sleep to 223.42311ms 2026/10/03 03:13:24 DEBUG : pacer: Reducing sleep to 277.191589ms 2026/10/03 03:13:24 DEBUG : pacer: Reducing sleep to 214.930475ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsNewObjectDir 2026/10/03 03:13:29 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurge 2026/10/03 03:13:29 DEBUG : pacer: Reducing sleep to 109.5567ms 2026/10/03 03:13:33 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:13:33 DEBUG : pacer: Reducing sleep to 93.104994ms 2026/10/03 03:13:33 DEBUG : pacer: Reducing sleep to 122.409628ms 2026/10/03 03:13:33 DEBUG : pacer: Reducing sleep to 237.738259ms 2026/10/03 03:13:38 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:13:38 DEBUG : pacer: Reducing sleep to 116.670816ms 2026/10/03 03:13:38 DEBUG : pacer: Reducing sleep to 274.375544ms 2026/10/03 03:13:38 DEBUG : pacer: Reducing sleep to 257.736742ms 2026/10/03 03:13:38 DEBUG : pacer: Reducing sleep to 155.738859ms 2026/10/03 03:13:39 DEBUG : pacer: Reducing sleep to 60.407336ms 2026/10/03 03:13:39 DEBUG : pacer: Reducing sleep to 70.305366ms 2026/10/03 03:13:39 DEBUG : pacer: Reducing sleep to 168.914277ms 2026/10/03 03:13:42 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:13:43 DEBUG : pacer: Reducing sleep to 112.884355ms 2026/10/03 03:13:43 DEBUG : pacer: Reducing sleep to 163.974257ms 2026/10/03 03:13:43 DEBUG : pacer: Reducing sleep to 223.670422ms 2026/10/03 03:13:43 DEBUG : pacer: Reducing sleep to 225.860484ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsPurgeRoot 2026/10/03 03:13:47 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:13:52 DEBUG : pacer: Reducing sleep to 94.397488ms 2026/10/03 03:13:52 DEBUG : pacer: Reducing sleep to 126.319447ms 2026/10/03 03:13:52 DEBUG : pacer: Reducing sleep to 239.926787ms 2026/10/03 03:13:56 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:13:56 DEBUG : pacer: Reducing sleep to 114.05659ms 2026/10/03 03:13:56 DEBUG : pacer: Reducing sleep to 276.67562ms 2026/10/03 03:13:57 DEBUG : pacer: Reducing sleep to 260.660288ms 2026/10/03 03:13:57 DEBUG : pacer: Reducing sleep to 146.397933ms 2026/10/03 03:13:57 DEBUG : pacer: Reducing sleep to 46.896656ms 2026/10/03 03:13:57 DEBUG : Creating backend with remote "TestZoho:rclone-test-vusuzup7gago/dirToPurgeFromRoot" 2026/10/03 03:13:57 DEBUG : pacer: Reducing sleep to 110.156583ms 2026/10/03 03:13:57 DEBUG : pacer: Reducing sleep to 227.31495ms 2026/10/03 03:13:58 DEBUG : pacer: Reducing sleep to 260.594112ms 2026/10/03 03:13:58 DEBUG : zoho root 'rclone-test-vusuzup7gago': folder listing limiter sweep: evicted 2 of 10 idle limiters 2026/10/03 03:13:58 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:13:58 DEBUG : pacer: Reducing sleep to 106.399265ms 2026/10/03 03:13:58 DEBUG : pacer: Reducing sleep to 219.904926ms 2026/10/03 03:13:58 DEBUG : pacer: Reducing sleep to 277.086483ms 2026/10/03 03:13:58 DEBUG : pacer: Reducing sleep to 225.556794ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsListRootedSubdir 2026/10/03 03:13:58 DEBUG : Creating backend with remote "TestZoho:rclone-test-vusuzup7gago/hello? sausage/êé" 2026/10/03 03:13:58 DEBUG : pacer: Reducing sleep to 114.710024ms 2026/10/03 03:13:58 DEBUG : pacer: Reducing sleep to 222.949865ms 2026/10/03 03:13:59 DEBUG : pacer: Reducing sleep to 280.712671ms 2026/10/03 03:13:59 DEBUG : pacer: Reducing sleep to 222.497996ms 2026/10/03 03:13:59 DEBUG : pacer: Reducing sleep to 84.078935ms 2026/10/03 03:13:59 DEBUG : pacer: Reducing sleep to 54.461572ms 2026/10/03 03:13:59 DEBUG : pacer: Reducing sleep to 109.71035ms 2026/10/03 03:13:59 DEBUG : pacer: Reducing sleep to 231.043692ms 2026/10/03 03:14:00 DEBUG : pacer: Reducing sleep to 109.901093ms 2026/10/03 03:14:00 DEBUG : pacer: Reducing sleep to 122.634129ms 2026/10/03 03:14:00 DEBUG : pacer: Reducing sleep to 137.571088ms 2026/10/03 03:14:00 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:01 DEBUG : pacer: Reducing sleep to 111.206817ms 2026/10/03 03:14:01 DEBUG : pacer: Reducing sleep to 224.554348ms 2026/10/03 03:14:01 DEBUG : pacer: Reducing sleep to 268.778291ms 2026/10/03 03:14:01 DEBUG : pacer: Reducing sleep to 221.899636ms 2026/10/03 03:14:01 DEBUG : pacer: Reducing sleep to 106.852009ms 2026/10/03 03:14:01 DEBUG : pacer: Reducing sleep to 60.395171ms 2026/10/03 03:14:01 DEBUG : pacer: Reducing sleep to 121.405259ms 2026/10/03 03:14:02 DEBUG : pacer: Reducing sleep to 220.339507ms 2026/10/03 03:14:02 DEBUG : pacer: Reducing sleep to 255.382722ms 2026/10/03 03:14:02 DEBUG : pacer: Reducing sleep to 206.871794ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsCopy 2026/10/03 03:14:02 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:02 DEBUG : pacer: Reducing sleep to 88.474182ms 2026/10/03 03:14:02 DEBUG : pacer: Reducing sleep to 203.613075ms 2026/10/03 03:14:02 DEBUG : pacer: Reducing sleep to 273.292889ms 2026/10/03 03:14:05 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:05 DEBUG : pacer: Reducing sleep to 113.385751ms 2026/10/03 03:14:05 DEBUG : pacer: Reducing sleep to 61.459113ms 2026/10/03 03:14:10 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/10/03 03:14:15 DEBUG : pacer: Reducing sleep to 23.956374ms 2026/10/03 03:14:15 DEBUG : pacer: Reducing sleep to 69.847148ms 2026/10/03 03:14:15 DEBUG : pacer: Reducing sleep to 183.924987ms 2026/10/03 03:14:15 DEBUG : pacer: Reducing sleep to 273.10879ms 2026/10/03 03:14:15 DEBUG : pacer: Reducing sleep to 261.94391ms 2026/10/03 03:14:16 DEBUG : pacer: Reducing sleep to 107.56552ms 2026/10/03 03:14:19 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:29 DEBUG : pacer: Reducing sleep to 90.676502ms 2026/10/03 03:14:29 DEBUG : pacer: Reducing sleep to 188.61503ms 2026/10/03 03:14:29 DEBUG : pacer: Reducing sleep to 231.463767ms 2026/10/03 03:14:29 DEBUG : pacer: Reducing sleep to 245.321361ms 2026/10/03 03:14:33 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:34 DEBUG : pacer: Reducing sleep to 37.232762ms 2026/10/03 03:14:34 DEBUG : pacer: Reducing sleep to 199.483517ms 2026/10/03 03:14:34 DEBUG : pacer: Reducing sleep to 262.879936ms 2026/10/03 03:14:34 DEBUG : pacer: Reducing sleep to 236.044471ms 2026/10/03 03:14:34 DEBUG : pacer: Reducing sleep to 147.501738ms 2026/10/03 03:14:34 DEBUG : pacer: Reducing sleep to 63.619076ms 2026/10/03 03:14:35 DEBUG : pacer: Reducing sleep to 69.502969ms 2026/10/03 03:14:35 DEBUG : pacer: Reducing sleep to 156.05458ms 2026/10/03 03:14:35 DEBUG : pacer: Reducing sleep to 264.727583ms 2026/10/03 03:14:38 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:38 DEBUG : pacer: Reducing sleep to 123.237832ms 2026/10/03 03:14:38 DEBUG : pacer: Reducing sleep to 269.658779ms 2026/10/03 03:14:38 DEBUG : pacer: Reducing sleep to 231.463759ms 2026/10/03 03:14:39 DEBUG : pacer: Reducing sleep to 130.997116ms 2026/10/03 03:14:39 DEBUG : pacer: Reducing sleep to 72.926939ms 2026/10/03 03:14:43 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:43 DEBUG : pacer: Reducing sleep to 107.498265ms 2026/10/03 03:14:43 DEBUG : pacer: Reducing sleep to 189.504972ms 2026/10/03 03:14:43 DEBUG : pacer: Reducing sleep to 266.066655ms 2026/10/03 03:14:47 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:47 DEBUG : pacer: Reducing sleep to 121.17218ms 2026/10/03 03:14:47 DEBUG : pacer: Reducing sleep to 280.966394ms 2026/10/03 03:14:48 DEBUG : pacer: Reducing sleep to 182.189584ms 2026/10/03 03:14:48 DEBUG : pacer: Reducing sleep to 115.860138ms 2026/10/03 03:14:48 DEBUG : pacer: Reducing sleep to 99.774589ms 2026/10/03 03:14:48 DEBUG : pacer: Reducing sleep to 144.69994ms 2026/10/03 03:14:48 DEBUG : pacer: Reducing sleep to 207.606373ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsMove/Metadata fstests.go:1437: Skipping test as can't write metadata === RUN TestIntegration/FsMkdir/FsPutFiles/FsDirMove 2026/10/03 03:14:48 DEBUG : Creating backend with remote "TestZoho:rclone-test-gowacem9quwo" 2026/10/03 03:14:49 DEBUG : pacer: Reducing sleep to 38.640701ms 2026/10/03 03:14:49 DEBUG : pacer: Reducing sleep to 82.66973ms 2026/10/03 03:14:49 DEBUG : pacer: Reducing sleep to 105.402196ms 2026/10/03 03:14:49 DEBUG : pacer: Reducing sleep to 221.197681ms 2026/10/03 03:14:49 DEBUG : pacer: Reducing sleep to 257.26375ms 2026/10/03 03:14:49 DEBUG : pacer: Reducing sleep to 225.480091ms 2026/10/03 03:14:50 DEBUG : pacer: Reducing sleep to 42.460741ms 2026/10/03 03:14:50 DEBUG : pacer: Reducing sleep to 60.654464ms 2026/10/03 03:14:50 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:50 DEBUG : pacer: Reducing sleep to 33.942114ms 2026/10/03 03:14:50 DEBUG : pacer: Reducing sleep to 149.369373ms 2026/10/03 03:14:52 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:52 DEBUG : pacer: Reducing sleep to 114.992908ms 2026/10/03 03:14:52 DEBUG : pacer: Reducing sleep to 182.343365ms 2026/10/03 03:14:52 DEBUG : pacer: Reducing sleep to 238.585903ms 2026/10/03 03:14:52 DEBUG : pacer: Reducing sleep to 211.452246ms 2026/10/03 03:14:54 DEBUG : pacer: Reducing sleep to 37.423564ms 2026/10/03 03:14:54 DEBUG : pacer: Reducing sleep to 150.807254ms 2026/10/03 03:14:54 DEBUG : pacer: Reducing sleep to 266.443788ms 2026/10/03 03:14:54 DEBUG : pacer: Reducing sleep to 280.405339ms 2026/10/03 03:14:55 DEBUG : pacer: Reducing sleep to 180.810185ms 2026/10/03 03:14:55 DEBUG : pacer: Reducing sleep to 57.462598ms 2026/10/03 03:14:55 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:14:55 DEBUG : pacer: Reducing sleep to 121.766012ms 2026/10/03 03:14:55 DEBUG : zoho root 'rclone-test-gowacem9quwo': Purge remote 2026/10/03 03:14:55 DEBUG : pacer: Reducing sleep to 117.676149ms 2026/10/03 03:14:55 DEBUG : pacer: Reducing sleep to 149.264492ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsRmdirFull 2026/10/03 03:14:55 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsPrecision === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectString 2026/10/03 03:14:56 DEBUG : pacer: Reducing sleep to 91.458169ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectFs 2026/10/03 03:14:56 DEBUG : pacer: Reducing sleep to 168.716249ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectRemote 2026/10/03 03:14:56 DEBUG : pacer: Reducing sleep to 278.830724ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectHashes 2026/10/03 03:14:56 DEBUG : pacer: Reducing sleep to 269.962877ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectModTime 2026/10/03 03:14:57 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMimeType fstests.go:1633: MimeType method not supported === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectMetadata 2026/10/03 03:15:01 DEBUG : zoho root 'rclone-test-vusuzup7gago': 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/10/03 03:15:25 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:25 UTC": parsing time "Sat, 03 Oct 2026 03:15:25 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:25 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenSeek 2026/10/03 03:15:29 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:29 UTC": parsing time "Sat, 03 Oct 2026 03:15:29 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:29 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenRange 2026/10/03 03:15:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:34 UTC": parsing time "Sat, 03 Oct 2026 03:15:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:34 UTC" as " " 2026/10/03 03:15:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:34 UTC": parsing time "Sat, 03 Oct 2026 03:15:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:34 UTC" as " " 2026/10/03 03:15:34 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:34 UTC": parsing time "Sat, 03 Oct 2026 03:15:34 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:34 UTC" as " " 2026/10/03 03:15:35 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:35 UTC": parsing time "Sat, 03 Oct 2026 03:15:35 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:35 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectPartialRead 2026/10/03 03:15:39 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:39 UTC": parsing time "Sat, 03 Oct 2026 03:15:39 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:39 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint 2026/10/03 03:15:43 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:43 UTC": parsing time "Sat, 03 Oct 2026 03:15:43 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:43 UTC" as " " 2026/10/03 03:15:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:48 UTC": parsing time "Sat, 03 Oct 2026 03:15:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:48 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/full_read 2026/10/03 03:15:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:48 UTC": parsing time "Sat, 03 Oct 2026 03:15:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:48 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/seek_read 2026/10/03 03:15:48 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:48 UTC": parsing time "Sat, 03 Oct 2026 03:15:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:48 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read 2026/10/03 03:15:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:48 UTC": parsing time "Sat, 03 Oct 2026 03:15:48 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:48 UTC" as " " 2026/10/03 03:15:49 DEBUG : pacer: Reducing sleep to 5.020368ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/range_read_to_end 2026/10/03 03:15:49 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:49 UTC": parsing time "Sat, 03 Oct 2026 03:15:49 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:49 UTC" as " " 2026/10/03 03:15:49 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectOpenFingerprint/refresh 2026/10/03 03:15:52 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:52 UTC": parsing time "Sat, 03 Oct 2026 03:15:52 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:52 UTC" as " " === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectUpdate 2026/10/03 03:15:57 DEBUG : pacer: Reducing sleep to 104.718285ms 2026/10/03 03:15:57 DEBUG : Couldn't parse Date: from server download.zoho.eu: "Sat, 03 Oct 2026 03:15:57 UTC": parsing time "Sat, 03 Oct 2026 03:15:57 UTC" as "Mon Jan _2 15:04:05 2006": cannot parse ", 03 Oct 2026 03:15:57 UTC" as " " 2026/10/03 03:15:57 DEBUG : pacer: Reducing sleep to 81.604777ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectStorable 2026/10/03 03:15:57 DEBUG : pacer: Reducing sleep to 196.347094ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile 2026/10/03 03:15:57 DEBUG : Creating backend with remote "TestZoho:rclone-test-vusuzup7gago/hello? sausage/êé/Hello, 世界/ \" ' @ < > & ? + ≠/z.txt" 2026/10/03 03:15:57 DEBUG : Config file has changed externally - reloading 2026/10/03 03:15:58 DEBUG : pacer: Reducing sleep to 117.323542ms 2026/10/03 03:15:58 DEBUG : pacer: Reducing sleep to 233.047523ms 2026/10/03 03:15:58 DEBUG : pacer: Reducing sleep to 280.680123ms 2026/10/03 03:15:58 DEBUG : pacer: Reducing sleep to 209.114363ms 2026/10/03 03:15:58 DEBUG : pacer: Reducing sleep to 92.697825ms 2026/10/03 03:15:59 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:15:59 DEBUG : pacer: Reducing sleep to 116.129467ms 2026/10/03 03:15:59 DEBUG : pacer: Reducing sleep to 231.68498ms 2026/10/03 03:15:59 DEBUG : pacer: Reducing sleep to 284.604395ms 2026/10/03 03:15:59 DEBUG : pacer: Reducing sleep to 210.674781ms 2026/10/03 03:15:59 DEBUG : pacer: Reducing sleep to 98.055465ms === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFile/FsRoot 2026/10/03 03:16:00 DEBUG : pacer: Reducing sleep to 0s === RUN TestIntegration/FsMkdir/FsPutFiles/FsIsFileNotFound 2026/10/03 03:16:00 DEBUG : Creating backend with remote "TestZoho:rclone-test-vusuzup7gago/not found.txt" 2026/10/03 03:16:00 DEBUG : pacer: Reducing sleep to 105.507216ms 2026/10/03 03:16:00 DEBUG : pacer: Reducing sleep to 124.221948ms 2026/10/03 03:16:05 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:16:05 DEBUG : zoho root 'rclone-test-vusuzup7gago/not found.txt': folder listing limiter sweep: evicted 4 of 10 idle limiters 2026/10/03 03:16:09 ERROR : error listing: directory not found === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot fstests.go:1994: Opening root remote "TestZoho:" path "rclone-test-vusuzup7gago" from "TestZoho:rclone-test-vusuzup7gago" 2026/10/03 03:16:09 DEBUG : Creating backend with remote "TestZoho:" === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/List 2026/10/03 03:16:14 DEBUG : pacer: Reducing sleep to 114.675061ms 2026/10/03 03:16:14 DEBUG : pacer: Reducing sleep to 230.287784ms 2026/10/03 03:16:14 DEBUG : pacer: Reducing sleep to 279.533439ms 2026/10/03 03:16:14 DEBUG : pacer: Reducing sleep to 210.749195ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/ListEntries 2026/10/03 03:16:19 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/10/03 03:16:33 DEBUG : pacer: Reducing sleep to 103.112022ms 2026/10/03 03:16:33 DEBUG : pacer: Reducing sleep to 218.671174ms 2026/10/03 03:16:33 DEBUG : pacer: Reducing sleep to 272.672102ms 2026/10/03 03:16:33 DEBUG : pacer: Reducing sleep to 224.679076ms === RUN TestIntegration/FsMkdir/FsPutFiles/FromRoot/Put/Remove 2026/10/03 03:16:33 DEBUG : pacer: Reducing sleep to 96.01418ms 2026/10/03 03:16:37 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:16:37 DEBUG : pacer: Reducing sleep to 104.636732ms 2026/10/03 03:16:37 DEBUG : pacer: Reducing sleep to 217.713274ms 2026/10/03 03:16:37 DEBUG : pacer: Reducing sleep to 270.733811ms 2026/10/03 03:16:38 DEBUG : pacer: Reducing sleep to 205.16912ms === 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/10/03 03:16:42 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:16:46 DEBUG : pacer: Reducing sleep to 110.433721ms 2026/10/03 03:16:46 DEBUG : pacer: Reducing sleep to 223.99019ms 2026/10/03 03:16:47 DEBUG : pacer: Reducing sleep to 272.396141ms 2026/10/03 03:16:47 DEBUG : pacer: Reducing sleep to 220.123664ms === RUN TestIntegration/FsMkdir/FsPutFiles/ObjectAbout 2026/10/03 03:16:47 DEBUG : pacer: Reducing sleep to 104.490362ms === 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/10/03 03:16:51 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:16:51 DEBUG : pacer: low level retry 1/1 (error HTTP error 500 (500 Internal Server Error) returned body: "") 2026/10/03 03:16:51 DEBUG : pacer: Rate limited, increasing sleep to 1.545141431s fstests.go:2660: Put returned an error after the injected failure (acceptable): upload error: HTTP error 500 (500 Internal Server Error) returned body: "" 2026/10/03 03:16:56 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:16:56 DEBUG : pacer: Reducing sleep to 116.629824ms === RUN TestIntegration/FsMkdir/FsPutRetry/Simple/NetError 2026/10/03 03:16:57 DEBUG : pacer: Reducing sleep to 0s 2026/10/03 03:16:57 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=q9uy5eabcc16f4a2d4c65b3c865f05020ad60": write tcp: unexpected EOF) 2026/10/03 03:16:57 DEBUG : pacer: Rate limited, increasing sleep to 1.168710621s 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=q9uy5eabcc16f4a2d4c65b3c865f05020ad60": write tcp: unexpected EOF 2026/10/03 03:16:58 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/10/03 03:16:58 DEBUG : pacer: Reducing sleep to 135.658462ms fstests.go:2784: Put returned an error (acceptable): upload error: HTTP error 400 (400 Bad Request) returned body: "\r\n