Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Too high log rate in docker agent when connection error occurs #3671

Open
3 tasks done
johanvdw opened this issue May 2, 2024 · 0 comments
Open
3 tasks done

Too high log rate in docker agent when connection error occurs #3671

johanvdw opened this issue May 2, 2024 · 0 comments
Labels
agent bug Something isn't working

Comments

@johanvdw
Copy link
Contributor

johanvdw commented May 2, 2024

Component

agent

Describe the bug

We are running a woodpecker-ci agent in docker , which connects to the server on a different host. We suddenly saw a huge spike in error logs (several gigabytes). There was an actual error it seems, because dns was not available for some time, but the issue is the rate at which the errors are logged, is far too high:

{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514550725Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514576053Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514600056Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514609191Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.51465298Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514660152Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514716556Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514788238Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514800782Z"}
{"log":"{\"level\":\"error\",\"error\":\"rpc error: code = Unavailable desc = connection error: desc = \\\"transport: Error while dialing: dial tcp: lookup woodpecker-server on 10.126.0.1:53: read udp 172.16.0.4:41552-\u003e10.126.0.1:53: read: connection refused\\\"\",\"time\":\"2024-04-24T18:50:11Z\",\"message\":\"grpc error: done(): code: Unavailable\"}\n","stream":"stderr","time":"2024-04-24T18:50:11.514806631Z"}

ie 10 messages in 0.2 ms which would be about 40000 per second, so filling up a disk quickly.

System Info

woodpecker 2.4.1 in docker on linux

Additional context

No response

Validations

  • Read the docs.
  • Check that there isn't already an issue that reports the same bug to avoid creating a duplicate.
  • Checked that the bug isn't fixed in the next version already [https://woodpecker-ci.org/faq#which-version-of-woodpecker-should-i-use]
@johanvdw johanvdw added the bug Something isn't working label May 2, 2024
@qwerty287 qwerty287 added the agent label May 2, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
agent bug Something isn't working
Projects
None yet
Development

No branches or pull requests

2 participants