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

Flaky test: 6/10k: Test/CZServerSocketMetricsStreamsAndMessagesCount #5344

Open
menghanl opened this issue May 10, 2022 · 5 comments
Open

Flaky test: 6/10k: Test/CZServerSocketMetricsStreamsAndMessagesCount #5344

menghanl opened this issue May 10, 2022 · 5 comments
Assignees
Labels
Area: Observability Includes Stats, Tracing, Channelz, Healthz, Binlog, Reflection, Admin, GCP Observability fixit P2 Type: Testing

Comments

@menghanl
Copy link
Contributor

https://github.com/grpc/grpc-go/runs/6329576820?check_suite_focus=true

--- FAIL: Test (173.15s)
    --- FAIL: Test/CZServerSocketMetricsStreamsAndMessagesCount (10.60s)
        channelz_test.go:1225: Running test in tcp-clear environment...
        tlogger.go:116: INFO server.go:588 [core] [Server #1] Server created  (t=+1.119807ms)
        tlogger.go:116: INFO clientconn.go:105 [core] [Channel #2] Channel created  (t=+1.434509ms)
        tlogger.go:116: INFO clientconn.go:1555 [core] [Channel #2] original dial target is: "passthrough:///localhost:40813"  (t=+1.599011ms)
        tlogger.go:116: INFO server.go:776 [core] [Server #1 ListenSocket #3] ListenSocket created  (t=+1.821212ms)
        tlogger.go:116: INFO clientconn.go:1562 [core] [Channel #2] parsed dial target is: {Scheme:passthrough Authority: Endpoint:localhost:40813 URL:{Scheme:passthrough Opaque: User: Host: Path:/localhost:40813 RawPath: ForceQuery:false RawQuery: Fragment: RawFragment:}}  (t=+3.613923ms)
        tlogger.go:116: INFO clientconn.go:259 [core] [Channel #2] Channel authority set to "localhost:40813"  (t=+3.899025ms)
        tlogger.go:116: INFO resolver_conn_wrapper.go:175 [core] [Channel #2] Resolver state updated: {
              "Addresses": [
                {
                  "Addr": "localhost:40813",
                  "ServerName": "",
                  "Attributes": null,
                  "BalancerAttributes": null,
                  "Type": 0,
                  "Metadata": null
                }
              ],
              "ServiceConfig": null,
              "Attributes": null
            } (resolver returned new addresses)  (t=+4.276828ms)
        tlogger.go:116: INFO balancer_conn_wrappers.go:271 [core] [Channel #2] Channel switches to new LB policy "round_robin"  (t=+4.536429ms)
        tlogger.go:116: INFO clientconn.go:721 [core] [Channel #2 SubChannel #4] Subchannel created  (t=+4.927732ms)
        tlogger.go:116: INFO clientconn.go:1078 [core] [Channel #2 SubChannel #4] Subchannel Connectivity change to CONNECTING  (t=+5.285334ms)
        tlogger.go:116: INFO clientconn.go:1189 [core] [Channel #2 SubChannel #4] Subchannel picks a new address "localhost:40813" to connect  (t=+5.477135ms)
        tlogger.go:116: INFO clientconn.go:414 [core] [Channel #2] Channel Connectivity change to CONNECTING  (t=+5.911938ms)
        tlogger.go:116: INFO clientconn.go:1078 [core] [Channel #2 SubChannel #4] Subchannel Connectivity change to READY  (t=+10.761669ms)
        tlogger.go:116: INFO roundrobin.go:50 [roundrobin] roundrobinPicker: Build called with info: {map[0xc00009b700:{{
              "Addr": "localhost:40813",
              "ServerName": "",
              "Attributes": null,
              "BalancerAttributes": null,
              "Type": 0,
              "Metadata": null
            }}]}  (t=+11.339273ms)
        tlogger.go:116: INFO clientconn.go:414 [core] [Channel #2] Channel Connectivity change to READY  (t=+23.199949ms)
        channelz_test.go:1275: server socket metric with ID 5, want (StreamsStarted, StreamsSucceeded, StreamsFailed, MessagesSent, MessagesReceived) = (3, 2, 1, 2, 2), got (3, 3, 1, 2, 2)
        tlogger.go:116: INFO clientconn.go:414 [core] [Channel #2] Channel Connectivity change to SHUTDOWN  (t=+10.57529787s)
        tlogger.go:116: INFO clientconn.go:1078 [core] [Channel #2 SubChannel #4] Subchannel Connectivity change to SHUTDOWN  (t=+10.575934[174](https://github.com/grpc/grpc-go/runs/6329576820?check_suite_focus=true#step:8:174)s)
        tlogger.go:116: INFO clientconn.go:1433 [core] [Channel #2 SubChannel #4] Subchannel deleted  (t=+10.576205[176](https://github.com/grpc/grpc-go/runs/6329576820?check_suite_focus=true#step:8:176)s)
        tlogger.go:116: INFO clientconn.go:1033 [core] [Channel #2] Channel deleted  (t=+10.576292976s)
        tlogger.go:116: INFO server.go:727 [core] [Server #1 ListenSocket #3] ListenSocket deleted  (t=+10.576668979s)
        tlogger.go:116: INFO types.go:68 [channelz] attempt to delete child with id 4 from a parent (id=2) that doesn't currently exist  (t=+10.577411[184](https://github.com/grpc/grpc-go/runs/6329576820?check_suite_focus=true#step:8:184)s)
        tlogger.go:116: INFO types.go:68 [channelz] attempt to delete child with id 4 from a parent (id=2) that doesn't currently exist  (t=+10.577557985s)
@zasweq
Copy link
Contributor

zasweq commented Sep 8, 2022

@arvindbr8
Copy link
Member

arvindbr8 commented Nov 28, 2022

Flaked 6 out of 10K times in a test run

prioritizing this for fixit week

@dfawley dfawley changed the title Flaky test: Test/CZServerSocketMetricsStreamsAndMessagesCount Flaky test: 6/10k: Test/CZServerSocketMetricsStreamsAndMessagesCount Nov 28, 2022
@easwars
Copy link
Contributor

easwars commented Apr 5, 2023

@dfawley
Copy link
Member

dfawley commented Aug 3, 2023

@dfawley dfawley self-assigned this Sep 18, 2023
@dfawley
Copy link
Member

dfawley commented Sep 18, 2023

I'll take a look at this as part of my channelz reworking.

@arjan-bal arjan-bal added the Area: Observability Includes Stats, Tracing, Channelz, Healthz, Binlog, Reflection, Admin, GCP Observability label Sep 4, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Area: Observability Includes Stats, Tracing, Channelz, Healthz, Binlog, Reflection, Admin, GCP Observability fixit P2 Type: Testing
Projects
None yet
Development

No branches or pull requests

6 participants