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

integration-tests: G10 (sink_hang + sigstop) run more than 25 minutes #334

Closed
pingyu opened this issue Feb 9, 2023 · 1 comment · Fixed by #335
Closed

integration-tests: G10 (sink_hang + sigstop) run more than 25 minutes #334

pingyu opened this issue Feb 9, 2023 · 1 comment · Fixed by #335
Labels
type/bug Something isn't working

Comments

@pingyu
Copy link
Collaborator

pingyu commented Feb 9, 2023

Bug Report

1. Describe the bug

Integration tests G10 (sink_hang + sigstop) run more than 25 minutes

See:
https://do.pingcap.net/jenkins/blue/organizations/jenkins/tikv%2Fmigration%2Fpull_integration_test/detail/pull_integration_test/75/pipeline/292/
https://do.pingcap.net/jenkins/blue/rest/organizations/jenkins/pipelines/tikv/pipelines/migration/pipelines/pull_integration_test/runs/75/nodes/292/steps/370/log/?start=0
G10-log.txt

2. Minimal reproduce step (Required)

Run CI in PR.

3. What did you see instead (Required)

CI success.

4. What did you expect to see? (Required)

CI failed.

5. What is your migration tool and TiKV version? (Required)

@pingyu pingyu added the type/bug Something isn't working label Feb 9, 2023
@pingyu
Copy link
Collaborator Author

pingyu commented Feb 10, 2023

The logs show that after kill -19 pd-server here, ETCD can not recover and block the pd-ctl health.

{"level":"warn","ts":"2023-02-09T07:56:24.178+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = context deadline exceeded"}
{"level":"warn","ts":"2023-02-09T07:56:28.179+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = Unavailable desc = keepalive ping failed to receive ACK within timeout"}
{"level":"warn","ts":"2023-02-09T07:56:30.179+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":1,"error":"rpc error: code = DeadlineExceeded desc = context deadline exceeded"}
{"level":"warn","ts":"2023-02-09T07:56:34.176+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = context deadline exceeded"}
{"level":"info","ts":"2023-02-09T07:56:34.176+0800","logger":"etcd-client","caller":"[email protected]/client.go:207","msg":"Auto sync endpoints failed.","error":"context deadline exceeded"}
{"level":"warn","ts":"2023-02-09T07:56:36.179+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = context deadline exceeded"}
{"level":"warn","ts":"2023-02-09T07:56:42.181+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = context deadline exceeded"}
{"level":"warn","ts":"2023-02-09T07:56:48.182+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59284->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:56:54.182+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59284->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:00.183+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59284->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:06.184+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59284->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:09.177+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59284->127.0.0.1:2379: use of closed network connection\""}
{"level":"info","ts":"2023-02-09T07:57:09.177+0800","logger":"etcd-client","caller":"[email protected]/client.go:207","msg":"Auto sync endpoints failed.","error":"context deadline exceeded"}
{"level":"warn","ts":"2023-02-09T07:57:12.185+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59582->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:18.185+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59582->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:24.187+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59582->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:30.188+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59582->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:36.189+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59878->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:42.190+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59878->127.0.0.1:2379: use of closed network connection\""}
{"level":"warn","ts":"2023-02-09T07:57:44.177+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59878->127.0.0.1:2379: use of closed network connection\""}
{"level":"info","ts":"2023-02-09T07:57:44.177+0800","logger":"etcd-client","caller":"[email protected]/client.go:207","msg":"Auto sync endpoints failed.","error":"context deadline exceeded"}
{"level":"warn","ts":"2023-02-09T07:57:48.192+0800","logger":"etcd-client","caller":"[email protected]/retry_interceptor.go:62","msg":"retrying of unary invoker failed","target":"etcd-endpoints://0xc000958a80/127.0.0.1:2379","attempt":0,"error":"rpc error: code = DeadlineExceeded desc = latest balancer error: last connection error: connection error: desc = \"error reading server preface: read tcp 127.0.0.1:59878->127.0.0.1:2379: use of closed network connection\""}

As it's not an issue of TiKV-CDC, I think we can just skip the check_count 2 "pd".

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
type/bug Something isn't working
Projects
None yet
Development

Successfully merging a pull request may close this issue.

1 participant