"go test -v -timeout 1h0m0s -remote TestZoho: -verbose" - Starting (try 4/5) === RUN TestIntegration fstests.go:442: Using remote "TestZoho:" 2024/10/17 07:55:09 DEBUG : Creating backend with remote "TestZoho:rclone-test-tuhocox0veve" 2024/10/17 07:55:09 DEBUG : Using config file from "/home/rclone/.rclone.conf" 2024/10/17 07:55:09 DEBUG : TestZoho: Loaded invalid token from config file - ignoring 2024/10/17 07:55:09 DEBUG : Saving config "token" in section "TestZoho" of the config file 2024/10/17 07:55:09 DEBUG : TestZoho: Saved new token in config file 2024/10/17 07:55:09 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 07:55:09 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 07:55:09 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2024/10/17 07:55:09 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 07:55:09 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 07:56:09 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 07:56:09 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 07:57:09 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 07:57:09 DEBUG : pacer: low level retry 4/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 07:58:10 DEBUG : pacer: Reducing sleep to 45s 2024/10/17 07:59:09 DEBUG : pacer: Reducing sleep to 33.75s === RUN TestIntegration/FsCheckWrap fstests.go:483: Not a wrapping Fs === RUN TestIntegration/FsCommand fstests.go:511: No commands in this remote === RUN TestIntegration/FsRmdirNotFound 2024/10/17 07:59:54 DEBUG : pacer: Reducing sleep to 25.3125s 2024/10/17 08:00:28 DEBUG : pacer: Reducing sleep to 18.984375s 2024/10/17 08:00:54 DEBUG : pacer: Reducing sleep to 14.23828125s 2024/10/17 08:01:12 DEBUG : pacer: Reducing sleep to 10.678710937s === RUN TestIntegration/FsString === RUN TestIntegration/FsName === RUN TestIntegration/FsRoot === RUN TestIntegration/FsRmdirEmpty 2024/10/17 08:01:27 DEBUG : pacer: Reducing sleep to 8.009033202s 2024/10/17 08:01:37 DEBUG : pacer: Reducing sleep to 6.006774901s === RUN TestIntegration/FsMkdir 2024/10/17 08:01:45 DEBUG : pacer: Reducing sleep to 4.505081175s 2024/10/17 08:01:52 DEBUG : pacer: Reducing sleep to 3.378810881s 2024/10/17 08:01:56 DEBUG : pacer: Reducing sleep to 2.53410816s === RUN TestIntegration/FsMkdir/FsMkdirRmdirSubdir 2024/10/17 08:01:56 DEBUG : dir/subdir: Making directory 2024/10/17 08:01:59 DEBUG : pacer: Reducing sleep to 1.90058112s 2024/10/17 08:02:02 DEBUG : pacer: Reducing sleep to 1.42543584s 2024/10/17 08:02:04 DEBUG : pacer: Reducing sleep to 1.06907688s 2024/10/17 08:02:05 DEBUG : pacer: Reducing sleep to 801.80766ms 2024/10/17 08:02:06 DEBUG : pacer: Reducing sleep to 601.355745ms 2024/10/17 08:02:07 DEBUG : pacer: Reducing sleep to 451.016808ms 2024/10/17 08:02:08 DEBUG : pacer: Reducing sleep to 338.262606ms 2024/10/17 08:02:08 INFO : dir/subdir: Removing directory 2024/10/17 08:02:08 DEBUG : pacer: Reducing sleep to 253.696954ms 2024/10/17 08:02:08 DEBUG : pacer: Reducing sleep to 190.272715ms 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 142.704536ms 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 107.028402ms 2024/10/17 08:02:09 INFO : dir: Removing directory 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 80.271301ms 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 60.203475ms 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 45.152606ms === RUN TestIntegration/FsMkdir/FsListEmpty 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 33.864454ms === RUN TestIntegration/FsMkdir/FsListDirEmpty 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 25.39834ms === RUN TestIntegration/FsMkdir/FsListRDirEmpty fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsListDirNotFound 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 19.048755ms 2024/10/17 08:02:09 ERROR : does not exist: error listing: directory not found === RUN TestIntegration/FsMkdir/FsListRDirNotFound fstests.go:403: FS has no ListR interface === RUN TestIntegration/FsMkdir/FsEncoding 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 14.286566ms === RUN TestIntegration/FsMkdir/FsEncoding/control_chars fstests.go:711: testing "␀␁␂␃␄␅␆␇␈␉␊␋␌␍␎␏␐␑␒␓␔␕␖␗␘␙␚␛␜␝␞␟␡" 2024/10/17 08:02:09 DEBUG : pacer: Reducing sleep to 10.714924ms 2024/10/17 08:02:10 DEBUG : pacer: Reducing sleep to 10ms === RUN TestIntegration/FsMkdir/FsEncoding/dot fstests.go:711: testing "．" === RUN TestIntegration/FsMkdir/FsEncoding/dot_dot fstests.go:711: testing "．．" === RUN TestIntegration/FsMkdir/FsEncoding/punctuation fstests.go:711: testing "!\"#$%&'()*+,-.／:;<=>?@[\\]^_`{|}~" 2024/10/17 08:02:13 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:02:13 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:02:13 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2024/10/17 08:02:13 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:02:13 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:03:13 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:03:13 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:04:13 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:04:13 DEBUG : pacer: low level retry 4/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:05:13 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:05:13 DEBUG : pacer: low level retry 5/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:06:13 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:06:13 DEBUG : pacer: low level retry 6/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:07:13 DEBUG : pacer: Reducing sleep to 45s === RUN TestIntegration/FsMkdir/FsEncoding/leading_space fstests.go:711: testing " leading space" 2024/10/17 08:08:13 DEBUG : pacer: Reducing sleep to 33.75s 2024/10/17 08:08:59 DEBUG : pacer: Reducing sleep to 25.3125s 2024/10/17 08:09:32 DEBUG : pacer: Reducing sleep to 18.984375s 2024/10/17 08:09:58 DEBUG : pacer: Reducing sleep to 14.23828125s 2024/10/17 08:10:16 DEBUG : pacer: Reducing sleep to 10.678710937s 2024/10/17 08:10:31 DEBUG : pacer: Reducing sleep to 8.009033202s 2024/10/17 08:10:41 DEBUG : pacer: Reducing sleep to 6.006774901s 2024/10/17 08:10:49 DEBUG : pacer: Reducing sleep to 4.505081175s 2024/10/17 08:10:55 DEBUG : pacer: Reducing sleep to 3.378810881s fstests.go:720: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:720 Error: Received unexpected error: directory not empty Test: TestIntegration/FsMkdir/FsEncoding/leading_space 2024/10/17 08:11:00 DEBUG : pacer: Reducing sleep to 2.53410816s 2024/10/17 08:11:03 DEBUG : pacer: Reducing sleep to 1.90058112s fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:11:06 DEBUG : pacer: Reducing sleep to 1.42543584s 2024/10/17 08:11:08 DEBUG : pacer: Reducing sleep to 1.06907688s 2024/10/17 08:11:09 DEBUG : pacer: Reducing sleep to 801.80766ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:11:11 DEBUG : pacer: Reducing sleep to 601.355745ms 2024/10/17 08:11:12 DEBUG : pacer: Reducing sleep to 451.016808ms 2024/10/17 08:11:13 DEBUG : pacer: Reducing sleep to 338.262606ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/leading_space Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_tilde fstests.go:711: testing "~leading tilde" 2024/10/17 08:11:17 DEBUG : pacer: Reducing sleep to 253.696954ms 2024/10/17 08:11:17 DEBUG : pacer: Reducing sleep to 190.272715ms 2024/10/17 08:11:17 DEBUG : pacer: Reducing sleep to 142.704536ms 2024/10/17 08:11:17 DEBUG : pacer: Reducing sleep to 107.028402ms 2024/10/17 08:11:18 DEBUG : pacer: Reducing sleep to 80.271301ms 2024/10/17 08:11:18 DEBUG : pacer: Reducing sleep to 60.203475ms 2024/10/17 08:11:18 DEBUG : pacer: Reducing sleep to 45.152606ms 2024/10/17 08:11:18 DEBUG : pacer: Reducing sleep to 33.864454ms 2024/10/17 08:11:18 DEBUG : pacer: Reducing sleep to 25.39834ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:11:19 DEBUG : pacer: Reducing sleep to 19.048755ms 2024/10/17 08:11:19 DEBUG : pacer: Reducing sleep to 14.286566ms 2024/10/17 08:11:19 DEBUG : pacer: Reducing sleep to 10.714924ms 2024/10/17 08:11:19 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"~leading tilde"} actual : []string{" leading space", "~leading tilde"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=14) "~leading tilde" Test: TestIntegration/FsMkdir/FsEncoding/leading_tilde Messages: directories fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/leading_tilde Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_CR fstests.go:711: testing "␍leading CR" fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:201 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:223 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:213 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:717 Error: Not equal: expected: 100 actual : 0 Test: TestIntegration/FsMkdir/FsEncoding/leading_CR Messages: ␍leading CR/␍leading CR: size incorrect file=100 vs obj=0 fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:299: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:299 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Should be true Test: TestIntegration/FsMkdir/FsEncoding/leading_CR Messages: listing wrong, want ␍leading CR/␍leading CR (100) got ␍leading CR/␍leading CR (0) fstest.go:144: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:144 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:189 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:302 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: 100 actual : 0 Test: TestIntegration/FsMkdir/FsEncoding/leading_CR Messages: ␍leading CR/␍leading CR: size incorrect file=100 vs obj=0 fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"␍leading CR"} actual : []string{" leading space", "␍leading CR"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=13) "␍leading CR" Test: TestIntegration/FsMkdir/FsEncoding/leading_CR Messages: directories fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/leading_CR Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_LF fstests.go:711: testing "␊leading LF" 2024/10/17 08:11:51 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:11:51 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:11:51 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2024/10/17 08:11:51 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:11:51 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:12:51 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:12:51 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:13:51 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:13:51 DEBUG : pacer: low level retry 4/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:14:51 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:14:51 DEBUG : pacer: low level retry 5/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:15:51 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:15:51 DEBUG : pacer: low level retry 6/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:16:51 DEBUG : pacer: Reducing sleep to 45s 2024/10/17 08:17:51 DEBUG : pacer: Reducing sleep to 33.75s 2024/10/17 08:18:36 DEBUG : pacer: Reducing sleep to 25.3125s fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:19:10 DEBUG : pacer: Reducing sleep to 18.984375s 2024/10/17 08:19:35 DEBUG : pacer: Reducing sleep to 14.23828125s 2024/10/17 08:19:54 DEBUG : pacer: Reducing sleep to 10.678710937s 2024/10/17 08:20:08 DEBUG : pacer: Reducing sleep to 8.009033202s fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:20:19 DEBUG : pacer: Reducing sleep to 6.006774901s 2024/10/17 08:20:27 DEBUG : pacer: Reducing sleep to 4.505081175s 2024/10/17 08:20:33 DEBUG : pacer: Reducing sleep to 3.378810881s 2024/10/17 08:20:38 DEBUG : pacer: Reducing sleep to 2.53410816s fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"␊leading LF"} actual : []string{" leading space", "␊leading LF"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=13) "␊leading LF" Test: TestIntegration/FsMkdir/FsEncoding/leading_LF Messages: directories 2024/10/17 08:20:42 DEBUG : pacer: Reducing sleep to 1.90058112s 2024/10/17 08:20:44 DEBUG : pacer: Reducing sleep to 1.42543584s 2024/10/17 08:20:46 DEBUG : pacer: Reducing sleep to 1.06907688s 2024/10/17 08:20:48 DEBUG : pacer: Reducing sleep to 801.80766ms 2024/10/17 08:20:49 DEBUG : pacer: Reducing sleep to 601.355745ms 2024/10/17 08:20:49 DEBUG : pacer: Reducing sleep to 451.016808ms 2024/10/17 08:20:50 DEBUG : pacer: Reducing sleep to 338.262606ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:20:51 DEBUG : pacer: Reducing sleep to 253.696954ms 2024/10/17 08:20:51 DEBUG : pacer: Reducing sleep to 190.272715ms 2024/10/17 08:20:52 DEBUG : pacer: Reducing sleep to 142.704536ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:20:54 DEBUG : pacer: Reducing sleep to 107.028402ms 2024/10/17 08:20:54 DEBUG : pacer: Reducing sleep to 80.271301ms 2024/10/17 08:20:54 DEBUG : pacer: Reducing sleep to 60.203475ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/leading_LF Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_HT fstests.go:711: testing "␉leading HT" 2024/10/17 08:20:58 DEBUG : pacer: Reducing sleep to 45.152606ms 2024/10/17 08:20:58 DEBUG : pacer: Reducing sleep to 33.864454ms 2024/10/17 08:20:58 DEBUG : pacer: Reducing sleep to 25.39834ms 2024/10/17 08:20:58 DEBUG : pacer: Reducing sleep to 19.048755ms 2024/10/17 08:20:58 DEBUG : pacer: Reducing sleep to 14.286566ms 2024/10/17 08:20:59 DEBUG : pacer: Reducing sleep to 10.714924ms 2024/10/17 08:20:59 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"␉leading HT"} actual : []string{" leading space", "␉leading HT"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=13) "␉leading HT" Test: TestIntegration/FsMkdir/FsEncoding/leading_HT Messages: directories fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/leading_HT Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_VT fstests.go:711: testing "␋leading VT" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"␋leading VT"} actual : []string{" leading space", "␋leading VT"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=13) "␋leading VT" Test: TestIntegration/FsMkdir/FsEncoding/leading_VT Messages: directories fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:21:27 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:21:27 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:21:27 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2024/10/17 08:21:27 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:21:27 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:22:27 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:22:27 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:23:27 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:23:27 DEBUG : pacer: low level retry 4/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:24:27 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:24:27 DEBUG : pacer: low level retry 5/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:25:27 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:25:27 DEBUG : pacer: low level retry 6/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:26:27 DEBUG : pacer: Reducing sleep to 45s 2024/10/17 08:27:27 DEBUG : pacer: Reducing sleep to 33.75s fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/leading_VT Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/leading_dot fstests.go:711: testing ".leading dot" 2024/10/17 08:28:12 DEBUG : pacer: Reducing sleep to 25.3125s 2024/10/17 08:28:46 DEBUG : pacer: Reducing sleep to 18.984375s 2024/10/17 08:29:11 DEBUG : pacer: Reducing sleep to 14.23828125s 2024/10/17 08:29:30 DEBUG : pacer: Reducing sleep to 10.678710937s 2024/10/17 08:29:44 DEBUG : pacer: Reducing sleep to 8.009033202s 2024/10/17 08:29:55 DEBUG : pacer: Reducing sleep to 6.006774901s 2024/10/17 08:30:03 DEBUG : pacer: Reducing sleep to 4.505081175s 2024/10/17 08:30:09 DEBUG : pacer: Reducing sleep to 3.378810881s 2024/10/17 08:30:13 DEBUG : pacer: Reducing sleep to 2.53410816s fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:30:17 DEBUG : pacer: Reducing sleep to 1.90058112s 2024/10/17 08:30:19 DEBUG : pacer: Reducing sleep to 1.42543584s 2024/10/17 08:30:21 DEBUG : pacer: Reducing sleep to 1.06907688s 2024/10/17 08:30:22 DEBUG : pacer: Reducing sleep to 801.80766ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:30:25 DEBUG : pacer: Reducing sleep to 601.355745ms 2024/10/17 08:30:25 DEBUG : pacer: Reducing sleep to 451.016808ms 2024/10/17 08:30:26 DEBUG : pacer: Reducing sleep to 338.262606ms 2024/10/17 08:30:26 DEBUG : pacer: Reducing sleep to 253.696954ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{".leading dot"} actual : []string{" leading space", ".leading dot"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=12) ".leading dot" Test: TestIntegration/FsMkdir/FsEncoding/leading_dot Messages: directories 2024/10/17 08:30:31 DEBUG : pacer: Reducing sleep to 190.272715ms 2024/10/17 08:30:31 DEBUG : pacer: Reducing sleep to 142.704536ms 2024/10/17 08:30:31 DEBUG : pacer: Reducing sleep to 107.028402ms 2024/10/17 08:30:31 DEBUG : pacer: Reducing sleep to 80.271301ms 2024/10/17 08:30:31 DEBUG : pacer: Reducing sleep to 60.203475ms 2024/10/17 08:30:31 DEBUG : pacer: Reducing sleep to 45.152606ms 2024/10/17 08:30:32 DEBUG : pacer: Reducing sleep to 33.864454ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:30:33 DEBUG : pacer: Reducing sleep to 25.39834ms 2024/10/17 08:30:33 DEBUG : pacer: Reducing sleep to 19.048755ms 2024/10/17 08:30:33 DEBUG : pacer: Reducing sleep to 14.286566ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:30:35 DEBUG : pacer: Reducing sleep to 10.714924ms 2024/10/17 08:30:35 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/leading_dot Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_space fstests.go:711: testing "trailing space " fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing space "} actual : []string{" leading space", "trailing space "} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=15) "trailing space " Test: TestIntegration/FsMkdir/FsEncoding/trailing_space Messages: directories fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/trailing_space Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_CR fstests.go:711: testing "trailing CR␍" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing CR␍"} actual : []string{" leading space", "trailing CR␍"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=14) "trailing CR␍" Test: TestIntegration/FsMkdir/FsEncoding/trailing_CR Messages: directories fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:31:06 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:31:06 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:31:06 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2024/10/17 08:31:06 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:31:06 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:32:06 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:32:06 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:33:06 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:33:06 DEBUG : pacer: low level retry 4/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:34:06 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:34:06 DEBUG : pacer: low level retry 5/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:35:06 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:35:06 DEBUG : pacer: low level retry 6/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:36:06 DEBUG : pacer: Reducing sleep to 45s 2024/10/17 08:37:06 DEBUG : pacer: Reducing sleep to 33.75s fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:37:51 DEBUG : pacer: Reducing sleep to 25.3125s 2024/10/17 08:38:25 DEBUG : pacer: Reducing sleep to 18.984375s 2024/10/17 08:38:50 DEBUG : pacer: Reducing sleep to 14.23828125s fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/trailing_CR Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_LF fstests.go:711: testing "trailing LF␊" 2024/10/17 08:39:09 DEBUG : pacer: Reducing sleep to 10.678710937s 2024/10/17 08:39:23 DEBUG : pacer: Reducing sleep to 8.009033202s 2024/10/17 08:39:34 DEBUG : pacer: Reducing sleep to 6.006774901s 2024/10/17 08:39:42 DEBUG : pacer: Reducing sleep to 4.505081175s 2024/10/17 08:39:48 DEBUG : pacer: Reducing sleep to 3.378810881s 2024/10/17 08:39:53 DEBUG : pacer: Reducing sleep to 2.53410816s 2024/10/17 08:39:56 DEBUG : pacer: Reducing sleep to 1.90058112s 2024/10/17 08:39:59 DEBUG : pacer: Reducing sleep to 1.42543584s 2024/10/17 08:40:00 DEBUG : pacer: Reducing sleep to 1.06907688s fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:40:02 DEBUG : pacer: Reducing sleep to 801.80766ms 2024/10/17 08:40:03 DEBUG : pacer: Reducing sleep to 601.355745ms 2024/10/17 08:40:04 DEBUG : pacer: Reducing sleep to 451.016808ms 2024/10/17 08:40:04 DEBUG : pacer: Reducing sleep to 338.262606ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:40:07 DEBUG : pacer: Reducing sleep to 253.696954ms 2024/10/17 08:40:07 DEBUG : pacer: Reducing sleep to 190.272715ms 2024/10/17 08:40:07 DEBUG : pacer: Reducing sleep to 142.704536ms 2024/10/17 08:40:07 DEBUG : pacer: Reducing sleep to 107.028402ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing LF␊"} actual : []string{" leading space", "trailing LF␊"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=14) "trailing LF␊" Test: TestIntegration/FsMkdir/FsEncoding/trailing_LF Messages: directories 2024/10/17 08:40:11 DEBUG : pacer: Reducing sleep to 80.271301ms 2024/10/17 08:40:12 DEBUG : pacer: Reducing sleep to 60.203475ms 2024/10/17 08:40:12 DEBUG : pacer: Reducing sleep to 45.152606ms 2024/10/17 08:40:12 DEBUG : pacer: Reducing sleep to 33.864454ms 2024/10/17 08:40:12 DEBUG : pacer: Reducing sleep to 25.39834ms 2024/10/17 08:40:12 DEBUG : pacer: Reducing sleep to 19.048755ms 2024/10/17 08:40:12 DEBUG : pacer: Reducing sleep to 14.286566ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:40:13 DEBUG : pacer: Reducing sleep to 10.714924ms 2024/10/17 08:40:13 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/trailing_LF Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_HT fstests.go:711: testing "trailing HT␉" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing HT␉"} actual : []string{" leading space", "trailing HT␉"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=14) "trailing HT␉" Test: TestIntegration/FsMkdir/FsEncoding/trailing_HT Messages: directories fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/trailing_HT Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_VT fstests.go:711: testing "trailing VT␋" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing VT␋"} actual : []string{" leading space", "trailing VT␋"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=14) "trailing VT␋" Test: TestIntegration/FsMkdir/FsEncoding/trailing_VT Messages: directories 2024/10/17 08:40:45 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:40:45 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:40:45 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2024/10/17 08:40:45 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:40:45 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:41:45 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:41:45 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:42:45 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:42:45 DEBUG : pacer: low level retry 4/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:43:45 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:43:45 DEBUG : pacer: low level retry 5/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:44:45 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:44:45 DEBUG : pacer: low level retry 6/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:45:45 DEBUG : pacer: Reducing sleep to 45s 2024/10/17 08:46:45 DEBUG : pacer: Reducing sleep to 33.75s fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:47:30 DEBUG : pacer: Reducing sleep to 25.3125s 2024/10/17 08:48:03 DEBUG : pacer: Reducing sleep to 18.984375s 2024/10/17 08:48:29 DEBUG : pacer: Reducing sleep to 14.23828125s fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:48:48 DEBUG : pacer: Reducing sleep to 10.678710937s 2024/10/17 08:49:02 DEBUG : pacer: Reducing sleep to 8.009033202s 2024/10/17 08:49:13 DEBUG : pacer: Reducing sleep to 6.006774901s fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/trailing_VT Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/trailing_dot fstests.go:711: testing "trailing dot." 2024/10/17 08:49:21 DEBUG : pacer: Reducing sleep to 4.505081175s 2024/10/17 08:49:27 DEBUG : pacer: Reducing sleep to 3.378810881s 2024/10/17 08:49:31 DEBUG : pacer: Reducing sleep to 2.53410816s 2024/10/17 08:49:35 DEBUG : pacer: Reducing sleep to 1.90058112s 2024/10/17 08:49:37 DEBUG : pacer: Reducing sleep to 1.42543584s 2024/10/17 08:49:39 DEBUG : pacer: Reducing sleep to 1.06907688s 2024/10/17 08:49:40 DEBUG : pacer: Reducing sleep to 801.80766ms 2024/10/17 08:49:41 DEBUG : pacer: Reducing sleep to 601.355745ms 2024/10/17 08:49:42 DEBUG : pacer: Reducing sleep to 451.016808ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:49:43 DEBUG : pacer: Reducing sleep to 338.262606ms 2024/10/17 08:49:44 DEBUG : pacer: Reducing sleep to 253.696954ms 2024/10/17 08:49:44 DEBUG : pacer: Reducing sleep to 190.272715ms 2024/10/17 08:49:44 DEBUG : pacer: Reducing sleep to 142.704536ms fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache 2024/10/17 08:49:46 DEBUG : pacer: Reducing sleep to 107.028402ms 2024/10/17 08:49:47 DEBUG : pacer: Reducing sleep to 80.271301ms 2024/10/17 08:49:47 DEBUG : pacer: Reducing sleep to 60.203475ms 2024/10/17 08:49:47 DEBUG : pacer: Reducing sleep to 45.152606ms fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"trailing dot."} actual : []string{" leading space", "trailing dot."} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=13) "trailing dot." Test: TestIntegration/FsMkdir/FsEncoding/trailing_dot Messages: directories 2024/10/17 08:49:51 DEBUG : pacer: Reducing sleep to 33.864454ms 2024/10/17 08:49:51 DEBUG : pacer: Reducing sleep to 25.39834ms 2024/10/17 08:49:51 DEBUG : pacer: Reducing sleep to 19.048755ms 2024/10/17 08:49:51 DEBUG : pacer: Reducing sleep to 14.286566ms 2024/10/17 08:49:51 DEBUG : pacer: Reducing sleep to 10.714924ms 2024/10/17 08:49:51 DEBUG : pacer: Reducing sleep to 10ms fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/trailing_dot Messages: directories === RUN TestIntegration/FsMkdir/FsEncoding/invalid_UTF-8 fstests.go:703: Skipping invalid UTF-8 === RUN TestIntegration/FsMkdir/FsEncoding/URL_encoding fstests.go:711: testing "test%46.txt" fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:718 Error: Not equal: expected: []string{"test%46.txt"} actual : []string{" leading space", "test%46.txt"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) (len=1) { +([]string) (len=2) { + (string) (len=14) " leading space", (string) (len=11) "test%46.txt" Test: TestIntegration/FsMkdir/FsEncoding/URL_encoding Messages: directories fstest.go:292: Sleeping for 1s for list eventual consistency: 1/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 2s for list eventual consistency: 2/3 fstest.go:295: Flushing the directory cache fstest.go:292: Sleeping for 4s for list eventual consistency: 3/3 fstest.go:295: Flushing the directory cache fstest.go:321: Error Trace: /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:321 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstest.go:332 /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:721 Error: Not equal: expected: []string{} actual : []string{" leading space"} Diff: --- Expected +++ Actual @@ -1,2 +1,3 @@ -([]string) { +([]string) (len=1) { + (string) (len=14) " leading space" } Test: TestIntegration/FsMkdir/FsEncoding/URL_encoding Messages: directories === RUN TestIntegration/FsMkdir/FsNewObjectNotFound === RUN TestIntegration/FsMkdir/FsPutError === RUN TestIntegration/FsMkdir/FsPutZeroLength 2024/10/17 08:50:16 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:50:16 DEBUG : pacer: low level retry 1/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:50:16 DEBUG : pacer: Rate limited, increasing sleep to 1m0s 2024/10/17 08:50:16 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:50:16 DEBUG : pacer: low level retry 2/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:51:16 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:51:16 DEBUG : pacer: low level retry 3/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:52:16 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:52:16 DEBUG : pacer: low level retry 4/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:53:16 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:53:16 DEBUG : pacer: low level retry 5/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") 2024/10/17 08:54:16 DEBUG : Too many requests. Trying again in 60 seconds. 2024/10/17 08:54:16 DEBUG : pacer: low level retry 6/10 (error HTTP error 429 (429 Too Many Requests) returned body: "{\"errors\":[{\"id\":\"F7008\",\"title\":\"Url throttles limit exceeded\"}]}\n") panic: test timed out after 1h0m0s running tests: TestIntegration (1h0m0s) TestIntegration/FsMkdir (53m31s) TestIntegration/FsMkdir/FsPutZeroLength (4m53s) goroutine 2437 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:2366 +0x385 created by time.goFunc /usr/local/go/src/time/sleep.go:177 +0x2d goroutine 1 [chan receive, 59 minutes]: testing.(*T).Run(0xc0000ab380, {0xb9bb2f?, 0x0?}, 0xc081e0) /usr/local/go/src/testing/testing.go:1750 +0x3ab testing.runTests.func1(0xc0000ab380) /usr/local/go/src/testing/testing.go:2161 +0x37 testing.tRunner(0xc0000ab380, 0xc00004fc70) /usr/local/go/src/testing/testing.go:1689 +0xfb testing.runTests(0xc000390c78, {0x111c310, 0x1, 0x1}, {0x1?, 0x51652e?, 0x1142580?}) /usr/local/go/src/testing/testing.go:2159 +0x445 testing.(*M).Run(0xc0000bea00) /usr/local/go/src/testing/testing.go:2027 +0x68b main.main() _testmain.go:49 +0x16c goroutine 5 [chan receive, 54 minutes]: testing.(*T).Run(0xc0000ab520, {0xb94779?, 0xc000264900?}, 0xc00026c180) /usr/local/go/src/testing/testing.go:1750 +0x3ab github.com/rclone/rclone/fstest/fstests.Run(0xc0000ab520, 0xc000365520) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:588 +0x1085 github.com/rclone/rclone/backend/zoho_test.TestIntegration(0xc0000ab520) /home/rclone/go/src/github.com/rclone/rclone/backend/zoho/zoho_test.go:13 +0x58 testing.tRunner(0xc0000ab520, 0xc081e0) /usr/local/go/src/testing/testing.go:1689 +0xfb created by testing.(*T).Run in goroutine 1 /usr/local/go/src/testing/testing.go:1742 +0x390 goroutine 20 [syscall, 59 minutes]: os/signal.signal_recv() /usr/local/go/src/runtime/sigqueue.go:152 +0x29 os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x13 created by os/signal.Notify.func1.1 in goroutine 5 /usr/local/go/src/os/signal/signal.go:151 +0x1f goroutine 21 [chan receive, 59 minutes]: github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler.func1() /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:24 +0x27 created by github.com/rclone/rclone/fs/accounting.(*tokenBucket).startSignalHandler in goroutine 5 /home/rclone/go/src/github.com/rclone/rclone/fs/accounting/accounting_unix.go:21 +0xab goroutine 121 [chan receive, 4 minutes]: testing.(*T).Run(0xc0000aa9c0, {0xb9c1ce?, 0x0?}, 0xc0000a8330) /usr/local/go/src/testing/testing.go:1750 +0x3ab github.com/rclone/rclone/fstest/fstests.Run.func13(0xc0000aa9c0) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:776 +0x7c9 testing.tRunner(0xc0000aa9c0, 0xc00026c180) /usr/local/go/src/testing/testing.go:1689 +0xfb created by testing.(*T).Run in goroutine 5 /usr/local/go/src/testing/testing.go:1742 +0x390 goroutine 2319 [sleep]: time.Sleep(0xdf8475800) /usr/local/go/src/runtime/time.go:195 +0x115 github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc00032f180?) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:167 +0x1c created by github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall in goroutine 2368 /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:166 +0xef goroutine 2368 [chan receive]: github.com/rclone/rclone/lib/pacer.(*Pacer).beginCall(0xc0001348a0) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:159 +0x25 github.com/rclone/rclone/lib/pacer.(*Pacer).call(0xc0001348a0, 0xc0003e0980, 0xa) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:196 +0x5c github.com/rclone/rclone/lib/pacer.(*Pacer).Call(0xc0001348a0, 0xc0003e0980) /home/rclone/go/src/github.com/rclone/rclone/lib/pacer/pacer.go:216 +0x92 github.com/rclone/rclone/backend/zoho.(*Fs).listAll(0xc000264900, {0xcc3c00, 0x11a3900}, {0xc00043e0c0, 0x25}, 0x0, 0x1, 0xc000557ac0) /home/rclone/go/src/github.com/rclone/rclone/backend/zoho/zoho.go:593 +0x416 github.com/rclone/rclone/backend/zoho.(*Fs).readMetaDataForPath(0xc000264900, {0xcc3c00, 0x11a3900}, {0xb9d21f?, 0x1?}) /home/rclone/go/src/github.com/rclone/rclone/backend/zoho/zoho.go:459 +0x115 github.com/rclone/rclone/backend/zoho.(*Object).readMetaData(0xc0001763c0, {0xcc3c00?, 0x11a3900?}) /home/rclone/go/src/github.com/rclone/rclone/backend/zoho/zoho.go:1307 +0x3e github.com/rclone/rclone/backend/zoho.(*Fs).newObjectWithInfo(0xc000264900, {0xcc3c00, 0x11a3900}, {0xb9d21f, 0x10}, 0x0) /home/rclone/go/src/github.com/rclone/rclone/backend/zoho/zoho.go:731 +0x10d github.com/rclone/rclone/backend/zoho.(*Fs).NewObject(...) /home/rclone/go/src/github.com/rclone/rclone/backend/zoho/zoho.go:742 github.com/rclone/rclone/backend/zoho.(*Fs).Put(0xc000264900, {0xcc3c00, 0x11a3900}, {0xcbdde0, 0xc0000fe0e0}, {0xcc7290, 0xc0001e60e0}, {0x0, 0x0, 0x0}) /home/rclone/go/src/github.com/rclone/rclone/backend/zoho/zoho.go:878 +0x85 github.com/rclone/rclone/fstest/fstests.testPutLarge.func1() /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:245 +0x233 github.com/rclone/rclone/fstest/fstests.retry(0xc00016e000, {0xb95c07, 0x8}, 0xc000557e70) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:137 +0x117 github.com/rclone/rclone/fstest/fstests.testPutLarge({0xcc3c00, 0x11a3900}, 0xc00016e000, {0xcc8320, 0xc000264900}, 0xc000557f20, 0x3?) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:235 +0x11d github.com/rclone/rclone/fstest/fstests.TestPutLarge(...) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:274 github.com/rclone/rclone/fstest/fstests.Run.func13.10(0xc00016e000) /home/rclone/go/src/github.com/rclone/rclone/fstest/fstests/fstests.go:779 +0xcd testing.tRunner(0xc00016e000, 0xc0000a8330) /usr/local/go/src/testing/testing.go:1689 +0xfb created by testing.(*T).Run in goroutine 121 /usr/local/go/src/testing/testing.go:1742 +0x390 exit status 2 FAIL github.com/rclone/rclone/backend/zoho 3600.016s "go test -v -timeout 1h0m0s -remote TestZoho: -verbose" - Finished ERROR in 1h0m0.510040304s (try 4/5): exit status 1: Failed []