Skip to content

Instantly share code, notes, and snippets.

@jmarantz

jmarantz/aws_lambda_filter_integration_test.log

Created March 27, 2020 15:58
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save jmarantz/0016130f59baf87d32530455b1f91ea5 to your computer and use it in GitHub Desktop.
Save jmarantz/0016130f59baf87d32530455b1f91ea5 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
aws_lambda_filter_integration_test.log
exec ${PAGER:-/usr/bin/less} "$0" || exit 1
Executing tests from //test/extensions/filters/http/aws_lambda:aws_lambda_filter_integration_test
-----------------------------------------------------------------------------
WARNING: Perftools heap leak checker is active -- Performance may suffer
[==========] Running 6 tests from 1 test suite.
[----------] Global test environment set-up.
[----------] 6 tests from IpVersions/AwsLambdaFilterIntegrationTest
[ RUN ] IpVersions/AwsLambdaFilterIntegrationTest.JsonWrappedHeaderOnlyRequest/IPv4
[2020-03-27 15:56:33.103][14][info][testing] [test/integration/fake_upstream.cc:398] starting fake server on socket 0.0.0.0:0. Address version is v4. UDP=false
[2020-03-27 15:56:33.103][14][debug][misc] [test/integration/integration.cc:330] Setting up file-based LDS
[2020-03-27 15:56:33.103][14][debug][misc] [test/config/utility.cc:450] No tap path set for tests
[2020-03-27 15:56:33.115][14][debug][misc] [test/integration/integration.cc:362] Running Envoy with configuration:
static_resources:
clusters:
- name: cluster_0
connect_timeout: 5s
metadata:
filter_metadata:
com.amazonaws.lambda:
egress_gateway: true
load_assignment:
cluster_name: cluster_0
endpoints:
- lb_endpoints:
- endpoint:
address:
socket_address:
address: 127.0.0.1
port_value: 41533
secrets:
- name: secret_static_0
tls_certificate:
certificate_chain:
inline_string: DUMMY_INLINE_BYTES
private_key:
inline_string: DUMMY_INLINE_BYTES
password:
inline_string: DUMMY_INLINE_BYTES
dynamic_resources:
lds_config:
path: /export/data/jmarantz/bazel-cache/_bazel_jmarantz/b7219eec556c5fdb49e848eb682aaa45/sandbox/linux-sandbox/1613/execroot/envoy/_tmp/23d0a72e3f7f7ed7321a631458573703/14_1585324593103720
admin:
access_log_path: /dev/null
address:
socket_address:
address: 127.0.0.1
port_value: 0
[2020-03-27 15:56:33.118][14][info][testing] [test/integration/server.cc:87] starting integration test server
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:253] initializing epoch 0 (hot restart version=disabled)
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:255] statically linked extensions:
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.grpc_credentials: envoy.grpc_credentials.default
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.udp_listeners: raw_udp_listener
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.access_loggers: envoy.access_loggers.file, envoy.file_access_log
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.clusters: envoy.cluster.eds, envoy.cluster.logical_dns, envoy.cluster.original_dst, envoy.cluster.static, envoy.cluster.strict_dns
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.transport_sockets.downstream: envoy.transport_sockets.raw_buffer, envoy.transport_sockets.tap, envoy.transport_sockets.tls, raw_buffer, tls
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.transport_sockets.upstream: envoy.transport_sockets.raw_buffer, envoy.transport_sockets.tap, envoy.transport_sockets.tls, raw_buffer, tls
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.resolvers: envoy.ip
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.filters.http: add-trailers-filter, call-decodedata-once-filter, decode-headers-only, decode-headers-return-stop-all-filter, encode-headers-only, encode-headers-return-stop-all-filter, envoy.filters.http.aws_lambda, envoy.filters.http.on_demand, envoy.filters.http.router, envoy.router, modify-buffer-filter, passthrough-filter, pause-filter
[2020-03-27 15:56:33.119][16][info][main] [source/server/server.cc:257] envoy.filters.network: envoy.filters.network.http_connection_manager, envoy.http_connection_manager
[2020-03-27 15:56:33.143][16][info][main] [source/server/server.cc:338] admin address: 127.0.0.1:0
[2020-03-27 15:56:33.159][16][debug][main] [source/server/overload_manager_impl.cc:184] No overload action is configured for envoy.overload_actions.shrink_heap.
[2020-03-27 15:56:33.160][16][debug][main] [source/server/overload_manager_impl.cc:184] No overload action is configured for envoy.overload_actions.stop_accepting_connections.
[2020-03-27 15:56:33.161][16][info][main] [source/server/server.cc:457] runtime: layers:
- name: base
static_layer:
{}
- name: admin
admin_layer:
{}
[2020-03-27 15:56:33.161][16][info][config] [source/server/configuration_impl.cc:103] loading tracing configuration
[2020-03-27 15:56:33.162][16][info][config] [source/server/configuration_impl.cc:69] loading 1 static secret(s)
[2020-03-27 15:56:33.162][16][debug][config] [source/server/configuration_impl.cc:71] static secret #0: secret_static_0
[2020-03-27 15:56:33.162][16][info][config] [source/server/configuration_impl.cc:75] loading 1 cluster(s)
[2020-03-27 15:56:33.169][20][debug][grpc] [source/common/grpc/google_async_client_impl.cc:45] completionThread running
[2020-03-27 15:56:33.184][16][debug][upstream] [source/common/upstream/upstream_impl.cc:274] transport socket match, socket default selected for host with address 127.0.0.1:41533
[2020-03-27 15:56:33.184][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:922] adding TLS initial cluster cluster_0
[2020-03-27 15:56:33.185][16][trace][upstream] [source/common/upstream/upstream_impl.cc:1215] Local locality: zone: "zone_name"
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/upstream_impl.cc:961] initializing Primary cluster cluster_0 completed
[2020-03-27 15:56:33.186][16][debug][init] [source/common/init/manager_impl.cc:45] init manager Cluster cluster_0 contains no targets
[2020-03-27 15:56:33.186][16][debug][init] [source/common/init/watcher_impl.cc:14] init manager Cluster cluster_0 initialized, notifying ClusterImplBase
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1081] membership update for TLS cluster cluster_0 added 1 removed 0
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:106] cm init: init complete: cluster=cluster_0 primary=0 secondary=0
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:126] maybe finish initialize state: 0
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:78] cm init: adding: cluster=cluster_0 primary=0 secondary=0
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:126] maybe finish initialize state: 1
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:134] maybe finish initialize primary init clusters empty: true
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:142] maybe finish initialize secondary init clusters empty: true
[2020-03-27 15:56:33.186][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:165] maybe finish initialize cds api ready: false
[2020-03-27 15:56:33.186][16][info][upstream] [source/common/upstream/cluster_manager_impl.cc:171] cm init: all clusters initialized
[2020-03-27 15:56:33.186][16][info][config] [source/server/configuration_impl.cc:79] loading 0 listener(s)
[2020-03-27 15:56:33.186][16][info][config] [source/server/configuration_impl.cc:129] loading stats sink configuration
[2020-03-27 15:56:33.186][16][debug][file] [source/common/filesystem/inotify/watcher_impl.cc:47] added watch for directory: '/export/data/jmarantz/bazel-cache/_bazel_jmarantz/b7219eec556c5fdb49e848eb682aaa45/sandbox/linux-sandbox/1613/execroot/envoy/_tmp/23d0a72e3f7f7ed7321a631458573703' file: '14_1585324593103720' fd: 1
[2020-03-27 15:56:33.186][16][debug][init] [source/common/init/manager_impl.cc:20] added target LDS to init manager Server
[2020-03-27 15:56:33.187][14][info][testing] [test/integration/server.cc:78] listener wait complete
[2020-03-27 15:56:33.189][16][info][main] [source/server/server.cc:531] all clusters initialized. initializing init manager
[2020-03-27 15:56:33.189][16][debug][init] [source/common/init/manager_impl.cc:49] init manager Server initializing
[2020-03-27 15:56:33.189][16][debug][init] [source/common/init/target_impl.cc:15] init manager Server initializing target LDS
[2020-03-27 15:56:33.189][16][debug][config] [source/common/config/filesystem_subscription_impl.cc:47] Filesystem config refresh for /export/data/jmarantz/bazel-cache/_bazel_jmarantz/b7219eec556c5fdb49e848eb682aaa45/sandbox/linux-sandbox/1613/execroot/envoy/_tmp/23d0a72e3f7f7ed7321a631458573703/14_1585324593103720
[2020-03-27 15:56:33.203][16][debug][config] [source/server/listener_manager_impl.cc:372] begin add/update listener: name=http hash=11905894650917321484
[2020-03-27 15:56:33.203][16][debug][config] [source/server/listener_manager_impl.cc:92] filter #0:
[2020-03-27 15:56:33.203][16][debug][config] [source/server/listener_manager_impl.cc:93] name: http
[2020-03-27 15:56:33.206][16][debug][config] [source/server/listener_manager_impl.cc:100] config: {
"@type": "type.googleapis.com/envoy.extensions.filters.network.http_connection_manager.v3.HttpConnectionManager",
"codec_type": "HTTP2",
"stat_prefix": "config_test",
"route_config": {
"name": "route_config_0",
"virtual_hosts": [
{
"name": "integration",
"domains": [
"*"
],
"routes": [
{
"match": {
"prefix": "/"
},
"route": {
"cluster": "cluster_0"
}
}
]
}
]
},
"http_filters": [
{
"name": "envoy.filters.http.aws_lambda",
"typed_config": {
"@type": "type.googleapis.com/envoy.extensions.filters.http.aws_lambda.v3.Config",
"payload_passthrough": false,
"arn": "arn:aws:lambda:us-west-2:123456789:function:test"
}
},
{
"name": "envoy.filters.http.router"
}
],
"access_log": [
{
"name": "accesslog",
"filter": {
"not_health_check_filter": {}
},
"typed_config": {
"@type": "type.googleapis.com/envoy.config.accesslog.v2.FileAccessLog",
"path": "/dev/null"
}
}
]
}
[2020-03-27 15:56:33.249][16][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:432] http filter #0
[2020-03-27 15:56:33.249][16][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:433] name: envoy.filters.http.aws_lambda
[2020-03-27 15:56:33.249][16][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:440] config: {
"@type": "type.googleapis.com/envoy.extensions.filters.http.aws_lambda.v3.Config",
"payload_passthrough": false,
"arn": "arn:aws:lambda:us-west-2:123456789:function:test"
}
[2020-03-27 15:56:33.249][16][warning][config] [bazel-out/k8-fastbuild/bin/include/envoy/registry/_virtual_includes/registry/envoy/registry/registry.h:222] Double registration for type: 'google.protobuf.Struct' by 'encode-headers-return-stop-all-filter' and 'add-trailers-filter'
[2020-03-27 15:56:33.249][16][warning][config] [bazel-out/k8-fastbuild/bin/include/envoy/registry/_virtual_includes/registry/envoy/registry/registry.h:222] Double registration for type: 'google.protobuf.Struct' by 'passthrough-filter' and ''
[2020-03-27 15:56:33.249][16][warning][config] [bazel-out/k8-fastbuild/bin/include/envoy/registry/_virtual_includes/registry/envoy/registry/registry.h:222] Double registration for type: 'google.protobuf.Struct' by 'decode-headers-return-stop-all-filter' and ''
[2020-03-27 15:56:33.249][16][warning][config] [bazel-out/k8-fastbuild/bin/include/envoy/registry/_virtual_includes/registry/envoy/registry/registry.h:222] Double registration for type: 'google.protobuf.Struct' by 'modify-buffer-filter' and ''
[2020-03-27 15:56:33.249][16][warning][config] [bazel-out/k8-fastbuild/bin/include/envoy/registry/_virtual_includes/registry/envoy/registry/registry.h:222] Double registration for type: 'google.protobuf.Struct' by 'call-decodedata-once-filter' and ''
[2020-03-27 15:56:33.249][16][warning][config] [bazel-out/k8-fastbuild/bin/include/envoy/registry/_virtual_includes/registry/envoy/registry/registry.h:222] Double registration for type: 'google.protobuf.Struct' by 'decode-headers-only' and ''
[2020-03-27 15:56:33.254][16][warning][config] [bazel-out/k8-fastbuild/bin/include/envoy/registry/_virtual_includes/registry/envoy/registry/registry.h:222] Double registration for type: 'google.protobuf.Struct' by 'encode-headers-only' and ''
[2020-03-27 15:56:33.254][16][warning][config] [bazel-out/k8-fastbuild/bin/include/envoy/registry/_virtual_includes/registry/envoy/registry/registry.h:222] Double registration for type: 'google.protobuf.Struct' by 'pause-filter' and ''
[2020-03-27 15:56:33.254][16][debug][aws] [source/extensions/common/aws/credentials_provider_impl.cc:189] Using environment credentials provider
[2020-03-27 15:56:33.254][16][debug][aws] [source/extensions/common/aws/credentials_provider_impl.cc:216] Using instance profile credentials provider
[2020-03-27 15:56:33.254][16][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:432] http filter #1
[2020-03-27 15:56:33.254][16][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:433] name: envoy.filters.http.router
[2020-03-27 15:56:33.254][16][debug][config] [source/extensions/filters/network/http_connection_manager/config.cc:440] config: {}
[2020-03-27 15:56:33.256][16][debug][init] [source/common/init/target_impl.cc:15] init manager Server initializing target Listener-init-target http
[2020-03-27 15:56:33.256][16][debug][init] [source/common/init/manager_impl.cc:45] init manager Listener-local-init-manager http contains no targets
[2020-03-27 15:56:33.256][16][debug][init] [source/common/init/watcher_impl.cc:14] init manager Listener-local-init-manager http initialized, notifying Listener-local-init-watcher http
[2020-03-27 15:56:33.256][16][debug][init] [source/common/init/watcher_impl.cc:14] target Listener-init-target http initialized, notifying init manager Server
[2020-03-27 15:56:33.256][16][debug][config] [source/server/listener_impl.cc:80] Create listen socket for listener http on address 127.0.0.1:0
[2020-03-27 15:56:33.256][16][debug][config] [source/server/listener_impl.cc:70] Set listener http socket factory local address to 127.0.0.1:36213
[2020-03-27 15:56:33.256][16][debug][config] [source/server/listener_impl.cc:408] add active listener: name=http, hash=11905894650917321484, address=127.0.0.1:0
[2020-03-27 15:56:33.256][16][info][upstream] [source/server/lds_api.cc:74] lds: add/update listener 'http'
[2020-03-27 15:56:33.256][16][debug][init] [source/common/init/watcher_impl.cc:14] target LDS initialized, notifying init manager Server
[2020-03-27 15:56:33.256][16][debug][init] [source/common/init/watcher_impl.cc:14] init manager Server initialized, notifying RunHelper
[2020-03-27 15:56:33.256][16][info][config] [source/server/listener_manager_impl.cc:700] all dependencies initialized. starting workers
[2020-03-27 15:56:33.256][16][debug][config] [source/server/listener_manager_impl.cc:711] starting worker 0
[2020-03-27 15:56:33.258][16][debug][config] [source/common/config/filesystem_subscription_impl.cc:58] Filesystem config update accepted for /export/data/jmarantz/bazel-cache/_bazel_jmarantz/b7219eec556c5fdb49e848eb682aaa45/sandbox/linux-sandbox/1613/execroot/envoy/_tmp/23d0a72e3f7f7ed7321a631458573703/14_1585324593103720: version_info: "0"
resources {
[type.googleapis.com/envoy.config.listener.v3.Listener] {
name: "http"
address {
socket_address {
address: "127.0.0.1"
port_value: 0
}
}
filter_chains {
filters {
name: "http"
typed_config {
[type.googleapis.com/envoy.extensions.filters.network.http_connection_manager.v3.HttpConnectionManager] {
codec_type: HTTP2
stat_prefix: "config_test"
route_config {
name: "route_config_0"
virtual_hosts {
name: "integration"
domains: "*"
routes {
match {
prefix: "/"
}
route {
cluster: "cluster_0"
}
}
}
}
http_filters {
name: "envoy.filters.http.aws_lambda"
typed_config {
[type.googleapis.com/envoy.extensions.filters.http.aws_lambda.v3.Config] {
arn: "arn:aws:lambda:us-west-2:123456789:function:test"
}
}
}
http_filters {
name: "envoy.filters.http.router"
}
access_log {
name: "accesslog"
filter {
not_health_check_filter {
}
}
typed_config {
[type.googleapis.com/envoy.config.accesslog.v2.FileAccessLog] {
path: "/dev/null"
}
}
}
}
}
}
}
}
}
183412668: "envoy.api.v2.DiscoveryResponse"
[2020-03-27 15:56:33.259][16][info][main] [source/server/server.cc:552] starting main dispatch loop
[2020-03-27 15:56:33.267][22][debug][main] [source/server/worker_impl.cc:103] worker entering dispatch loop
[2020-03-27 15:56:33.268][22][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:922] adding TLS initial cluster cluster_0
[2020-03-27 15:56:33.268][22][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:1081] membership update for TLS cluster cluster_0 added 1 removed 0
[2020-03-27 15:56:33.271][23][debug][grpc] [source/common/grpc/google_async_client_impl.cc:45] completionThread running
[2020-03-27 15:56:33.283][14][debug][testing] [test/integration/integration.cc:424] registered 'http' as port 36213.
[2020-03-27 15:56:33.287][14][debug][upstream] [source/common/upstream/upstream_impl.cc:274] transport socket match, socket test selected for host with address 127.0.0.1:80
[2020-03-27 15:56:33.287][14][debug][client] [source/common/http/codec_client.cc:34] [C0] connecting
[2020-03-27 15:56:33.287][14][debug][connection] [source/common/network/connection_impl.cc:727] [C0] connecting to 127.0.0.1:36213
[2020-03-27 15:56:33.287][14][debug][connection] [source/common/network/connection_impl.cc:736] [C0] connection in progress
[2020-03-27 15:56:33.288][22][debug][conn_handler] [source/server/connection_handler_impl.cc:372] [C1] new connection
[2020-03-27 15:56:33.288][22][trace][connection] [source/common/network/connection_impl.cc:493] [C1] socket event: 2
[2020-03-27 15:56:33.288][22][trace][connection] [source/common/network/connection_impl.cc:581] [C1] write ready
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = (nil)
[2020-03-27 15:56:33.288][14][debug][http2] [source/common/http/http2/codec_impl.cc:960] [C0] updating connection-level initial window size to 268435456
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/connection_impl.cc:493] [C0] socket event: 2
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/connection_impl.cc:581] [C0] write ready
[2020-03-27 15:56:33.288][14][debug][connection] [source/common/network/connection_impl.cc:592] [C0] connected
[2020-03-27 15:56:33.288][14][debug][client] [source/common/http/codec_client.cc:72] [C0] connected
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:750] [C0] send data: bytes=24
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/connection_impl.cc:429] [C0] writing 24 bytes, end_stream false
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: end transmission of client magic
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = (nil)
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: next frame: payloadlen=30, type=4, flags=0x00, stream_id=0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:696] [C0] about to send frame type=4, flags=0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: start transmitting frame type=4, length=39
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:750] [C0] send data: bytes=39
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/connection_impl.cc:429] [C0] writing 39 bytes, end_stream false
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: end transmission of a frame
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:639] [C0] sent frame type=4
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = 0x63097c0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: next frame: payloadlen=4, type=8, flags=0x00, stream_id=0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:696] [C0] about to send frame type=8, flags=0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: start transmitting frame type=8, length=13
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:750] [C0] send data: bytes=13
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/connection_impl.cc:429] [C0] writing 13 bytes, end_stream false
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: end transmission of a frame
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:639] [C0] sent frame type=8
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = 0x6309b80
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: dep_add dep_stream(0x660fe20)=0, stream(0x6560ff0)=1
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: deflating :scheme: http
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: name/value match index=5
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit indexed index=5, 1 bytes
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: deflating :method: GET
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: name/value match index=1
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit indexed index=1, 1 bytes
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: deflating :path: /resize?type=jpg
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: name match index=3
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit indname index=3, valuelen=16, indexing_mode=1
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit string str=/resize?type=jpg, length=16, huffman=1, encoded_length=13
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: deflating :authority: host
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: name match index=0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit indname index=0, valuelen=4, indexing_mode=0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit string str=host, length=4, huffman=1, encoded_length=3
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: deflating s3-location: mybucket/images/123.jpg
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit newname namelen=11, valuelen=23, indexing_mode=0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit string str=s3-location, length=11, huffman=1, encoded_length=8
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: emit string str=mybucket/images/123.jpg, length=23, huffman=1, encoded_length=17
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: deflatehd: all input name/value pairs were deflated
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: HEADERS/PUSH_PROMISE, payloadlen=50
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: before padding, HEADERS serialized in 59 bytes
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: padding selected: payloadlen=50, padlen=0
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: padlen = 0, nothing to do
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: HEADERS finally serialized in 59 bytes
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: next frame: payloadlen=50, type=1, flags=0x05, stream_id=1
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:696] [C0] about to send frame type=1, flags=5
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: start transmitting frame type=1, length=59
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:750] [C0] send data: bytes=59
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/connection_impl.cc:429] [C0] writing 59 bytes, end_stream false
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: end transmission of a frame
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/codec_impl.cc:639] [C0] sent frame type=1
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:33.288][14][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = 0x6309e00
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/connection_impl.cc:493] [C0] socket event: 2
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/connection_impl.cc:581] [C0] write ready
[2020-03-27 15:56:33.288][22][trace][connection] [source/common/network/connection_impl.cc:493] [C1] socket event: 3
[2020-03-27 15:56:33.288][14][trace][connection] [source/common/network/raw_buffer_socket.cc:68] [C0] write returns: 135
[2020-03-27 15:56:33.288][22][trace][connection] [source/common/network/connection_impl.cc:581] [C1] write ready
[2020-03-27 15:56:33.288][22][trace][connection] [source/common/network/connection_impl.cc:531] [C1] read ready
[2020-03-27 15:56:33.288][22][trace][connection] [source/common/network/raw_buffer_socket.cc:25] [C1] read returns: 135
[2020-03-27 15:56:33.288][22][trace][connection] [source/common/network/raw_buffer_socket.cc:39] [C1] read error: Resource temporarily unavailable
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = (nil)
[2020-03-27 15:56:33.289][22][debug][http2] [source/common/http/http2/codec_impl.cc:960] [C1] updating connection-level initial window size to 268435456
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:453] [C1] dispatching 135 bytes
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: connection recv_window_size=0, local_window=268435456
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_FIRST_SETTINGS]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_HEAD]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: payloadlen=30, type=4, flags=0x00, stream_id=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: SETTINGS
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:513] [C1] about to recv frame type=4, flags=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:1224] [C1] track inbound frame type=4 flags=0 length=30 padding_length=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_SETTINGS]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: readlen=6, payloadleft=24
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_SETTINGS]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: readlen=6, payloadleft=18
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_SETTINGS]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: readlen=6, payloadleft=12
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_SETTINGS]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: readlen=6, payloadleft=6
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_SETTINGS]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: readlen=6, payloadleft=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:528] [C1] recv frame type=4
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_HEAD]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: payloadlen=4, type=8, flags=0x00, stream_id=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: WINDOW_UPDATE
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:513] [C1] about to recv frame type=8, flags=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:1224] [C1] track inbound frame type=8 flags=0 length=4 padding_length=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_NBYTE]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: readlen=4, payloadleft=0, left=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:528] [C1] recv frame type=8
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_HEAD]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: payloadlen=50, type=1, flags=0x05, stream_id=1
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: HEADERS
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: no padding in payload
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:513] [C1] about to recv frame type=1, flags=5
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: dep_add dep_stream(0x6670020)=0, stream(0x5ff0ff0)=1
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept closed streams num_closed_streams=0, num_incoming_streams=1, max_concurrent_streams=2147483647
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: call on_begin_headers callback stream_id=1
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/codec_impl.cc:1224] [C1] track inbound frame type=1 flags=5 length=50 padding_length=0
[2020-03-27 15:56:33.289][22][debug][http] [source/common/http/conn_manager_impl.cc:268] [C1] new stream
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: [IB_READ_HEADER_BLOCK]
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: readlen=50, payloadleft=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: block final=1
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: decoding header block 50 bytes
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: start state=1
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: indexed repr
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: decoded integer is 6
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: index=6
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: header emission: :scheme: http
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: proclen=1
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: start state=2
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: indexed repr
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: decoded integer is 2
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: index=2
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: header emission: :method: GET
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: proclen=1
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: start state=2
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: literal header repr - indexed name
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: indexing required=0, no_index=0
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: decoded integer is 4
[2020-03-27 15:56:33.289][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: index=4
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: huffman encoded=1
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: decoded integer is 13
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: valuelen=13
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: 13 bytes read
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: header emission: :path: /resize?type=jpg
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: proclen=15
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: start state=2
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: literal header repr - indexed name
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: indexing required=1, no_index=0
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: decoded integer is 1
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: index=1
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: huffman encoded=1
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: decoded integer is 3
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: valuelen=3
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: 3 bytes read
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: header emission: :authority: host
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: proclen=5
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: start state=2
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: literal header repr - new name
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: indexing required=1, no_index=0
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: huffman encoded=1
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: decoded integer is 8
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: 8 bytes read
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: huffman encoded=1
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: decoded integer is 17
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: valuelen=17
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: 17 bytes read
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: header emission: s3-location: mybucket/images/123.jpg
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: proclen=28
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: start state=2
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: all input bytes were processed
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: inflatehd: in_final set
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: recv: proclen=0
[2020-03-27 15:56:33.290][22][trace][http2] [source/common/http/http2/codec_impl.cc:528] [C1] recv frame type=1
[2020-03-27 15:56:33.290][22][debug][http] [source/common/http/conn_manager_impl.cc:774] [C1][S8481185755286632111] request headers complete (end_stream=true):
':scheme', 'http'
':method', 'GET'
':path', '/resize?type=jpg'
':authority', 'host'
's3-location', 'mybucket/images/123.jpg'
[2020-03-27 15:56:33.290][22][debug][http] [source/common/http/conn_manager_impl.cc:1327] [C1][S8481185755286632111] request end stream
[2020-03-27 15:56:33.291][22][debug][aws] [source/extensions/common/aws/credentials_provider_impl.cc:41] Getting AWS credentials from the environment
[2020-03-27 15:56:33.291][22][debug][aws] [source/extensions/common/aws/credentials_provider_impl.cc:71] Getting AWS credentials from the instance metadata
[2020-03-27 15:56:38.190][16][debug][main] [source/server/server.cc:175] flushing stats
[2020-03-27 15:56:38.293][22][debug][misc] [source/extensions/common/aws/utility.cc:138] Could not fetch AWS metadata: Timeout was reached
test/extensions/filters/http/aws_lambda/aws_lambda_filter_integration_test.cc:84: Failure
Value of: fake_upstreams_[0]->waitForHttpConnection(*dispatcher_, fake_upstream_connection_)
Actual: false (Timed out waiting for new connection.)
Expected: true
Stack trace:
0x1dc487c: Envoy::(anonymous namespace)::AwsLambdaFilterIntegrationTest::runTest()
0x1dc3d60: Envoy::(anonymous namespace)::AwsLambdaFilterIntegrationTest_JsonWrappedHeaderOnlyRequest_Test::TestBody()
0x490c324: testing::internal::HandleSehExceptionsInMethodIfSupported<>()
0x48fcdfb: testing::internal::HandleExceptionsInMethodIfSupported<>()
0x48ea713: testing::Test::Run()
0x48eb0d7: testing::TestInfo::Run()
... Google Test internal frames ...
[2020-03-27 15:56:43.530][14][info][testing] [test/integration/server.cc:219] stopping integration test server
[2020-03-27 15:56:43.538][14][debug][upstream] [source/common/upstream/upstream_impl.cc:274] transport socket match, socket test selected for host with address 127.0.0.1:80
[2020-03-27 15:56:43.538][14][debug][client] [source/common/http/codec_client.cc:34] [C2] connecting
[2020-03-27 15:56:43.538][14][debug][connection] [source/common/network/connection_impl.cc:727] [C2] connecting to 127.0.0.1:42921
[2020-03-27 15:56:43.538][16][debug][conn_handler] [source/server/connection_handler_impl.cc:372] [C3] new connection
[2020-03-27 15:56:43.538][16][trace][connection] [source/common/network/connection_impl.cc:493] [C3] socket event: 2
[2020-03-27 15:56:43.538][16][trace][connection] [source/common/network/connection_impl.cc:581] [C3] write ready
[2020-03-27 15:56:43.538][14][debug][connection] [source/common/network/connection_impl.cc:736] [C2] connection in progress
[2020-03-27 15:56:43.539][14][trace][connection] [source/common/network/connection_impl.cc:429] [C2] writing 62 bytes, end_stream false
[2020-03-27 15:56:43.539][14][trace][connection] [source/common/network/connection_impl.cc:493] [C2] socket event: 2
[2020-03-27 15:56:43.539][14][trace][connection] [source/common/network/connection_impl.cc:581] [C2] write ready
[2020-03-27 15:56:43.539][14][debug][connection] [source/common/network/connection_impl.cc:592] [C2] connected
[2020-03-27 15:56:43.539][14][debug][client] [source/common/http/codec_client.cc:72] [C2] connected
[2020-03-27 15:56:43.539][14][trace][connection] [source/common/network/connection_impl.cc:581] [C2] write ready
[2020-03-27 15:56:43.539][16][trace][connection] [source/common/network/connection_impl.cc:493] [C3] socket event: 3
[2020-03-27 15:56:43.539][16][trace][connection] [source/common/network/connection_impl.cc:581] [C3] write ready
[2020-03-27 15:56:43.539][16][trace][connection] [source/common/network/connection_impl.cc:531] [C3] read ready
[2020-03-27 15:56:43.539][16][trace][connection] [source/common/network/raw_buffer_socket.cc:25] [C3] read returns: 62
[2020-03-27 15:56:43.539][16][trace][connection] [source/common/network/raw_buffer_socket.cc:39] [C3] read error: Resource temporarily unavailable
[2020-03-27 15:56:43.539][16][trace][http] [source/common/http/http1/codec_impl.cc:465] [C3] parsing 62 bytes
[2020-03-27 15:56:43.539][16][trace][http] [source/common/http/http1/codec_impl.cc:636] [C3] message begin
[2020-03-27 15:56:43.539][16][debug][http] [source/common/http/conn_manager_impl.cc:268] [C3] new stream
[2020-03-27 15:56:43.539][16][trace][http] [source/common/http/http1/codec_impl.cc:425] [C3] completed header: key=host value=host
[2020-03-27 15:56:43.539][16][trace][http] [source/common/http/http1/codec_impl.cc:559] [C3] onHeadersCompleteBase
[2020-03-27 15:56:43.539][16][trace][http] [source/common/http/http1/codec_impl.cc:425] [C3] completed header: key=content-length value=0
[2020-03-27 15:56:43.539][16][trace][http] [source/common/http/http1/codec_impl.cc:733] [C3] Server: onHeadersComplete size=2
[2020-03-27 15:56:43.539][16][trace][http] [source/common/http/http1/codec_impl.cc:615] [C3] message complete
[2020-03-27 15:56:43.539][16][debug][http] [source/common/http/conn_manager_impl.cc:774] [C3][S3231570133068256118] request headers complete (end_stream=true):
':authority', 'host'
':path', '/quitquitquit'
':method', 'POST'
'content-length', '0'
[2020-03-27 15:56:43.539][16][debug][http] [source/common/http/conn_manager_impl.cc:1327] [C3][S3231570133068256118] request end stream
[2020-03-27 15:56:43.539][16][debug][admin] [source/server/http/admin_filter.cc:66] [C3][S3231570133068256118] request complete: path: /quitquitquit
[2020-03-27 15:56:43.540][16][info][main] [source/server/server.cc:611] shutting down server instance
[2020-03-27 15:56:43.540][16][trace][http] [source/common/http/conn_manager_impl.cc:1557] [C3][S3231570133068256118] encode headers called: filter=0x65ee7d0 status=0
[2020-03-27 15:56:43.540][16][debug][http] [source/common/http/conn_manager_impl.cc:1697] [C3][S3231570133068256118] encoding headers via codec (end_stream=false):
':status', '200'
'content-type', 'text/plain; charset=UTF-8'
'cache-control', 'no-cache, max-age=0'
'x-content-type-options', 'nosniff'
'date', 'Fri, 27 Mar 2020 15:56:43 GMT'
'server', 'envoy'
[2020-03-27 15:56:43.540][16][trace][connection] [source/common/network/connection_impl.cc:429] [C3] writing 209 bytes, end_stream false
[2020-03-27 15:56:43.540][16][trace][http] [source/common/http/conn_manager_impl.cc:1818] [C3][S3231570133068256118] encode data called: filter=0x65ee7d0 status=0
[2020-03-27 15:56:43.540][16][trace][http] [source/common/http/conn_manager_impl.cc:1844] [C3][S3231570133068256118] encoding data via codec (size=3 end_stream=true)
[2020-03-27 15:56:43.540][16][trace][connection] [source/common/network/connection_impl.cc:429] [C3] writing 13 bytes, end_stream false
[2020-03-27 15:56:43.540][16][trace][main] [source/common/event/dispatcher_impl.cc:163] item added to deferred deletion list (size=1)
[2020-03-27 15:56:43.540][16][trace][http] [source/common/http/conn_manager_impl.cc:1036] [C3][S3231570133068256118] decode headers called: filter=0x6632d20 status=1
[2020-03-27 15:56:43.540][16][trace][http] [source/common/http/http1/codec_impl.cc:483] [C3] parsed 62 bytes
[2020-03-27 15:56:43.540][16][trace][connection] [source/common/network/connection_impl.cc:493] [C3] socket event: 2
[2020-03-27 15:56:43.540][16][trace][connection] [source/common/network/connection_impl.cc:581] [C3] write ready
[2020-03-27 15:56:43.541][16][trace][connection] [source/common/network/raw_buffer_socket.cc:68] [C3] write returns: 222
[2020-03-27 15:56:43.541][16][trace][main] [source/common/event/dispatcher_impl.cc:79] clearing deferred deletion list (size=1)
[2020-03-27 15:56:43.541][16][info][main] [source/server/server.cc:558] main dispatch loop exited
[2020-03-27 15:56:43.541][14][trace][connection] [source/common/network/raw_buffer_socket.cc:68] [C2] write returns: 62
[2020-03-27 15:56:43.541][14][trace][connection] [source/common/network/connection_impl.cc:493] [C2] socket event: 3
[2020-03-27 15:56:43.541][14][trace][connection] [source/common/network/connection_impl.cc:581] [C2] write ready
[2020-03-27 15:56:43.541][14][trace][connection] [source/common/network/connection_impl.cc:531] [C2] read ready
[2020-03-27 15:56:43.541][14][trace][connection] [source/common/network/raw_buffer_socket.cc:25] [C2] read returns: 222
[2020-03-27 15:56:43.541][14][trace][connection] [source/common/network/raw_buffer_socket.cc:39] [C2] read error: Resource temporarily unavailable
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:465] [C2] parsing 222 bytes
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:636] [C2] message begin
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:425] [C2] completed header: key=content-type value=text/plain; charset=UTF-8
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:425] [C2] completed header: key=cache-control value=no-cache, max-age=0
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:425] [C2] completed header: key=x-content-type-options value=nosniff
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:425] [C2] completed header: key=date value=Fri, 27 Mar 2020 15:56:43 GMT
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:425] [C2] completed header: key=server value=envoy
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:559] [C2] onHeadersCompleteBase
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:425] [C2] completed header: key=transfer-encoding value=chunked
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:924] [C2] Client: onHeadersComplete size=6
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:615] [C2] message complete
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:958] [C2] message complete
[2020-03-27 15:56:43.541][14][debug][client] [source/common/http/codec_client.cc:104] [C2] response complete
[2020-03-27 15:56:43.541][14][trace][main] [source/common/event/dispatcher_impl.cc:163] item added to deferred deletion list (size=1)
[2020-03-27 15:56:43.541][14][debug][connection] [source/common/network/connection_impl.cc:109] [C2] closing data_to_write=0 type=1
[2020-03-27 15:56:43.541][14][debug][connection] [source/common/network/connection_impl.cc:200] [C2] closing socket: 1
[2020-03-27 15:56:43.541][14][debug][client] [source/common/http/codec_client.cc:91] [C2] disconnect. resetting 0 pending requests
[2020-03-27 15:56:43.541][14][trace][http] [source/common/http/http1/codec_impl.cc:483] [C2] parsed 222 bytes
[2020-03-27 15:56:43.541][14][trace][main] [source/common/event/dispatcher_impl.cc:79] clearing deferred deletion list (size=1)
[2020-03-27 15:56:44.295][22][debug][misc] [source/extensions/common/aws/utility.cc:138] Could not fetch AWS metadata: Timeout was reached
[2020-03-27 15:56:50.296][22][debug][misc] [source/extensions/common/aws/utility.cc:138] Could not fetch AWS metadata: Timeout was reached
[2020-03-27 15:56:56.297][22][debug][misc] [source/extensions/common/aws/utility.cc:138] Could not fetch AWS metadata: Timeout was reached
[2020-03-27 15:56:57.298][22][error][aws] [source/extensions/common/aws/credentials_provider_impl.cc:77] Could not retrieve credentials listing from the instance metadata
[2020-03-27 15:56:57.298][22][debug][aws] [source/extensions/common/aws/credentials_provider_impl.cc:182] No AWS credentials found, using anonymous credentials
[2020-03-27 15:56:57.298][22][trace][http] [source/common/http/conn_manager_impl.cc:1036] [C1][S8481185755286632111] decode headers called: filter=0x6589ce0 status=0
[2020-03-27 15:56:57.298][22][debug][router] [source/common/router/router.cc:477] [C1][S8481185755286632111] cluster 'cluster_0' match for URL '/2015-03-31/functions/test/invocations'
[2020-03-27 15:56:57.298][22][debug][router] [source/common/router/router.cc:633] [C1][S8481185755286632111] router decoding headers:
':scheme', 'http'
':method', 'POST'
':path', '/2015-03-31/functions/test/invocations'
':authority', 'host'
's3-location', 'mybucket/images/123.jpg'
'x-forwarded-proto', 'http'
'x-request-id', '90783e56-cc4b-47c6-9611-0a5fac398e32'
'x-amz-invocation-type', 'RequestResponse'
'content-length', '256'
'content-type', 'application/json'
'x-amz-content-sha256', '0a5cd34dd42245f2273f84906ffa7749a36d6601240fe817ca6b4e2160cbfaf2'
'x-envoy-expected-rq-timeout-ms', '15000'
[2020-03-27 15:56:57.298][22][debug][pool] [source/common/http/conn_pool_base.cc:337] queueing request due to no available connections
[2020-03-27 15:56:57.298][22][debug][pool] [source/common/http/conn_pool_base.cc:47] creating a new connection
[2020-03-27 15:56:57.299][22][debug][client] [source/common/http/codec_client.cc:34] [C4] connecting
[2020-03-27 15:56:57.299][22][debug][connection] [source/common/network/connection_impl.cc:727] [C4] connecting to 127.0.0.1:41533
[2020-03-27 15:56:57.299][22][debug][connection] [source/common/network/connection_impl.cc:736] [C4] connection in progress
[2020-03-27 15:56:57.299][15][trace][connection] [source/common/network/connection_impl.cc:315] [C5] readDisable: enabled=true disable=true state=0
[2020-03-27 15:56:57.299][15][debug][conn_handler] [source/server/connection_handler_impl.cc:372] [C5] new connection
[2020-03-27 15:56:57.300][15][trace][connection] [source/common/network/connection_impl.cc:493] [C5] socket event: 2
[2020-03-27 15:56:57.300][15][trace][connection] [source/common/network/connection_impl.cc:581] [C5] write ready
[2020-03-27 15:56:57.300][22][trace][http] [source/common/http/conn_manager_impl.cc:1036] [C1][S8481185755286632111] decode headers called: filter=0x6589740 status=1
[2020-03-27 15:56:57.300][22][trace][http] [source/common/http/conn_manager_impl.cc:2034] [C1][S8481185755286632111] continuing filter chain: filter=0x6589ce0
[2020-03-27 15:56:57.300][22][trace][router] [source/common/router/upstream_request.cc:177] [C1][S8481185755286632111] buffering 256 bytes
[2020-03-27 15:56:57.300][22][trace][http] [source/common/http/conn_manager_impl.cc:1175] [C1][S8481185755286632111] decode data called: filter=0x6589740 status=3
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/codec_impl.cc:469] [C1] dispatched 135 bytes
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: next frame: payloadlen=24, type=4, flags=0x00, stream_id=0
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/codec_impl.cc:696] [C1] about to send frame type=4, flags=0
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: start transmitting frame type=4, length=33
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/codec_impl.cc:750] [C1] send data: bytes=33
[2020-03-27 15:56:57.300][22][trace][connection] [source/common/network/connection_impl.cc:429] [C1] writing 33 bytes, end_stream false
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: end transmission of a frame
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/codec_impl.cc:639] [C1] sent frame type=4
[2020-03-27 15:56:57.300][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = 0x6662140
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: next frame: payloadlen=0, type=4, flags=0x01, stream_id=0
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/codec_impl.cc:696] [C1] about to send frame type=4, flags=1
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: start transmitting frame type=4, length=9
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/codec_impl.cc:750] [C1] send data: bytes=9
[2020-03-27 15:56:57.301][22][trace][connection] [source/common/network/connection_impl.cc:429] [C1] writing 9 bytes, end_stream false
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: end transmission of a frame
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/codec_impl.cc:639] [C1] sent frame type=4
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = 0x6662280
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: next frame: payloadlen=4, type=8, flags=0x00, stream_id=0
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/codec_impl.cc:696] [C1] about to send frame type=8, flags=0
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: start transmitting frame type=8, length=13
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/codec_impl.cc:750] [C1] send data: bytes=13
[2020-03-27 15:56:57.301][22][trace][connection] [source/common/network/connection_impl.cc:429] [C1] writing 13 bytes, end_stream false
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: end transmission of a frame
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/codec_impl.cc:639] [C1] sent frame type=8
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:57.301][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = 0x66621e0
[2020-03-27 15:56:57.302][22][trace][connection] [source/common/network/connection_impl.cc:493] [C1] socket event: 2
[2020-03-27 15:56:57.302][22][trace][connection] [source/common/network/connection_impl.cc:581] [C1] write ready
[2020-03-27 15:56:57.302][22][trace][connection] [source/common/network/raw_buffer_socket.cc:68] [C1] write returns: 55
[2020-03-27 15:56:57.302][22][debug][main] [source/server/worker_impl.cc:111] worker exited dispatch loop
[2020-03-27 15:56:57.302][22][debug][connection] [source/common/network/connection_impl.cc:109] [C1] closing data_to_write=0 type=1
[2020-03-27 15:56:57.302][22][debug][connection] [source/common/network/connection_impl.cc:200] [C1] closing socket: 1
[2020-03-27 15:56:57.302][22][debug][http] [source/common/http/conn_manager_impl.cc:1927] [C1][S8481185755286632111] stream reset
[2020-03-27 15:56:57.302][22][debug][pool] [source/common/http/conn_pool_base.cc:359] cancelling pending request
[2020-03-27 15:56:57.302][22][debug][router] [source/common/router/upstream_request.cc:259] [C1][S8481185755286632111] canceled pool request
[2020-03-27 15:56:57.302][22][trace][main] [source/common/event/dispatcher_impl.cc:163] item added to deferred deletion list (size=1)
[2020-03-27 15:56:57.302][22][debug][conn_handler] [source/server/connection_handler_impl.cc:86] [C1] adding to cleanup list
[2020-03-27 15:56:57.302][22][trace][main] [source/common/event/dispatcher_impl.cc:163] item added to deferred deletion list (size=2)
[2020-03-27 15:56:57.302][22][trace][main] [source/common/event/dispatcher_impl.cc:163] item added to deferred deletion list (size=3)
[2020-03-27 15:56:57.303][22][trace][main] [source/common/event/dispatcher_impl.cc:79] clearing deferred deletion list (size=3)
[2020-03-27 15:56:57.305][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: reset nghttp2_active_outbound_item
[2020-03-27 15:56:57.305][22][trace][http2] [source/common/http/http2/nghttp2.cc:25] nghttp2: send: aob->item = (nil)
[2020-03-27 15:56:57.305][22][debug][grpc] [source/common/grpc/google_async_client_impl.cc:35] Joining completionThread
[2020-03-27 15:56:57.305][23][debug][grpc] [source/common/grpc/google_async_client_impl.cc:68] completionThread exiting
[2020-03-27 15:56:57.305][22][debug][grpc] [source/common/grpc/google_async_client_impl.cc:37] Joined completionThread
[2020-03-27 15:56:57.305][22][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:934] shutting down thread local cluster manager
[2020-03-27 15:56:57.305][22][debug][connection] [source/common/network/connection_impl.cc:109] [C4] closing data_to_write=0 type=1
[2020-03-27 15:56:57.305][22][debug][connection] [source/common/network/connection_impl.cc:200] [C4] closing socket: 1
[2020-03-27 15:56:57.305][22][debug][client] [source/common/http/codec_client.cc:91] [C4] disconnect. resetting 0 pending requests
[2020-03-27 15:56:57.305][15][trace][connection] [source/common/network/connection_impl.cc:493] [C5] socket event: 6
[2020-03-27 15:56:57.305][22][debug][pool] [source/common/http/conn_pool_base.cc:265] [C4] client disconnected, failure reason:
[2020-03-27 15:56:57.305][15][debug][connection] [source/common/network/connection_impl.cc:514] [C5] remote early close
[2020-03-27 15:56:57.305][15][debug][connection] [source/common/network/connection_impl.cc:200] [C5] closing socket: 0
[2020-03-27 15:56:57.306][15][critical][assert] [./test/integration/fake_upstream.h:338] assert failure: parented_ || allow_unexpected_disconnects_. Details: An queued upstream connection was torn down without being associated with a fake connection. Either manage the connection via waitForRawConnection() or waitForHttpConnection(), or set_allow_unexpected_disconnects(true).
See https://github.com/envoyproxy/envoy/blob/master/test/integration/README.md#unparented-upstream-connections
[2020-03-27 15:56:57.306][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:104] Caught Aborted, suspect faulting address 0x110900000000e
[2020-03-27 15:56:57.306][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:91] Backtrace (use tools/stack_decode.py to get line numbers):
[2020-03-27 15:56:57.306][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:92] Envoy version: 0/1.14.0-dev/redacted/DEBUG/BoringSSL
[2020-03-27 15:56:57.306][22][trace][main] [source/common/event/dispatcher_impl.cc:163] item added to deferred deletion list (size=1)
[2020-03-27 15:56:57.306][22][trace][main] [source/common/event/dispatcher_impl.cc:79] clearing deferred deletion list (size=1)
[2020-03-27 15:56:57.306][16][debug][main] [source/server/server.cc:175] flushing stats
[2020-03-27 15:56:57.310][16][debug][init] [source/common/init/watcher_impl.cc:27] ClusterImplBase destroyed
[2020-03-27 15:56:57.310][16][debug][init] [source/common/init/watcher_impl.cc:27] init manager Cluster cluster_0 destroyed
[2020-03-27 15:56:57.310][16][debug][connection] [source/common/network/connection_impl.cc:109] [C3] closing data_to_write=0 type=1
[2020-03-27 15:56:57.310][16][debug][connection] [source/common/network/connection_impl.cc:200] [C3] closing socket: 1
[2020-03-27 15:56:57.310][16][debug][conn_handler] [source/server/connection_handler_impl.cc:86] [C3] adding to cleanup list
[2020-03-27 15:56:57.310][16][trace][main] [source/common/event/dispatcher_impl.cc:163] item added to deferred deletion list (size=1)
[2020-03-27 15:56:57.310][16][trace][main] [source/common/event/dispatcher_impl.cc:163] item added to deferred deletion list (size=2)
[2020-03-27 15:56:57.310][16][trace][main] [source/common/event/dispatcher_impl.cc:79] clearing deferred deletion list (size=2)
[2020-03-27 15:56:57.312][16][debug][grpc] [source/common/grpc/google_async_client_impl.cc:35] Joining completionThread
[2020-03-27 15:56:57.312][20][debug][grpc] [source/common/grpc/google_async_client_impl.cc:68] completionThread exiting
[2020-03-27 15:56:57.312][16][debug][grpc] [source/common/grpc/google_async_client_impl.cc:37] Joined completionThread
[2020-03-27 15:56:57.312][16][debug][upstream] [source/common/upstream/cluster_manager_impl.cc:934] shutting down thread local cluster manager
[2020-03-27 15:56:57.313][16][info][main] [source/server/server.cc:604] exiting
[2020-03-27 15:56:57.313][16][debug][init] [source/common/init/watcher_impl.cc:27] RunHelper destroyed
[2020-03-27 15:56:57.313][16][debug][init] [source/common/init/target_impl.cc:32] target LDS destroyed
[2020-03-27 15:56:57.327][16][debug][init] [source/common/init/watcher_impl.cc:27] init manager Listener-local-init-manager http destroyed
[2020-03-27 15:56:57.327][16][debug][init] [source/common/init/target_impl.cc:32] target Listener-init-target http destroyed
[2020-03-27 15:56:57.327][16][debug][init] [source/common/init/watcher_impl.cc:27] Listener-local-init-watcher http destroyed
[2020-03-27 15:56:57.332][16][debug][init] [source/common/init/watcher_impl.cc:27] init manager Server destroyed
[2020-03-27 15:56:57.350][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #0: Envoy::SignalAction::sigHandler() [0x3a5aa7c]
[2020-03-27 15:56:57.350][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #1: __restore_rt [0x7f05f575c520]
[2020-03-27 15:56:57.390][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #2: std::__1::__invoke<>() [0x1f9f0ed]
[2020-03-27 15:56:57.430][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #3: std::__1::__invoke_void_return_wrapper<>::__call<>() [0x1f9f09d]
[2020-03-27 15:56:57.468][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #4: std::__1::__function::__alloc_func<>::operator()() [0x1f9f06d]
[2020-03-27 15:56:57.505][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #5: std::__1::__function::__func<>::operator()() [0x1f9e1ae]
[2020-03-27 15:56:57.538][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #6: std::__1::__function::__value_func<>::operator()() [0x1f64e45]
[2020-03-27 15:56:57.571][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #7: std::__1::function<>::operator()() [0x1f64d05]
[2020-03-27 15:56:57.604][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #8: Envoy::Common::CallbackManager<>::runCallbacks() [0x1f64bdd]
[2020-03-27 15:56:57.637][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #9: Envoy::SharedConnectionWrapper::onEvent() [0x1f64535]
[2020-03-27 15:56:57.670][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #10: Envoy::Network::ConnectionImplBase::raiseConnectionEvent() [0x363f791]
[2020-03-27 15:56:57.703][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #11: Envoy::Network::ConnectionImpl::raiseEvent() [0x36337f5]
[2020-03-27 15:56:57.736][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #12: Envoy::Network::ConnectionImpl::closeSocket() [0x36325af]
[2020-03-27 15:56:57.770][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #13: Envoy::Network::ConnectionImpl::onFileEvent() [0x36348ba]
[2020-03-27 15:56:57.803][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #14: Envoy::Network::ConnectionImpl::ConnectionImpl()::$_2::operator()() [0x363a95e]
[2020-03-27 15:56:57.836][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #15: std::__1::__invoke<>() [0x363a921]
[2020-03-27 15:56:57.869][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #16: std::__1::__invoke_void_return_wrapper<>::__call<>() [0x363a8c2]
[2020-03-27 15:56:57.902][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #17: std::__1::__function::__alloc_func<>::operator()() [0x363a882]
[2020-03-27 15:56:57.936][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #18: std::__1::__function::__func<>::operator()() [0x36399c3]
[2020-03-27 15:56:57.970][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #19: std::__1::__function::__value_func<>::operator()() [0x2bc0f8d]
[2020-03-27 15:56:58.004][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #20: std::__1::function<>::operator()() [0x2bc0f0f]
[2020-03-27 15:56:58.038][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #21: Envoy::Event::FileEventImpl::assignEvents()::$_0::operator()() [0x362957c]
[2020-03-27 15:56:58.071][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #22: Envoy::Event::FileEventImpl::assignEvents()::$_0::__invoke() [0x3629406]
[2020-03-27 15:56:58.105][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #23: event_persist_closure [0x3a35b7e]
[2020-03-27 15:56:58.139][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #24: event_process_active_single_queue [0x3a351a8]
[2020-03-27 15:56:58.172][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #25: event_process_active [0x3a2f96a]
[2020-03-27 15:56:58.206][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #26: event_base_loop [0x3a2e83c]
[2020-03-27 15:56:58.240][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #27: Envoy::Event::LibeventScheduler::run() [0x36f5cc8]
[2020-03-27 15:56:58.273][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #28: Envoy::Event::DispatcherImpl::run() [0x361eb6a]
[2020-03-27 15:56:58.306][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #29: Envoy::FakeUpstream::threadRoutine() [0x1f7410f]
[2020-03-27 15:56:58.340][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #30: Envoy::FakeUpstream::FakeUpstream()::$_12::operator()() [0x1f87c78]
[2020-03-27 15:56:58.373][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #31: std::__1::__invoke<>() [0x1f87c3d]
[2020-03-27 15:56:58.406][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #32: std::__1::__invoke_void_return_wrapper<>::__call<>() [0x1f87bed]
[2020-03-27 15:56:58.439][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #33: std::__1::__function::__alloc_func<>::operator()() [0x1f87bbd]
[2020-03-27 15:56:58.472][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #34: std::__1::__function::__func<>::operator()() [0x1f86d0e]
[2020-03-27 15:56:58.472][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #35: std::__1::__function::__value_func<>::operator()() [0x1f64e45]
[2020-03-27 15:56:58.472][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #36: std::__1::function<>::operator()() [0x1f64d05]
[2020-03-27 15:56:58.505][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #37: Envoy::Thread::ThreadImplPosix::ThreadImplPosix()::$_0::operator()() [0x3a20b82]
[2020-03-27 15:56:58.538][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #38: Envoy::Thread::ThreadImplPosix::ThreadImplPosix()::$_0::__invoke() [0x3a20b55]
[2020-03-27 15:56:58.538][15][critical][backtrace] [bazel-out/k8-fastbuild/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:96] #39: start_thread [0x7f05f5751fb7]
external/bazel_tools/tools/test/test-setup.sh: line 310: 14 Aborted "${TEST_PATH}" "$@" 2>&1
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment