# ./greeter_client D0101 02:33:27.146492703 1 ev_posix.cc:174] Using polling engine: poll D0101 02:33:27.146492703 1 dns_resolver_ares.cc:503] Using ares dns resolver E0101 02:33:27.146492703 1 trace.cc:65] Unknown trace var: 'client_channel' I0101 02:33:27.147493171 1 init.cc:165] grpc_init(void) I0101 02:33:27.147493171 1 init.cc:165] grpc_init(void) I0101 02:33:27.147493171 1 channel_create.cc:96] grpc_insecure_channel_create(target=localhost:50051, args=100c7990, reserved=0) I0101 02:33:27.147493171 1 init.cc:165] grpc_init(void) I0101 02:33:27.148493066 1 combiner.cc:56] grpc_combiner_create() function start I0101 02:33:27.148493066 1 combiner.cc:62] grpc_combiner_create() function end I0101 02:33:27.148493066 1 init.cc:165] grpc_init(void) I0101 02:33:27.148493066 1 init.cc:212] grpc_shutdown(void) I0101 02:33:27.148493066 1 init.cc:212] grpc_shutdown(void) I0101 02:33:27.148493066 1 init.cc:165] grpc_init(void) I0101 02:33:27.149492753 1 completion_queue.cc:525] grpc_completion_queue_create_internal(completion_type=1, polling_type=0) I0101 02:33:27.149492753 1 channel.cc:459] grpc_channel_create_registered_call(channel=10364300, parent_call=0, propagation_mask=ffff, completion_queue=10364090, registered_call_handle=1036cb70, deadline=gpr_timespec { tv_sec: 9223372036854775807, tv_nsec: 0, clock_type: 1 }, reserved=0) I0101 02:33:27.149492753 1 grpc_context.cc:28] grpc_census_call_set_context(call=10362860, census_context=0) I0101 02:33:27.149492753 1 call.cc:1967] grpc_call_start_batch(call=10362860, ops=100c7118, nops=6, tag=100c7590, reserved=0) I0101 02:33:27.149492753 1 call.cc:1565] ops[0]: SEND_INITIAL_METADATA(nil) I0101 02:33:27.149492753 1 call.cc:1565] ops[1]: SEND_MESSAGE ptr=103626b0 I0101 02:33:27.149492753 1 call.cc:1565] ops[2]: RECV_INITIAL_METADATA ptr=100c7a18 I0101 02:33:27.149492753 1 call.cc:1565] ops[3]: RECV_MESSAGE ptr=100c7630 I0101 02:33:27.150493273 1 call.cc:1565] ops[4]: SEND_CLOSE_FROM_CLIENT I0101 02:33:27.150493273 1 call.cc:1565] ops[5]: RECV_STATUS_ON_CLIENT metadata=100c7a50 status=100c7668 details=100c7670 I0101 02:33:27.150493273 1 call_combiner.cc:116] ==> CallCombiner::Start() [10362870] closure=10363768 [executing batch] error="No Error" D0101 02:33:27.150493273 1 dns_resolver_ares.cc:175] (c-ares resolver) resolver:10362560 AresDnsResolver::StartLocked() is called. D0101 02:33:27.150493273 1 grpc_ares_wrapper.cc:605] (c-ares resolver) request:1036de90 c-ares grpc_dns_lookup_ares_locked_impl name=localhost:50051, default_port=https D0101 02:33:27.151492856 1 grpc_ares_ev_driver.cc:157] (c-ares resolver) request:1036de90 grpc_ares_ev_driver_create_locked D0101 02:33:27.151492856 1 grpc_ares_wrapper.cc:160] (c-ares resolver) request:1036de90 create_hostbyname_request_locked host:localhost port:33731 is_balancer:0 D0101 02:33:27.151492856 1 grpc_ares_wrapper.cc:160] (c-ares resolver) request:1036de90 create_hostbyname_request_locked host:localhost port:33731 is_balancer:0 D0101 02:33:27.152493324 1 grpc_ares_ev_driver.cc:392] (c-ares resolver) request:1036de90 new fd: c-ares fd: 3 D0101 02:33:27.152493324 1 grpc_ares_ev_driver.cc:98] (c-ares resolver) request:1036de90 Ref ev_driver 10362460 D0101 02:33:27.152493324 1 grpc_ares_ev_driver.cc:407] (c-ares resolver) request:1036de90 notify read on: c-ares fd: 3 D0101 02:33:27.153493740 1 grpc_ares_ev_driver.cc:465] (c-ares resolver) request:1036de90 ev_driver=10362460 grpc_ares_ev_driver_start_locked. timeout in 120005 ms D0101 02:33:27.153493740 1 grpc_ares_ev_driver.cc:98] (c-ares resolver) request:1036de90 Ref ev_driver 10362460 D0101 02:33:27.153493740 1 grpc_ares_ev_driver.cc:230] (c-ares resolver) request:1036de90 ev_driver=10362460. next ares process poll time in 1000 ms D0101 02:33:27.153493740 1 grpc_ares_ev_driver.cc:98] (c-ares resolver) request:1036de90 Ref ev_driver 10362460 D0101 02:33:27.153493740 1 dns_resolver_ares.cc:445] (c-ares resolver) resolver:10362560 Started resolving. pending_request_:1036de90 D0101 02:33:27.154493062 1 grpc_ares_ev_driver.cc:313] (c-ares resolver) request:1036de90 readable on c-ares fd: 3 D0101 02:33:27.154493062 1 grpc_ares_ev_driver.cc:98] (c-ares resolver) request:1036de90 Ref ev_driver 10362460 D0101 02:33:27.154493062 1 grpc_ares_ev_driver.cc:407] (c-ares resolver) request:1036de90 notify read on: c-ares fd: 3 D0101 02:33:27.155493791 1 grpc_ares_ev_driver.cc:105] (c-ares resolver) request:1036de90 Unref ev_driver 10362460 D0101 02:33:27.155493791 1 grpc_ares_ev_driver.cc:313] (c-ares resolver) request:1036de90 readable on c-ares fd: 3 D0101 02:33:27.155493791 1 grpc_ares_wrapper.cc:243] (c-ares resolver) request:1036de90 on_hostbyname_done_locked host=localhost C-ares status is not ARES_SUCCESS: Could not contact DNS servers D0101 02:33:27.155493791 1 grpc_ares_ev_driver.cc:98] (c-ares resolver) request:1036de90 Ref ev_driver 10362460 D0101 02:33:27.156492645 1 grpc_ares_ev_driver.cc:407] (c-ares resolver) request:1036de90 notify read on: c-ares fd: 3 D0101 02:33:27.156492645 1 grpc_ares_ev_driver.cc:105] (c-ares resolver) request:1036de90 Unref ev_driver 10362460 D0101 02:33:27.156492645 1 grpc_ares_ev_driver.cc:313] (c-ares resolver) request:1036de90 readable on c-ares fd: 3 D0101 02:33:27.156492645 1 grpc_ares_ev_driver.cc:98] (c-ares resolver) request:1036de90 Ref ev_driver 10362460 D0101 02:33:27.156492645 1 grpc_ares_ev_driver.cc:407] (c-ares resolver) request:1036de90 notify read on: c-ares fd: 3 D0101 02:33:27.157493165 1 grpc_ares_ev_driver.cc:105] (c-ares resolver) request:1036de90 Unref ev_driver 10362460 D0101 02:33:27.157493165 1 grpc_ares_ev_driver.cc:313] (c-ares resolver) request:1036de90 readable on c-ares fd: 3 D0101 02:33:27.157493165 1 grpc_ares_wrapper.cc:243] (c-ares resolver) request:1036de90 on_hostbyname_done_locked host=localhost C-ares status is not ARES_SUCCESS: Could not contact DNS servers D0101 02:33:27.157493165 1 grpc_ares_ev_driver.cc:105] (c-ares resolver) request:1036de90 Unref ev_driver 10362460 D0101 02:33:27.158492956 1 grpc_ares_ev_driver.cc:119] (c-ares resolver) request:1036de90 delete fd: c-ares fd: 3 D0101 02:33:27.158492956 1 grpc_ares_ev_driver.cc:449] (c-ares resolver) request:1036de90 ev driver stop working D0101 02:33:27.158492956 1 grpc_ares_ev_driver.cc:105] (c-ares resolver) request:1036de90 Unref ev_driver 10362460 D0101 02:33:27.158492956 1 grpc_ares_ev_driver.cc:247] (c-ares resolver) request:1036de90 ev_driver=10362460 on_timeout_locked. driver->shutting_down=1. err="Cancelled" D0101 02:33:27.159493737 1 grpc_ares_ev_driver.cc:105] (c-ares resolver) request:1036de90 Unref ev_driver 10362460 D0101 02:33:27.159493737 1 grpc_ares_ev_driver.cc:276] (c-ares resolver) request:1036de90 ev_driver=10362460 on_ares_backup_poll_alarm_locked. driver->shutting_down=1. err="Cancelled" D0101 02:33:27.159493737 1 grpc_ares_ev_driver.cc:105] (c-ares resolver) request:1036de90 Unref ev_driver 10362460 D0101 02:33:27.159493737 1 grpc_ares_ev_driver.cc:108] (c-ares resolver) request:1036de90 destroy ev_driver 10362460 D0101 02:33:27.160493320 1 dns_resolver_ares.cc:367] (c-ares resolver) resolver:10362560 dns resolution failed: {"created":"@9207.157493165","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244,"referenced_errors":[{"created":"@9207.155493791","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244}]} D0101 02:33:27.160493320 1 dns_resolver_ares.cc:376] (c-ares resolver) resolver:10362560 dns resolution failed (will retry): {"created":"@9207.157493165","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244,"referenced_errors":[{"created":"@9207.155493791","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244}]} D0101 02:33:27.160493320 1 dns_resolver_ares.cc:385] (c-ares resolver) resolver:10362560 retrying in 1000 milliseconds I0101 02:33:27.160493320 1 call_combiner.cc:116] ==> CallCombiner::Start() [10362870] closure=103630a8 [failing recv_message_ready] error={"created":"@9207.160493320","description":"Failed to pick subchannel","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/client_channel.cc","file_line":3941,"referenced_errors":[{"created":"@9207.160493320","description":"Resolver transient failure","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolving_lb_policy.cc","file_line":262,"referenced_errors":[{"created":"@9207.160493320","description":"DNS resolution failed","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/dns_resolver_ares.cc","file_line":370,"grpc_status":14,"referenced_errors":[{"created":"@9207.157493165","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244,"referenced_errors":[{"created":"@9207.155493791","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244}]}]}]}]} I0101 02:33:27.161494257 1 call_combiner.cc:116] ==> CallCombiner::Start() [10362870] closure=10363248 [failing recv_trailing_metadata_ready] error={"created":"@9207.160493320","description":"Failed to pick subchannel","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/client_channel.cc","file_line":3941,"referenced_errors":[{"created":"@9207.160493320","description":"Resolver transient failure","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolving_lb_policy.cc","file_line":262,"referenced_errors":[{"created":"@9207.160493320","description":"DNS resolution failed","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/dns_resolver_ares.cc","file_line":370,"grpc_status":14,"referenced_errors":[{"created":"@9207.157493165","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244,"referenced_errors":[{"created":"@9207.155493791","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244}]}]}]}]} I0101 02:33:27.162492798 1 call_combiner.cc:116] ==> CallCombiner::Start() [10362870] closure=10363788 [failing on_complete] error={"created":"@9207.160493320","description":"Failed to pick subchannel","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/client_channel.cc","file_line":3941,"referenced_errors":[{"created":"@9207.160493320","description":"Resolver transient failure","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolving_lb_policy.cc","file_line":262,"referenced_errors":[{"created":"@9207.160493320","description":"DNS resolution failed","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/dns_resolver_ares.cc","file_line":370,"grpc_status":14,"referenced_errors":[{"created":"@9207.157493165","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244,"referenced_errors":[{"created":"@9207.155493791","description":"C-ares status is not ARES_SUCCESS: Could not contact DNS servers","file":"/local/mnt/workspace/gitlab/grpc/src/core/ext/filters/client_channel/resolver/dns/c_ares/grpc_ares_wrapper.cc","file_line":244}]}]}]}]} I0101 02:33:27.163493891 1 call_combiner.cc:116] ==> CallCombiner::Start() [10362870] closure=10370cf8 [executing batch] error="No Error" I0101 02:33:27.163493891 1 completion_queue.cc:788] cq_end_op_for_pluck(cq=10364090, tag=100c7590, error="No Error", done=101c3a90, done_arg=103636b0, storage=103636f8) I0101 02:33:27.163493891 1 completion_queue.cc:1319] RETURN_EVENT[10364090]: OP_COMPLETE: tag:100c7590 OK I0101 02:33:27.163493891 1 completion_queue.cc:1425] grpc_completion_queue_destroy(cq=10364090) I0101 02:33:27.164492380 1 completion_queue.cc:1419] grpc_completion_queue_shutdown(cq=10364090) I0101 02:33:27.164492380 1 init.cc:212] grpc_shutdown(void) 14: DNS resolution failed I0101 02:33:27.164492380 1 call.cc:573] grpc_call_unref(c=10362860) I0101 02:33:27.164492380 1 metadata_array.cc:34] grpc_metadata_array_destroy(array=100c7a50) I0101 02:33:27.164492380 1 metadata_array.cc:34] grpc_metadata_array_destroy(array=100c7a18) Greeter received: RPC failed I0101 02:33:27.164492380 1 channel.cc:510] grpc_channel_destroy(channel=10364300) D0101 02:33:27.164492380 1 dns_resolver_ares.cc:214] (c-ares resolver) resolver:10362560 re-resolution timer fired. error: "Cancelled". shutdown_initiated_: 1 D0101 02:33:27.164492380 1 dns_resolver_ares.cc:166] (c-ares resolver) resolver:10362560 destroying AresDnsResolver I0101 02:33:27.165492952 1 init.cc:212] grpc_shutdown(void) I0101 02:33:27.165492952 1 init.cc:212] grpc_shutdown(void)