client.log

[2019-08-13 18:40:42.351][19331][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:156] [C1] handshake complete
[2019-08-13 18:40:42.351][19331][debug][client] [source/common/http/codec_client.cc:77] [C1] connected
[2019-08-13 18:40:42.351][19331][debug][pool] [source/common/http/httpx/conn_pool.cc:244] [C1] attaching to next request
[2019-08-13 18:40:42.351][19331][debug][router] [source/common/router/router.cc:1543] [C0][S16466317785413821041] pool ready
[2019-08-13 18:40:42.351][19331][trace][connection] [source/common/network/connection_impl.cc:393] [C1] writing 213 bytes, end_stream false
[2019-08-13 18:40:42.351][19331][info][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:396] jianfeih debug consulting the alpn in sslsocket.
[2019-08-13 18:40:42.351][19331][info][pool] [source/common/http/httpx/conn_pool.cc:190] incfly debug print out the connection alpn h2
[2019-08-13 18:40:42.352][19331][trace][http2] [source/common/http/http2/nghttp2.cc:20] nghttp2: send: reset nghttp2_active_outbound_item
[2019-08-13 18:40:42.352][19331][trace][http2] [source/common/http/http2/nghttp2.cc:20] nghttp2: send: aob->item = (nil)
[2019-08-13 18:40:42.352][19331][debug][http2] [source/common/http/http2/codec_impl.cc:726] [C1] setting stream-level initial window size to 268435456
[2019-08-13 18:40:42.352][19331][debug][http2] [source/common/http/http2/codec_impl.cc:748] [C1] updating connection-level initial window size to 268435456
[2019-08-13 18:40:42.352][19331][trace][connection] [source/common/network/connection_impl.cc:542] [C1] write ready
[2019-08-13 18:40:42.352][19331][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:233] [C1] ssl write returns: 213
[2019-08-13 18:40:42.352][19331][trace][connection] [source/common/network/connection_impl.cc:495] [C1] read ready
[2019-08-13 18:40:42.352][19331][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:72] [C1] ssl read returns: -1
[2019-08-13 18:40:42.352][19331][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:147] [C1] ssl read 0 bytes into 0 slices
[2019-08-13 18:40:42.352][19331][trace][connection] [source/common/network/connection_impl.cc:457] [C1] socket event: 2
[2019-08-13 18:40:42.352][19331][trace][connection] [source/common/network/connection_impl.cc:542] [C1] write ready
[2019-08-13 18:40:43.350][19331][trace][connection] [source/common/network/connection_impl.cc:457] [C1] socket event: 3
[2019-08-13 18:40:43.350][19331][trace][connection] [source/common/network/connection_impl.cc:542] [C1] write ready
[2019-08-13 18:40:43.350][19331][trace][connection] [source/common/network/connection_impl.cc:495] [C1] read ready
[2019-08-13 18:40:43.350][19331][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:72] [C1] ssl read returns: 0
[2019-08-13 18:40:43.350][19331][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:147] [C1] ssl read 0 bytes into 0 slices
[2019-08-13 18:40:43.350][19331][debug][connection] [source/common/network/connection_impl.cc:519] [C1] remote close
[2019-08-13 18:40:43.350][19331][debug][connection] [source/common/network/connection_impl.cc:189] [C1] closing socket: 0
[2019-08-13 18:40:43.350][19331][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:269] [C1] SSL shutdown: rc=1
[2019-08-13 18:40:43.350][19331][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:200] [C1]
[2019-08-13 18:40:43.350][19331][debug][client] [source/common/http/codec_client.cc:95] [C1] disconnect. resetting 1 pending requests
[2019-08-13 18:40:43.350][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:81] Caught Segmentation fault, suspect faulting address 0x0
[2019-08-13 18:40:43.350][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:69] Backtrace (use tools/stack_decode.py to get line numbers):
[2019-08-13 18:40:43.350][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:69] Backtrace (use tools/stack_decode.py to get line numbers):
[2019-08-13 18:40:43.367][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #0: Envoy::SignalAction::sigHandler() [0x1cffc2c]
[2019-08-13 18:40:43.367][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #1: __restore_rt [0x7f05fa2a5890]
[2019-08-13 18:40:43.384][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #2: Envoy::Network::ConnectionImpl::raiseEvent() [0x11bb9b3]
[2019-08-13 18:40:43.400][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #3: Envoy::Network::ConnectionImpl::closeSocket() [0x11ba616]
[2019-08-13 18:40:43.416][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #4: Envoy::Network::ConnectionImpl::onReadReady() [0x11bdc8e]
[2019-08-13 18:40:43.432][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #5: Envoy::Network::ConnectionImpl::onFileEvent() [0x11bd92d]
[2019-08-13 18:40:43.449][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #6: Envoy::Network::ConnectionImpl::ConnectionImpl()::$_2::operator()() [0x11bfcee]
[2019-08-13 18:40:43.465][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #7: std::_Function_handler<>::_M_invoke() [0x11bfba1]
[2019-08-13 18:40:43.481][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #8: std::function<>::operator()() [0x11b1fda]
[2019-08-13 18:40:43.497][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #9: Envoy::Event::FileEventImpl::assignEvents()::$_0::operator()() [0x11b1ba7]
[2019-08-13 18:40:43.513][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #10: Envoy::Event::FileEventImpl::assignEvents()::$_0::__invoke() [0x11b19d9]
[2019-08-13 18:40:43.529][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #11: event_process_active_single_queue [0x1820ead]
[2019-08-13 18:40:43.546][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #12: event_base_loop [0x181d720]
[2019-08-13 18:40:43.562][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #13: Envoy::Event::LibeventScheduler::run() [0x11d833c]
[2019-08-13 18:40:43.578][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #14: Envoy::Event::DispatcherImpl::run() [0x11abe48]
[2019-08-13 18:40:43.594][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #15: Envoy::Server::WorkerImpl::threadRoutine() [0x1194621]
[2019-08-13 18:40:43.610][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #16: Envoy::Server::WorkerImpl::start()::$_3::operator()() [0x119564f]
[2019-08-13 18:40:43.626][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #17: std::_Function_handler<>::_M_invoke() [0x11954fd]
[2019-08-13 18:40:43.641][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #18: std::function<>::operator()() [0x62493e]
[2019-08-13 18:40:43.658][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #19: Envoy::Thread::ThreadImplPosix::ThreadImplPosix()::$_0::operator()() [0x1d03ba0]
[2019-08-13 18:40:43.674][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #20: Envoy::Thread::ThreadImplPosix::ThreadImplPosix()::$_0::__invoke() [0x1d03b75]
[2019-08-13 18:40:43.674][19331][critical][backtrace] [bazel-out/k8-dbg/bin/source/server/_virtual_includes/backtrace_lib/server/backtrace.h:73] #21: start_thread [0x7f05fa29a6db]

server.log

[2019-08-13 18:40:42.345][19175][debug][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:72] tls inspector: new connection accepted
[2019-08-13 18:40:42.345][19175][trace][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:141] tls inspector: recv: 140 
[2019-08-13 18:40:42.346][19175][trace][filter] [source/extensions/filters/listener/tls_inspector/tls_inspector.cc:162] tls inspector: done: true       
[2019-08-13 18:40:42.346][19175][debug][main] [source/server/connection_handler_impl.cc:280] [C0] new connection                                 
[2019-08-13 18:40:42.346][19175][trace][main] [source/common/event/dispatcher_impl.cc:158] item added to deferred deletion list (size=1)         
[2019-08-13 18:40:42.346][19175][trace][main] [source/common/event/dispatcher_impl.cc:76] clearing deferred deletion list (size=1)               
[2019-08-13 18:40:42.346][19175][trace][connection] [source/common/network/connection_impl.cc:457] [C0] socket event: 3                              
[2019-08-13 18:40:42.346][19175][trace][connection] [source/common/network/connection_impl.cc:542] [C0] write ready                                            
[2019-08-13 18:40:42.349][19175][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:167] [C0] handshake error: 2                        
[2019-08-13 18:40:42.349][19175][trace][connection] [source/common/network/connection_impl.cc:495] [C0] read ready                               
[2019-08-13 18:40:42.349][19175][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:167] [C0] handshake error: 2          
[2019-08-13 18:40:42.350][19175][trace][connection] [source/common/network/connection_impl.cc:457] [C0] socket event: 3                               
[2019-08-13 18:40:42.350][19175][trace][connection] [source/common/network/connection_impl.cc:542] [C0] write ready                                        
[2019-08-13 18:40:42.351][19175][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:156] [C0] handshake complete          
[2019-08-13 18:40:42.351][19175][trace][connection] [source/common/network/connection_impl.cc:495] [C0] read ready                                          
[2019-08-13 18:40:42.351][19175][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:72] [C0] ssl read returns: -1                        
[2019-08-13 18:40:42.351][19175][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:147] [C0] ssl read 0 bytes into 0 slices       
[2019-08-13 18:40:42.352][19175][trace][connection] [source/common/network/connection_impl.cc:457] [C0] socket event: 3                          
[2019-08-13 18:40:42.352][19175][trace][connection] [source/common/network/connection_impl.cc:542] [C0] write ready                                     
[2019-08-13 18:40:42.352][19175][trace][connection] [source/common/network/connection_impl.cc:495] [C0] read ready             
[2019-08-13 18:40:42.352][19175][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:72] [C0] ssl read returns: 213
[2019-08-13 18:40:42.352][19175][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:72] [C0] ssl read returns: -1
[2019-08-13 18:40:42.352][19175][trace][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:147] [C0] ssl read 213 bytes into 1 slices
[2019-08-13 18:40:42.352][19175][info][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:396] jianfeih debug consulting the alpn in sslsocket.
[2019-08-13 18:40:42.352][19175][info][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:396] jianfeih debug consulting the alpn in sslsocket.
[2019-08-13 18:40:42.352][19175][trace][http2] [source/common/http/http2/nghttp2.cc:20] nghttp2: send: reset nghttp2_active_outbound_item
[2019-08-13 18:40:42.352][19175][trace][http2] [source/common/http/http2/nghttp2.cc:20] nghttp2: send: aob->item = (nil)                 
[2019-08-13 18:40:42.352][19175][debug][http2] [source/common/http/http2/codec_impl.cc:726] [C0] setting stream-level initial window size to 268435456
[2019-08-13 18:40:42.352][19175][debug][http2] [source/common/http/http2/codec_impl.cc:748] [C0] updating connection-level initial window size to 268435456
[2019-08-13 18:40:42.352][19175][trace][http2] [source/common/http/http2/codec_impl.cc:343] [C0] dispatching 213 bytes
[2019-08-13 18:40:42.352][19175][trace][http2] [source/common/http/http2/nghttp2.cc:20] nghttp2: recv: connection recv_window_size=0, local_window=268435456
[2019-08-13 18:40:42.352][19175][trace][http2] [source/common/http/http2/nghttp2.cc:20] nghttp2: stream: adjusting kept idle streams num_idle_streams=0, max=100
[2019-08-13 18:40:42.353][19175][debug][http] [source/common/http/conn_manager_impl.cc:282] [C0] dispatch error: Received bad client magic byte string
[2019-08-13 18:40:42.353][19175][debug][connection] [source/common/network/connection_impl.cc:102] [C0] closing data_to_write=0 type=2
[2019-08-13 18:40:42.353][19175][debug][connection] [source/common/network/connection_impl.cc:652] [C0] setting delayed close timer with timeout 1000 ms
[2019-08-13 18:40:43.350][19175][debug][connection] [source/common/network/connection_impl.cc:641] [C0] triggered delayed close
[2019-08-13 18:40:43.350][19175][debug][connection] [source/common/network/connection_impl.cc:189] [C0] closing socket: 1
[2019-08-13 18:40:43.350][19175][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:269] [C0] SSL shutdown: rc=0
[2019-08-13 18:40:43.350][19175][debug][connection] [source/extensions/transport_sockets/tls/ssl_socket.cc:200] [C0]
[2019-08-13 18:40:43.350][19175][debug][main] [source/server/connection_handler_impl.cc:80] [C0] adding to cleanup list
[2019-08-13 18:40:43.350][19175][trace][main] [source/common/event/dispatcher_impl.cc:158] item added to deferred deletion list (size=1)
[2019-08-13 18:40:43.350][19175][trace][main] [source/common/event/dispatcher_impl.cc:76] clearing deferred deletion list (size=1)
[2019-08-13 18:40:43.350][19175][trace][http2] [source/common/http/http2/nghttp2.cc:20] nghttp2: send: reset nghttp2_active_outbound_item
[2019-08-13 18:40:43.350][19175][trace][http2] [source/common/http/http2/nghttp2.cc:20] nghttp2: send: aob->item = (nil)