c-ares with grpclb is broken since C# 2.36 #25654
Description
What version of gRPC and what language are you using?
gRPC C# 2.36
What operating system (Linux, Windows,...) and version?
Windows 10 v1909
What runtime / compiler are you using (e.g. python version or version of gcc)
.NET Core 3.1.12
What did you do?
We use c-ares with grpclb as workaround for the missing name resolution feature in C# (see #19874 and #11879).
We plan to switch to xDS once it's out of experimental. We know, that grpclb support will be removed in the future once xds is ready.
The target to resolve is dns://127.0.0.1:8010/MqttInterface.li.my-company.com and the channel is using the following options:
grpc.lb_policy_name = grpclb
grpc.service_config = {"serviceConfig":{"load_balancing_config":{ "grpclb": {} },"waitForReady":true}}
grpc.dns_enable_srv_queries = 1
What did you expect to see?
Behavior with gRPC 2.35:
D0309 09:48:18.577281 Grpc.Core.Internal.NativeExtension gRPC native library loaded successfully.
D0309 09:48:18.590310 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "grpclb"
D0309 09:48:18.590368 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "priority_experimental"
D0309 09:48:18.590385 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "weighted_target_experimental"
D0309 09:48:18.590401 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "pick_first"
D0309 09:48:18.590416 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "round_robin"
D0309 09:48:18.590435 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:500: Using ares dns resolver
D0309 09:48:18.590487 0 xds\certificate_provider_registry.cc:33: registering certificate provider factory for "file_watcher"
D0309 09:48:18.590509 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "cds_experimental"
D0309 09:48:18.590524 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "xds_cluster_impl_experimental"
D0309 09:48:18.590539 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "xds_cluster_resolver_experimental"
D0309 09:48:18.590553 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "xds_cluster_manager_experimental"
D0309 09:48:22.475801 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:178: (c-ares resolver) resolver:00000239FB5CD210 AresDnsResolver::StartLocked() is called.
D0309 09:48:22.475835 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1086: (c-ares resolver) request:00000239FB4E33E0 c-ares grpc_dns_lookup_ares_locked_impl name=MqttInterface.li.my-company.com, default_port=https
D0309 09:48:22.482378 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:541: (c-ares resolver) request:00000239FB4E33E0 grpc_ares_ev_driver_create_locked
D0309 09:48:22.482412 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:866: (c-ares resolver) request:00000239FB4E33E0 Using DNS server 127.0.0.1:8010
D0309 09:48:22.482584 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:644: (c-ares resolver) request:00000239FB4E33E0 create_hostbyname_request_locked host:MqttInterface.li.my-company.com port:47873 is_balancer:0 qtype:AAAA
D0309 09:48:22.482819 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:766: (c-ares resolver) fd:|c-ares socket: 2704| created with params af:2 type:2 protocol:0
D0309 09:48:22.482841 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:490: (c-ares resolver) fd:c-ares socket: 2704 ConnectUDP
D0309 09:48:22.483032 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:501: (c-ares resolver) fd:c-ares socket: 2704 WSAConnect error code:|0| msg:|The operation completed successfully.
|
D0309 09:48:22.483060 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2704| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:22.483074 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2704| SendVUDP called
D0309 09:48:22.483257 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2704| SendWriteBuf WSASend buf.len:51 *bytes_sent_ptr:51 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:22.483279 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:644: (c-ares resolver) request:00000239FB4E33E0 create_hostbyname_request_locked host:MqttInterface.li.my-company.com port:47873 is_balancer:0 qtype:A
D0309 09:48:22.483412 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2704| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:22.483430 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2704| SendVUDP called
D0309 09:48:22.483499 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2704| SendWriteBuf WSASend buf.len:51 *bytes_sent_ptr:51 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:22.483614 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2704| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:22.483632 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2704| SendVUDP called
D0309 09:48:22.483704 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2704| SendWriteBuf WSASend buf.len:64 *bytes_sent_ptr:64 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:22.483723 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:431: (c-ares resolver) request:00000239FB4E33E0 new fd: c-ares socket: 2704
D0309 09:48:22.483735 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E33E0 Ref ev_driver 00000239FB5543A0
D0309 09:48:22.483748 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E33E0 notify read on: c-ares socket: 2704
D0309 09:48:22.483770 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:506: (c-ares resolver) request:00000239FB4E33E0 ev_driver=00000239FB5543A0 grpc_ares_ev_driver_start_locked. timeout in 123892 ms
D0309 09:48:22.483781 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E33E0 Ref ev_driver 00000239FB5543A0
D0309 09:48:22.483793 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:268: (c-ares resolver) request:00000239FB4E33E0 ev_driver=00000239FB5543A0. next ares process poll time in 1000 ms
D0309 09:48:22.483803 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E33E0 Ref ev_driver 00000239FB5543A0
D0309 09:48:22.483817 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:442: (c-ares resolver) resolver:00000239FB5CD210 Started resolving. pending_request_:00000239FB4E33E0
D0309 09:48:22.483829 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2704| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:22.483865 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2704| RegisterForOnReadableLocked WSARecvFrom error code:|10054| msg:|An existing connection was forcibly closed by the remote host.
|
D0309 09:48:22.483896 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E33E0 readable on c-ares socket: 2704
D0309 09:48:22.483915 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:727: (c-ares resolver) request:00000239FB4E33E0 on_hostbyname_done_locked: C-ares status is not ARES_SUCCESS qtype=AAAA name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled
D0309 09:48:22.483933 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:727: (c-ares resolver) request:00000239FB4E33E0 on_hostbyname_done_locked: C-ares status is not ARES_SUCCESS qtype=A name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled
D0309 09:48:22.483954 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:771: (c-ares resolver) request:00000239FB4E33E0 on_srv_query_done_locked: C-ares status is not ARES_SUCCESS qtype=SRV name=_grpclb._tcp.MqttInterface.li.my-company.com: DNS query cancelled
D0309 09:48:22.483968 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E33E0 Unref ev_driver 00000239FB5543A0
D0309 09:48:22.484071 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:204: (c-ares resolver) request:00000239FB4E33E0 delete fd: c-ares socket: 2704
D0309 09:48:22.484085 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:490: (c-ares resolver) request:00000239FB4E33E0 ev driver stop working
D0309 09:48:22.484102 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E33E0 Unref ev_driver 00000239FB5543A0
D0309 09:48:22.484119 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:277: (c-ares resolver) request:00000239FB4E33E0 ev_driver=00000239FB5543A0 on_timeout_locked. driver->shutting_down=1. err="Cancelled"
D0309 09:48:22.484133 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E33E0 Unref ev_driver 00000239FB5543A0
D0309 09:48:22.484148 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:319: (c-ares resolver) request:00000239FB4E33E0 ev_driver=00000239FB5543A0 on_ares_backup_poll_alarm_locked. driver->shutting_down=1. err="Cancelled"
D0309 09:48:22.484159 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E33E0 Unref ev_driver 00000239FB5543A0
D0309 09:48:22.484172 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:194: (c-ares resolver) request:00000239FB4E33E0 destroy ev_driver 00000239FB5543A0
D0309 09:48:22.484184 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:805: (c-ares resolver) CloseSocket called for socket: c-ares socket: 2704
D0309 09:48:22.484245 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:366: (c-ares resolver) resolver:00000239FB5CD210 dns resolution failed: {"created":"@1615279702.483000000","description":"C-ares status is not ARES_SUCCESS qtype=SRV name=_grpclb._tcp.MqttInterface.li.my-company.com: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":772,"referenced_errors":[{"created":"@1615279702.483000000","description":"C-ares status is not ARES_SUCCESS qtype=A name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":728,"referenced_errors":[{"created":"@1615279702.483000000","description":"C-ares status is not ARES_SUCCESS qtype=AAAA name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":728}]}]}
D0309 09:48:22.484291 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:377: (c-ares resolver) resolver:00000239FB5CD210 dns resolution failed (will retry): {"created":"@1615279702.483000000","description":"C-ares status is not ARES_SUCCESS qtype=SRV name=_grpclb._tcp.MqttInterface.li.my-company.com: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":772,"referenced_errors":[{"created":"@1615279702.483000000","description":"C-ares status is not ARES_SUCCESS qtype=A name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":728,"referenced_errors":[{"created":"@1615279702.483000000","description":"C-ares status is not ARES_SUCCESS qtype=AAAA name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":728}]}]}
D0309 09:48:22.484316 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:386: (c-ares resolver) resolver:00000239FB5CD210 retrying in 1000 milliseconds
D0309 09:48:22.588324 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:178: (c-ares resolver) resolver:00000239FB5CDC90 AresDnsResolver::StartLocked() is called.
D0309 09:48:22.588362 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1086: (c-ares resolver) request:00000239FB4E2960 c-ares grpc_dns_lookup_ares_locked_impl name=127.0.0.1:8010, default_port=https
D0309 09:48:22.588581 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:442: (c-ares resolver) resolver:00000239FB5CDC90 Started resolving. pending_request_:00000239FB4E2960
I0309 09:48:22.589667 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 00000239FB7E1CE0 for subchannel 00000239FB5C6E60
D0309 09:48:22.775720 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:178: (c-ares resolver) resolver:00000239FB5CE710 AresDnsResolver::StartLocked() is called.
D0309 09:48:22.775758 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1086: (c-ares resolver) request:00000239FB4E4410 c-ares grpc_dns_lookup_ares_locked_impl name=127.0.0.1:64527, default_port=https
D0309 09:48:22.775946 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:442: (c-ares resolver) resolver:00000239FB5CE710 Started resolving. pending_request_:00000239FB4E4410
I0309 09:48:22.776730 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 00000239F9E8A4E0 for subchannel 00000239FC81E4D0
D0309 09:48:22.793284 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:171: (c-ares resolver) resolver:00000239FB5CE710 destroying AresDnsResolver
D0309 09:48:22.794736 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:178: (c-ares resolver) resolver:00000239FB5CE710 AresDnsResolver::StartLocked() is called.
D0309 09:48:22.794773 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1086: (c-ares resolver) request:00000239FB4E43A0 c-ares grpc_dns_lookup_ares_locked_impl name=127.0.0.1:64528, default_port=https
D0309 09:48:22.795002 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:442: (c-ares resolver) resolver:00000239FB5CE710 Started resolving. pending_request_:00000239FB4E43A0
I0309 09:48:22.795902 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 00000239F8D253D0 for subchannel 00000239FC81E4D0
D0309 09:48:22.796933 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:171: (c-ares resolver) resolver:00000239FB5CE710 destroying AresDnsResolver
D0309 09:48:23.486413 2447584681728 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:216: (c-ares resolver) resolver:00000239FB5CD210 re-resolution timer fired. error: "No Error". shutdown_initiated_: 0
D0309 09:48:23.487160 2447584681728 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:221: (c-ares resolver) resolver:00000239FB5CD210 start resolving due to re-resolution timer
D0309 09:48:23.487240 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1086: (c-ares resolver) request:00000239FB4E0DD0 c-ares grpc_dns_lookup_ares_locked_impl name=MqttInterface.li.my-company.com, default_port=https
D0309 09:48:23.492676 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:541: (c-ares resolver) request:00000239FB4E0DD0 grpc_ares_ev_driver_create_locked
D0309 09:48:23.492710 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:866: (c-ares resolver) request:00000239FB4E0DD0 Using DNS server 127.0.0.1:8010
D0309 09:48:23.492781 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:644: (c-ares resolver) request:00000239FB4E0DD0 create_hostbyname_request_locked host:MqttInterface.li.my-company.com port:47873 is_balancer:0 qtype:AAAA
D0309 09:48:23.492970 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:766: (c-ares resolver) fd:|c-ares socket: 2852| created with params af:2 type:2 protocol:0
D0309 09:48:23.492987 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:490: (c-ares resolver) fd:c-ares socket: 2852 ConnectUDP
D0309 09:48:23.493108 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:501: (c-ares resolver) fd:c-ares socket: 2852 WSAConnect error code:|0| msg:|The operation completed successfully.
|
D0309 09:48:23.493126 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.493140 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.493368 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:51 *bytes_sent_ptr:51 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.493396 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:644: (c-ares resolver) request:00000239FB4E0DD0 create_hostbyname_request_locked host:MqttInterface.li.my-company.com port:47873 is_balancer:0 qtype:A
D0309 09:48:23.493626 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.493656 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.493731 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:51 *bytes_sent_ptr:51 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.493751 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.493762 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.493822 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:64 *bytes_sent_ptr:64 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.493838 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:431: (c-ares resolver) request:00000239FB4E0DD0 new fd: c-ares socket: 2852
D0309 09:48:23.493849 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.493860 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.493876 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:506: (c-ares resolver) request:00000239FB4E0DD0 ev_driver=00000239FB6F5B90 grpc_ares_ev_driver_start_locked. timeout in 124900 ms
D0309 09:48:23.493945 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.493962 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:268: (c-ares resolver) request:00000239FB4E0DD0 ev_driver=00000239FB6F5B90. next ares process poll time in 1000 ms
D0309 09:48:23.493974 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.493989 2447584681728 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:442: (c-ares resolver) resolver:00000239FB5CD210 Started resolving. pending_request_:00000239FB4E0DD0
D0309 09:48:23.494001 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.494031 2447584681728 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2852| RegisterForOnReadableLocked WSARecvFrom error code:|997| msg:|Overlapped I/O operation is in progress.
|
D0309 09:48:23.565127 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|51|
D0309 09:48:23.565159 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.565173 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|51|
D0309 09:48:23.565201 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.565212 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.565295 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:77 *bytes_sent_ptr:77 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.565316 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.565333 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.565347 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.565361 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.565376 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.565411 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2852| RegisterForOnReadableLocked WSARecvFrom error code:|997| msg:|Overlapped I/O operation is in progress.
|
D0309 09:48:23.610843 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|51|
D0309 09:48:23.610873 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.610887 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|51|
D0309 09:48:23.610911 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.610921 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.610996 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:77 *bytes_sent_ptr:77 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.611017 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.611029 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.611040 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.611053 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.611066 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.611092 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2852| RegisterForOnReadableLocked WSARecvFrom error code:|997| msg:|Overlapped I/O operation is in progress.
|
D0309 09:48:23.620373 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|152|
D0309 09:48:23.620406 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.620427 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|152|
D0309 09:48:23.620442 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:741: (c-ares resolver) request:00000239FB4E0DD0 on_srv_query_done_locked name=_grpclb._tcp.MqttInterface.li.my-company.com ARES_SUCCESS
D0309 09:48:23.620454 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:745: (c-ares resolver) request:00000239FB4E0DD0 ares_parse_srv_reply: 0
D0309 09:48:23.620467 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:644: (c-ares resolver) request:00000239FB4E0DD0 create_hostbyname_request_locked host:lb.li.my-company.com port:18975 is_balancer:1 qtype:AAAA
D0309 09:48:23.620683 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.620721 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.620799 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:40 *bytes_sent_ptr:40 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.620815 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:644: (c-ares resolver) request:00000239FB4E0DD0 create_hostbyname_request_locked host:lb.li.my-company.com port:18975 is_balancer:1 qtype:A
D0309 09:48:23.620939 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.620956 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.621015 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:40 *bytes_sent_ptr:40 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.621032 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.621044 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.621058 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.621074 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.621086 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.621110 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|77|
D0309 09:48:23.621122 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.621134 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|77|
D0309 09:48:23.621152 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.621162 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.621222 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:68 *bytes_sent_ptr:68 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.621237 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.621264 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.621275 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.621287 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.621298 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.621316 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|77|
D0309 09:48:23.621332 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.621344 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|77|
D0309 09:48:23.621358 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.621367 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.621429 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:68 *bytes_sent_ptr:68 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.621447 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.621459 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.621469 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.621491 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.621503 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.621525 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|40|
D0309 09:48:23.621537 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.621548 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|40|
D0309 09:48:23.621561 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.621622 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.621680 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:66 *bytes_sent_ptr:66 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.621698 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.621709 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.621722 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.621739 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.621750 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.621774 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2852| RegisterForOnReadableLocked WSARecvFrom error code:|997| msg:|Overlapped I/O operation is in progress.
|
D0309 09:48:23.622447 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|78|
D0309 09:48:23.622469 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.622485 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|78|
D0309 09:48:23.622500 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:670: (c-ares resolver) request:00000239FB4E0DD0 on_hostbyname_done_locked qtype=A host=lb.li.my-company.com ARES_SUCCESS
D0309 09:48:23.622520 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:717: (c-ares resolver) request:00000239FB4E0DD0 c-ares resolver gets a AF_INET result:
addr: 127.0.0.1
port: 8010
D0309 09:48:23.622533 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.622546 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.622556 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.622568 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.622582 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.622603 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|68|
D0309 09:48:23.622631 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.622644 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|68|
D0309 09:48:23.622659 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.622669 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.622733 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:64 *bytes_sent_ptr:64 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.622753 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.622765 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.622775 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.622787 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.622798 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.622817 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|68|
D0309 09:48:23.622828 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.622840 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|68|
D0309 09:48:23.622856 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.622867 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.622922 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:64 *bytes_sent_ptr:64 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.622936 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.622947 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.622957 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.622982 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.622997 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.623015 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|66|
D0309 09:48:23.623026 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.623037 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|66|
D0309 09:48:23.623052 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.623062 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.623122 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:57 *bytes_sent_ptr:57 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.623145 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.623158 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.623170 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.623182 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.623193 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.623210 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|64|
D0309 09:48:23.623221 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.623234 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|64|
D0309 09:48:23.623251 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.623261 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.623321 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:77 *bytes_sent_ptr:77 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.623348 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.623360 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.623370 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.623382 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.623393 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.623414 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|64|
D0309 09:48:23.623426 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.623438 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|64|
D0309 09:48:23.623451 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.623460 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.623529 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:77 *bytes_sent_ptr:77 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.623543 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.623557 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.623568 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.623579 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.623592 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.623609 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|57|
D0309 09:48:23.623620 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.623632 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|57|
D0309 09:48:23.623657 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.623671 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.623730 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:53 *bytes_sent_ptr:53 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.623745 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.623756 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.623768 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.623779 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.623790 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.623810 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|77|
D0309 09:48:23.623822 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.623835 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|77|
D0309 09:48:23.623848 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.623858 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.623931 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:71 *bytes_sent_ptr:71 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.623947 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.623958 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.623971 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.623983 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.623994 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.624023 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|77|
D0309 09:48:23.624035 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.624047 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|77|
D0309 09:48:23.624060 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.624072 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.624133 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:71 *bytes_sent_ptr:71 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.624148 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.624159 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.624169 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.624180 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.624193 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.624210 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|53|
D0309 09:48:23.624227 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.624240 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|53|
D0309 09:48:23.624253 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.624264 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.624336 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:66 *bytes_sent_ptr:66 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.624351 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.624361 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.624384 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.624400 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.624411 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.624431 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|71|
D0309 09:48:23.624445 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.624459 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|71|
D0309 09:48:23.624473 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.624482 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.624549 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:64 *bytes_sent_ptr:64 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.624565 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.624577 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.624588 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.624599 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.624610 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.624627 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|71|
D0309 09:48:23.624640 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.624654 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|71|
D0309 09:48:23.624668 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.624678 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.624756 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:64 *bytes_sent_ptr:64 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.624783 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.624795 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.624806 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.624817 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.624833 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.624850 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|66|
D0309 09:48:23.624862 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.624873 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|66|
D0309 09:48:23.624886 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.624897 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.624965 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:60 *bytes_sent_ptr:60 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.624982 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.624994 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.625004 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.625017 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.625029 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.625045 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|64|
D0309 09:48:23.625057 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.625068 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|64|
D0309 09:48:23.625126 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:727: (c-ares resolver) request:00000239FB4E0DD0 on_hostbyname_done_locked: C-ares status is not ARES_SUCCESS qtype=AAAA name=MqttInterface.li.my-company.com is_balancer=0: DNS server returned answer with no data
D0309 09:48:23.625147 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.625159 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.625169 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.625183 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.625198 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.625216 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|64|
D0309 09:48:23.625227 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.625239 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|64|
D0309 09:48:23.625253 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:727: (c-ares resolver) request:00000239FB4E0DD0 on_hostbyname_done_locked: C-ares status is not ARES_SUCCESS qtype=A name=MqttInterface.li.my-company.com is_balancer=0: DNS server returned answer with no data
D0309 09:48:23.625267 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.625277 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.625294 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.625307 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.625319 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.625334 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|60|
D0309 09:48:23.625345 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.625356 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|60|
D0309 09:48:23.625382 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2852| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:48:23.625392 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2852| SendVUDP called
D0309 09:48:23.625439 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2852| SendWriteBuf WSASend buf.len:53 *bytes_sent_ptr:53 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:48:23.625454 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.625467 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:184: (c-ares resolver) request:00000239FB4E0DD0 Ref ev_driver 00000239FB6F5B90
D0309 09:48:23.625477 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:446: (c-ares resolver) request:00000239FB4E0DD0 notify read on: c-ares socket: 2852
D0309 09:48:23.625488 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.625499 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2852| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:48:23.625518 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2852| RegisterForOnReadableLocked WSARecvFrom error code:|997| msg:|Overlapped I/O operation is in progress.
|
D0309 09:48:23.625664 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2852| OnIocpReadable finishing. read buf length now:|53|
D0309 09:48:23.625683 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:356: (c-ares resolver) request:00000239FB4E0DD0 readable on c-ares socket: 2852
D0309 09:48:23.625696 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:1 Current read buf length:|53|
D0309 09:48:23.625711 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:727: (c-ares resolver) request:00000239FB4E0DD0 on_hostbyname_done_locked: C-ares status is not ARES_SUCCESS qtype=AAAA name=lb.li.my-company.com is_balancer=1: DNS server returned answer with no data
D0309 09:48:23.625727 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.625740 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2852| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:48:23.625801 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:204: (c-ares resolver) request:00000239FB4E0DD0 delete fd: c-ares socket: 2852
D0309 09:48:23.625814 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:490: (c-ares resolver) request:00000239FB4E0DD0 ev driver stop working
D0309 09:48:23.625827 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.625840 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:277: (c-ares resolver) request:00000239FB4E0DD0 ev_driver=00000239FB6F5B90 on_timeout_locked. driver->shutting_down=1. err="Cancelled"
D0309 09:48:23.625866 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.625881 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:319: (c-ares resolver) request:00000239FB4E0DD0 ev_driver=00000239FB6F5B90 on_ares_backup_poll_alarm_locked. driver->shutting_down=1. err="Cancelled"
D0309 09:48:23.625891 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:191: (c-ares resolver) request:00000239FB4E0DD0 Unref ev_driver 00000239FB6F5B90
D0309 09:48:23.625901 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:194: (c-ares resolver) request:00000239FB4E0DD0 destroy ev_driver 00000239FB6F5B90
D0309 09:48:23.625913 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:805: (c-ares resolver) CloseSocket called for socket: c-ares socket: 2852
I0309 09:48:23.626108 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:1347: [grpclb 00000239FB4D54D0] Will use 'MqttInterface.li.my-company.com' as the server name for LB request.
I0309 09:48:23.626243 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:1512: [grpclb 00000239FB4D54D0] Query for backends (lb_channel: 00000239F9E4B0D0, lb_calld: 00000239FC81DC90)
I0309 09:48:23.626257 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:822: [grpclb 00000239FB4D54D0] lb_calld=00000239FC81DC90: Starting LB call 00000239F9286720
I0309 09:48:23.626928 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 00000239FACB87C0 for subchannel 00000239FC81C110
I0309 09:48:24.725164 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:1062: [grpclb 00000239FB4D54D0] lb_calld=00000239FC81DC90: Received initial LB response message; client load reporting interval = 10000 milliseconds
I0309 09:48:25.133467 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:1082: [grpclb 00000239FB4D54D0] lb_calld=00000239FC81DC90: Serverlist with 1 servers received:
0: 127.0.0.1:64527 token=
I0309 09:48:25.133571 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:572: Missing LB token for backend address 'ipv4:127.0.0.1:64527'. The empty token will be used instead
I0309 09:48:25.133600 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:1639: [grpclb 00000239FB4D54D0] Created new child policy handler (00000239F8504DB0)
I0309 09:48:25.133612 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:1677: [grpclb 00000239FB4D54D0] Updating child policy handler 00000239F8504DB0
I0309 09:48:25.133629 0 filters\client_channel\lb_policy\child_policy_handler.cc:214: [child_policy_handler 00000239F8504DB0] creating new child policy round_robin
I0309 09:48:25.133650 0 filters\client_channel\lb_policy\child_policy_handler.cc:275: [child_policy_handler 00000239F8504DB0] created new LB policy "round_robin" (00000239F9D847E0)
I0309 09:48:25.133665 0 filters\client_channel\lb_policy\child_policy_handler.cc:233: [child_policy_handler 00000239F8504DB0] updating child policy 00000239F9D847E0
I0309 09:48:25.134168 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:714: [grpclb 00000239FB4D54D0 helper 00000239FACB8A80] state=CONNECTING (OK) wrapping child picker 00000239FC2D53E0 (serverlist=0000000000000000, client_stats=00000239F9D84920)
I0309 09:48:25.134639 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 00000239F9288A20 for subchannel 00000239FC81C7F0
I0309 09:48:25.134687 0 filters\client_channel\lb_policy\grpclb\grpclb.cc:714: [grpclb 00000239FB4D54D0 helper 00000239FACB8A80] state=READY (OK) wrapping child picker 00000239F9C85660 (serverlist=00000239FACC4C30, client_stats=00000239F9D84920)
D0309 09:48:26.781550 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:178: (c-ares resolver) resolver:00000239FC815A00 AresDnsResolver::StartLocked() is called.
D0309 09:48:26.781550 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:178: (c-ares resolver) resolver:00000239FC814C80 AresDnsResolver::StartLocked() is called.
D0309 09:48:26.781724 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1086: (c-ares resolver) request:00000239F99EE2C0 c-ares grpc_dns_lookup_ares_locked_impl name=127.0.0.1:8010, default_port=https
D0309 09:48:26.781727 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1086: (c-ares resolver) request:00000239F91EFE60 c-ares grpc_dns_lookup_ares_locked_impl name=127.0.0.1:8010, default_port=https
D0309 09:48:26.781979 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:442: (c-ares resolver) resolver:00000239FC815A00 Started resolving. pending_request_:00000239F99EE2C0
D0309 09:48:26.781981 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:442: (c-ares resolver) resolver:00000239FC814C80 Started resolving. pending_request_:00000239F91EFE60
I0309 09:48:26.782850 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 00000239F90D1B40 for subchannel 00000239FC81D450
I0309 09:48:27.886188 0 transport\chttp2\transport\chttp2_transport.cc:1715: ipv4:127.0.0.1:64535: Sending goaway err={"created":"@1615279707.882000000","description":"Server shutdown","file":"..\..\..\src\core\lib\surface\server.cc","file_line":468,"grpc_status":0}What did you see instead?
Behavior with gRPC 2.36:
D0309 09:49:40.129356 Grpc.Core.Internal.UnmanagedLibrary Attempting to load native library "K:\SVN_li_local3\grpc_csharp_ext.x64.dll"
D0309 09:49:40.253665 Grpc.Core.Internal.NativeExtension gRPC native library loaded successfully.
D0309 09:49:40.262070 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "grpclb"
D0309 09:49:40.262131 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "priority_experimental"
D0309 09:49:40.262163 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "weighted_target_experimental"
D0309 09:49:40.262186 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "pick_first"
D0309 09:49:40.262203 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "round_robin"
D0309 09:49:40.262227 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:491: Using ares dns resolver
D0309 09:49:40.262322 0 xds\certificate_provider_registry.cc:33: registering certificate provider factory for "file_watcher"
D0309 09:49:40.262347 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "cds_experimental"
D0309 09:49:40.262388 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "xds_cluster_impl_experimental"
D0309 09:49:40.262408 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "xds_cluster_resolver_experimental"
D0309 09:49:40.262433 0 filters\client_channel\lb_policy_registry.cc:42: registering LB policy factory for "xds_cluster_manager_experimental"
D0309 09:49:44.428507 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:169: (c-ares resolver) resolver:000001C46ADCD800 AresDnsResolver::StartLocked() is called.
D0309 09:49:44.428555 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1074: (c-ares resolver) request:000001C46ADB6300 c-ares grpc_dns_lookup_ares_locked_impl name=MqttInterface.li.my-company.com, default_port=https
D0309 09:49:44.435334 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:530: (c-ares resolver) request:000001C46ADB6300 grpc_ares_ev_driver_create_locked
D0309 09:49:44.435378 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:854: (c-ares resolver) request:000001C46ADB6300 Using DNS server 127.0.0.1:8010
D0309 09:49:44.435590 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:632: (c-ares resolver) request:000001C46ADB6300 create_hostbyname_request_locked host:MqttInterface.li.my-company.com port:47873 is_balancer:0 qtype:AAAA
D0309 09:49:44.435880 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:766: (c-ares resolver) fd:|c-ares socket: 2712| created with params af:2 type:2 protocol:0
D0309 09:49:44.435903 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:490: (c-ares resolver) fd:c-ares socket: 2712 ConnectUDP
D0309 09:49:44.436218 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:501: (c-ares resolver) fd:c-ares socket: 2712 WSAConnect error code:|0| msg:|The operation completed successfully.
|
D0309 09:49:44.436278 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2712| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:44.436300 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2712| SendVUDP called
D0309 09:49:44.436538 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2712| SendWriteBuf WSASend buf.len:51 *bytes_sent_ptr:51 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:44.436588 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:632: (c-ares resolver) request:000001C46ADB6300 create_hostbyname_request_locked host:MqttInterface.li.my-company.com port:47873 is_balancer:0 qtype:A
D0309 09:49:44.436770 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2712| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:44.436790 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2712| SendVUDP called
D0309 09:49:44.436910 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2712| SendWriteBuf WSASend buf.len:51 *bytes_sent_ptr:51 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:44.437071 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2712| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:44.437128 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2712| SendVUDP called
D0309 09:49:44.437222 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2712| SendWriteBuf WSASend buf.len:64 *bytes_sent_ptr:64 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:44.437238 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:429: (c-ares resolver) request:000001C46ADB6300 new fd: c-ares socket: 2712
D0309 09:49:44.437248 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46ADB6300 Ref ev_driver 000001C46AE5D710
D0309 09:49:44.437272 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:444: (c-ares resolver) request:000001C46ADB6300 notify read on: c-ares socket: 2712
D0309 09:49:44.437303 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:496: (c-ares resolver) request:000001C46ADB6300 ev_driver=000001C46AE5D710 grpc_ares_ev_driver_start_locked. timeout in 124174 ms
D0309 09:49:44.437315 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46ADB6300 Ref ev_driver 000001C46AE5D710
D0309 09:49:44.437350 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:266: (c-ares resolver) request:000001C46ADB6300 ev_driver=000001C46AE5D710. next ares process poll time in 1000 ms
D0309 09:49:44.437362 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46ADB6300 Ref ev_driver 000001C46AE5D710
D0309 09:49:44.437375 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:433: (c-ares resolver) resolver:000001C46ADCD800 Started resolving. pending_request_:000001C46ADB6300
D0309 09:49:44.437388 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2712| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:49:44.437467 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2712| RegisterForOnReadableLocked WSARecvFrom error code:|10054| msg:|An existing connection was forcibly closed by the remote host.
|
D0309 09:49:44.437524 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:354: (c-ares resolver) request:000001C46ADB6300 readable on c-ares socket: 2712
D0309 09:49:44.437549 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:715: (c-ares resolver) request:000001C46ADB6300 on_hostbyname_done_locked: C-ares status is not ARES_SUCCESS qtype=AAAA name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled
D0309 09:49:44.437566 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:715: (c-ares resolver) request:000001C46ADB6300 on_hostbyname_done_locked: C-ares status is not ARES_SUCCESS qtype=A name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled
D0309 09:49:44.437585 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:759: (c-ares resolver) request:000001C46ADB6300 on_srv_query_done_locked: C-ares status is not ARES_SUCCESS qtype=SRV name=_grpclb._tcp.MqttInterface.li.my-company.com: DNS query cancelled
D0309 09:49:44.437599 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:189: (c-ares resolver) request:000001C46ADB6300 Unref ev_driver 000001C46AE5D710
D0309 09:49:44.437708 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:202: (c-ares resolver) request:000001C46ADB6300 delete fd: c-ares socket: 2712
D0309 09:49:44.437752 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:189: (c-ares resolver) request:000001C46ADB6300 Unref ev_driver 000001C46AE5D710
D0309 09:49:44.437771 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:275: (c-ares resolver) request:000001C46ADB6300 ev_driver=000001C46AE5D710 on_timeout_locked. driver->shutting_down=1. err="Cancelled"
D0309 09:49:44.437799 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:189: (c-ares resolver) request:000001C46ADB6300 Unref ev_driver 000001C46AE5D710
D0309 09:49:44.437812 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:317: (c-ares resolver) request:000001C46ADB6300 ev_driver=000001C46AE5D710 on_ares_backup_poll_alarm_locked. driver->shutting_down=1. err="Cancelled"
D0309 09:49:44.437826 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:189: (c-ares resolver) request:000001C46ADB6300 Unref ev_driver 000001C46AE5D710
D0309 09:49:44.437839 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:192: (c-ares resolver) request:000001C46ADB6300 destroy ev_driver 000001C46AE5D710
D0309 09:49:44.437856 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:805: (c-ares resolver) CloseSocket called for socket: c-ares socket: 2712
D0309 09:49:44.437932 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:357: (c-ares resolver) resolver:000001C46ADCD800 dns resolution failed: {"created":"@1615279784.437000000","description":"C-ares status is not ARES_SUCCESS qtype=SRV name=_grpclb._tcp.MqttInterface.li.my-company.com: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":760,"referenced_errors":[{"created":"@1615279784.437000000","description":"C-ares status is not ARES_SUCCESS qtype=A name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":716,"referenced_errors":[{"created":"@1615279784.437000000","description":"C-ares status is not ARES_SUCCESS qtype=AAAA name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":716}]}]}
D0309 09:49:44.437979 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:368: (c-ares resolver) resolver:000001C46ADCD800 dns resolution failed (will retry): {"created":"@1615279784.437000000","description":"C-ares status is not ARES_SUCCESS qtype=SRV name=_grpclb._tcp.MqttInterface.li.my-company.com: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":760,"referenced_errors":[{"created":"@1615279784.437000000","description":"C-ares status is not ARES_SUCCESS qtype=A name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":716,"referenced_errors":[{"created":"@1615279784.437000000","description":"C-ares status is not ARES_SUCCESS qtype=AAAA name=MqttInterface.li.my-company.com is_balancer=0: DNS query cancelled","file":"filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc","file_line":716}]}]}
D0309 09:49:44.437995 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:377: (c-ares resolver) resolver:000001C46ADCD800 retrying in 1000 milliseconds
D0309 09:49:44.548291 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:169: (c-ares resolver) resolver:000001C46ADCB9D0 AresDnsResolver::StartLocked() is called.
D0309 09:49:44.548327 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1074: (c-ares resolver) request:000001C46BBCE860 c-ares grpc_dns_lookup_ares_locked_impl name=127.0.0.1:8010, default_port=https
D0309 09:49:44.548569 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:433: (c-ares resolver) resolver:000001C46ADCB9D0 Started resolving. pending_request_:000001C46BBCE860
I0309 09:49:44.549816 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 000001C46B073780 for subchannel 000001C4697BA1C0
D0309 09:49:44.733764 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:169: (c-ares resolver) resolver:000001C46ADC84A0 AresDnsResolver::StartLocked() is called.
D0309 09:49:44.733820 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1074: (c-ares resolver) request:000001C46B1BEC70 c-ares grpc_dns_lookup_ares_locked_impl name=127.0.0.1:64605, default_port=https
D0309 09:49:44.734155 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:433: (c-ares resolver) resolver:000001C46ADC84A0 Started resolving. pending_request_:000001C46B1BEC70
I0309 09:49:44.734971 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 000001C468ABFD20 for subchannel 000001C46C0C3670
D0309 09:49:44.747752 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:163: (c-ares resolver) resolver:000001C46ADC84A0 destroying AresDnsResolver
D0309 09:49:44.756994 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:169: (c-ares resolver) resolver:000001C46ADCA5B0 AresDnsResolver::StartLocked() is called.
D0309 09:49:44.757041 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1074: (c-ares resolver) request:000001C46B1AD5B0 c-ares grpc_dns_lookup_ares_locked_impl name=127.0.0.1:64606, default_port=https
D0309 09:49:44.757266 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:433: (c-ares resolver) resolver:000001C46ADCA5B0 Started resolving. pending_request_:000001C46B1AD5B0
I0309 09:49:44.758272 0 filters\client_channel\subchannel.cc:1113: New connected subchannel at 000001C46A2C2170 for subchannel 000001C46C0C2CD0
D0309 09:49:44.761321 0 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:163: (c-ares resolver) resolver:000001C46ADCA5B0 destroying AresDnsResolver
D0309 09:49:45.428942 1943131491344 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:207: (c-ares resolver) resolver:000001C46ADCD800 re-resolution timer fired. error: "No Error". shutdown_initiated_: 0
D0309 09:49:45.429147 1943131491344 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:212: (c-ares resolver) resolver:000001C46ADCD800 start resolving due to re-resolution timer
D0309 09:49:45.429166 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:1074: (c-ares resolver) request:000001C46B124E80 c-ares grpc_dns_lookup_ares_locked_impl name=MqttInterface.li.my-company.com, default_port=https
D0309 09:49:45.433874 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:530: (c-ares resolver) request:000001C46B124E80 grpc_ares_ev_driver_create_locked
D0309 09:49:45.433905 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:854: (c-ares resolver) request:000001C46B124E80 Using DNS server 127.0.0.1:8010
D0309 09:49:45.433928 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:632: (c-ares resolver) request:000001C46B124E80 create_hostbyname_request_locked host:MqttInterface.li.my-company.com port:47873 is_balancer:0 qtype:AAAA
D0309 09:49:45.434224 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:766: (c-ares resolver) fd:|c-ares socket: 2864| created with params af:2 type:2 protocol:0
D0309 09:49:45.434242 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:490: (c-ares resolver) fd:c-ares socket: 2864 ConnectUDP
D0309 09:49:45.434366 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:501: (c-ares resolver) fd:c-ares socket: 2864 WSAConnect error code:|0| msg:|The operation completed successfully.
|
D0309 09:49:45.434384 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2864| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:45.434398 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2864| SendVUDP called
D0309 09:49:45.434653 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2864| SendWriteBuf WSASend buf.len:51 *bytes_sent_ptr:51 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:45.434697 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:632: (c-ares resolver) request:000001C46B124E80 create_hostbyname_request_locked host:MqttInterface.li.my-company.com port:47873 is_balancer:0 qtype:A
D0309 09:49:45.434958 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2864| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:45.434990 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2864| SendVUDP called
D0309 09:49:45.435105 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2864| SendWriteBuf WSASend buf.len:51 *bytes_sent_ptr:51 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:45.435131 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2864| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:45.435146 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2864| SendVUDP called
D0309 09:49:45.435231 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2864| SendWriteBuf WSASend buf.len:64 *bytes_sent_ptr:64 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:45.435253 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:429: (c-ares resolver) request:000001C46B124E80 new fd: c-ares socket: 2864
D0309 09:49:45.435265 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46B124E80 Ref ev_driver 000001C46AE5CA10
D0309 09:49:45.435275 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:444: (c-ares resolver) request:000001C46B124E80 notify read on: c-ares socket: 2864
D0309 09:49:45.435291 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:496: (c-ares resolver) request:000001C46B124E80 ev_driver=000001C46AE5CA10 grpc_ares_ev_driver_start_locked. timeout in 125174 ms
D0309 09:49:45.435362 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46B124E80 Ref ev_driver 000001C46AE5CA10
D0309 09:49:45.435378 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:266: (c-ares resolver) request:000001C46B124E80 ev_driver=000001C46AE5CA10. next ares process poll time in 1000 ms
D0309 09:49:45.435392 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46B124E80 Ref ev_driver 000001C46AE5CA10
D0309 09:49:45.435406 1943131491344 filters\client_channel\resolver\dns\c_ares\dns_resolver_ares.cc:433: (c-ares resolver) resolver:000001C46ADCD800 Started resolving. pending_request_:000001C46B124E80
D0309 09:49:45.435418 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2864| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:49:45.435448 1943131491344 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2864| RegisterForOnReadableLocked WSARecvFrom error code:|997| msg:|Overlapped I/O operation is in progress.
|
D0309 09:49:45.505958 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2864| OnIocpReadable finishing. read buf length now:|51|
D0309 09:49:45.505998 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:354: (c-ares resolver) request:000001C46B124E80 readable on c-ares socket: 2864
D0309 09:49:45.506014 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:1 Current read buf length:|51|
D0309 09:49:45.506045 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2864| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:45.506056 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2864| SendVUDP called
D0309 09:49:45.506168 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2864| SendWriteBuf WSASend buf.len:77 *bytes_sent_ptr:77 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:45.506198 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:49:45.506215 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46B124E80 Ref ev_driver 000001C46AE5CA10
D0309 09:49:45.506229 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:444: (c-ares resolver) request:000001C46B124E80 notify read on: c-ares socket: 2864
D0309 09:49:45.506243 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:189: (c-ares resolver) request:000001C46B124E80 Unref ev_driver 000001C46AE5CA10
D0309 09:49:45.506258 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2864| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:49:45.506286 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2864| RegisterForOnReadableLocked WSARecvFrom error code:|997| msg:|Overlapped I/O operation is in progress.
|
D0309 09:49:45.547762 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2864| OnIocpReadable finishing. read buf length now:|51|
D0309 09:49:45.547792 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:354: (c-ares resolver) request:000001C46B124E80 readable on c-ares socket: 2864
D0309 09:49:45.547806 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:1 Current read buf length:|51|
D0309 09:49:45.547824 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2864| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:45.547834 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2864| SendVUDP called
D0309 09:49:45.547910 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2864| SendWriteBuf WSASend buf.len:77 *bytes_sent_ptr:77 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:45.547931 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|0|
D0309 09:49:45.547943 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46B124E80 Ref ev_driver 000001C46AE5CA10
D0309 09:49:45.547954 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:444: (c-ares resolver) request:000001C46B124E80 notify read on: c-ares socket: 2864
D0309 09:49:45.547966 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:189: (c-ares resolver) request:000001C46B124E80 Unref ev_driver 000001C46AE5CA10
D0309 09:49:45.547980 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:164: (c-ares resolver) fd:|c-ares socket: 2864| InnerContinueRegisterForOnReadableLocked wsa_connect_error_:0
D0309 09:49:45.548005 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:185: (c-ares resolver) fd:|c-ares socket: 2864| RegisterForOnReadableLocked WSARecvFrom error code:|997| msg:|Overlapped I/O operation is in progress.
|
D0309 09:49:45.557173 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:618: (c-ares resolver) fd:|c-ares socket: 2864| OnIocpReadable finishing. read buf length now:|152|
D0309 09:49:45.557210 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:354: (c-ares resolver) request:000001C46B124E80 readable on c-ares socket: 2864
D0309 09:49:45.557230 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:1 Current read buf length:|152|
D0309 09:49:45.557246 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:729: (c-ares resolver) request:000001C46B124E80 on_srv_query_done_locked name=_grpclb._tcp.MqttInterface.li.my-company.com ARES_SUCCESS
D0309 09:49:45.557259 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:733: (c-ares resolver) request:000001C46B124E80 ares_parse_srv_reply: 0
D0309 09:49:45.557272 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:632: (c-ares resolver) request:000001C46B124E80 create_hostbyname_request_locked host:lb.li.my-company.com port:18975 is_balancer:1 qtype:AAAA
D0309 09:49:45.557481 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2864| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:45.557518 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2864| SendVUDP called
D0309 09:49:45.557599 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2864| SendWriteBuf WSASend buf.len:40 *bytes_sent_ptr:40 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:45.557616 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:632: (c-ares resolver) request:000001C46B124E80 create_hostbyname_request_locked host:lb.li.my-company.com port:18975 is_balancer:1 qtype:A
D0309 09:49:45.557739 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:334: (c-ares resolver) fd:|c-ares socket: 2864| SendV called connect_done_:1 wsa_connect_error_:0
D0309 09:49:45.557755 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:358: (c-ares resolver) fd:|c-ares socket: 2864| SendVUDP called
D0309 09:49:45.557817 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:326: (c-ares resolver) fd:|c-ares socket: 2864| SendWriteBuf WSASend buf.len:40 *bytes_sent_ptr:40 overlapped:0000000000000000 return:0 *wsa_error_code:0
D0309 09:49:45.557832 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:182: (c-ares resolver) request:000001C46B124E80 Ref ev_driver 000001C46AE5CA10
D0309 09:49:45.557842 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_wrapper.cc:444: (c-ares resolver) request:000001C46B124E80 notify read on: c-ares socket: 2864
D0309 09:49:45.557863 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557878 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557890 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557901 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557912 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557925 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557936 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557950 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557962 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
D0309 09:49:45.557973 0 filters\client_channel\resolver\dns\c_ares\grpc_ares_ev_driver_windows.cc:272: (c-ares resolver) fd:|c-ares socket: 2864| RecvFrom called read_buf_has_data:0 Current read buf length:|4192|
...This line repeats every 8-12 µs until I kill the application.
Anything else we should know about your project / environment?
The logs were created with GRPC_TRACE=cares_resolver,glb and GRPC_VERBOSITY=DEBUG. I also removed the ..\..\..\src\core\ext\ lines to shorten the logs.
/CC: @apolcyn