Skip to content

Instantly share code, notes, and snippets.

@Shikugawa
Created November 22, 2021 08:15
Show Gist options
  • Save Shikugawa/537f3df4fe9c58f20ebdcf94c1ca1952 to your computer and use it in GitHub Desktop.
Save Shikugawa/537f3df4fe9c58f20ebdcf94c1ca1952 to your computer and use it in GitHub Desktop.
envoy#18897
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:368] initializing epoch 0 (base id=0, hot restart version=11.104)
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:370] statically linked extensions:
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.formatter: envoy.formatter.metadata, envoy.formatter.req_without_query
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.resolvers: envoy.ip
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.http.cache: envoy.extensions.http.cache.simple
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.matching.http.input: request-headers, request-trailers, response-headers, response-trailers
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.retry_priorities: envoy.retry_priorities.previous_priorities
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.guarddog_actions: envoy.watchdog.abort_action, envoy.watchdog.profile_action
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.quic.proof_source: envoy.quic.proof_source.filter_chain
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.http.stateful_header_formatters: preserve_case
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.resource_monitors: envoy.resource_monitors.fixed_heap, envoy.resource_monitors.injected_resource
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.filters.udp_listener: envoy.filters.udp.dns_filter, envoy.filters.udp_listener.udp_proxy
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.dubbo_proxy.serializers: dubbo.hessian2
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.upstream_options: envoy.extensions.upstreams.http.v3.HttpProtocolOptions, envoy.upstreams.http.http_protocol_options
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.health_checkers: envoy.health_checkers.redis
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.dubbo_proxy.filters: envoy.filters.dubbo.router
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.upstreams: envoy.filters.connection_pools.tcp.generic
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.thrift_proxy.filters: envoy.filters.thrift.rate_limit, envoy.filters.thrift.router
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.dubbo_proxy.protocols: dubbo
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.matching.common_inputs: envoy.matching.common_inputs.environment_variable
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.tls.cert_validator: envoy.tls.cert_validator.default, envoy.tls.cert_validator.spiffe
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.common.key_value: envoy.key_value.file_based
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.dubbo_proxy.route_matchers: default
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.thrift_proxy.protocols: auto, binary, binary/non-strict, compact, twitter
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.matching.input_matchers: envoy.matching.matchers.consistent_hashing, envoy.matching.matchers.ip
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.quic.server.crypto_stream: envoy.quic.crypto_stream.server.quiche
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.matching.action: composite-action, skip
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.request_id: envoy.request_id.uuid
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.wasm.runtime: envoy.wasm.runtime.null, envoy.wasm.runtime.v8
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.rate_limit_descriptors: envoy.rate_limit_descriptors.expr
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.bootstrap: envoy.bootstrap.wasm, envoy.extensions.network.socket_interface.default_socket_interface
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.thrift_proxy.transports: auto, framed, header, unframed
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.compression.decompressor: envoy.compression.brotli.decompressor, envoy.compression.gzip.decompressor
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.compression.compressor: envoy.compression.brotli.compressor, envoy.compression.gzip.compressor
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][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
[2021-11-22 08:07:34.284][1][info][main] [source/server/server.cc:372] envoy.rbac.matchers: envoy.rbac.matchers.upstream.upstream_ip_port
[2021-11-22 08:07:34.287][1][info][main] [source/server/server.cc:390] HTTP header map info:
[2021-11-22 08:07:34.288][1][debug][runtime] [source/common/runtime/runtime_features.cc:31] Unable to use runtime singleton for feature envoy.http.headermap.lazy_map_min_size
[2021-11-22 08:07:34.288][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
[2021-11-22 08:07:34.288][1][debug][runtime] [source/common/runtime/runtime_features.cc:31] Unable to use runtime singleton for feature envoy.http.headermap.lazy_map_min_size
[2021-11-22 08:07:34.288][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
[2021-11-22 08:07:34.288][1][debug][runtime] [source/common/runtime/runtime_features.cc:31] Unable to use runtime singleton for feature envoy.http.headermap.lazy_map_min_size
[2021-11-22 08:07:34.288][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
[2021-11-22 08:07:34.288][1][debug][runtime] [source/common/runtime/runtime_features.cc:31] Unable to use runtime singleton for feature envoy.http.headermap.lazy_map_min_size
[2021-11-22 08:07:34.288][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
[2021-11-22 08:07:34.288][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
[2021-11-22 08:07:34.288][1][info][main] [source/server/server.cc:393] request trailer map: 136 bytes:
[2021-11-22 08:07:34.288][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
[2021-11-22 08:07:34.288][1][info][main] [source/server/server.cc:393] response trailer map: 160 bytes: grpc-message,grpc-status
[2021-11-22 08:07:34.289][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.shrink_heap.
[2021-11-22 08:07:34.289][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reduce_timeouts.
[2021-11-22 08:07:34.289][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.stop_accepting_connections.
[2021-11-22 08:07:34.289][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reject_incoming_connections.
[2021-11-22 08:07:34.289][1][debug][main] [source/server/overload_manager_impl.cc:376] No overload action is configured for envoy.overload_actions.reset_high_memory_stream.
[2021-11-22 08:07:34.289][1][info][main] [source/server/server.cc:740] runtime: {}
[2021-11-22 08:07:34.289][1][warning][main] [source/server/server.cc:585] No admin address given, so no admin HTTP server started.
[2021-11-22 08:07:34.289][1][info][config] [source/server/configuration_impl.cc:127] loading tracing configuration
[2021-11-22 08:07:34.289][1][info][config] [source/server/configuration_impl.cc:87] loading 0 static secret(s)
[2021-11-22 08:07:34.289][1][info][config] [source/server/configuration_impl.cc:93] loading 1 cluster(s)
[2021-11-22 08:07:34.289][12][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2021-11-22 08:07:34.295][1][debug][upstream] [source/common/upstream/upstream_impl.cc:1156] initializing Primary cluster example_application completed
[2021-11-22 08:07:34.295][1][debug][init] [source/common/init/manager_impl.cc:49] init manager Cluster example_application contains no targets
[2021-11-22 08:07:34.295][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager Cluster example_application initialized, notifying ClusterImplBase
[2021-11-22 08:07:34.295][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster example_application
[2021-11-22 08:07:34.295][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:134] cm init: init complete: cluster=example_application primary=0 secondary=0
[2021-11-22 08:07:34.295][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:154] maybe finish initialize state: 0
[2021-11-22 08:07:34.295][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:100] cm init: adding: cluster=example_application primary=0 secondary=0
[2021-11-22 08:07:34.295][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:154] maybe finish initialize state: 1
[2021-11-22 08:07:34.295][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:163] maybe finish initialize primary init clusters empty: true
[2021-11-22 08:07:34.295][1][info][config] [source/server/configuration_impl.cc:97] loading 1 listener(s)
[2021-11-22 08:07:34.295][1][debug][config] [source/server/configuration_impl.cc:99] listener #0:
[2021-11-22 08:07:34.296][1][debug][config] [source/server/listener_manager_impl.cc:391] begin add/update listener: name=envoy_proxy hash=1163153861056860990
[2021-11-22 08:07:34.296][1][debug][config] [source/server/listener_manager_impl.cc:428] use full listener update path for listener name=envoy_proxy hash=1163153861056860990
[2021-11-22 08:07:34.296][1][debug][config] [source/server/listener_manager_impl.cc:85] filter #0:
[2021-11-22 08:07:34.296][1][debug][config] [source/server/listener_manager_impl.cc:86] name: envoy.filters.network.http_connection_manager
[2021-11-22 08:07:34.296][1][debug][config] [source/server/listener_manager_impl.cc:89] config: {"@type":"type.googleapis.com/envoy.extensions.filters.network.http_connection_manager.v3.HttpConnectionManager","http_filters":[{"typed_config":{"@type":"type.googleapis.com/envoy.extensions.filters.http.lua.v3.Lua","inline_code":"function envoy_on_request(request_handle)\n local service = request_handle:headers():get(\"service\")\n request_handle:headers():replace(\":destination\", service .. \".example.com:8002\")\nend\n"},"name":"envoy.filters.http.lua"},{"typed_config":{"@type":"type.googleapis.com/envoy.extensions.filters.http.dynamic_forward_proxy.v3.FilterConfig","dns_cache_config":{"dns_lookup_family":"V4_ONLY","name":"dynamic_forward_proxy_cache_config"}},"name":"envoy.filters.http.dynamic_forward_proxy"},{"typed_config":{"@type":"type.googleapis.com/envoy.extensions.filters.http.router.v3.Router","suppress_envoy_headers":true},"name":"envoy.filters.http.router"}],"stat_prefix":"test","route_config":{"virtual_hosts":[{"name":"envoy_host","domains":["*"],"routes":[{"typed_per_filter_config":{"envoy.filters.http.dynamic_forward_proxy":{"@type":"type.googleapis.com/envoy.extensions.filters.http.dynamic_forward_proxy.v3.PerRouteConfig","host_rewrite_header":":destination"}},"match":{"prefix":"/"},"route":{"cluster":"example_application"},"name":"some_route"}]}],"name":"route_configuration"}}
[2021-11-22 08:07:34.297][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:625] http filter #0
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua13BufferWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua18MetadataMapWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua19MetadataMapIteratorE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua17ConnectionWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua20SslConnectionWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua16HeaderMapWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua17HeaderMapIteratorE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua17StreamInfoWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua25DynamicMetadataMapWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua26DynamicMetadataMapIteratorE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua16PublicKeyWrapperE
[2021-11-22 08:07:34.298][1][debug][lua] [source/extensions/filters/common/lua/lua.cc:83] definition for 'envoy_on_response' not found in script
[2021-11-22 08:07:34.298][1][info][lua] [source/extensions/filters/http/lua/lua_filter.cc:189] envoy_on_response() function not found. Lua filter will not hook responses.
[2021-11-22 08:07:34.298][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:654] name: envoy.filters.http.lua
[2021-11-22 08:07:34.298][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:657] config: {
"@type": "type.googleapis.com/envoy.extensions.filters.http.lua.v3.Lua",
"inline_code": "function envoy_on_request(request_handle)\n local service = request_handle:headers():get(\"service\")\n request_handle:headers():replace(\":destination\", service .. \".example.com:8002\")\nend\n"
}
[2021-11-22 08:07:34.298][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:625] http filter #1
[2021-11-22 08:07:34.298][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:654] name: envoy.filters.http.dynamic_forward_proxy
[2021-11-22 08:07:34.298][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:657] config: {
"@type": "type.googleapis.com/envoy.extensions.filters.http.dynamic_forward_proxy.v3.FilterConfig",
"dns_cache_config": {
"dns_lookup_family": "V4_ONLY",
"name": "dynamic_forward_proxy_cache_config"
}
}
[2021-11-22 08:07:34.298][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:625] http filter #2
[2021-11-22 08:07:34.298][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:654] name: envoy.filters.http.router
[2021-11-22 08:07:34.298][1][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:657] config: {
"@type": "type.googleapis.com/envoy.extensions.filters.http.router.v3.Router",
"suppress_envoy_headers": true
}
[2021-11-22 08:07:34.299][1][debug][config] [source/server/filter_chain_manager_impl.cc:237] new fc_contexts has 1 filter chains, including 1 newly built
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/manager_impl.cc:24] added target Listener-init-target envoy_proxy to init manager Server
[2021-11-22 08:07:34.299][1][debug][config] [source/server/listener_impl.cc:145] Create listen socket for listener envoy_proxy on address 0.0.0.0:8080
[2021-11-22 08:07:34.299][1][debug][config] [source/server/listener_impl.cc:155] envoy_proxy: Setting socket options succeeded
[2021-11-22 08:07:34.299][1][debug][config] [source/server/listener_impl.cc:99] Set listener envoy_proxy socket factory local address to 0.0.0.0:8080
[2021-11-22 08:07:34.299][1][debug][config] [source/server/listener_impl.cc:743] add active listener: name=envoy_proxy, hash=1163153861056860990, address=0.0.0.0:8080
[2021-11-22 08:07:34.299][1][info][config] [source/server/configuration_impl.cc:109] loading stats configuration
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/manager_impl.cc:49] init manager RTDS contains no targets
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager RTDS initialized, notifying RTDS
[2021-11-22 08:07:34.299][1][info][runtime] [source/common/runtime/runtime_impl.cc:449] RTDS has finished initialization
[2021-11-22 08:07:34.299][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:224] continue initializing secondary clusters
[2021-11-22 08:07:34.299][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:154] maybe finish initialize state: 2
[2021-11-22 08:07:34.299][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:163] maybe finish initialize primary init clusters empty: true
[2021-11-22 08:07:34.299][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:178] maybe finish initialize secondary init clusters empty: true
[2021-11-22 08:07:34.299][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:200] maybe finish initialize cds api ready: false
[2021-11-22 08:07:34.299][1][info][upstream] [source/common/upstream/cluster_manager_impl.cc:206] cm init: all clusters initialized
[2021-11-22 08:07:34.299][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
[2021-11-22 08:07:34.299][1][info][main] [source/server/server.cc:817] all clusters initialized. initializing init manager
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/manager_impl.cc:53] init manager Server initializing
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/target_impl.cc:15] init manager Server initializing target Listener-init-target envoy_proxy
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/manager_impl.cc:49] init manager Listener-local-init-manager envoy_proxy 1163153861056860990 contains no targets
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager Listener-local-init-manager envoy_proxy 1163153861056860990 initialized, notifying Listener-local-init-watcher envoy_proxy
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/watcher_impl.cc:14] target Listener-init-target envoy_proxy initialized, notifying init manager Server
[2021-11-22 08:07:34.299][1][debug][init] [source/common/init/watcher_impl.cc:14] init manager Server initialized, notifying RunHelper
[2021-11-22 08:07:34.299][1][info][config] [source/server/listener_manager_impl.cc:779] all dependencies initialized. starting workers
[2021-11-22 08:07:34.299][1][debug][config] [source/server/listener_manager_impl.cc:816] starting worker 0
[2021-11-22 08:07:34.299][15][debug][main] [source/server/worker_impl.cc:123] worker entering dispatch loop
[2021-11-22 08:07:34.299][16][debug][grpc] [source/common/grpc/google_async_client_impl.cc:50] completionThread running
[2021-11-22 08:07:34.299][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1024] adding TLS cluster example_application
[2021-11-22 08:07:34.299][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua13BufferWrapperE
[2021-11-22 08:07:34.299][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua18MetadataMapWrapperE
[2021-11-22 08:07:34.299][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua19MetadataMapIteratorE
[2021-11-22 08:07:34.299][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua17ConnectionWrapperE
[2021-11-22 08:07:34.299][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions7Filters6Common3Lua20SslConnectionWrapperE
[2021-11-22 08:07:34.299][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua16HeaderMapWrapperE
[2021-11-22 08:07:34.299][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua17HeaderMapIteratorE
[2021-11-22 08:07:34.300][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua17StreamInfoWrapperE
[2021-11-22 08:07:34.300][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua25DynamicMetadataMapWrapperE
[2021-11-22 08:07:34.300][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua26DynamicMetadataMapIteratorE
[2021-11-22 08:07:34.300][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE
[2021-11-22 08:07:34.300][15][debug][lua] [./source/extensions/filters/common/lua/lua.h:182] registering new type: N5Envoy10Extensions11HttpFilters3Lua16PublicKeyWrapperE
[2021-11-22 08:07:34.300][15][debug][lua] [source/extensions/filters/common/lua/lua.cc:83] definition for 'envoy_on_response' not found in script
[2021-11-22 08:07:34.300][1][info][main] [source/server/server.cc:836] starting main dispatch loop
[2021-11-22 08:07:39.299][1][debug][main] [source/server/server.cc:229] flushing stats
[2021-11-22 08:07:43.788][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77180 for 3600000ms, min is 3600000ms
### start service1.example.com ###
[2021-11-22 08:07:43.788][15][debug][conn_handler] [source/server/active_tcp_listener.cc:140] [C0] new connection from 172.26.0.1:36758
[2021-11-22 08:07:43.788][15][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 3
[2021-11-22 08:07:43.788][15][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2021-11-22 08:07:43.788][15][trace][connection] [source/common/network/connection_impl.cc:592] [C0] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:43.788][15][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C0] read returns: 100
[2021-11-22 08:07:43.788][15][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C0] read error: Resource temporarily unavailable
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:579] [C0] parsing 100 bytes
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:858] [C0] message begin
[2021-11-22 08:07:43.788][15][debug][http] [source/common/http/conn_manager_impl.cc:274] [C0] new stream
[2021-11-22 08:07:43.788][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77300 for 300000ms, min is 300000ms
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C0] completed header: key=Host value=localhost:8080
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C0] completed header: key=User-Agent value=curl/7.68.0
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C0] completed header: key=Accept value=*/*
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:709] [C0] onHeadersCompleteBase
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C0] completed header: key=service value=service1
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:1056] [C0] Server: onHeadersComplete size=4
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:836] [C0] message complete
[2021-11-22 08:07:43.788][15][trace][connection] [source/common/network/connection_impl.cc:356] [C0] readDisable: disable=true disable_count=0 state=0 buffer_length=100
[2021-11-22 08:07:43.788][15][debug][http] [source/common/http/conn_manager_impl.cc:867] [C0][S15031825648262010483] request headers complete (end_stream=true):
':authority', 'localhost:8080'
':path', '/test'
':method', 'GET'
'user-agent', 'curl/7.68.0'
'accept', '*/*'
'service', 'service1'
[2021-11-22 08:07:43.788][15][debug][http] [source/common/http/filter_manager.cc:835] [C0][S15031825648262010483] request end stream
[2021-11-22 08:07:43.788][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:149] creating N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE at 0x7f2ec73e53e0
[2021-11-22 08:07:43.788][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:149] creating N5Envoy10Extensions11HttpFilters3Lua16HeaderMapWrapperE at 0x7f2ec73d9bb0
[2021-11-22 08:07:43.788][15][debug][lua] [source/extensions/filters/common/lua/lua.cc:39] coroutine finished
[2021-11-22 08:07:43.788][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:210] marking dead N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE at 0x7f2ec73e53e0
[2021-11-22 08:07:43.788][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:210] marking dead N5Envoy10Extensions11HttpFilters3Lua16HeaderMapWrapperE at 0x7f2ec73d9bb0
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/filter_manager.cc:547] [C0][S15031825648262010483] decode headers called: filter=0x5781fea940e0 status=0
[2021-11-22 08:07:43.788][15][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:104] thread local lookup for host 'service1.example.com:8002'
[2021-11-22 08:07:43.788][15][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:125] cache miss for host 'service1.example.com:8002', posting to main thread
[2021-11-22 08:07:43.788][15][debug][forward_proxy] [source/extensions/filters/http/dynamic_forward_proxy/proxy_filter.cc:139] [C0][S15031825648262010483] waiting to load DNS cache entry
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/filter_manager.cc:547] [C0][S15031825648262010483] decode headers called: filter=0x5781fea94000 status=4
[2021-11-22 08:07:43.788][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77300 for 300000ms, min is 300000ms
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/http1/codec_impl.cc:628] [C0] parsed 100 bytes
[2021-11-22 08:07:43.788][15][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2021-11-22 08:07:43.788][15][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2021-11-22 08:07:43.788][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:293] starting main thread resolve for host='service1.example.com:8002' dns='service1.example.com' port='8002'
[2021-11-22 08:07:43.788][1][debug][dns] [source/common/network/dns_impl.cc:270] dns resolution for service1.example.com started
[2021-11-22 08:07:43.788][1][debug][dns] [source/common/network/dns_impl.cc:188] dns resolution for service1.example.com completed with status 0
[2021-11-22 08:07:43.788][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:316] main thread resolve complete for host 'service1.example.com:8002': [172.26.0.3:0]
[2021-11-22 08:07:43.788][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:360] host 'service1.example.com:8002' address has changed
[2021-11-22 08:07:43.788][1][debug][upstream] [source/extensions/clusters/dynamic_forward_proxy/cluster.cc:106] Adding host info for service1.example.com:8002
[2021-11-22 08:07:43.788][1][debug][upstream] [source/extensions/clusters/dynamic_forward_proxy/cluster.cc:85] adding new dfproxy cluster host 'service1.example.com:8002'
[2021-11-22 08:07:43.788][1][debug][upstream] [source/common/upstream/upstream_impl.cc:256] transport socket match, socket default selected for host with address 172.26.0.3:8002
[2021-11-22 08:07:43.788][1][trace][upstream] [source/common/upstream/upstream_impl.cc:1468] Local locality:
[2021-11-22 08:07:43.788][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster example_application added 1 removed 0
[2021-11-22 08:07:43.788][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster example_application added 1 removed 0
[2021-11-22 08:07:43.788][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster example_application
[2021-11-22 08:07:43.788][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster example_application
[2021-11-22 08:07:43.788][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:395] DNS refresh rate reset for host 'service1.example.com:8002', refresh rate 60000 ms
[2021-11-22 08:07:43.788][15][debug][forward_proxy] [source/extensions/filters/http/dynamic_forward_proxy/proxy_filter.cc:185] [C0][S15031825648262010483] load DNS cache complete, continuing after adding resolved host: service1.example.com
[2021-11-22 08:07:43.788][15][trace][http] [source/common/http/filter_manager.cc:70] [C0][S15031825648262010483] continuing filter chain: filter=0x5781fea94000
[2021-11-22 08:07:43.788][15][debug][router] [source/common/router/router.cc:457] [C0][S15031825648262010483] cluster 'example_application' match for URL '/test'
[2021-11-22 08:07:43.788][15][debug][router] [source/common/router/router.cc:673] [C0][S15031825648262010483] router decoding headers:
':authority', 'service1.example.com:8002'
':path', '/test'
':method', 'GET'
':scheme', 'http'
':destination', 'service1.example.com:8002'
'user-agent', 'curl/7.68.0'
'accept', '*/*'
'service', 'service1'
'x-forwarded-proto', 'http'
'x-request-id', '3a1c8e4f-33e0-475b-ac35-3f983b26ae10'
[2021-11-22 08:07:43.788][15][debug][pool] [source/common/http/conn_pool_base.cc:74] queueing stream due to no available connections
[2021-11-22 08:07:43.788][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:255] trying to create new connection
[2021-11-22 08:07:43.788][15][trace][pool] [source/common/conn_pool/conn_pool_base.cc:256] ConnPoolImplBase 0x5781fead4600, 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
[2021-11-22 08:07:43.788][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:143] creating a new connection
[2021-11-22 08:07:43.789][15][debug][client] [source/common/http/codec_client.cc:60] [C1] connecting
[2021-11-22 08:07:43.789][15][debug][connection] [source/common/network/connection_impl.cc:890] [C1] connecting to 172.26.0.3:8002
[2021-11-22 08:07:43.789][15][debug][connection] [source/common/network/connection_impl.cc:909] [C1] connection in progress
[2021-11-22 08:07:43.789][15][trace][pool] [source/common/conn_pool/conn_pool_base.cc:129] not creating a new connection, shouldCreateNewConnection returned false.
[2021-11-22 08:07:43.789][15][trace][http] [source/common/http/filter_manager.cc:547] [C0][S15031825648262010483] decode headers called: filter=0x5781fea942a0 status=1
[2021-11-22 08:07:43.789][15][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2021-11-22 08:07:43.789][15][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2021-11-22 08:07:43.789][15][debug][connection] [source/common/network/connection_impl.cc:672] [C1] connected
[2021-11-22 08:07:43.789][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_handshaker.cc:236] [C1] ssl error occurred while read: WANT_READ
[2021-11-22 08:07:43.798][15][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2021-11-22 08:07:43.798][15][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2021-11-22 08:07:43.799][15][debug][connection] [source/extensions/transport_sockets/tls/cert_validator/default_validator.cc:198] X509_verify_cert: certificate verification error at depth 0: self signed certificate
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_handshaker.cc:236] [C1] ssl error occurred while read: WANT_READ
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_handshaker.cc:236] [C1] ssl error occurred while read: WANT_READ
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:417] [C1] raising connection event 2
[2021-11-22 08:07:43.799][15][debug][client] [source/common/http/codec_client.cc:88] [C1] connected
[2021-11-22 08:07:43.799][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:293] [C1] attaching to next stream
[2021-11-22 08:07:43.799][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:176] [C1] creating stream
[2021-11-22 08:07:43.799][15][debug][router] [source/common/router/upstream_request.cc:416] [C0][S15031825648262010483] pool ready
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:477] [C1] writing 189 bytes, end_stream false
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:252] [C1] ssl write returns: 189
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:85] [C1] ssl read returns: -1
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:125] [C1] ssl error occurred while read: WANT_READ
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:161] [C1] ssl read 0 bytes
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 2
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:554] [C1] socket event: 3
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:663] [C1] write ready
[2021-11-22 08:07:43.799][15][trace][connection] [source/common/network/connection_impl.cc:592] [C1] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:85] [C1] ssl read returns: 246
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:85] [C1] ssl read returns: -1
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:125] [C1] ssl error occurred while read: WANT_READ
[2021-11-22 08:07:43.799][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:161] [C1] ssl read 246 bytes
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:579] [C1] parsing 246 bytes
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:858] [C1] message begin
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C1] completed header: key=Server value=nginx/1.21.3
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C1] completed header: key=Date value=Mon, 22 Nov 2021 08:07:43 GMT
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C1] completed header: key=Content-Type value=text/plain
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C1] completed header: key=Content-Length value=98
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:709] [C1] onHeadersCompleteBase
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C1] completed header: key=Connection value=keep-alive
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:1283] [C1] status_code 200
[2021-11-22 08:07:43.799][15][trace][http] [source/common/http/http1/codec_impl.cc:1293] [C1] Client: onHeadersComplete size=5
[2021-11-22 08:07:43.799][15][debug][router] [source/common/router/router.cc:1285] [C0][S15031825648262010483] upstream headers complete: end_stream=false
[2021-11-22 08:07:43.800][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77300 for 300000ms, min is 300000ms
[2021-11-22 08:07:43.800][15][trace][http] [source/common/http/filter_manager.cc:1112] [C0][S15031825648262010483] encode headers called: filter=0x5781fea94070 status=0
[2021-11-22 08:07:43.800][15][debug][http] [source/common/http/conn_manager_impl.cc:1467] [C0][S15031825648262010483] encoding headers via codec (end_stream=false):
':status', '200'
'server', 'envoy'
'date', 'Mon, 22 Nov 2021 08:07:43 GMT'
'content-type', 'text/plain'
'content-length', '98'
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 117 bytes, end_stream false
[2021-11-22 08:07:43.800][15][trace][http] [source/common/http/http1/codec_impl.cc:836] [C1] message complete
[2021-11-22 08:07:43.800][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77300 for 300000ms, min is 300000ms
[2021-11-22 08:07:43.800][15][trace][http] [source/common/http/filter_manager.cc:1280] [C0][S15031825648262010483] encode data called: filter=0x5781fea94070 status=0
[2021-11-22 08:07:43.800][15][trace][http] [source/common/http/conn_manager_impl.cc:1476] [C0][S15031825648262010483] encoding data via codec (size=98 end_stream=false)
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:477] [C0] writing 98 bytes, end_stream false
[2021-11-22 08:07:43.800][15][trace][http] [source/common/http/http1/codec_impl.cc:1365] [C1] message complete
[2021-11-22 08:07:43.800][15][debug][client] [source/common/http/codec_client.cc:132] [C1] response complete
[2021-11-22 08:07:43.800][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2021-11-22 08:07:43.800][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77300 for 300000ms, min is 300000ms
[2021-11-22 08:07:43.800][15][trace][http] [source/common/http/filter_manager.cc:1280] [C0][S15031825648262010483] encode data called: filter=0x5781fea94070 status=0
[2021-11-22 08:07:43.800][15][trace][http] [source/common/http/conn_manager_impl.cc:1476] [C0][S15031825648262010483] encoding data via codec (size=0 end_stream=true)
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:356] [C0] readDisable: disable=false disable_count=1 state=0 buffer_length=0
[2021-11-22 08:07:43.800][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=2)
[2021-11-22 08:07:43.800][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77180 for 3600000ms, min is 3600000ms
[2021-11-22 08:07:43.800][15][debug][pool] [source/common/http/http1/conn_pool.cc:53] [C1] response complete
[2021-11-22 08:07:43.800][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:204] [C1] destroying stream: 0 remaining
[2021-11-22 08:07:43.800][15][trace][http] [source/common/http/http1/codec_impl.cc:628] [C1] parsed 246 bytes
[2021-11-22 08:07:43.800][15][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=2)
[2021-11-22 08:07:43.800][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:210] marking dead N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE at 0x7f2ec73e53e0
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 2
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C0] write returns: 215
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:554] [C0] socket event: 3
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:663] [C0] write ready
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:592] [C0] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C0] read returns: 0
[2021-11-22 08:07:43.800][15][debug][connection] [source/common/network/connection_impl.cc:640] [C0] remote close
[2021-11-22 08:07:43.800][15][debug][connection] [source/common/network/connection_impl.cc:249] [C0] closing socket: 0
[2021-11-22 08:07:43.800][15][trace][connection] [source/common/network/connection_impl.cc:417] [C0] raising connection event 0
[2021-11-22 08:07:43.800][15][trace][conn_handler] [source/server/active_stream_listener_base.cc:111] [C0] connection on event 0
[2021-11-22 08:07:43.800][15][debug][conn_handler] [source/server/active_stream_listener_base.cc:120] [C0] adding to cleanup list
[2021-11-22 08:07:43.800][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2021-11-22 08:07:43.800][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=2)
[2021-11-22 08:07:43.800][15][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=2)
[2021-11-22 08:07:44.297][1][debug][main] [source/server/server.cc:229] flushing stats
[2021-11-22 08:07:47.242][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77200 for 3600000ms, min is 3600000ms
### start service2.example.com ###
[2021-11-22 08:07:47.242][15][debug][conn_handler] [source/server/active_tcp_listener.cc:140] [C2] new connection from 172.26.0.1:36766
[2021-11-22 08:07:47.242][15][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 3
[2021-11-22 08:07:47.242][15][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2021-11-22 08:07:47.242][15][trace][connection] [source/common/network/connection_impl.cc:592] [C2] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:47.242][15][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C2] read returns: 100
[2021-11-22 08:07:47.242][15][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C2] read error: Resource temporarily unavailable
[2021-11-22 08:07:47.242][15][trace][http] [source/common/http/http1/codec_impl.cc:579] [C2] parsing 100 bytes
[2021-11-22 08:07:47.242][15][trace][http] [source/common/http/http1/codec_impl.cc:858] [C2] message begin
[2021-11-22 08:07:47.242][15][debug][http] [source/common/http/conn_manager_impl.cc:274] [C2] new stream
[2021-11-22 08:07:47.242][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77380 for 300000ms, min is 300000ms
[2021-11-22 08:07:47.242][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C2] completed header: key=Host value=localhost:8080
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C2] completed header: key=User-Agent value=curl/7.68.0
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C2] completed header: key=Accept value=*/*
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/http1/codec_impl.cc:709] [C2] onHeadersCompleteBase
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C2] completed header: key=service value=service2
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/http1/codec_impl.cc:1056] [C2] Server: onHeadersComplete size=4
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/http1/codec_impl.cc:836] [C2] message complete
[2021-11-22 08:07:47.243][15][trace][connection] [source/common/network/connection_impl.cc:356] [C2] readDisable: disable=true disable_count=0 state=0 buffer_length=100
[2021-11-22 08:07:47.243][15][debug][http] [source/common/http/conn_manager_impl.cc:867] [C2][S7769712806432640307] request headers complete (end_stream=true):
':authority', 'localhost:8080'
':path', '/test'
':method', 'GET'
'user-agent', 'curl/7.68.0'
'accept', '*/*'
'service', 'service2'
[2021-11-22 08:07:47.243][15][debug][http] [source/common/http/filter_manager.cc:835] [C2][S7769712806432640307] request end stream
[2021-11-22 08:07:47.243][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:149] creating N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE at 0x7f2ec73e58a0
[2021-11-22 08:07:47.243][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:149] creating N5Envoy10Extensions11HttpFilters3Lua16HeaderMapWrapperE at 0x7f2ec73e5a60
[2021-11-22 08:07:47.243][15][debug][lua] [source/extensions/filters/common/lua/lua.cc:39] coroutine finished
[2021-11-22 08:07:47.243][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:210] marking dead N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE at 0x7f2ec73e58a0
[2021-11-22 08:07:47.243][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:210] marking dead N5Envoy10Extensions11HttpFilters3Lua16HeaderMapWrapperE at 0x7f2ec73e5a60
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/filter_manager.cc:547] [C2][S7769712806432640307] decode headers called: filter=0x5781fea94000 status=0
[2021-11-22 08:07:47.243][15][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:104] thread local lookup for host 'service2.example.com:8002'
[2021-11-22 08:07:47.243][15][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:125] cache miss for host 'service2.example.com:8002', posting to main thread
[2021-11-22 08:07:47.243][15][debug][forward_proxy] [source/extensions/filters/http/dynamic_forward_proxy/proxy_filter.cc:139] [C2][S7769712806432640307] waiting to load DNS cache entry
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/filter_manager.cc:547] [C2][S7769712806432640307] decode headers called: filter=0x5781fea94070 status=4
[2021-11-22 08:07:47.243][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77380 for 300000ms, min is 300000ms
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/http1/codec_impl.cc:628] [C2] parsed 100 bytes
[2021-11-22 08:07:47.243][15][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 2
[2021-11-22 08:07:47.243][15][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2021-11-22 08:07:47.243][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:293] starting main thread resolve for host='service2.example.com:8002' dns='service2.example.com' port='8002'
[2021-11-22 08:07:47.243][1][debug][dns] [source/common/network/dns_impl.cc:270] dns resolution for service2.example.com started
[2021-11-22 08:07:47.243][1][debug][dns] [source/common/network/dns_impl.cc:188] dns resolution for service2.example.com completed with status 0
[2021-11-22 08:07:47.243][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:316] main thread resolve complete for host 'service2.example.com:8002': [172.26.0.3:0]
[2021-11-22 08:07:47.243][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:360] host 'service2.example.com:8002' address has changed
[2021-11-22 08:07:47.243][1][debug][upstream] [source/extensions/clusters/dynamic_forward_proxy/cluster.cc:106] Adding host info for service2.example.com:8002
[2021-11-22 08:07:47.243][1][debug][upstream] [source/extensions/clusters/dynamic_forward_proxy/cluster.cc:85] adding new dfproxy cluster host 'service2.example.com:8002'
[2021-11-22 08:07:47.243][1][debug][upstream] [source/common/upstream/upstream_impl.cc:256] transport socket match, socket default selected for host with address 172.26.0.3:8002
[2021-11-22 08:07:47.243][1][trace][upstream] [source/common/upstream/upstream_impl.cc:1468] Local locality:
[2021-11-22 08:07:47.243][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster example_application added 1 removed 0
[2021-11-22 08:07:47.243][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster example_application
[2021-11-22 08:07:47.243][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1087] membership update for TLS cluster example_application added 1 removed 0
[2021-11-22 08:07:47.243][1][debug][forward_proxy] [source/extensions/common/dynamic_forward_proxy/dns_cache_impl.cc:395] DNS refresh rate reset for host 'service2.example.com:8002', refresh rate 60000 ms
[2021-11-22 08:07:47.243][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1093] re-creating local LB for TLS cluster example_application
[2021-11-22 08:07:47.243][15][debug][forward_proxy] [source/extensions/filters/http/dynamic_forward_proxy/proxy_filter.cc:185] [C2][S7769712806432640307] load DNS cache complete, continuing after adding resolved host: service2.example.com
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/filter_manager.cc:70] [C2][S7769712806432640307] continuing filter chain: filter=0x5781fea94070
[2021-11-22 08:07:47.243][15][debug][router] [source/common/router/router.cc:457] [C2][S7769712806432640307] cluster 'example_application' match for URL '/test'
[2021-11-22 08:07:47.243][15][debug][router] [source/common/router/router.cc:673] [C2][S7769712806432640307] router decoding headers:
':authority', 'service2.example.com:8002'
':path', '/test'
':method', 'GET'
':scheme', 'http'
':destination', 'service2.example.com:8002'
'user-agent', 'curl/7.68.0'
'accept', '*/*'
'service', 'service2'
'x-forwarded-proto', 'http'
'x-request-id', '6e5937f7-1fb7-4012-becc-883f3d3a64ac'
[2021-11-22 08:07:47.243][15][debug][pool] [source/common/http/conn_pool_base.cc:74] queueing stream due to no available connections
[2021-11-22 08:07:47.243][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:255] trying to create new connection
[2021-11-22 08:07:47.243][15][trace][pool] [source/common/conn_pool/conn_pool_base.cc:256] ConnPoolImplBase 0x5781fead4480, 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
[2021-11-22 08:07:47.243][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:143] creating a new connection
[2021-11-22 08:07:47.243][15][debug][client] [source/common/http/codec_client.cc:60] [C3] connecting
[2021-11-22 08:07:47.243][15][debug][connection] [source/common/network/connection_impl.cc:890] [C3] connecting to 172.26.0.3:8002
[2021-11-22 08:07:47.243][15][debug][connection] [source/common/network/connection_impl.cc:909] [C3] connection in progress
[2021-11-22 08:07:47.243][15][trace][pool] [source/common/conn_pool/conn_pool_base.cc:129] not creating a new connection, shouldCreateNewConnection returned false.
[2021-11-22 08:07:47.243][15][trace][http] [source/common/http/filter_manager.cc:547] [C2][S7769712806432640307] decode headers called: filter=0x5781fea94310 status=1
[2021-11-22 08:07:47.243][15][trace][connection] [source/common/network/connection_impl.cc:554] [C3] socket event: 2
[2021-11-22 08:07:47.243][15][trace][connection] [source/common/network/connection_impl.cc:663] [C3] write ready
[2021-11-22 08:07:47.243][15][debug][connection] [source/common/network/connection_impl.cc:672] [C3] connected
[2021-11-22 08:07:47.243][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_handshaker.cc:236] [C3] ssl error occurred while read: WANT_READ
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:554] [C3] socket event: 3
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:663] [C3] write ready
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:417] [C3] raising connection event 2
[2021-11-22 08:07:47.244][15][debug][client] [source/common/http/codec_client.cc:88] [C3] connected
[2021-11-22 08:07:47.244][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:293] [C3] attaching to next stream
[2021-11-22 08:07:47.244][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:176] [C3] creating stream
[2021-11-22 08:07:47.244][15][debug][router] [source/common/router/upstream_request.cc:416] [C2][S7769712806432640307] pool ready
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:477] [C3] writing 189 bytes, end_stream false
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:663] [C3] write ready
[2021-11-22 08:07:47.244][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:252] [C3] ssl write returns: 189
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:592] [C3] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:47.244][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:85] [C3] ssl read returns: -1
[2021-11-22 08:07:47.244][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:125] [C3] ssl error occurred while read: WANT_READ
[2021-11-22 08:07:47.244][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:161] [C3] ssl read 0 bytes
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:554] [C3] socket event: 2
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:663] [C3] write ready
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:554] [C3] socket event: 3
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:663] [C3] write ready
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:592] [C3] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:47.244][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:85] [C3] ssl read returns: 246
[2021-11-22 08:07:47.244][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:85] [C3] ssl read returns: -1
[2021-11-22 08:07:47.244][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:125] [C3] ssl error occurred while read: WANT_READ
[2021-11-22 08:07:47.244][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:161] [C3] ssl read 246 bytes
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:579] [C3] parsing 246 bytes
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:858] [C3] message begin
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C3] completed header: key=Server value=nginx/1.21.3
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C3] completed header: key=Date value=Mon, 22 Nov 2021 08:07:47 GMT
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C3] completed header: key=Content-Type value=text/plain
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C3] completed header: key=Content-Length value=98
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:709] [C3] onHeadersCompleteBase
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:499] [C3] completed header: key=Connection value=keep-alive
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:1283] [C3] status_code 200
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:1293] [C3] Client: onHeadersComplete size=5
[2021-11-22 08:07:47.244][15][debug][router] [source/common/router/router.cc:1285] [C2][S7769712806432640307] upstream headers complete: end_stream=false
[2021-11-22 08:07:47.244][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77380 for 300000ms, min is 300000ms
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/filter_manager.cc:1112] [C2][S7769712806432640307] encode headers called: filter=0x5781fea940e0 status=0
[2021-11-22 08:07:47.244][15][debug][http] [source/common/http/conn_manager_impl.cc:1467] [C2][S7769712806432640307] encoding headers via codec (end_stream=false):
':status', '200'
'server', 'envoy'
'date', 'Mon, 22 Nov 2021 08:07:47 GMT'
'content-type', 'text/plain'
'content-length', '98'
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:477] [C2] writing 117 bytes, end_stream false
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:836] [C3] message complete
[2021-11-22 08:07:47.244][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77380 for 300000ms, min is 300000ms
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/filter_manager.cc:1280] [C2][S7769712806432640307] encode data called: filter=0x5781fea940e0 status=0
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/conn_manager_impl.cc:1476] [C2][S7769712806432640307] encoding data via codec (size=98 end_stream=false)
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:477] [C2] writing 98 bytes, end_stream false
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:1365] [C3] message complete
[2021-11-22 08:07:47.244][15][debug][client] [source/common/http/codec_client.cc:132] [C3] response complete
[2021-11-22 08:07:47.244][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2021-11-22 08:07:47.244][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77380 for 300000ms, min is 300000ms
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/filter_manager.cc:1280] [C2][S7769712806432640307] encode data called: filter=0x5781fea940e0 status=0
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/conn_manager_impl.cc:1476] [C2][S7769712806432640307] encoding data via codec (size=0 end_stream=true)
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:356] [C2] readDisable: disable=false disable_count=1 state=0 buffer_length=0
[2021-11-22 08:07:47.244][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=2)
[2021-11-22 08:07:47.244][15][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x5781fea77200 for 3600000ms, min is 3600000ms
[2021-11-22 08:07:47.244][15][debug][pool] [source/common/http/http1/conn_pool.cc:53] [C3] response complete
[2021-11-22 08:07:47.244][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:204] [C3] destroying stream: 0 remaining
[2021-11-22 08:07:47.244][15][trace][http] [source/common/http/http1/codec_impl.cc:628] [C3] parsed 246 bytes
[2021-11-22 08:07:47.244][15][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=2)
[2021-11-22 08:07:47.244][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:210] marking dead N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE at 0x7f2ec73e58a0
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 2
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C2] write returns: 215
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:554] [C2] socket event: 3
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:663] [C2] write ready
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:592] [C2] read ready. dispatch_buffered_data=false
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C2] read returns: 0
[2021-11-22 08:07:47.244][15][debug][connection] [source/common/network/connection_impl.cc:640] [C2] remote close
[2021-11-22 08:07:47.244][15][debug][connection] [source/common/network/connection_impl.cc:249] [C2] closing socket: 0
[2021-11-22 08:07:47.244][15][trace][connection] [source/common/network/connection_impl.cc:417] [C2] raising connection event 0
[2021-11-22 08:07:47.244][15][trace][conn_handler] [source/server/active_stream_listener_base.cc:111] [C2] connection on event 0
[2021-11-22 08:07:47.244][15][debug][conn_handler] [source/server/active_stream_listener_base.cc:120] [C2] adding to cleanup list
[2021-11-22 08:07:47.244][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2021-11-22 08:07:47.244][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=2)
[2021-11-22 08:07:47.244][15][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=2)
[2021-11-22 08:07:49.297][1][debug][main] [source/server/server.cc:229] flushing stats
[2021-11-22 08:07:51.241][1][warning][main] [source/server/server.cc:780] caught SIGINT
[2021-11-22 08:07:51.241][1][info][main] [source/server/server.cc:899] shutting down server instance
[2021-11-22 08:07:51.241][1][info][main] [source/server/server.cc:841] main dispatch loop exited
[2021-11-22 08:07:51.241][15][debug][main] [source/server/worker_impl.cc:132] worker exited dispatch loop
[2021-11-22 08:07:51.241][15][trace][main] [source/common/event/dispatcher_impl.cc:337] shutdown destroyed 0 thread local objects. Peek 0 deferred deletables, 0 post callbacks.
[2021-11-22 08:07:51.241][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:172] destroying N5Envoy10Extensions11HttpFilters3Lua16HeaderMapWrapperE at 0x7f2ec73e5a60
[2021-11-22 08:07:51.241][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:172] destroying N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE at 0x7f2ec73e58a0
[2021-11-22 08:07:51.241][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:172] destroying N5Envoy10Extensions11HttpFilters3Lua16HeaderMapWrapperE at 0x7f2ec73d9bb0
[2021-11-22 08:07:51.241][15][trace][lua] [./source/extensions/filters/common/lua/lua.h:172] destroying N5Envoy10Extensions11HttpFilters3Lua19StreamHandleWrapperE at 0x7f2ec73e53e0
[2021-11-22 08:07:51.242][15][debug][grpc] [source/common/grpc/google_async_client_impl.cc:40] Joining completionThread
[2021-11-22 08:07:51.242][16][debug][grpc] [source/common/grpc/google_async_client_impl.cc:73] completionThread exiting
[2021-11-22 08:07:51.242][15][debug][grpc] [source/common/grpc/google_async_client_impl.cc:42] Joined completionThread
[2021-11-22 08:07:51.242][15][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1166] shutting down thread local cluster manager
[2021-11-22 08:07:51.242][15][debug][connection] [source/common/network/connection_impl.cc:138] [C3] closing data_to_write=0 type=1
[2021-11-22 08:07:51.242][15][debug][connection] [source/common/network/connection_impl.cc:249] [C3] closing socket: 1
[2021-11-22 08:07:51.242][15][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:304] [C3] SSL shutdown: rc=0
[2021-11-22 08:07:51.242][15][trace][connection] [source/common/network/connection_impl.cc:417] [C3] raising connection event 1
[2021-11-22 08:07:51.242][15][debug][client] [source/common/http/codec_client.cc:106] [C3] disconnect. resetting 0 pending requests
[2021-11-22 08:07:51.242][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:422] [C3] client disconnected, failure reason:
[2021-11-22 08:07:51.242][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2021-11-22 08:07:51.242][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:397] invoking idle callbacks - is_draining_for_deletion_=false
[2021-11-22 08:07:51.242][15][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=1)
[2021-11-22 08:07:51.242][15][debug][connection] [source/common/network/connection_impl.cc:138] [C1] closing data_to_write=0 type=1
[2021-11-22 08:07:51.242][15][debug][connection] [source/common/network/connection_impl.cc:249] [C1] closing socket: 1
[2021-11-22 08:07:51.242][15][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:304] [C1] SSL shutdown: rc=0
[2021-11-22 08:07:51.242][15][trace][connection] [source/common/network/connection_impl.cc:417] [C1] raising connection event 1
[2021-11-22 08:07:51.242][15][debug][client] [source/common/http/codec_client.cc:106] [C1] disconnect. resetting 0 pending requests
[2021-11-22 08:07:51.242][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:422] [C1] client disconnected, failure reason:
[2021-11-22 08:07:51.242][15][trace][main] [source/common/event/dispatcher_impl.cc:255] item added to deferred deletion list (size=1)
[2021-11-22 08:07:51.242][15][debug][pool] [source/common/conn_pool/conn_pool_base.cc:397] invoking idle callbacks - is_draining_for_deletion_=false
[2021-11-22 08:07:51.242][15][trace][main] [source/common/event/dispatcher_impl.cc:117] clearing deferred deletion list (size=1)
[2021-11-22 08:07:51.242][1][debug][main] [source/server/server.cc:229] flushing stats
[2021-11-22 08:07:51.242][1][debug][init] [source/common/init/watcher_impl.cc:31] ClusterImplBase destroyed
[2021-11-22 08:07:51.242][1][debug][init] [source/common/init/watcher_impl.cc:31] init manager Cluster example_application destroyed
[2021-11-22 08:07:51.242][1][debug][grpc] [source/common/grpc/google_async_client_impl.cc:40] Joining completionThread
[2021-11-22 08:07:51.242][12][debug][grpc] [source/common/grpc/google_async_client_impl.cc:73] completionThread exiting
[2021-11-22 08:07:51.242][1][debug][grpc] [source/common/grpc/google_async_client_impl.cc:42] Joined completionThread
[2021-11-22 08:07:51.242][1][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1166] shutting down thread local cluster manager
[2021-11-22 08:07:51.242][1][trace][upstream] [source/common/upstream/upstream_impl.cc:1036] Schedule destroy cluster info example_application
[2021-11-22 08:07:51.242][1][info][main] [source/server/server.cc:891] exiting
[2021-11-22 08:07:51.242][1][debug][init] [source/common/init/watcher_impl.cc:31] RunHelper destroyed
[2021-11-22 08:07:51.242][1][debug][main] [source/server/server.cc:146] destroying listener manager
[2021-11-22 08:07:51.242][1][debug][main] [source/common/event/dispatcher_impl.cc:82] destroying dispatcher worker_0
[2021-11-22 08:07:51.242][1][debug][init] [source/common/init/watcher_impl.cc:31] Listener-local-init-watcher envoy_proxy destroyed
[2021-11-22 08:07:51.243][1][debug][init] [source/common/init/watcher_impl.cc:31] init manager Listener-local-init-manager envoy_proxy 1163153861056860990 destroyed
[2021-11-22 08:07:51.243][1][debug][init] [source/common/init/target_impl.cc:34] target Listener-init-target envoy_proxy destroyed
[2021-11-22 08:07:51.243][1][debug][main] [source/server/server.cc:148] destroyed listener manager
[2021-11-22 08:07:51.244][1][trace][main] [source/common/event/dispatcher_impl.cc:337] shutdown destroyed 0 thread local objects. Peek 0 deferred deletables, 1 post callbacks.
[2021-11-22 08:07:51.244][1][debug][main] [source/common/event/dispatcher_impl.cc:82] destroying dispatcher workers_guarddog_thread
[2021-11-22 08:07:51.244][1][debug][main] [source/common/event/dispatcher_impl.cc:82] destroying dispatcher main_thread_guarddog_thread
[2021-11-22 08:07:51.244][1][debug][main] [source/common/access_log/access_log_manager_impl.cc:21] destroyed access loggers
[2021-11-22 08:07:51.244][1][debug][init] [source/common/init/watcher_impl.cc:31] init manager RTDS destroyed
[2021-11-22 08:07:51.244][1][debug][init] [source/common/init/watcher_impl.cc:31] RTDS destroyed
[2021-11-22 08:07:51.244][1][debug][main] [source/common/event/dispatcher_impl.cc:82] destroying dispatcher main_thread
[2021-11-22 08:07:51.244][1][debug][init] [source/common/init/watcher_impl.cc:31] init manager Server destroyed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment