envoy inspect egress traffic

envoy_tls_ext_auth.log

[2022-01-03 22:34:38.504][1][info][main] [source/server/server.cc:368] initializing epoch 0 (base id=0, hot restart version=11.104)
[2022-01-03 22:34:38.504][1][info][main] [source/server/server.cc:370] statically linked extensions:
[2022-01-03 22:34:38.504][1][info][main] [source/server/server.cc:372]   envoy.filters.udp_listener: envoy.filters.udp.dns_filter, envoy.filters.udp_listener.udp_proxy
[2022-01-03 22:34:38.504][1][info][main] [source/server/server.cc:372]   envoy.grpc_credentials: envoy.grpc_credentials.aws_iam, envoy.grpc_credentials.default, envoy.grpc_credentials.file_based_metadata
[2022-01-03 22:34:38.504][1][info][main] [source/server/server.cc:372]   envoy.transport_sockets.upstream: envoy.transport_sockets.alts, envoy.transport_sockets.quic, envoy.transport_sockets.raw_buffer, envoy.transport_sockets.starttls, envoy.transport_sockets.tap, envoy.transport_sockets.tls, envoy.transport_sockets.upstream_proxy_protocol, raw_buffer, starttls, tls
[2022-01-03 22:34:38.504][1][info][main] [source/server/server.cc:372]   envoy.http.stateful_header_formatters: preserve_case
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.upstream_options: envoy.extensions.upstreams.http.v3.HttpProtocolOptions, envoy.upstreams.http.http_protocol_options
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.http.cache: envoy.extensions.http.cache.simple
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.resolvers: envoy.ip
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.tls.cert_validator: envoy.tls.cert_validator.default, envoy.tls.cert_validator.spiffe
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.thrift_proxy.filters: envoy.filters.thrift.rate_limit, envoy.filters.thrift.router
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.dubbo_proxy.protocols: dubbo
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.matching.action: composite-action, skip
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.internal_redirect_predicates: envoy.internal_redirect_predicates.allow_listed_routes, envoy.internal_redirect_predicates.previous_routes, envoy.internal_redirect_predicates.safe_cross_scheme
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.stats_sinks: envoy.dog_statsd, envoy.graphite_statsd, envoy.metrics_service, envoy.stat_sinks.dog_statsd, envoy.stat_sinks.graphite_statsd, envoy.stat_sinks.hystrix, envoy.stat_sinks.metrics_service, envoy.stat_sinks.statsd, envoy.stat_sinks.wasm, envoy.statsd
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.rate_limit_descriptors: envoy.rate_limit_descriptors.expr
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.thrift_proxy.transports: auto, framed, header, unframed
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.http.original_ip_detection: envoy.http.original_ip_detection.custom_header, envoy.http.original_ip_detection.xff
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.upstreams: envoy.filters.connection_pools.tcp.generic
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.bootstrap: envoy.bootstrap.wasm, envoy.extensions.network.socket_interface.default_socket_interface
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.thrift_proxy.protocols: auto, binary, binary/non-strict, compact, twitter
[2022-01-03 22:34:38.505][1][info][main] [source/server/server.cc:372]   envoy.access_loggers: envoy.access_loggers.file, envoy.access_loggers.http_grpc, envoy.access_loggers.open_telemetry, envoy.access_loggers.stderr, envoy.access_loggers.stdout, envoy.access_loggers.tcp_grpc, envoy.access_loggers.wasm, envoy.file_access_log, envoy.http_grpc_access_log, envoy.open_telemetry_access_log, envoy.stderr_access_log, envoy.stdout_access_log, envoy.tcp_grpc_access_log, envoy.wasm_access_log
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.filters.network: envoy.client_ssl_auth, envoy.echo, envoy.ext_authz, envoy.filters.network.client_ssl_auth, envoy.filters.network.connection_limit, envoy.filters.network.direct_response, envoy.filters.network.dubbo_proxy, envoy.filters.network.echo, envoy.filters.network.ext_authz, envoy.filters.network.http_connection_manager, envoy.filters.network.local_ratelimit, envoy.filters.network.mongo_proxy, envoy.filters.network.ratelimit, envoy.filters.network.rbac, envoy.filters.network.redis_proxy, envoy.filters.network.sni_cluster, envoy.filters.network.sni_dynamic_forward_proxy, envoy.filters.network.tcp_proxy, envoy.filters.network.thrift_proxy, envoy.filters.network.wasm, envoy.filters.network.zookeeper_proxy, envoy.http_connection_manager, envoy.mongo_proxy, envoy.ratelimit, envoy.redis_proxy, envoy.tcp_proxy
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.transport_sockets.downstream: envoy.transport_sockets.alts, envoy.transport_sockets.quic, envoy.transport_sockets.raw_buffer, envoy.transport_sockets.starttls, envoy.transport_sockets.tap, envoy.transport_sockets.tls, raw_buffer, starttls, tls
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.tracers: envoy.dynamic.ot, envoy.lightstep, envoy.tracers.datadog, envoy.tracers.dynamic_ot, envoy.tracers.lightstep, envoy.tracers.opencensus, envoy.tracers.skywalking, envoy.tracers.xray, envoy.tracers.zipkin, envoy.zipkin
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.guarddog_actions: envoy.watchdog.abort_action, envoy.watchdog.profile_action
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.quic.server.crypto_stream: envoy.quic.crypto_stream.server.quiche
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.clusters: envoy.cluster.eds, envoy.cluster.logical_dns, envoy.cluster.original_dst, envoy.cluster.static, envoy.cluster.strict_dns, envoy.clusters.aggregate, envoy.clusters.dynamic_forward_proxy, envoy.clusters.redis
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.health_checkers: envoy.health_checkers.redis
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.wasm.runtime: envoy.wasm.runtime.null, envoy.wasm.runtime.v8
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.formatter: envoy.formatter.metadata, envoy.formatter.req_without_query
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.request_id: envoy.request_id.uuid
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.resource_monitors: envoy.resource_monitors.fixed_heap, envoy.resource_monitors.injected_resource
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.retry_priorities: envoy.retry_priorities.previous_priorities
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.rbac.matchers: envoy.rbac.matchers.upstream.upstream_ip_port
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.matching.http.input: request-headers, request-trailers, response-headers, response-trailers
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.retry_host_predicates: envoy.retry_host_predicates.omit_canary_hosts, envoy.retry_host_predicates.omit_host_metadata, envoy.retry_host_predicates.previous_hosts
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.common.key_value: envoy.key_value.file_based
[2022-01-03 22:34:38.506][1][info][main] [source/server/server.cc:372]   envoy.compression.compressor: envoy.compression.brotli.compressor, envoy.compression.gzip.compressor
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.quic.proof_source: envoy.quic.proof_source.filter_chain
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.matching.input_matchers: envoy.matching.matchers.consistent_hashing, envoy.matching.matchers.ip
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.filters.http: envoy.bandwidth_limit, envoy.buffer, envoy.cors, envoy.csrf, envoy.ext_authz, envoy.ext_proc, envoy.fault, envoy.filters.http.adaptive_concurrency, envoy.filters.http.admission_control, envoy.filters.http.alternate_protocols_cache, envoy.filters.http.aws_lambda, envoy.filters.http.aws_request_signing, envoy.filters.http.bandwidth_limit, envoy.filters.http.buffer, envoy.filters.http.cache, envoy.filters.http.cdn_loop, envoy.filters.http.composite, envoy.filters.http.compressor, envoy.filters.http.cors, envoy.filters.http.csrf, envoy.filters.http.decompressor, envoy.filters.http.dynamic_forward_proxy, envoy.filters.http.dynamo, envoy.filters.http.ext_authz, envoy.filters.http.ext_proc, envoy.filters.http.fault, envoy.filters.http.grpc_http1_bridge, envoy.filters.http.grpc_http1_reverse_bridge, envoy.filters.http.grpc_json_transcoder, envoy.filters.http.grpc_stats, envoy.filters.http.grpc_web, envoy.filters.http.header_to_metadata, envoy.filters.http.health_check, envoy.filters.http.ip_tagging, envoy.filters.http.jwt_authn, envoy.filters.http.local_ratelimit, envoy.filters.http.lua, envoy.filters.http.oauth2, envoy.filters.http.on_demand, envoy.filters.http.original_src, envoy.filters.http.ratelimit, envoy.filters.http.rbac, envoy.filters.http.router, envoy.filters.http.set_metadata, envoy.filters.http.tap, envoy.filters.http.wasm, envoy.grpc_http1_bridge, envoy.grpc_json_transcoder, envoy.grpc_web, envoy.health_check, envoy.http_dynamo_filter, envoy.ip_tagging, envoy.local_rate_limit, envoy.lua, envoy.rate_limit, envoy.router, match-wrapper
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.dubbo_proxy.route_matchers: default
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.matching.common_inputs: envoy.matching.common_inputs.environment_variable
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.compression.decompressor: envoy.compression.brotli.decompressor, envoy.compression.gzip.decompressor
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.filters.listener: envoy.filters.listener.http_inspector, envoy.filters.listener.original_dst, envoy.filters.listener.original_src, envoy.filters.listener.proxy_protocol, envoy.filters.listener.tls_inspector, envoy.listener.http_inspector, envoy.listener.original_dst, envoy.listener.original_src, envoy.listener.proxy_protocol, envoy.listener.tls_inspector
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.dubbo_proxy.filters: envoy.filters.dubbo.router
[2022-01-03 22:34:38.507][1][info][main] [source/server/server.cc:372]   envoy.dubbo_proxy.serializers: dubbo.hessian2
[2022-01-03 22:34:38.532][1][info][main] [source/server/server.cc:390] HTTP header map info:
[2022-01-03 22:34:38.535][1][debug][runtime] [source/common/runtime/runtime_features.cc:31] Unable to use runtime singleton for feature envoy.http.headermap.lazy_map_min_size
[2022-01-03 22:34:38.535][1][debug][runtime] [source/common/runtime/runtime_features.cc:20] Unable to use runtime singleton for feature envoy.reloadable_features.header_map_correctly_coalesce_cookies
[2022-01-03 22:34:38.535][1][debug][runtime] [source/common/runtime/runtime_features.cc:31] Unable to use runtime singleton for feature envoy.http.headermap.lazy_map_min_size
[2022-01-03 22:34:38.535][1][debug][runtime] [source/common/runtime/runtime_features.cc:20] Unable to use runtime singleton for feature envoy.reloadable_features.header_map_correctly_coalesce_cookies
[2022-01-03 22:34:38.536][1][debug][runtime] [source/common/runtime/runtime_features.cc:31] Unable to use runtime singleton for feature envoy.http.headermap.lazy_map_min_size
[2022-01-03 22:34:38.537][1][debug][runtime] [source/common/runtime/runtime_features.cc:20] Unable to use runtime singleton for feature envoy.reloadable_features.header_map_correctly_coalesce_cookies
[2022-01-03 22:34:38.537][1][debug][runtime] [source/common/runtime/runtime_features.cc:31] Unable to use runtime singleton for feature envoy.http.headermap.lazy_map_min_size
[2022-01-03 22:34:38.537][1][debug][runtime] [source/common/runtime/runtime_features.cc:20] Unable to use runtime singleton for feature envoy.reloadable_features.header_map_correctly_coalesce_cookies
[2022-01-03 22:34:38.537][1][info][main] [source/server/server.cc:393]   request header map: 640 bytes: :authority,:method,:path,:protocol,:scheme,accept,accept-encoding,access-control-request-method,authentication,authorization,cache-control,cdn-loop,connection,content-encoding,content-length,content-type,expect,grpc-accept-encoding,grpc-timeout,if-match,if-modified-since,if-none-match,if-range,if-unmodified-since,keep-alive,origin,pragma,proxy-connection,referer,te,transfer-encoding,upgrade,user-agent,via,x-client-trace-id,x-envoy-attempt-count,x-envoy-decorator-operation,x-envoy-downstream-service-cluster,x-envoy-downstream-service-node,x-envoy-expected-rq-timeout-ms,x-envoy-external-address,x-envoy-force-trace,x-envoy-hedge-on-per-try-timeout,x-envoy-internal,x-envoy-ip-tags,x-envoy-max-retries,x-envoy-original-path,x-envoy-original-url,x-envoy-retriable-header-names,x-envoy-retriable-status-codes,x-envoy-retry-grpc-on,x-envoy-retry-on,x-envoy-upstream-alt-stat-name,x-envoy-upstream-rq-per-try-timeout-ms,x-envoy-upstream-rq-timeout-alt-response,x-envoy-upstream-rq-timeout-ms,x-envoy-upstream-stream-duration-ms,x-forwarded-client-cert,x-forwarded-for,x-forwarded-proto,x-ot-span-context,x-request-id
[2022-01-03 22:34:38.537][1][info][main] [source/server/server.cc:393]   request trailer map: 136 bytes: 
[2022-01-03 22:34:38.537][1][info][main] [source/server/server.cc:393]   response header map: 432 bytes: :status,access-control-allow-credentials,access-control-allow-headers,access-control-allow-methods,access-control-allow-origin,access-control-expose-headers,access-control-max-age,age,cache-control,connection,content-encoding,content-length,content-type,date,etag,expires,grpc-message,grpc-status,keep-alive,last-modified,location,proxy-connection,server,transfer-encoding,upgrade,vary,via,x-envoy-attempt-count,x-envoy-decorator-operation,x-envoy-degraded,x-envoy-immediate-health-check-fail,x-envoy-ratelimited,x-envoy-upstream-canary,x-envoy-upstream-healthchecked-cluster,x-envoy-upstream-service-time,x-request-id
[2022-01-03 22:34:38.537][1][info][main] [source/server/server.cc:393]   response trailer map: 160 bytes: grpc-message,grpc-status
[2022-01-03 22:34:38.541][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.shrink_heap.
[2022-01-03 22:34:38.542][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reduce_timeouts.
[2022-01-03 22:34:38.542][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.stop_accepting_connections.
[2022-01-03 22:34:38.542][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reject_incoming_connections.
[2022-01-03 22:34:38.542][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reset_high_memory_stream.
[2022-01-03 22:34:38.542][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reduce_timeouts.
[2022-01-03 22:34:38.542][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.stop_accepting_connections.
[2022-01-03 22:34:38.542][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reject_incoming_connections.
[2022-01-03 22:34:38.542][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reset_high_memory_stream.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reduce_timeouts.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.stop_accepting_connections.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reject_incoming_connections.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reset_high_memory_stream.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reduce_timeouts.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.stop_accepting_connections.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reject_incoming_connections.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reset_high_memory_stream.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reduce_timeouts.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.stop_accepting_connections.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reject_incoming_connections.
[2022-01-03 22:34:38.543][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reset_high_memory_stream.
[2022-01-03 22:34:38.544][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reduce_timeouts.
[2022-01-03 22:34:38.544][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.stop_accepting_connections.
[2022-01-03 22:34:38.544][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reject_incoming_connections.
[2022-01-03 22:34:38.545][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reset_high_memory_stream.
[2022-01-03 22:34:38.546][1][info][main] [source/server/server.cc:740] runtime: {}
[2022-01-03 22:34:38.547][1][info][admin] [source/server/admin/admin.cc:135] admin address: 127.0.0.1:9901
[2022-01-03 22:34:38.548][1][info][config] [source/server/configuration_impl.cc:127] loading tracing configuration
[2022-01-03 22:34:38.548][1][info][config] [source/server/configuration_impl.cc:87] loading 0 static secret(s)
[2022-01-03 22:34:38.548][1][info][config] [source/server/configuration_impl.cc:93] loading 2 cluster(s)
[2022-01-03 22:34:38.550][12][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2022-01-03 22:34:38.555][1][debug][upstream] [source/common/upstream/upstream_impl.cc:1156] initializing Primary cluster dynamic_forward_proxy_cluster_sni completed
[2022-01-03 22:34:38.555][1][debug][init] [source/common/init/manager_impl.cc:49] init manager Cluster dynamic_forward_proxy_cluster_sni contains no targets
[2022-01-03 22:34:38.555][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager Cluster dynamic_forward_proxy_cluster_sni initialized, notifying ClusterImplBase
[2022-01-03 22:34:38.555][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:38.556][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:134] cm init: init complete: cluster=dynamic_forward_proxy_cluster_sni primary=0 secondary=0
[2022-01-03 22:34:38.556][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:154] maybe finish initialize state: 0
[2022-01-03 22:34:38.556][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:100] cm init: adding: cluster=dynamic_forward_proxy_cluster_sni primary=0 secondary=0
[2022-01-03 22:34:38.556][1][trace][upstream] [source/common/upstream/strict_dns_cluster.cc:105] starting async DNS resolution for host.docker.internal
[2022-01-03 22:34:38.556][1][debug][dns] [source/common/network/dns_impl.cc:270] dns resolution for host.docker.internal started
[2022-01-03 22:34:38.557][1][trace][dns] [source/common/network/dns_impl.cc:233] Setting DNS resolution timer for 5000 milliseconds
[2022-01-03 22:34:38.557][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:100] cm init: adding: cluster=ext_authz-grpc-service primary=1 secondary=0
[2022-01-03 22:34:38.557][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:154] maybe finish initialize state: 1
[2022-01-03 22:34:38.557][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:163] maybe finish initialize primary init clusters empty: false
[2022-01-03 22:34:38.557][1][info][config] [source/server/configuration_impl.cc:97] loading 1 listener(s)
[2022-01-03 22:34:38.557][1][debug][config] [source/server/configuration_impl.cc:99] listener #0:
[2022-01-03 22:34:38.558][1][debug][config] [source/server/listener_manager_impl.cc:391] begin add/update listener: name=listener_0 hash=6725935714605597536
[2022-01-03 22:34:38.559][1][debug][config] [source/server/listener_manager_impl.cc:428] use full listener update path for listener name=listener_0 hash=6725935714605597536
[2022-01-03 22:34:38.559][1][debug][config] [source/server/listener_manager_impl.cc:116]   filter #0:
[2022-01-03 22:34:38.559][1][debug][config] [source/server/listener_manager_impl.cc:117]     name: envoy.filters.listener.tls_inspector
[2022-01-03 22:34:38.560][1][debug][config] [source/server/listener_manager_impl.cc:120]   config: {}
[2022-01-03 22:34:38.560][1][debug][config] [source/server/listener_manager_impl.cc:85]   filter #0:
[2022-01-03 22:34:38.560][1][debug][config] [source/server/listener_manager_impl.cc:86]     name: envoy.filters.network.sni_dynamic_forward_proxy
[2022-01-03 22:34:38.561][1][debug][config] [source/server/listener_manager_impl.cc:89]   config: {"@type":"type.googleapis.com/envoy.extensions.filters.network.sni_dynamic_forward_proxy.v3.FilterConfig","port_value":443,"dns_cache_config":{"dns_lookup_family":"V4_ONLY","name":"dynamic_forward_proxy_cache_config"}}
[2022-01-03 22:34:38.565][1][debug][config] [source/server/listener_manager_impl.cc:85]   filter #1:
[2022-01-03 22:34:38.565][1][debug][config] [source/server/listener_manager_impl.cc:86]     name: envoy.filters.network.ext_authz
[2022-01-03 22:34:38.565][1][debug][config] [source/server/listener_manager_impl.cc:89]   config: {"@type":"type.googleapis.com/envoy.extensions.filters.network.ext_authz.v3.ExtAuthz","grpc_service":{"timeout":"1s","envoy_grpc":{"cluster_name":"ext_authz-grpc-service"}},"stat_prefix":"ext_authz","transport_api_version":"V3"}
[2022-01-03 22:34:38.566][1][debug][config] [source/server/listener_manager_impl.cc:85]   filter #2:
[2022-01-03 22:34:38.566][1][debug][config] [source/server/listener_manager_impl.cc:86]     name: envoy.tcp_proxy
[2022-01-03 22:34:38.566][1][debug][config] [source/server/listener_manager_impl.cc:89]   config: {"@type":"type.googleapis.com/envoy.extensions.filters.network.tcp_proxy.v3.TcpProxy","stat_prefix":"tcp","cluster":"dynamic_forward_proxy_cluster_sni"}
[2022-01-03 22:34:38.567][1][debug][config] [source/server/filter_chain_manager_impl.cc:237] new fc_contexts has 1 filter chains, including 1 newly built
[2022-01-03 22:34:38.567][1][debug][init] [source/common/init/manager_impl.cc:24] added target Listener-init-target listener_0 to init manager Server
[2022-01-03 22:34:38.567][1][debug][config] [source/server/listener_impl.cc:145] Create listen socket for listener listener_0 on address 0.0.0.0:10000
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:155] listener_0: Setting socket options succeeded
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:99] Set listener listener_0 socket factory local address to 0.0.0.0:10000
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:145] Create listen socket for listener listener_0 on address 0.0.0.0:10000
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:155] listener_0: Setting socket options succeeded
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:145] Create listen socket for listener listener_0 on address 0.0.0.0:10000
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:155] listener_0: Setting socket options succeeded
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:145] Create listen socket for listener listener_0 on address 0.0.0.0:10000
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:155] listener_0: Setting socket options succeeded
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:145] Create listen socket for listener listener_0 on address 0.0.0.0:10000
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:155] listener_0: Setting socket options succeeded
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:145] Create listen socket for listener listener_0 on address 0.0.0.0:10000
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:155] listener_0: Setting socket options succeeded
[2022-01-03 22:34:38.568][1][debug][config] [source/server/listener_impl.cc:743] add active listener: name=listener_0, hash=6725935714605597536, address=0.0.0.0:10000
[2022-01-03 22:34:38.568][1][info][config] [source/server/configuration_impl.cc:109] loading stats configuration
[2022-01-03 22:34:38.571][1][info][main] [source/server/server.cc:836] starting main dispatch loop
[2022-01-03 22:34:38.571][1][trace][dns] [source/common/network/dns_impl.cc:233] Setting DNS resolution timer for 5000 milliseconds
[2022-01-03 22:34:38.575][1][debug][dns] [source/common/network/dns_impl.cc:188] dns resolution for host.docker.internal completed with status 0
[2022-01-03 22:34:38.576][1][trace][upstream] [source/common/upstream/strict_dns_cluster.cc:113] async DNS resolution complete for host.docker.internal
[2022-01-03 22:34:38.576][1][debug][upstream] [source/common/upstream/upstream_impl.cc:256] transport socket match, socket default selected for host with address 192.168.65.2:6000
[2022-01-03 22:34:38.576][1][debug][upstream] [source/common/upstream/strict_dns_cluster.cc:149] DNS hosts have changed for host.docker.internal
[2022-01-03 22:34:38.576][1][trace][upstream] [source/common/upstream/upstream_impl.cc:1468] Local locality: 
[2022-01-03 22:34:38.576][1][debug][upstream] [source/common/upstream/strict_dns_cluster.cc:177] DNS refresh rate reset for host.docker.internal, refresh rate 900000 ms
[2022-01-03 22:34:38.576][1][debug][upstream] [source/common/upstream/upstream_impl.cc:1156] initializing Primary cluster ext_authz-grpc-service completed
[2022-01-03 22:34:38.576][1][debug][init] [source/common/init/manager_impl.cc:49] init manager Cluster ext_authz-grpc-service contains no targets
[2022-01-03 22:34:38.576][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager Cluster ext_authz-grpc-service initialized, notifying ClusterImplBase
[2022-01-03 22:34:38.577][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster ext_authz-grpc-service
[2022-01-03 22:34:38.577][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster ext_authz-grpc-service added 1 removed 0
[2022-01-03 22:34:38.578][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:134] cm init: init complete: cluster=ext_authz-grpc-service primary=0 secondary=0
[2022-01-03 22:34:38.578][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:154] maybe finish initialize state: 1
[2022-01-03 22:34:38.578][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:163] maybe finish initialize primary init clusters empty: true
[2022-01-03 22:34:38.578][1][debug][init] [source/common/init/manager_impl.cc:49] init manager RTDS contains no targets
[2022-01-03 22:34:38.578][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager RTDS initialized, notifying RTDS
[2022-01-03 22:34:38.578][1][info][runtime] [source/common/runtime/runtime_impl.cc:449] RTDS has finished initialization
[2022-01-03 22:34:38.578][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:224] continue initializing secondary clusters
[2022-01-03 22:34:38.578][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:154] maybe finish initialize state: 2
[2022-01-03 22:34:38.578][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:163] maybe finish initialize primary init clusters empty: true
[2022-01-03 22:34:38.578][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:178] maybe finish initialize secondary init clusters empty: true
[2022-01-03 22:34:38.578][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:200] maybe finish initialize cds api ready: false
[2022-01-03 22:34:38.578][1][info][upstream] [source/common/upstream/cluster_manager_impl.cc:206] cm init: all clusters initialized
[2022-01-03 22:34:38.578][1][info][main] [source/server/server.cc:817] all clusters initialized. initializing init manager
[2022-01-03 22:34:38.578][1][debug][init] [source/common/init/manager_impl.cc:53] init manager Server initializing
[2022-01-03 22:34:38.578][1][debug][init] [source/common/init/target_impl.cc:15] init manager Server initializing target Listener-init-target listener_0
[2022-01-03 22:34:38.578][1][debug][init] [source/common/init/manager_impl.cc:49] init manager Listener-local-init-manager listener_0 6725935714605597536 contains no targets
[2022-01-03 22:34:38.578][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager Listener-local-init-manager listener_0 6725935714605597536 initialized, notifying Listener-local-init-watcher listener_0
[2022-01-03 22:34:38.578][1][debug][init] [source/common/init/watcher_impl.cc:14] target Listener-init-target listener_0 initialized, notifying init manager Server
[2022-01-03 22:34:38.578][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager Server initialized, notifying RunHelper
[2022-01-03 22:34:38.578][1][info][config] [source/server/listener_manager_impl.cc:779] all dependencies initialized. starting workers
[2022-01-03 22:34:38.578][1][debug][config] [source/server/listener_manager_impl.cc:816] starting worker 0
[2022-01-03 22:34:38.578][1][debug][config] [source/server/listener_manager_impl.cc:816] starting worker 1
[2022-01-03 22:34:38.578][15][debug][main] [source/server/worker_impl.cc:123] worker entering dispatch loop
[2022-01-03 22:34:38.580][1][debug][config] [source/server/listener_manager_impl.cc:816] starting worker 2
[2022-01-03 22:34:38.580][16][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2022-01-03 22:34:38.580][17][debug][main] [source/server/worker_impl.cc:123] worker entering dispatch loop
[2022-01-03 22:34:38.581][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:38.582][17][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:38.582][1][debug][config] [source/server/listener_manager_impl.cc:816] starting worker 3
[2022-01-03 22:34:38.583][19][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2022-01-03 22:34:38.582][18][debug][main] [source/server/worker_impl.cc:123] worker entering dispatch loop
[2022-01-03 22:34:38.583][17][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster ext_authz-grpc-service
[2022-01-03 22:34:38.583][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster ext_authz-grpc-service
[2022-01-03 22:34:38.584][17][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster ext_authz-grpc-service added 1 removed 0
[2022-01-03 22:34:38.584][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster ext_authz-grpc-service added 1 removed 0
[2022-01-03 22:34:38.585][20][debug][main] [source/server/worker_impl.cc:123] worker entering dispatch loop
[2022-01-03 22:34:38.585][18][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:38.585][18][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster ext_authz-grpc-service
[2022-01-03 22:34:38.585][18][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster ext_authz-grpc-service added 1 removed 0
[2022-01-03 22:34:38.585][21][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2022-01-03 22:34:38.585][22][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2022-01-03 22:34:38.585][20][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:38.586][20][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster ext_authz-grpc-service
[2022-01-03 22:34:38.586][20][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster ext_authz-grpc-service added 1 removed 0
[2022-01-03 22:34:38.586][1][debug][config] [source/server/listener_manager_impl.cc:816] starting worker 4
[2022-01-03 22:34:38.587][23][debug][main] [source/server/worker_impl.cc:123] worker entering dispatch loop
[2022-01-03 22:34:38.587][1][debug][config] [source/server/listener_manager_impl.cc:816] starting worker 5
[2022-01-03 22:34:38.588][23][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:38.588][24][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2022-01-03 22:34:38.590][23][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster ext_authz-grpc-service
[2022-01-03 22:34:38.590][23][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster ext_authz-grpc-service added 1 removed 0
[2022-01-03 22:34:38.591][25][debug][main] [source/server/worker_impl.cc:123] worker entering dispatch loop
[2022-01-03 22:34:38.592][26][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2022-01-03 22:34:38.592][25][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:38.593][25][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster ext_authz-grpc-service
[2022-01-03 22:34:38.593][25][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster ext_authz-grpc-service added 1 removed 0
[2022-01-03 22:34:38.594][1][warning][main] [source/server/server.cc:715] there is no configured limit to the number of allowed active connections. Set a limit via the runtime key overload.global_downstream_max_connections
[2022-01-03 22:34:42.914][20][debug][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:77] tls inspector: new connection accepted
[2022-01-03 22:34:42.914][20][trace][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:169] tls inspector: recv: 0
[2022-01-03 22:34:42.925][20][trace][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:169] tls inspector: recv: 231
[2022-01-03 22:34:42.925][20][trace][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:139] tls:onALPN(), ALPN: h2,http/1.1
[2022-01-03 22:34:42.925][20][debug][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:148] tls:onServerName(), requestedServerName: www.bing.com
[2022-01-03 22:34:42.925][20][trace][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:191] tls inspector: done: true
[2022-01-03 22:34:42.926][20][debug][filter] [source/common/tcp_proxy/tcp_proxy.cc:190] [C0] new tcp proxy session
[2022-01-03 22:34:42.927][20][trace][connection] [source/common/network/connection_impl.cc:356] [C0] readDisable: disable=true disable_count=0 state=0 buffer_length=0
[2022-01-03 22:34:42.927][20][trace][forward_proxy] [source/extensions/filters/network/sni_dynamic_forward_proxy/proxy_filter.cc:30] [C0] sni_dynamic_forward_proxy: new connection with server name 'www.bing.com'
[2022-01-03 22:34:42.927][20][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:104] thread local lookup for host 'www.bing.com'
[2022-01-03 22:34:42.927][20][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:125] cache miss for host 'www.bing.com', posting to main thread
[2022-01-03 22:34:42.927][20][debug][forward_proxy] [source/extensions/filters/network/sni_dynamic_forward_proxy/proxy_filter.cc:61] [C0] waiting to load DNS cache entry
[2022-01-03 22:34:42.927][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:293] starting main thread resolve for host='www.bing.com' dns='www.bing.com' port='443'
[2022-01-03 22:34:42.927][1][debug][dns] [source/common/network/dns_impl.cc:270] dns resolution for www.bing.com started
[2022-01-03 22:34:42.927][20][debug][conn_handler] [source/server/active_tcp_listener.cc:140] [C0] new connection from 172.17.0.1:56746
[2022-01-03 22:34:42.928][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2022-01-03 22:34:42.928][20][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=1)
[2022-01-03 22:34:42.928][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:42.928][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:42.929][1][trace][dns] [source/common/network/dns_impl.cc:233] Setting DNS resolution timer for 4999 milliseconds
[2022-01-03 22:34:42.975][1][debug][dns] [source/common/network/dns_impl.cc:188] dns resolution for www.bing.com completed with status 0
[2022-01-03 22:34:42.976][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:316] main thread resolve complete for host 'www.bing.com': [204.79.197.200:0, 13.107.21.200:0]
[2022-01-03 22:34:42.976][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:360] host 'www.bing.com' address has changed
[2022-01-03 22:34:42.976][1][debug][upstream] [source/extensions/clusters/dynamic_forward_proxy/cluster.cc:106] Adding host info for www.bing.com
[2022-01-03 22:34:42.976][1][debug][upstream] [source/extensions/clusters/dynamic_forward_proxy/cluster.cc:85] adding new dfproxy cluster host 'www.bing.com'
[2022-01-03 22:34:42.977][1][debug][upstream] [source/common/upstream/upstream_impl.cc:256] transport socket match, socket default selected for host with address 204.79.197.200:443
[2022-01-03 22:34:42.977][1][trace][upstream] [source/common/upstream/upstream_impl.cc:1468] Local locality: 
[2022-01-03 22:34:42.978][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster dynamic_forward_proxy_cluster_sni added 1 removed 0
[2022-01-03 22:34:42.978][17][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster dynamic_forward_proxy_cluster_sni added 1 removed 0
[2022-01-03 22:34:42.978][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:42.978][17][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:42.978][18][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster dynamic_forward_proxy_cluster_sni added 1 removed 0
[2022-01-03 22:34:42.978][18][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:42.978][20][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster dynamic_forward_proxy_cluster_sni added 1 removed 0
[2022-01-03 22:34:42.978][20][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:42.978][23][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster dynamic_forward_proxy_cluster_sni added 1 removed 0
[2022-01-03 22:34:42.978][23][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:42.978][25][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster dynamic_forward_proxy_cluster_sni added 1 removed 0
[2022-01-03 22:34:42.979][25][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:42.978][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster dynamic_forward_proxy_cluster_sni added 1 removed 0
[2022-01-03 22:34:42.979][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:42.979][20][debug][forward_proxy] [source/extensions/filters/network/sni_dynamic_forward_proxy/proxy_filter.cc:74] [C0] load DNS cache complete, continuing
[2022-01-03 22:34:42.980][20][debug][filter] [source/common/tcp_proxy/tcp_proxy.cc:338] [C0] Creating connection to cluster dynamic_forward_proxy_cluster_sni
[2022-01-03 22:34:42.980][20][debug][misc] [source/common/upstream/cluster_manager_impl.cc:1715] Allocating TCP conn pool
[2022-01-03 22:34:42.980][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:255] trying to create new connection
[2022-01-03 22:34:42.980][20][trace][pool] [source/common/conn_pool/conn_pool_base.cc:256] ConnPoolImplBase 0x14e6bf4d1100, ready_clients_.size(): 0, busy_clients_.size(): 0, connecting_clients_.size(): 0, connecting_stream_capacity_: 0, num_active_streams_: 0, pending_streams_.size(): 1 per upstream preconnect ratio: 1
[2022-01-03 22:34:42.980][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:143] creating a new connection
[2022-01-03 22:34:42.980][20][debug][connection] [source/common/network/connection_impl.cc:890] [C1] connecting to 204.79.197.200:443
[2022-01-03 22:34:42.981][20][debug][connection] [source/common/network/connection_impl.cc:909] [C1] connection in progress
[2022-01-03 22:34:42.981][20][trace][pool] [source/common/conn_pool/conn_pool_base.cc:129] not creating a new connection, shouldCreateNewConnection returned false.
[2022-01-03 22:34:42.981][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:395] DNS refresh rate reset for host 'www.bing.com', refresh rate 60000 ms
[2022-01-03 22:34:43.013][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.014][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.014][20][debug][connection] [source/common/network/connection_impl.cc:672] [C1] connected
[2022-01-03 22:34:43.014][20][trace][connection] [source/common/network/connection_impl.cc:417] [C1] raising connection event 2
[2022-01-03 22:34:43.014][20][trace][connection] [source/common/network/connection_impl.cc:356] [C1] readDisable: disable=true disable_count=0 state=0 buffer_length=0
[2022-01-03 22:34:43.014][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:293] [C1] attaching to next stream
[2022-01-03 22:34:43.015][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:176] [C1] creating stream
[2022-01-03 22:34:43.015][20][trace][connection] [source/common/network/connection_impl.cc:356] [C1] readDisable: disable=false disable_count=1 state=0 buffer_length=0
[2022-01-03 22:34:43.015][20][trace][connection] [source/common/network/connection_impl.cc:356] [C0] readDisable: disable=false disable_count=1 state=0 buffer_length=0
[2022-01-03 22:34:43.015][20][debug][filter] [source/common/tcp_proxy/tcp_proxy.cc:602] [C0] TCP:onUpstreamEvent(), requestedServerName: www.bing.com
[2022-01-03 22:34:43.015][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.015][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.015][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 3
[2022-01-03 22:34:43.015][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.015][20][trace][connection] [source/common/network/connection_impl.cc:592] [C0] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.016][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C0] read returns: 231
[2022-01-03 22:34:43.016][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C0] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.016][20][trace][ext_authz] [source/extensions/filters/common/ext_authz/ext_authz_grpc_impl.cc:42] Sending CheckRequest: attributes {
  source {
    address {
      socket_address {
        address: "172.17.0.1"
        port_value: 56746
      }
    }
  }
  destination {
    address {
      socket_address {
        address: "172.17.0.2"
        port_value: 10000
      }
    }
  }
}

[2022-01-03 22:34:43.017][20][debug][router] [source/common/router/router.cc:457] [C0][S11718549438522297941] cluster 'ext_authz-grpc-service' match for URL '/envoy.service.auth.v3.Authorization/Check'
[2022-01-03 22:34:43.018][20][debug][router] [source/common/router/router.cc:673] [C0][S11718549438522297941] router decoding headers:
':method', 'POST'
':path', '/envoy.service.auth.v3.Authorization/Check'
':authority', 'ext_authz-grpc-service'
':scheme', 'http'
'te', 'trailers'
'grpc-timeout', '1000m'
'content-type', 'application/grpc'
'x-envoy-internal', 'true'
'x-forwarded-for', '172.17.0.2'
'x-envoy-expected-rq-timeout-ms', '1000'

[2022-01-03 22:34:43.018][20][debug][pool] [source/common/http/conn_pool_base.cc:74] queueing stream due to no available connections
[2022-01-03 22:34:43.018][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:255] trying to create new connection
[2022-01-03 22:34:43.018][20][trace][pool] [source/common/conn_pool/conn_pool_base.cc:256] ConnPoolImplBase 0x14e6bf50c300, ready_clients_.size(): 0, busy_clients_.size(): 0, connecting_clients_.size(): 0, connecting_stream_capacity_: 0, num_active_streams_: 0, pending_streams_.size(): 1 per upstream preconnect ratio: 1
[2022-01-03 22:34:43.018][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:143] creating a new connection
[2022-01-03 22:34:43.019][20][trace][http2] [source/common/http/http2/codec_impl.cc:1489] Codec does not have Metadata frame support.
[2022-01-03 22:34:43.020][20][debug][http2] [source/common/http/http2/codec_impl.cc:1313] [C2] updating connection-level initial window size to 268435456
[2022-01-03 22:34:43.020][20][debug][client] [source/common/http/codec_client.cc:60] [C2] connecting
[2022-01-03 22:34:43.020][20][debug][connection] [source/common/network/connection_impl.cc:890] [C2] connecting to 192.168.65.2:6000
[2022-01-03 22:34:43.021][20][debug][connection] [source/common/network/connection_impl.cc:909] [C2] connection in progress
[2022-01-03 22:34:43.021][20][trace][pool] [source/common/conn_pool/conn_pool_base.cc:129] not creating a new connection, shouldCreateNewConnection returned false.
[2022-01-03 22:34:43.021][20][trace][router] [source/common/router/upstream_request.cc:246] [C0][S11718549438522297941] buffering 50 bytes
[2022-01-03 22:34:43.021][20][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 2
[2022-01-03 22:34:43.021][20][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2022-01-03 22:34:43.021][20][debug][connection] [source/common/network/connection_impl.cc:672] [C2] connected
[2022-01-03 22:34:43.021][20][trace][connection] [source/common/network/connection_impl.cc:417] [C2] raising connection event 2
[2022-01-03 22:34:43.021][20][debug][client] [source/common/http/codec_client.cc:88] [C2] connected
[2022-01-03 22:34:43.022][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:293] [C2] attaching to next stream
[2022-01-03 22:34:43.022][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:176] [C2] creating stream
[2022-01-03 22:34:43.022][20][debug][router] [source/common/router/upstream_request.cc:416] [C0][S11718549438522297941] pool ready
[2022-01-03 22:34:43.022][20][trace][http2] [source/common/http/http2/codec_impl.cc:1065] [C2] send data: bytes=24
[2022-01-03 22:34:43.023][20][trace][connection] [source/common/network/connection_impl.cc:477] [C2] writing 24 bytes, end_stream false
[2022-01-03 22:34:43.023][20][trace][http2] [source/common/http/http2/codec_impl.cc:1043] [C2] about to send frame type=4, flags=0
[2022-01-03 22:34:43.023][20][trace][http2] [source/common/http/http2/codec_impl.cc:1065] [C2] send data: bytes=39
[2022-01-03 22:34:43.023][20][trace][connection] [source/common/network/connection_impl.cc:477] [C2] writing 39 bytes, end_stream false
[2022-01-03 22:34:43.023][20][trace][http2] [source/common/http/http2/codec_impl.cc:951] [C2] sent frame type=4
[2022-01-03 22:34:43.023][20][trace][http2] [source/common/http/http2/codec_impl.cc:1043] [C2] about to send frame type=8, flags=0
[2022-01-03 22:34:43.023][20][trace][http2] [source/common/http/http2/codec_impl.cc:1065] [C2] send data: bytes=13
[2022-01-03 22:34:43.024][20][trace][connection] [source/common/network/connection_impl.cc:477] [C2] writing 13 bytes, end_stream false
[2022-01-03 22:34:43.024][20][trace][http2] [source/common/http/http2/codec_impl.cc:951] [C2] sent frame type=8
[2022-01-03 22:34:43.024][20][trace][http2] [source/common/http/http2/codec_impl.cc:1043] [C2] about to send frame type=1, flags=4
[2022-01-03 22:34:43.024][20][trace][http2] [source/common/http/http2/codec_impl.cc:1065] [C2] send data: bytes=169
[2022-01-03 22:34:43.024][20][trace][connection] [source/common/network/connection_impl.cc:477] [C2] writing 169 bytes, end_stream false
[2022-01-03 22:34:43.024][20][trace][http2] [source/common/http/http2/codec_impl.cc:951] [C2] sent frame type=1
[2022-01-03 22:34:43.024][20][trace][connection] [source/common/network/connection_impl.cc:477] [C2] writing 59 bytes, end_stream false
[2022-01-03 22:34:43.024][20][trace][http2] [source/common/http/http2/codec_impl.cc:951] [C2] sent frame type=0
[2022-01-03 22:34:43.024][20][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2022-01-03 22:34:43.024][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C2] write returns: 304
[2022-01-03 22:34:43.024][20][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 2
[2022-01-03 22:34:43.025][20][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2022-01-03 22:34:43.122][20][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 3
[2022-01-03 22:34:43.122][20][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2022-01-03 22:34:43.123][20][trace][connection] [source/common/network/connection_impl.cc:592] [C2] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.123][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C2] read returns: 49
[2022-01-03 22:34:43.123][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C2] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.123][20][trace][http2] [source/common/http/http2/codec_impl.cc:710] [C2] dispatching 49 bytes
[2022-01-03 22:34:43.123][20][trace][http2] [source/common/http/http2/codec_impl.cc:814] [C2] about to recv frame type=4, flags=0, stream_id=0
[2022-01-03 22:34:43.123][20][trace][http2] [source/common/http/http2/codec_impl.cc:1703] [C2] track inbound frame type=4 flags=0 length=18 padding_length=0
[2022-01-03 22:34:43.123][20][trace][http2] [source/common/http/http2/codec_impl.cc:842] [C2] recv frame type=4
[2022-01-03 22:34:43.123][20][trace][pool] [source/common/http/conn_pool_base.cc:127] [C2] Decreasing stream capacity by 536870812
[2022-01-03 22:34:43.123][20][trace][http2] [source/common/http/http2/codec_impl.cc:814] [C2] about to recv frame type=8, flags=0, stream_id=0
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:1703] [C2] track inbound frame type=8 flags=0 length=4 padding_length=0
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:842] [C2] recv frame type=8
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:814] [C2] about to recv frame type=4, flags=1, stream_id=0
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:1703] [C2] track inbound frame type=4 flags=1 length=0 padding_length=0
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:842] [C2] recv frame type=4
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:745] [C2] dispatched 49 bytes
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:1043] [C2] about to send frame type=4, flags=1
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:1065] [C2] send data: bytes=9
[2022-01-03 22:34:43.124][20][trace][connection] [source/common/network/connection_impl.cc:477] [C2] writing 9 bytes, end_stream false
[2022-01-03 22:34:43.124][20][trace][http2] [source/common/http/http2/codec_impl.cc:951] [C2] sent frame type=4
[2022-01-03 22:34:43.124][20][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 2
[2022-01-03 22:34:43.124][20][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2022-01-03 22:34:43.124][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C2] write returns: 9
[2022-01-03 22:34:43.220][20][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 3
[2022-01-03 22:34:43.220][20][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2022-01-03 22:34:43.220][20][trace][connection] [source/common/network/connection_impl.cc:592] [C2] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.220][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C2] read returns: 110
[2022-01-03 22:34:43.221][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C2] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.221][20][trace][http2] [source/common/http/http2/codec_impl.cc:710] [C2] dispatching 110 bytes
[2022-01-03 22:34:43.221][20][trace][http2] [source/common/http/http2/codec_impl.cc:814] [C2] about to recv frame type=1, flags=4, stream_id=1
[2022-01-03 22:34:43.221][20][trace][http2] [source/common/http/http2/codec_impl.cc:1703] [C2] track inbound frame type=1 flags=4 length=59 padding_length=0
[2022-01-03 22:34:43.221][20][trace][http2] [source/common/http/http2/codec_impl.cc:842] [C2] recv frame type=1
[2022-01-03 22:34:43.221][20][debug][router] [source/common/router/router.cc:1285] [C0][S11718549438522297941] upstream headers complete: end_stream=false
[2022-01-03 22:34:43.222][20][debug][http] [source/common/http/async_client_impl.cc:101] async http request response headers (end_stream=false):
':status', '200'
'date', 'Mon, 03 Jan 2022 22:34:42 GMT'
'content-type', 'application/grpc'
'server', 'Kestrel'
'x-envoy-upstream-service-time', '200'

[2022-01-03 22:34:43.222][20][trace][http2] [source/common/http/http2/codec_impl.cc:814] [C2] about to recv frame type=0, flags=0, stream_id=1
[2022-01-03 22:34:43.223][20][trace][http2] [source/common/http/http2/codec_impl.cc:842] [C2] recv frame type=0
[2022-01-03 22:34:43.223][20][trace][http2] [source/common/http/http2/codec_impl.cc:1703] [C2] track inbound frame type=0 flags=0 length=9 padding_length=0
[2022-01-03 22:34:43.223][20][trace][http] [source/common/http/async_client_impl.cc:118] async http request response data (length=9 end_stream=false)
[2022-01-03 22:34:43.223][20][trace][http2] [source/common/http/http2/codec_impl.cc:814] [C2] about to recv frame type=1, flags=5, stream_id=1
[2022-01-03 22:34:43.223][20][trace][http2] [source/common/http/http2/codec_impl.cc:1703] [C2] track inbound frame type=1 flags=5 length=15 padding_length=0
[2022-01-03 22:34:43.223][20][trace][http2] [source/common/http/http2/codec_impl.cc:842] [C2] recv frame type=1
[2022-01-03 22:34:43.223][20][debug][client] [source/common/http/codec_client.cc:132] [C2] response complete
[2022-01-03 22:34:43.223][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2022-01-03 22:34:43.223][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:204] [C2] destroying stream: 0 remaining
[2022-01-03 22:34:43.223][20][debug][http] [source/common/http/async_client_impl.cc:128] async http request response trailers:
'grpc-status', '0'

[2022-01-03 22:34:43.223][20][trace][ext_authz] [source/extensions/filters/common/ext_authz/ext_authz_grpc_impl.cc:48] Received CheckResponse: status {
}
ok_response {
}

[2022-01-03 22:34:43.223][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:512] [C0] downstream connection received 231 bytes, end_stream=false
[2022-01-03 22:34:43.223][20][trace][connection] [source/common/network/connection_impl.cc:477] [C1] writing 231 bytes, end_stream false
[2022-01-03 22:34:43.223][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=2)
[2022-01-03 22:34:43.224][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=3)
[2022-01-03 22:34:43.224][20][debug][http2] [source/common/http/http2/codec_impl.cc:1083] [C2] stream closed: 0
[2022-01-03 22:34:43.224][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=4)
[2022-01-03 22:34:43.224][20][trace][http2] [source/common/http/http2/codec_impl.cc:745] [C2] dispatched 110 bytes
[2022-01-03 22:34:43.224][20][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=4)
[2022-01-03 22:34:43.224][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.224][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.224][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C1] write returns: 231
[2022-01-03 22:34:43.255][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.255][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.255][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.255][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 5273
[2022-01-03 22:34:43.256][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.256][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 5273 bytes, end_stream=false
[2022-01-03 22:34:43.256][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 5273 bytes, end_stream false
[2022-01-03 22:34:43.256][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.257][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.257][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.257][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.258][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 5273
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 3
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/connection_impl.cc:592] [C0] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C0] read returns: 158
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C0] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.267][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:512] [C0] downstream connection received 158 bytes, end_stream=false
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/connection_impl.cc:477] [C1] writing 158 bytes, end_stream false
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.267][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C1] write returns: 158
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 120
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.282][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 120 bytes, end_stream=false
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 120 bytes, end_stream false
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.282][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 120
[2022-01-03 22:34:43.285][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 3
[2022-01-03 22:34:43.285][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.286][20][trace][connection] [source/common/network/connection_impl.cc:592] [C0] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.286][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C0] read returns: 256
[2022-01-03 22:34:43.286][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C0] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.286][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:512] [C0] downstream connection received 256 bytes, end_stream=false
[2022-01-03 22:34:43.286][20][trace][connection] [source/common/network/connection_impl.cc:477] [C1] writing 256 bytes, end_stream false
[2022-01-03 22:34:43.286][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.286][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.286][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.286][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.287][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C1] write returns: 256
[2022-01-03 22:34:43.311][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.311][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.311][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.311][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 38
[2022-01-03 22:34:43.312][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.312][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 38 bytes, end_stream=false
[2022-01-03 22:34:43.312][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 38 bytes, end_stream false
[2022-01-03 22:34:43.312][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.312][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.312][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 38
[2022-01-03 22:34:43.341][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.342][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.342][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.342][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 1460
[2022-01-03 22:34:43.345][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.345][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 1460 bytes, end_stream=false
[2022-01-03 22:34:43.345][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 1460 bytes, end_stream false
[2022-01-03 22:34:43.345][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.345][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.345][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.346][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 11892
[2022-01-03 22:34:43.347][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 10504
[2022-01-03 22:34:43.347][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.347][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 22396 bytes, end_stream=false
[2022-01-03 22:34:43.348][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 22396 bytes, end_stream false
[2022-01-03 22:34:43.348][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.348][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.348][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 23856
[2022-01-03 22:34:43.348][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.349][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.390][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.390][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.391][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.392][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 4165
[2022-01-03 22:34:43.392][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.392][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 4165 bytes, end_stream=false
[2022-01-03 22:34:43.392][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 4165 bytes, end_stream false
[2022-01-03 22:34:43.392][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.392][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.392][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.392][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.393][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 4165
[2022-01-03 22:34:43.394][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.396][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.396][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.396][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 18980
[2022-01-03 22:34:43.397][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.397][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 18980 bytes, end_stream=false
[2022-01-03 22:34:43.397][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 18980 bytes, end_stream false
[2022-01-03 22:34:43.397][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.398][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.398][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.398][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.399][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 18980
[2022-01-03 22:34:43.399][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.399][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.399][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.399][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 29414
[2022-01-03 22:34:43.399][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.400][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 29414 bytes, end_stream=false
[2022-01-03 22:34:43.400][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 29414 bytes, end_stream false
[2022-01-03 22:34:43.400][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.400][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.400][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 29414
[2022-01-03 22:34:43.424][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.425][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 7300
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.426][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 7300 bytes, end_stream=false
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 7300 bytes, end_stream false
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.426][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 7300
[2022-01-03 22:34:43.432][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.433][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.433][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.433][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 1460
[2022-01-03 22:34:43.434][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.441][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 1460 bytes, end_stream=false
[2022-01-03 22:34:43.441][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 1460 bytes, end_stream false
[2022-01-03 22:34:43.441][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.442][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.442][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 1460
[2022-01-03 22:34:43.443][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.443][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.443][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.443][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 7581
[2022-01-03 22:34:43.443][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C1] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.443][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 7581 bytes, end_stream=false
[2022-01-03 22:34:43.444][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 7581 bytes, end_stream false
[2022-01-03 22:34:43.444][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2022-01-03 22:34:43.444][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.444][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 7581
[2022-01-03 22:34:43.454][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 3
[2022-01-03 22:34:43.454][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.454][20][trace][connection] [source/common/network/connection_impl.cc:592] [C0] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.455][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C0] read returns: 31
[2022-01-03 22:34:43.455][20][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C0] read error: Resource temporarily unavailable
[2022-01-03 22:34:43.455][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:512] [C0] downstream connection received 31 bytes, end_stream=false
[2022-01-03 22:34:43.455][20][trace][connection] [source/common/network/connection_impl.cc:477] [C1] writing 31 bytes, end_stream false
[2022-01-03 22:34:43.455][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.455][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.455][20][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C1] write returns: 31
[2022-01-03 22:34:43.458][20][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 3
[2022-01-03 22:34:43.458][20][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2022-01-03 22:34:43.458][20][trace][connection] [source/common/network/connection_impl.cc:592] [C0] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.458][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C0] read returns: 0
[2022-01-03 22:34:43.458][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:512] [C0] downstream connection received 0 bytes, end_stream=true
[2022-01-03 22:34:43.458][20][trace][connection] [source/common/network/connection_impl.cc:477] [C1] writing 0 bytes, end_stream true
[2022-01-03 22:34:43.458][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.459][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.459][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.460][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.460][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2022-01-03 22:34:43.460][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.484][20][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2022-01-03 22:34:43.484][20][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2022-01-03 22:34:43.484][20][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2022-01-03 22:34:43.485][20][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C1] read returns: 0
[2022-01-03 22:34:43.485][20][trace][filter] [source/common/tcp_proxy/tcp_proxy.cc:558] [C0] upstream connection received 0 bytes, end_stream=true
[2022-01-03 22:34:43.485][20][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 0 bytes, end_stream true
[2022-01-03 22:34:43.485][20][debug][connection] [source/common/network/connection_impl.cc:640] [C1] remote close
[2022-01-03 22:34:43.485][20][debug][connection] [source/common/network/connection_impl.cc:249] [C1] closing socket: 0
[2022-01-03 22:34:43.485][20][trace][connection] [source/common/network/connection_impl.cc:417] [C1] raising connection event 0
[2022-01-03 22:34:43.485][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:422] [C1] client disconnected, failure reason: 
[2022-01-03 22:34:43.485][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2022-01-03 22:34:43.486][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:397] invoking idle callbacks - is_draining_for_deletion_=false
[2022-01-03 22:34:43.486][20][trace][upstream] [source/common/upstream/cluster_manager_impl.cc:1634] Idle pool, erasing pool for host 0x14e6bf50a908
[2022-01-03 22:34:43.486][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=2)
[2022-01-03 22:34:43.486][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:204] [C1] destroying stream: 0 remaining
[2022-01-03 22:34:43.486][20][debug][pool] [source/common/conn_pool/conn_pool_base.cc:397] invoking idle callbacks - is_draining_for_deletion_=false
[2022-01-03 22:34:43.486][20][debug][connection] [source/common/network/connection_impl.cc:138] [C0] closing data_to_write=0 type=0
[2022-01-03 22:34:43.489][20][debug][connection] [source/common/network/connection_impl.cc:249] [C0] closing socket: 1
[2022-01-03 22:34:43.490][20][trace][connection] [source/common/network/connection_impl.cc:417] [C0] raising connection event 1
[2022-01-03 22:34:43.490][20][trace][conn_handler] [source/server/active_stream_listener_base.cc:111] [C0] connection on event 1
[2022-01-03 22:34:43.490][20][debug][conn_handler] [source/server/active_stream_listener_base.cc:120] [C0] adding to cleanup list
[2022-01-03 22:34:43.490][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=3)
[2022-01-03 22:34:43.490][20][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=4)
[2022-01-03 22:34:43.490][20][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=4)
[2022-01-03 22:34:43.568][1][debug][main] [source/server/server.cc:229] flushing stats
[2022-01-03 22:34:48.570][1][debug][main] [source/server/server.cc:229] flushing stats
[2022-01-03 22:34:53.572][1][debug][main] [source/server/server.cc:229] flushing stats
[2022-01-03 22:34:58.573][1][debug][main] [source/server/server.cc:229] flushing stats
[2022-01-03 22:35:03.574][1][debug][main] [source/server/server.cc:229] flushing stats
[2022-01-03 22:35:08.575][1][debug][main] [source/server/server.cc:229] flushing stats
[2022-01-03 22:35:13.575][1][debug][main] [source/server/server.cc:229] flushing stats
[2022-01-03 22:35:18.576][1][debug][main] [source/server/server.cc:229] flushing stats
[2022-01-03 22:35:23.577][1][debug][main] [source/server/server.cc:229] flushing stats

envoy_tls_ext_auth.yaml

admin:
  address:
    socket_address:
      protocol: TCP
      address: 127.0.0.1
      port_value: 9901
static_resources:
  listeners:
  - name: listener_0
    address:
      socket_address:
        protocol: TCP
        address: 0.0.0.0
        port_value: 10000
    listener_filters:
      - name: envoy.filters.listener.tls_inspector
    filter_chains:
      - filters:
          # SNI forward proxy
          - name: envoy.filters.network.sni_dynamic_forward_proxy
            typed_config:
              "@type": type.googleapis.com/envoy.extensions.filters.network.sni_dynamic_forward_proxy.v3.FilterConfig
              port_value: 443
              dns_cache_config:
                name: dynamic_forward_proxy_cache_config
                dns_lookup_family: V4_ONLY
          - name: envoy.filters.network.ext_authz
            typed_config:
              "@type": type.googleapis.com/envoy.extensions.filters.network.ext_authz.v3.ExtAuthz
              stat_prefix: ext_authz
              grpc_service:
                envoy_grpc:
                  cluster_name: ext_authz-grpc-service
                timeout: 1s
              transport_api_version: V3
          - name: envoy.tcp_proxy
            typed_config:
              "@type": type.googleapis.com/envoy.extensions.filters.network.tcp_proxy.v3.TcpProxy
              stat_prefix: tcp
              cluster: dynamic_forward_proxy_cluster_sni

  clusters:
  - name: dynamic_forward_proxy_cluster_sni
    lb_policy: CLUSTER_PROVIDED
    cluster_type:
      name: envoy.clusters.dynamic_forward_proxy
      typed_config:
        "@type": type.googleapis.com/envoy.extensions.clusters.dynamic_forward_proxy.v3.ClusterConfig
        dns_cache_config:
          name: dynamic_forward_proxy_cache_config
          dns_lookup_family: V4_ONLY
  - name: ext_authz-grpc-service
    type: STRICT_DNS
    lb_policy: ROUND_ROBIN
    dns_refresh_rate: 900s
    typed_extension_protocol_options:
      envoy.extensions.upstreams.http.v3.HttpProtocolOptions:
        "@type": type.googleapis.com/envoy.extensions.upstreams.http.v3.HttpProtocolOptions
        explicit_http_config:
          http2_protocol_options: {}
    load_assignment:
      cluster_name: ext_authz-grpc-service
      endpoints:
      - lb_endpoints:
        - endpoint:
            address:
              socket_address:
                address: host.docker.internal
                port_value: 6000