Created
November 22, 2021 08:15
-
-
Save Shikugawa/537f3df4fe9c58f20ebdcf94c1ca1952 to your computer and use it in GitHub Desktop.
envoy#18897
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
[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