[2023-03-28 21:55:10.708][1][debug][main] [source/server/server.cc:265] flushing stats
[2023-03-28 21:55:10.818][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:108] starting async DNS resolution for web
[2023-03-28 21:55:10.818][1][debug][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:354] dns resolution for web started
[2023-03-28 21:55:10.818][1][trace][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:317] Setting DNS resolution timer for 5000 milliseconds
[2023-03-28 21:55:10.819][1][debug][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:275] dns resolution for web completed with status 0
[2023-03-28 21:55:10.819][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:116] async DNS resolution complete for web
[2023-03-28 21:55:10.819][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:167] DNS refresh rate reset for web, refresh rate 5000 ms
[2023-03-28 21:55:11.688][15][trace][connection] [source/common/network/connection_impl.cc:568] [C35] socket event: 3
[2023-03-28 21:55:11.688][15][trace][connection] [source/common/network/connection_impl.cc:679] [C35] write ready
[2023-03-28 21:55:11.688][15][trace][connection] [source/common/network/connection_impl.cc:608] [C35] read ready. dispatch_buffered_data=0
[2023-03-28 21:55:11.688][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:87] [C35] ssl read returns: 0
[2023-03-28 21:55:11.688][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:127] [C35] ssl error occurred while read: ZERO_RETURN
[2023-03-28 21:55:11.688][15][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:163] [C35] ssl read 0 bytes
[2023-03-28 21:55:11.688][15][debug][connection] [source/common/network/connection_impl.cc:656] [C35] remote close
[2023-03-28 21:55:11.688][15][debug][connection] [source/common/network/connection_impl.cc:250] [C35] closing socket: 0
[2023-03-28 21:55:11.688][15][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:320] [C35] SSL shutdown: rc=1
[2023-03-28 21:55:11.689][15][trace][connection] [source/common/network/connection_impl.cc:423] [C35] raising connection event 0
[2023-03-28 21:55:11.689][15][trace][conn_handler] [source/extensions/listener_managers/listener_manager/active_stream_listener_base.cc:111] [C35] connection on event 0
[2023-03-28 21:55:11.689][15][debug][conn_handler] [source/extensions/listener_managers/listener_manager/active_stream_listener_base.cc:120] [C35] adding to cleanup list
[2023-03-28 21:55:11.689][15][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=1)
[2023-03-28 21:55:11.689][15][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=2)
[2023-03-28 21:55:11.689][15][trace][main] [source/common/event/dispatcher_impl.cc:125] clearing deferred deletion list (size=2)
[2023-03-28 21:55:15.708][1][debug][main] [source/server/server.cc:265] flushing stats
[2023-03-28 21:55:15.818][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:108] starting async DNS resolution for web
[2023-03-28 21:55:15.819][1][debug][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:354] dns resolution for web started
[2023-03-28 21:55:15.819][1][trace][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:317] Setting DNS resolution timer for 5000 milliseconds
[2023-03-28 21:55:15.819][1][debug][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:275] dns resolution for web completed with status 0
[2023-03-28 21:55:15.820][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:116] async DNS resolution complete for web
[2023-03-28 21:55:15.820][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:167] DNS refresh rate reset for web, refresh rate 5000 ms
[2023-03-28 21:55:20.708][1][debug][main] [source/server/server.cc:265] flushing stats
[2023-03-28 21:55:20.820][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:108] starting async DNS resolution for web
[2023-03-28 21:55:20.820][1][debug][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:354] dns resolution for web started
[2023-03-28 21:55:20.820][1][trace][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:317] Setting DNS resolution timer for 5000 milliseconds
[2023-03-28 21:55:20.820][1][debug][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:275] dns resolution for web completed with status 0
[2023-03-28 21:55:20.820][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:116] async DNS resolution complete for web
[2023-03-28 21:55:20.820][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:167] DNS refresh rate reset for web, refresh rate 5000 ms
[2023-03-28 21:55:23.740][19][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x165fbf101780 for 3600000ms, min is 3600000ms
[2023-03-28 21:55:23.740][19][debug][conn_handler] [source/extensions/listener_managers/listener_manager/active_tcp_listener.cc:147] [C38] new connection from 172.21.0.3:36360
[2023-03-28 21:55:23.740][19][trace][connection] [source/common/network/connection_impl.cc:568] [C38] socket event: 2
[2023-03-28 21:55:23.740][19][trace][connection] [source/common/network/connection_impl.cc:679] [C38] write ready
[2023-03-28 21:55:23.740][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_handshaker.cc:93] [C38] ssl error occurred while read: WANT_READ
[2023-03-28 21:55:23.740][19][trace][connection] [source/common/network/connection_impl.cc:568] [C38] socket event: 3
[2023-03-28 21:55:23.740][19][trace][connection] [source/common/network/connection_impl.cc:679] [C38] write ready
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_handshaker.cc:93] [C38] ssl error occurred while read: WANT_READ
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:608] [C38] read ready. dispatch_buffered_data=0
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_handshaker.cc:93] [C38] ssl error occurred while read: WANT_READ
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:568] [C38] socket event: 3
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:679] [C38] write ready
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:423] [C38] raising connection event 2
[2023-03-28 21:55:23.741][19][trace][conn_handler] [source/extensions/listener_managers/listener_manager/active_stream_listener_base.cc:111] [C38] connection on event 2
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:608] [C38] read ready. dispatch_buffered_data=0
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:87] [C38] ssl read returns: 64
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:87] [C38] ssl read returns: -1
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:127] [C38] ssl error occurred while read: WANT_READ
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:163] [C38] ssl read 64 bytes
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1805] Codec does not have Metadata frame support.
[2023-03-28 21:55:23.741][19][debug][http2] [source/common/http/http2/codec_impl.cc:1597] [C38] updating connection-level initial window size to 268435456
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:967] [C38] dispatching 64 bytes
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1069] [C38] about to recv frame type=4, flags=0, stream_id=0
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:2115] [C38] track inbound frame type=4 flags=0 length=18 padding_length=0
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1095] [C38] recv frame type=4
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1069] [C38] about to recv frame type=8, flags=0, stream_id=0
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:2115] [C38] track inbound frame type=8 flags=0 length=4 padding_length=0
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1095] [C38] recv frame type=8
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1002] [C38] dispatched 64 bytes
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1314] [C38] about to send frame type=4, flags=0
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1336] [C38] send data: bytes=33
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:483] [C38] writing 33 bytes, end_stream false
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1216] [C38] sent frame type=4, stream_id=0, length=24
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1314] [C38] about to send frame type=4, flags=1
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1336] [C38] send data: bytes=9
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:483] [C38] writing 9 bytes, end_stream false
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1216] [C38] sent frame type=4, stream_id=0, length=0
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1314] [C38] about to send frame type=8, flags=0
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1336] [C38] send data: bytes=13
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:483] [C38] writing 13 bytes, end_stream false
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1216] [C38] sent frame type=8, stream_id=0, length=4
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:568] [C38] socket event: 3
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:679] [C38] write ready
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:268] [C38] ssl write returns: 55
[2023-03-28 21:55:23.741][19][trace][connection] [source/common/network/connection_impl.cc:608] [C38] read ready. dispatch_buffered_data=0
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:87] [C38] ssl read returns: 1253
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:87] [C38] ssl read returns: 9
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:87] [C38] ssl read returns: -1
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:127] [C38] ssl error occurred while read: WANT_READ
[2023-03-28 21:55:23.741][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:163] [C38] ssl read 1262 bytes
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:967] [C38] dispatching 1262 bytes
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:1069] [C38] about to recv frame type=1, flags=4, stream_id=1
[2023-03-28 21:55:23.741][19][trace][http2] [source/common/http/http2/codec_impl.cc:2115] [C38] track inbound frame type=1 flags=4 length=1244 padding_length=0
[2023-03-28 21:55:23.741][19][debug][http] [source/common/http/conn_manager_impl.cc:306] [C38] new stream
[2023-03-28 21:55:23.741][19][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x165fbf35fb00 for 300000ms, min is 300000ms
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:1095] [C38] recv frame type=1
[2023-03-28 21:55:23.742][19][debug][http] [source/common/http/conn_manager_impl.cc:972] [C38][S16903837606675993007] request headers complete (end_stream=false):
':authority', 'envoy:10000'
':method', 'GET'
':path', '/'
':scheme', 'https'
'x-forwarded-server', '7cf4f3bb7b83'
'x-forwarded-host', 'envoyweb.teleport.example.com:3080'
'accept-encoding', 'gzip'
'x-forwarded-for', '172.21.0.5'
'teleport-jwt-assertion', 'eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOlsiaHR0cHM6Ly9lbnZveToxMDAwMCJdLCJleHAiOjE2ODAwODEwNjcsImlhdCI6MTY4MDA0MDQ3MSwiaXNzIjoidGVsZXBvcnQuZXhhbXBsZS5jb20iLCJuYmYiOjE2ODAwNDA0NjEsInJvbGVzIjpbImFjY2VzcyIsImVkaXRvciIsImF1ZGl0b3IiXSwic3ViIjoiamVmZiIsInRyYWl0cyI6e30sInVzZXJuYW1lIjoiamVmZiJ9.SXOxjNtFMHgcwsX73JgrtlmvBa_KlyXLdIJvSkP0ryF-_6dydwJt11v6OdSkleWejb7TdYKf63_QUD2pXwdNPthnJwZjDgQ_4qbG8lmoEypEumW3s5Hx_1UUJFJodeLkP7OO1EfVhzQ4ch0s5gwt970OdNCpzCXeyEBDSW367FGpGZneTfQ9ZLDLMgoz6AgF3u8KnmAa6NaCXzThKOaZEVdsIN5oeFa-iisCiXvrOb6BygFULw8AjH8WCXsk4s3dSXbqfq0hYQbJiIbuHTnsdQ3xbTrBLfMElXll5N1iLaTsr1JUdsQUxtmSn4FRVsuK_SIadk9-DcEHHmM6wBuvnA'
'user-agent', 'curl/7.68.0'
'accept', '*/*'
'x-forwarded-port', '3080'
'x-real-ip', '172.21.0.6'
'x-forwarded-proto', 'https'
'x-forwarded-ssl', 'on'
'cf-access-token', 'eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOlsiaHR0cHM6Ly9lbnZveToxMDAwMCJdLCJleHAiOjE2ODAwODEwNjcsImlhdCI6MTY4MDA0MDQ3MSwiaXNzIjoidGVsZXBvcnQuZXhhbXBsZS5jb20iLCJuYmYiOjE2ODAwNDA0NjEsInJvbGVzIjpbImFjY2VzcyIsImVkaXRvciIsImF1ZGl0b3IiXSwic3ViIjoiamVmZiIsInRyYWl0cyI6e30sInVzZXJuYW1lIjoiamVmZiJ9.SXOxjNtFMHgcwsX73JgrtlmvBa_KlyXLdIJvSkP0ryF-_6dydwJt11v6OdSkleWejb7TdYKf63_QUD2pXwdNPthnJwZjDgQ_4qbG8lmoEypEumW3s5Hx_1UUJFJodeLkP7OO1EfVhzQ4ch0s5gwt970OdNCpzCXeyEBDSW367FGpGZneTfQ9ZLDLMgoz6AgF3u8KnmAa6NaCXzThKOaZEVdsIN5oeFa-iisCiXvrOb6BygFULw8AjH8WCXsk4s3dSXbqfq0hYQbJiIbuHTnsdQ3xbTrBLfMElXll5N1iLaTsr1JUdsQUxtmSn4FRVsuK_SIadk9-DcEHHmM6wBuvnA'

[2023-03-28 21:55:23.742][19][debug][connection] [./source/common/network/connection_impl.h:92] [C38] current connecting state: false
[2023-03-28 21:55:23.742][19][debug][router] [source/common/router/router.cc:470] [C38][S16903837606675993007] cluster 'upstream_service' match for URL '/'
[2023-03-28 21:55:23.742][19][debug][router] [source/common/router/router.cc:678] [C38][S16903837606675993007] router decoding headers:
':authority', 'envoy:10000'
':method', 'GET'
':path', '/'
':scheme', 'https'
'x-forwarded-server', '7cf4f3bb7b83'
'x-forwarded-host', 'envoyweb.teleport.example.com:3080'
'accept-encoding', 'gzip'
'x-forwarded-for', '172.21.0.5'
'teleport-jwt-assertion', 'eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOlsiaHR0cHM6Ly9lbnZveToxMDAwMCJdLCJleHAiOjE2ODAwODEwNjcsImlhdCI6MTY4MDA0MDQ3MSwiaXNzIjoidGVsZXBvcnQuZXhhbXBsZS5jb20iLCJuYmYiOjE2ODAwNDA0NjEsInJvbGVzIjpbImFjY2VzcyIsImVkaXRvciIsImF1ZGl0b3IiXSwic3ViIjoiamVmZiIsInRyYWl0cyI6e30sInVzZXJuYW1lIjoiamVmZiJ9.SXOxjNtFMHgcwsX73JgrtlmvBa_KlyXLdIJvSkP0ryF-_6dydwJt11v6OdSkleWejb7TdYKf63_QUD2pXwdNPthnJwZjDgQ_4qbG8lmoEypEumW3s5Hx_1UUJFJodeLkP7OO1EfVhzQ4ch0s5gwt970OdNCpzCXeyEBDSW367FGpGZneTfQ9ZLDLMgoz6AgF3u8KnmAa6NaCXzThKOaZEVdsIN5oeFa-iisCiXvrOb6BygFULw8AjH8WCXsk4s3dSXbqfq0hYQbJiIbuHTnsdQ3xbTrBLfMElXll5N1iLaTsr1JUdsQUxtmSn4FRVsuK_SIadk9-DcEHHmM6wBuvnA'
'user-agent', 'curl/7.68.0'
'accept', '*/*'
'x-forwarded-port', '3080'
'x-real-ip', '172.21.0.6'
'x-forwarded-proto', 'https'
'x-forwarded-ssl', 'on'
'cf-access-token', 'eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJhdWQiOlsiaHR0cHM6Ly9lbnZveToxMDAwMCJdLCJleHAiOjE2ODAwODEwNjcsImlhdCI6MTY4MDA0MDQ3MSwiaXNzIjoidGVsZXBvcnQuZXhhbXBsZS5jb20iLCJuYmYiOjE2ODAwNDA0NjEsInJvbGVzIjpbImFjY2VzcyIsImVkaXRvciIsImF1ZGl0b3IiXSwic3ViIjoiamVmZiIsInRyYWl0cyI6e30sInVzZXJuYW1lIjoiamVmZiJ9.SXOxjNtFMHgcwsX73JgrtlmvBa_KlyXLdIJvSkP0ryF-_6dydwJt11v6OdSkleWejb7TdYKf63_QUD2pXwdNPthnJwZjDgQ_4qbG8lmoEypEumW3s5Hx_1UUJFJodeLkP7OO1EfVhzQ4ch0s5gwt970OdNCpzCXeyEBDSW367FGpGZneTfQ9ZLDLMgoz6AgF3u8KnmAa6NaCXzThKOaZEVdsIN5oeFa-iisCiXvrOb6BygFULw8AjH8WCXsk4s3dSXbqfq0hYQbJiIbuHTnsdQ3xbTrBLfMElXll5N1iLaTsr1JUdsQUxtmSn4FRVsuK_SIadk9-DcEHHmM6wBuvnA'
'x-envoy-internal', 'true'
'x-request-id', 'cfc23491-c728-48b0-8448-cb52c0ee0cab'
'x-envoy-expected-rq-timeout-ms', '15000'

[2023-03-28 21:55:23.742][19][debug][pool] [source/common/http/conn_pool_base.cc:78] queueing stream due to no available connections (ready=0 busy=0 connecting=0)
[2023-03-28 21:55:23.742][19][debug][pool] [source/common/conn_pool/conn_pool_base.cc:291] trying to create new connection
[2023-03-28 21:55:23.742][19][trace][pool] [source/common/conn_pool/conn_pool_base.cc:292] ConnPoolImplBase 0x165fbfdb1c00, 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
[2023-03-28 21:55:23.742][19][debug][pool] [source/common/conn_pool/conn_pool_base.cc:145] creating a new connection (connecting=0)
[2023-03-28 21:55:23.742][19][debug][connection] [./source/common/network/connection_impl.h:92] [C39] current connecting state: true
[2023-03-28 21:55:23.742][19][debug][client] [source/common/http/codec_client.cc:57] [C39] connecting
[2023-03-28 21:55:23.742][19][debug][connection] [source/common/network/connection_impl.cc:939] [C39] connecting to 172.21.0.2:9000
[2023-03-28 21:55:23.742][19][debug][connection] [source/common/network/connection_impl.cc:958] [C39] connection in progress
[2023-03-28 21:55:23.742][19][trace][pool] [source/common/conn_pool/conn_pool_base.cc:131] not creating a new connection, shouldCreateNewConnection returned false.
[2023-03-28 21:55:23.742][19][trace][http] [source/common/http/filter_manager.cc:539] [C38][S16903837606675993007] decode headers called: filter=envoy.filters.http.upstream_codec status=4
[2023-03-28 21:55:23.742][19][trace][http] [source/common/http/filter_manager.cc:539] [C38][S16903837606675993007] decode headers called: filter=envoy.filters.http.router status=1
[2023-03-28 21:55:23.742][19][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x165fbf35fb00 for 300000ms, min is 300000ms
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:1069] [C38] about to recv frame type=0, flags=1, stream_id=1
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:1095] [C38] recv frame type=0
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:2115] [C38] track inbound frame type=0 flags=1 length=0 padding_length=0
[2023-03-28 21:55:23.742][19][debug][http] [source/common/http/conn_manager_impl.cc:955] [C38][S16903837606675993007] request end stream
[2023-03-28 21:55:23.742][19][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x165fbf35fb00 for 300000ms, min is 300000ms
[2023-03-28 21:55:23.742][19][trace][http] [source/common/http/filter_manager.cc:671] [C38][S16903837606675993007] decode data called: filter=envoy.filters.http.router status=3
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:1002] [C38] dispatched 1262 bytes
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:568] [C38] socket event: 3
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:679] [C38] write ready
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:608] [C38] read ready. dispatch_buffered_data=0
[2023-03-28 21:55:23.742][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:87] [C38] ssl read returns: 9
[2023-03-28 21:55:23.742][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:87] [C38] ssl read returns: -1
[2023-03-28 21:55:23.742][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:127] [C38] ssl error occurred while read: WANT_READ
[2023-03-28 21:55:23.742][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:163] [C38] ssl read 9 bytes
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:967] [C38] dispatching 9 bytes
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:1069] [C38] about to recv frame type=4, flags=1, stream_id=0
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:2115] [C38] track inbound frame type=4 flags=1 length=0 padding_length=0
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:1095] [C38] recv frame type=4
[2023-03-28 21:55:23.742][19][trace][http2] [source/common/http/http2/codec_impl.cc:1002] [C38] dispatched 9 bytes
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:568] [C39] socket event: 2
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:679] [C39] write ready
[2023-03-28 21:55:23.742][19][debug][connection] [source/common/network/connection_impl.cc:688] [C39] connected
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:423] [C39] raising connection event 2
[2023-03-28 21:55:23.742][19][debug][client] [source/common/http/codec_client.cc:88] [C39] connected
[2023-03-28 21:55:23.742][19][debug][pool] [source/common/conn_pool/conn_pool_base.cc:328] [C39] attaching to next stream
[2023-03-28 21:55:23.742][19][debug][pool] [source/common/conn_pool/conn_pool_base.cc:182] [C39] creating stream
[2023-03-28 21:55:23.742][19][debug][router] [source/common/router/upstream_request.cc:581] [C38][S16903837606675993007] pool ready
[2023-03-28 21:55:23.742][19][trace][router] [source/common/router/upstream_codec_filter.cc:61] [C38][S16903837606675993007] proxying headers
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:483] [C39] writing 1769 bytes, end_stream false
[2023-03-28 21:55:23.742][19][trace][http] [source/common/http/filter_manager.cc:68] [C38][S16903837606675993007] continuing filter chain: filter=0x165fbfdd5200
[2023-03-28 21:55:23.742][19][trace][router] [source/common/router/upstream_codec_filter.cc:93] [C38][S16903837606675993007] proxying 0 bytes
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:483] [C39] writing 5 bytes, end_stream false
[2023-03-28 21:55:23.742][19][debug][client] [source/common/http/codec_client.cc:139] [C39] encode complete
[2023-03-28 21:55:23.742][19][trace][http] [source/common/http/filter_manager.cc:671] [C38][S16903837606675993007] decode data called: filter=envoy.filters.http.upstream_codec status=0
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:679] [C39] write ready
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/raw_buffer_socket.cc:67] [C39] write returns: 1774
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:568] [C39] socket event: 2
[2023-03-28 21:55:23.742][19][trace][connection] [source/common/network/connection_impl.cc:679] [C39] write ready
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/connection_impl.cc:568] [C39] socket event: 3
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/connection_impl.cc:679] [C39] write ready
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/connection_impl.cc:608] [C39] read ready. dispatch_buffered_data=0
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C39] read returns: 93
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/raw_buffer_socket.cc:24] [C39] read returns: 2034
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/raw_buffer_socket.cc:38] [C39] read error: Resource temporarily unavailable
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:625] [C39] parsing 2127 bytes
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:567] [C39] message begin
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:524] [C39] completed header: key=Date value=Tue, 28 Mar 2023 21:55:23 GMT
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:524] [C39] completed header: key=Server value=WSGIServer/0.2 CPython/3.11.2
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:524] [C39] completed header: key=Content-Length value=1970
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:818] [C39] onHeadersCompleteBase
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:524] [C39] completed header: key=Content-Type value=text/html; charset=UTF-8
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:1408] [C39] status_code 200
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:1418] [C39] Client: onHeadersComplete size=4
[2023-03-28 21:55:23.743][19][trace][router] [source/common/router/upstream_request.cc:238] [C38][S16903837606675993007] upstream response headers:
':status', '200'
'date', 'Tue, 28 Mar 2023 21:55:23 GMT'
'server', 'WSGIServer/0.2 CPython/3.11.2'
'content-length', '1970'
'content-type', 'text/html; charset=UTF-8'

[2023-03-28 21:55:23.743][19][debug][router] [source/common/router/router.cc:1363] [C38][S16903837606675993007] upstream headers complete: end_stream=false
[2023-03-28 21:55:23.743][19][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x165fbf35fb00 for 300000ms, min is 300000ms
[2023-03-28 21:55:23.743][19][debug][http] [source/common/http/conn_manager_impl.cc:1588] [C38][S16903837606675993007] encoding headers via codec (end_stream=false):
':status', '200'
'date', 'Tue, 28 Mar 2023 21:55:23 GMT'
'server', 'envoy'
'content-length', '1970'
'content-type', 'text/html; charset=UTF-8'
'x-envoy-upstream-service-time', '1'

[2023-03-28 21:55:23.743][19][trace][http2] [source/common/http/http2/codec_impl.cc:1314] [C38] about to send frame type=1, flags=4
[2023-03-28 21:55:23.743][19][trace][http2] [source/common/http/http2/codec_impl.cc:1336] [C38] send data: bytes=91
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/connection_impl.cc:483] [C38] writing 91 bytes, end_stream false
[2023-03-28 21:55:23.743][19][trace][http2] [source/common/http/http2/codec_impl.cc:1216] [C38] sent frame type=1, stream_id=1, length=82
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:922] [C39] message complete
[2023-03-28 21:55:23.743][19][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x165fbf35fb00 for 300000ms, min is 300000ms
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/conn_manager_impl.cc:1598] [C38][S16903837606675993007] encoding data via codec (size=1970 end_stream=false)
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/connection_impl.cc:483] [C38] writing 1979 bytes, end_stream false
[2023-03-28 21:55:23.743][19][trace][http2] [source/common/http/http2/codec_impl.cc:1216] [C38] sent frame type=0, stream_id=1, length=1970
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/http1/codec_impl.cc:1490] [C39] message complete
[2023-03-28 21:55:23.743][19][debug][client] [source/common/http/codec_client.cc:126] [C39] response complete
[2023-03-28 21:55:23.743][19][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=1)
[2023-03-28 21:55:23.743][19][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=2)
[2023-03-28 21:55:23.743][19][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=3)
[2023-03-28 21:55:23.743][19][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x165fbf35fb00 for 300000ms, min is 300000ms
[2023-03-28 21:55:23.743][19][trace][http] [source/common/http/conn_manager_impl.cc:1598] [C38][S16903837606675993007] encoding data via codec (size=0 end_stream=true)
[2023-03-28 21:55:23.743][19][trace][connection] [source/common/network/connection_impl.cc:483] [C38] writing 9 bytes, end_stream false
[2023-03-28 21:55:23.743][19][trace][http2] [source/common/http/http2/codec_impl.cc:1216] [C38] sent frame type=0, stream_id=1, length=0
[2023-03-28 21:55:23.743][19][debug][http2] [source/common/http/http2/codec_impl.cc:1361] [C38] stream 1 closed: 0
[2023-03-28 21:55:23.743][19][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=4)
[2023-03-28 21:55:23.743][19][debug][http2] [source/common/http/http2/codec_impl.cc:1414] [C38] Recouping 0 bytes of flow control window for stream 1.
[2023-03-28 21:55:23.744][19][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=5)
[2023-03-28 21:55:23.744][19][trace][misc] [source/common/event/scaled_range_timer_manager_impl.cc:60] enableTimer called on 0x165fbf101780 for 3600000ms, min is 3600000ms
[2023-03-28 21:55:23.744][19][debug][pool] [source/common/http/http1/conn_pool.cc:53] [C39] response complete
[2023-03-28 21:55:23.744][19][debug][pool] [source/common/http/http1/conn_pool.cc:59] [C39] saw upstream close connection
[2023-03-28 21:55:23.744][19][debug][connection] [source/common/network/connection_impl.cc:139] [C39] closing data_to_write=0 type=1
[2023-03-28 21:55:23.744][19][debug][connection] [source/common/network/connection_impl.cc:250] [C39] closing socket: 1
[2023-03-28 21:55:23.744][19][trace][connection] [source/common/network/connection_impl.cc:423] [C39] raising connection event 1
[2023-03-28 21:55:23.744][19][debug][client] [source/common/http/codec_client.cc:107] [C39] disconnect. resetting 0 pending requests
[2023-03-28 21:55:23.744][19][debug][pool] [source/common/conn_pool/conn_pool_base.cc:484] [C39] client disconnected, failure reason: 
[2023-03-28 21:55:23.744][19][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=6)
[2023-03-28 21:55:23.744][19][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=7)
[2023-03-28 21:55:23.744][19][debug][pool] [source/common/conn_pool/conn_pool_base.cc:454] invoking idle callbacks - is_draining_for_deletion_=false
[2023-03-28 21:55:23.744][19][trace][upstream] [source/common/upstream/cluster_manager_impl.cc:1729] Erasing idle pool for host web
[2023-03-28 21:55:23.744][19][trace][main] [source/common/event/dispatcher_impl.cc:250] item added to deferred deletion list (size=8)
[2023-03-28 21:55:23.744][19][trace][upstream] [source/common/upstream/cluster_manager_impl.cc:1736] Pool container empty for host web, erasing host entry
[2023-03-28 21:55:23.744][19][trace][http] [source/common/http/http1/codec_impl.cc:675] [C39] parsed 2127 bytes
[2023-03-28 21:55:23.744][19][trace][main] [source/common/event/dispatcher_impl.cc:125] clearing deferred deletion list (size=8)
[2023-03-28 21:55:23.744][19][debug][pool] [source/common/conn_pool/conn_pool_base.cc:215] [C39] destroying stream: 0 remaining
[2023-03-28 21:55:23.744][19][trace][connection] [source/common/network/connection_impl.cc:568] [C38] socket event: 2
[2023-03-28 21:55:23.744][19][trace][connection] [source/common/network/connection_impl.cc:679] [C38] write ready
[2023-03-28 21:55:23.744][19][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:268] [C38] ssl write returns: 2079
[2023-03-28 21:55:25.710][1][debug][main] [source/server/server.cc:265] flushing stats
[2023-03-28 21:55:25.821][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:108] starting async DNS resolution for web
[2023-03-28 21:55:25.821][1][debug][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:354] dns resolution for web started
[2023-03-28 21:55:25.821][1][trace][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:317] Setting DNS resolution timer for 5000 milliseconds
[2023-03-28 21:55:25.822][1][debug][dns] [source/extensions/network/dns_resolver/cares/dns_impl.cc:275] dns resolution for web completed with status 0
[2023-03-28 21:55:25.822][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:116] async DNS resolution complete for web
[2023-03-28 21:55:25.822][1][debug][upstream] [source/extensions/clusters/logical_dns/logical_dns_cluster.cc:167] DNS refresh rate reset for web, refresh rate 5000 ms