Skip to content

Flaky test: Test/Watchers #8462

@arjan-bal

Description

@arjan-bal

This was added recently in #8428

https://github.com/grpc/grpc-go/actions/runs/16366627119/job/46245246201?pr=8459

FAIL: Test (5.47s)
    --- FAIL: Test/Watchers (5.01s)
        cdsbalancer_test.go:202: Created new snapshot cache...
        tlogger.go:126: INFO server.go:715 [core] [Server #632]Server created  (t=+212.5µs)
        cdsbalancer_test.go:202: Registered Aggregated Discovery Service (ADS)...
        cdsbalancer_test.go:202: xDS management server serving at: 127.0.0.1:46087...
        tlogger.go:126: INFO server.go:911 [core] [Server #632 ListenSocket #633]ListenSocket created  (t=+389.558µs)
        tlogger.go:126: INFO pool.go:289 [xds] xDS node ID: dd45cf54-c18a-4221-924f-7b34798b37fd  (t=+1.033897ms)
        tlogger.go:126: INFO clientconn.go:1687 [core] original dial target is: "whatever:///test.service"  (t=+1.080835ms)
        tlogger.go:126: INFO clientconn.go:333 [core] [Channel #634]Channel created  (t=+1.104048ms)
        tlogger.go:126: INFO clientconn.go:207 [core] [Channel #634]parsed dial target is: resolver.Target{URL:url.URL{Scheme:"whatever", Opaque:"", User:(*url.Userinfo)(nil), Host:"", Path:"/test.service", RawPath:"", OmitHost:false, ForceQuery:false, RawQuery:"", Fragment:"", RawFragment:""}}  (t=+1.128273ms)
        tlogger.go:126: INFO clientconn.go:208 [core] [Channel #634]Channel authority set to "test.service"  (t=+1.139093ms)
        tlogger.go:126: INFO resolver_wrapper.go:210 [core] [Channel #634]Resolver state updated: {
              "Addresses": null,
              "Endpoints": [],
              "ServiceConfig": {
                "Config": {
                  "Config": null,
                  "Methods": {}
                },
                "Err": null
              },
              "Attributes": {
        tlogger.go:126: INFO clientconn.go:1352 [core] [Channel #635 SubChannel #636]Subchannel picks a new address "127.0.0.1:46087" to connect  (t=+1.84289ms)
        tlogger.go:126: INFO clientconn.go:1233 [core] [Channel #635 SubChannel #636]Subchannel Connectivity change to READY  (t=+2.100659ms)
        tlogger.go:126: INFO clientconn.go:563 [core] [Channel #635]Channel Connectivity change to READY  (t=+2.128992ms)
        logging.go:30: nodeID "dd45cf54-c18a-4221-924f-7b34798b37fd" requested type.googleapis.com/envoy.config.cluster.v3.Cluster[cluster1] and known map[]. Diff [cluster1]
        logging.go:30: respond type.googleapis.com/envoy.config.cluster.v3.Cluster[cluster1] version "" with version "1"
        tlogger.go:126: INFO cdsbalancer.go:458 [xds] [cds-lb 0xc000548d80] Received Cluster resource: {
              "ClusterType": 2,
              "ClusterName": "cluster1",
              "EDSServiceName": "",
              "LRSServerConfig": null,
              "SecurityCfg": null,
              "MaxRequests": null,
              "DNSHostName": "",
              "PrioritizedClusterNames": [
                "cluster1-B",
                "cluster1-C"
              ],
              "LBPolicy": [
                {
                  "xds_wrr_locality_experimental": {
                    "childPolicy": [
                      {
                        "round_robin": {}
                      }
                    ]
                  }
                }
              ],
              "OutlierDetection": null,
              "Raw": {
                "type_url": "type.googleapis.com/envoy.config.cluster.v3.Cluster",
                "value": "CghjbHVzdGVyMbICgAEKGGVudm95LmNsdXN0ZXJzLmFnZ3JlZ2F0ZRJkCkh0eXBlLmdvb2dsZWFwaXMuY29tL2Vudm95LmV4dGVuc2lvbnMuY2x1c3RlcnMuYWdncmVnYXRlLnYzLkNsdXN0ZXJDb25maWcSGAoKY2x1c3RlcjEtQgoKY2x1c3RlcjEtQw=="
              },
              "TelemetryLabels": {
                "csm.service_name": "unknown",
                "csm.service_namespace_name": "unknown"
              }
            }  (t=+2.624498ms)
        logging.go:30: nodeID "dd45cf54-c18a-4221-924f-7b34798b37fd" requested type.googleapis.com/envoy.config.cluster.v3.Cluster[cluster1] and known map[cluster1:{}]. Diff []
        logging.go:30: open watch 1 for type.googleapis.com/envoy.config.cluster.v3.Cluster[cluster1] from nodeID "dd45cf54-c18a-4221-924f-7b34798b37fd", version "1"
        logging.go:30: nodeID "dd45cf54-c18a-4221-924f-7b34798b37fd" requested type.googleapis.com/envoy.config.cluster.v3.Cluster[cluster1 cluster1-B cluster1-C] and known map[cluster1:{}]. Diff [cluster1-B cluster1-C]
        logging.go:30: open watch 2 for type.googleapis.com/envoy.config.cluster.v3.Cluster[cluster1 cluster1-B cluster1-C] from nodeID "dd45cf54-c18a-4221-924f-7b34798b37fd", version "1"
        aggregate_cluster_test.go:900: Timeout when waiting for appropriate Cluster resources to be requested
        tlogger.go:126: INFO clientconn.go:563 [core] [Channel #634]Channel Connectivity change to SHUTDOWN  (t=+5.006006048s)
        tlogger.go:126: INFO resolver_wrapper.go:111 [core] [Channel #634]Closing the name resolver  (t=+5.006025615s)
        tlogger.go:126: INFO balancer_wrapper.go:160 [core] [Channel #634]ccBalancerWrapper: closing  (t=+5.006062945s)
        tlogger.go:126: WARNING ads_stream.go:453 [xds] [xds-client 0xc000039050] [xds-channel 0xc00086efa0] [ads-stream 0xc000590a00] Sending ADS request for type "type.googleapis.com/envoy.config.cluster.v3.Cluster", resources: [], version: "1", nonce: "1" failed: EOF  (t=+5.006208724s)
        tlogger.go:126: WARNING ads_stream.go:496 [xds] [xds-client 0xc000039050] [xds-channel 0xc00086efa0] [ads-stream 0xc000590a00] ADS stream closed: rpc error: code = Canceled desc = context canceled  (t=+5.006258788s)
        tlogger.go:126: INFO ads_stream.go:155 [xds] [xds-client 0xc000039050] [xds-channel 0xc00086efa0] [ads-stream 0xc000590a00] Shutdown ADS stream  (t=+5.006290928s)
        tlogger.go:126: INFO clientconn.go:563 [core] [Channel #635]Channel Connectivity change to SHUTDOWN  (t=+5.006317337s)
        tlogger.go:126: INFO resolver_wrapper.go:111 [core] [Channel #635]Closing the name resolver  (t=+5.0063296s)
        tlogger.go:126: INFO balancer_wrapper.go:160 [core] [Channel #635]ccBalancerWrapper: closing  (t=+5.006352573s)
        tlogger.go:126: INFO clientconn.go:1233 [core] [Channel #635 SubChannel #636]Subchannel Connectivity change to SHUTDOWN  (t=+5.006400263s)
        tlogger.go:126: INFO clientconn.go:1560 [core] [Channel #635 SubChannel #636]Subchannel deleted  (t=+5.006417314s)
        tlogger.go:126: INFO clientconn.go:333 [core] [Channel #635]Channel deleted  (t=+5.00654178s)
        tlogger.go:126: INFO channel.go:142 [xds] [xds-client 0xc000039050] [xds-channel 0xc00086efa0] Shutdown  (t=+5.00658982s)
        tlogger.go:126: INFO cdsbalancer.go:411 [xds] [cds-lb 0xc000548d80] Shutdown  (t=+5.006603626s)
        tlogger.go:126: INFO clientconn.go:333 [core] [Channel #634]Channel deleted  (t=+5.006613695s)
        tlogger.go:126: INFO xdsclient.go:207 [xds] [xds-client 0xc000039050] Shutdown  (t=+5.006644973s)
        tlogger.go:126: INFO server.go:847 [core] [Server #632 ListenSocket #633]ListenSocket deleted  (t=+5.006702421s)

Metadata

Metadata

Labels

Area: TestingIncludes tests and testing utilities that we have for unit and e2e tests within our repo.P1Type: Bug

Type

Projects

No projects

Milestone

No milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions