Skip to content

Instantly share code, notes, and snippets.

Embed
What would you like to do?
time="2019-06-14T00:20:28Z" level=info msg="running version stable-2.3.2"
INFO [ 0.001275s] linkerd2_proxy::app::main using destination service at Some(ControlAddr { addr: Name(NameAddr { name: "linkerd-destination.linkerd.svc.cluster.local", port: 8086 }), identity: Some("linkerd-controller.linkerd.serviceaccount.identity.linkerd.cluster.local") })
INFO [ 0.001575s] linkerd2_proxy::app::main using identity service at Name(NameAddr { name: "linkerd-identity.linkerd.svc.cluster.local", port: 8080 })
INFO [ 0.001750s] linkerd2_proxy::app::main routing on V4(127.0.0.1:4140)
INFO [ 0.001961s] linkerd2_proxy::app::main proxying on V4(0.0.0.0:4143) to None
INFO [ 0.002152s] linkerd2_proxy::app::main serving admin endpoint metrics on V4(0.0.0.0:4191)
INFO [ 0.002277s] linkerd2_proxy::app::main protocol detection disabled for inbound ports {25, 3306}
INFO [ 0.002407s] linkerd2_proxy::app::main protocol detection disabled for outbound ports {25, 3306}
INFO [ 0.004537s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.007093s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.008887s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.009846s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.010666s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.011524s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.012361s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.016025s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.019684s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.022317s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.025046s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.027728s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
INFO [ 0.030501s] trust_dns_proto::xfer::dns_exchange sending message via: UDP(10.11.240.10:53)
DBUG [ 0.035287s] linkerd-identity.linkerd.svc.cluster.local:8080 h2::client binding client connection
DBUG [ 0.035504s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::client client connection bound
DBUG [ 0.035654s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write send; frame=Settings { flags: (0x0), enable_push: 0 }
TRCE [ 0.035780s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::frame::settings encoding SETTINGS; len=6
TRCE [ 0.035982s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::frame::settings encoding setting; val=EnablePush(0)
TRCE [ 0.036102s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write encoded settings; rem=15
TRCE [ 0.036226s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 0.036341s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 0.036453s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize Prioritize::new; flow=FlowControl { window_size: Window(65535), available: Window(65535) }
TRCE [ 0.036647s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=None
TRCE [ 0.036759s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.036970s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll; bytes=9B
TRCE [ 0.037120s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read decoding frame from 9B
TRCE [ 0.037242s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read -> kind=Settings
DBUG [ 0.037354s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read received; frame=Settings { flags: (0x0) }
TRCE [ 0.037466s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::connection recv SETTINGS; frame=Settings { flags: (0x0) }
TRCE [ 0.037581s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=Some(Settings { flags: (0x0) })
DBUG [ 0.037695s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write send; frame=Settings { flags: (0x1: ACK) }
TRCE [ 0.037817s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 0.037948s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write encoded settings; rem=24
TRCE [ 0.038081s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings ACK sent; applying settings
TRCE [ 0.038223s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.038360s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.038472s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize poll_complete
TRCE [ 0.038588s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 0.038697s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame
TRCE [ 0.038803s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flush
TRCE [ 0.038814s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write -> not a queued data frame
TRCE [ 0.038876s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flushing buffer
TRCE [ 0.038885s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.039122s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 0.039135s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 0.039147s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::send send_headers; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }; init_window=65535
TRCE [ 0.039162s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_send; StreamId(1)
TRCE [ 0.039167s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store Queue::push
TRCE [ 0.039170s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store -> first entry
TRCE [ 0.039178s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize reserve_capacity; stream=StreamId(1); requested=1; effective=1; curr=0
TRCE [ 0.039183s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try_assign_capacity; stream=StreamId(1), requested=1; additional=1; buffered=0; window=65535; conn=65535
TRCE [ 0.039188s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize assigning; stream=StreamId(1), capacity=1
TRCE [ 0.039192s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::stream assigned capacity to stream; available=1; buffered=0; id=StreamId(1)
TRCE [ 0.039196s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::stream notifying task
TRCE [ 0.039200s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try_assign_capacity(2); available=1; requested=1; buffered=0; has_unavailable=true
TRCE [ 0.039230s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize send_data; sz=1432; buffered=1432; requested=1
TRCE [ 0.039234s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try_assign_capacity; stream=StreamId(1), requested=1432; additional=1431; buffered=1432; window=65535; conn=65534
TRCE [ 0.039239s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize assigning; stream=StreamId(1), capacity=1431
TRCE [ 0.039242s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::stream assigned capacity to stream; available=1432; buffered=1432; id=StreamId(1)
TRCE [ 0.039246s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try_assign_capacity(2); available=1432; requested=1432; buffered=1432; has_unavailable=true
TRCE [ 0.039251s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store Queue::push
TRCE [ 0.039275s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store -> already queued
TRCE [ 0.039279s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize send_data (2); available=1432; buffered=1432
TRCE [ 0.039286s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_send; StreamId(1)
TRCE [ 0.039291s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store Queue::push
TRCE [ 0.039294s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store -> already queued
TRCE [ 0.039298s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Open { local: Streaming, remote: AwaitingHeaders } }; is_closed=false; pending_send_empty=false; buffered_send_data=1432; num_recv=0; num_send=1
TRCE [ 0.039306s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize reserve_capacity; stream=StreamId(1); requested=1; effective=1433; curr=1432
TRCE [ 0.039311s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try_assign_capacity; stream=StreamId(1), requested=1433; additional=1; buffered=1432; window=65535; conn=64103
TRCE [ 0.039315s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize assigning; stream=StreamId(1), capacity=1
TRCE [ 0.039319s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::stream assigned capacity to stream; available=1433; buffered=1432; id=StreamId(1)
TRCE [ 0.039323s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::stream notifying task
TRCE [ 0.039327s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try_assign_capacity(2); available=1433; requested=1433; buffered=1432; has_unavailable=true
TRCE [ 0.039331s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store Queue::push
TRCE [ 0.039335s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store -> already queued
TRCE [ 0.039344s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize reserve_capacity; stream=StreamId(1); requested=0; effective=1432; curr=1433
TRCE [ 0.039348s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize assign_connection_capacity; inc=1
TRCE [ 0.039354s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize send_data; sz=0; buffered=1432; requested=1432
TRCE [ 0.039358s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::state send_close: Open => HalfClosedLocal(AwaitingHeaders)
TRCE [ 0.039362s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize reserve_capacity; stream=StreamId(1); requested=0; effective=1432; curr=1432
TRCE [ 0.039366s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize send_data (2); available=1432; buffered=1432
TRCE [ 0.039370s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_send; StreamId(1)
TRCE [ 0.039375s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store Queue::push
TRCE [ 0.039379s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store -> already queued
TRCE [ 0.039382s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }; is_closed=false; pending_send_empty=false; buffered_send_data=1432; num_recv=0; num_send=1
TRCE [ 0.039389s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::streams drop_stream_ref; stream=Stream { id: StreamId(1), state: State { inner: HalfClosedLocal(AwaitingHeaders) }, is_counted: true, ref_count: 2, next_pending_send: None, is_pending_send: true, send_flow: FlowControl { window_size: Window(65535), available: Window(1432) }, requested_send_capacity: 1432, buffered_send_data: 1432, send_task: Some(Task), pending_send: Deque { indices: Some(Indices { head: 0, tail: 2 }) }, next_pending_send_capacity: None, is_pending_send_capacity: false, send_capacity_inc: true, next_open: None, is_pending_open: false, next_pending_accept: None, is_pending_accept: false, recv_flow: FlowControl { window_size: Window(65535), available: Window(65535) }, in_flight_recv_data: 0, next_window_update: None, is_pending_window_update: false, reset_at: None, next_reset_expire: None, pending_recv: Deque { indices: None }, recv_task: None, pending_push_promises: Queue { indices: None, _p: PhantomData }, content_length: Omitted }
TRCE [ 0.039414s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }; is_closed=false; pending_send_empty=false; buffered_send_data=1432; num_recv=0; num_send=1
TRCE [ 0.039435s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=None
TRCE [ 0.039440s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.039458s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll; bytes=9B
TRCE [ 0.039462s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read decoding frame from 9B
TRCE [ 0.039465s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read -> kind=Settings
DBUG [ 0.039470s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read received; frame=Settings { flags: (0x1: ACK) }
TRCE [ 0.039475s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::connection recv SETTINGS; frame=Settings { flags: (0x1: ACK) }
DBUG [ 0.039480s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings received remote settings ack
TRCE [ 0.039483s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=None
TRCE [ 0.039487s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.039498s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.039503s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize poll_complete
TRCE [ 0.039507s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 0.039511s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame
TRCE [ 0.039515s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame; stream=StreamId(1); stream.state=State { inner: HalfClosedLocal(AwaitingHeaders) }
TRCE [ 0.039520s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize --> stream=StreamId(1); is_pending_reset=false;
TRCE [ 0.039525s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 0.039530s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store Queue::push
TRCE [ 0.039534s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store -> first entry
TRCE [ 0.039538s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }; is_closed=false; pending_send_empty=false; buffered_send_data=1432; num_recv=0; num_send=1
TRCE [ 0.039543s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize writing frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
DBUG [ 0.039547s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write send; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 0.039566s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.039570s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 0.039574s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame
TRCE [ 0.039577s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame; stream=StreamId(1); stream.state=State { inner: HalfClosedLocal(AwaitingHeaders) }
TRCE [ 0.039582s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize --> stream=StreamId(1); is_pending_reset=false;
TRCE [ 0.039586s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize --> data frame; stream=StreamId(1); sz=1432; eos=false; window=1432; available=1432; requested=1432; buffered=1432;
TRCE [ 0.039591s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize --> sending data frame; len=1432
TRCE [ 0.039595s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize -- updating stream flow --
TRCE [ 0.039599s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control send_data; sz=1432; window=65535; available=1432
TRCE [ 0.039604s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize -- updating connection flow --
TRCE [ 0.039608s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control send_data; sz=1432; window=65535; available=65535
TRCE [ 0.039612s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame; frame=Data { stream_id: StreamId(1) }
TRCE [ 0.039616s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store Queue::push
TRCE [ 0.039620s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::store -> first entry
TRCE [ 0.039624s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=0; num_send=1
TRCE [ 0.039629s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize writing frame=Data { stream_id: StreamId(1) }
DBUG [ 0.039633s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write send; frame=Data { stream_id: StreamId(1) }
TRCE [ 0.039638s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flush
TRCE [ 0.039642s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write -> queued data frame
TRCE [ 0.039679s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write -> queued data frame
TRCE [ 0.039733s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flushing buffer
TRCE [ 0.039739s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.039742s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize -> reclaimed; frame=Data { stream_id: StreamId(1) }; sz=0
TRCE [ 0.039748s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 0.039752s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame
TRCE [ 0.039756s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame; stream=StreamId(1); stream.state=State { inner: HalfClosedLocal(AwaitingHeaders) }
TRCE [ 0.039761s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize --> stream=StreamId(1); is_pending_reset=false;
TRCE [ 0.039765s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize --> data frame; stream=StreamId(1); sz=0; eos=true; window=0; available=0; requested=0; buffered=0;
TRCE [ 0.039770s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize --> sending data frame; len=0
TRCE [ 0.039775s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize -- updating stream flow --
TRCE [ 0.039780s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control send_data; sz=0; window=64103; available=0
TRCE [ 0.039784s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize -- updating connection flow --
TRCE [ 0.039787s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control send_data; sz=0; window=64103; available=64103
TRCE [ 0.039792s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame; frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) }
TRCE [ 0.039797s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }; is_closed=false; pending_send_empty=true; buffered_send_data=0; num_recv=0; num_send=1
TRCE [ 0.039802s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize writing frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) }
DBUG [ 0.039807s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write send; frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) }
TRCE [ 0.039813s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.039817s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize -> reclaimed; frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) }; sz=0
TRCE [ 0.039845s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 0.039850s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame
TRCE [ 0.039854s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flush
TRCE [ 0.039858s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write -> not a queued data frame
TRCE [ 0.039890s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flushing buffer
TRCE [ 0.039896s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.049668s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=None
TRCE [ 0.049691s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.049751s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll; bytes=47B
TRCE [ 0.049755s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read decoding frame from 47B
TRCE [ 0.049759s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read -> kind=Headers
TRCE [ 0.049764s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::frame::headers loading headers; flags=(0x4: END_HEADERS)
TRCE [ 0.049771s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::hpack::decoder decode
TRCE [ 0.049775s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::hpack::decoder Indexed; rem=38
TRCE [ 0.049782s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::hpack::decoder LiteralWithIndexing; rem=37
TRCE [ 0.049794s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::hpack::decoder LiteralWithIndexing; rem=24
DBUG [ 0.049803s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read received; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 0.049810s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::connection recv HEADERS; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 0.049819s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::streams recv_headers; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }
TRCE [ 0.049866s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::recv opening stream; init_window=65535
TRCE [ 0.049876s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(Streaming) }; is_closed=false; pending_send_empty=true; buffered_send_data=0; num_recv=0; num_send=1
TRCE [ 0.049883s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=None
TRCE [ 0.049887s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.049891s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll; bytes=946B
TRCE [ 0.049895s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read decoding frame from 946B
TRCE [ 0.049899s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read -> kind=Data
DBUG [ 0.049904s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read received; frame=Data { stream_id: StreamId(1) }
TRCE [ 0.049908s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::connection recv DATA; frame=Data { stream_id: StreamId(1) }
TRCE [ 0.049915s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::recv recv_data; size=937; connection=65535; stream=65535
TRCE [ 0.049925s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control send_data; sz=937; window=65535; available=65535
TRCE [ 0.049929s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::flow_control send_data; sz=937; window=65535; available=65535
TRCE [ 0.049935s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(Streaming) }; is_closed=false; pending_send_empty=true; buffered_send_data=0; num_recv=0; num_send=1
TRCE [ 0.049940s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=None
TRCE [ 0.049944s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.049948s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll; bytes=33B
TRCE [ 0.049951s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read decoding frame from 33B
TRCE [ 0.049955s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read -> kind=Headers
TRCE [ 0.049959s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::frame::headers loading headers; flags=(0x5: END_HEADERS | END_STREAM)
TRCE [ 0.049963s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::hpack::decoder decode
TRCE [ 0.049967s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::hpack::decoder LiteralWithIndexing; rem=24
TRCE [ 0.049984s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::hpack::decoder LiteralWithIndexing; rem=12
DBUG [ 0.049991s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read received; frame=Headers { stream_id: StreamId(1), flags: (0x5: END_HEADERS | END_STREAM) }
TRCE [ 0.049997s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::connection recv HEADERS; frame=Headers { stream_id: StreamId(1), flags: (0x5: END_HEADERS | END_STREAM) }
TRCE [ 0.050002s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::streams recv_headers; stream=StreamId(1); state=State { inner: HalfClosedLocal(Streaming) }
TRCE [ 0.050007s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::state recv_close: HalfClosedLocal => Closed
TRCE [ 0.050013s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(EndStream) }; is_closed=true; pending_send_empty=true; buffered_send_data=0; num_recv=0; num_send=1
TRCE [ 0.050019s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts dec_num_streams; stream=StreamId(1)
TRCE [ 0.050023s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=None
TRCE [ 0.050027s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.050142s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.050149s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize poll_complete
TRCE [ 0.050153s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 0.050157s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame
TRCE [ 0.050161s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flush
TRCE [ 0.050165s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flushing buffer
TRCE [ 0.050169s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.050188s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::streams drop_stream_ref; stream=Stream { id: StreamId(1), state: State { inner: Closed(EndStream) }, is_counted: false, ref_count: 2, next_pending_send: None, is_pending_send: false, send_flow: FlowControl { window_size: Window(64103), available: Window(0) }, requested_send_capacity: 0, buffered_send_data: 0, send_task: Some(Task), pending_send: Deque { indices: None }, next_pending_send_capacity: None, is_pending_send_capacity: false, send_capacity_inc: true, next_open: None, is_pending_open: false, next_pending_accept: None, is_pending_accept: false, recv_flow: FlowControl { window_size: Window(64598), available: Window(64598) }, in_flight_recv_data: 937, next_window_update: None, is_pending_window_update: false, reset_at: None, next_reset_expire: None, pending_recv: Deque { indices: Some(Indices { head: 1, tail: 2 }) }, recv_task: None, pending_push_promises: Queue { indices: None, _p: PhantomData }, content_length: Omitted }
TRCE [ 0.050210s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(EndStream) }; is_closed=true; pending_send_empty=true; buffered_send_data=0; num_recv=0; num_send=0
TRCE [ 0.050277s] admin={bg=identity} h2::proto::streams::recv release_capacity; size=937
TRCE [ 0.050283s] admin={bg=identity} h2::proto::streams::recv release_connection_capacity; size=937, connection in_flight_data=937
TRCE [ 0.050590s] admin={bg=identity} h2::proto::streams::streams drop_stream_ref; stream=Stream { id: StreamId(1), state: State { inner: Closed(EndStream) }, is_counted: false, ref_count: 1, next_pending_send: None, is_pending_send: false, send_flow: FlowControl { window_size: Window(64103), available: Window(0) }, requested_send_capacity: 0, buffered_send_data: 0, send_task: Some(Task), pending_send: Deque { indices: None }, next_pending_send_capacity: None, is_pending_send_capacity: false, send_capacity_inc: true, next_open: None, is_pending_open: false, next_pending_accept: None, is_pending_accept: false, recv_flow: FlowControl { window_size: Window(64598), available: Window(65535) }, in_flight_recv_data: 0, next_window_update: None, is_pending_window_update: false, reset_at: None, next_reset_expire: None, pending_recv: Deque { indices: None }, recv_task: None, pending_push_promises: Queue { indices: None, _p: PhantomData }, content_length: Omitted }
TRCE [ 0.050612s] admin={bg=identity} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(EndStream) }; is_closed=true; pending_send_empty=true; buffered_send_data=0; num_recv=0; num_send=0
INFO [ 0.051329s] linkerd2_proxy::app::main Certified identity: grpc-ingress-nginx-ingress.grpc-ingress.serviceaccount.identity.linkerd.cluster.local
TRCE [ 0.051346s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::settings send_pending_ack; pending=None
TRCE [ 0.051352s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_read poll
TRCE [ 0.051377s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
TRCE [ 0.051381s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize poll_complete
TRCE [ 0.051385s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 0.051389s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize pop_frame
TRCE [ 0.051392s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flush
TRCE [ 0.051396s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::codec::framed_write flushing buffer
TRCE [ 0.051400s] admin={client=control dst=linkerd-identity.linkerd.svc.cluster.local:8080 remote=10.11.249.66:8080} h2::proto::streams::prioritize try reclaim frame
DBUG [ 32.712116s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write send; frame=Settings { flags: (0x0) }
TRCE [ 32.712166s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 32.712171s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write encoded settings; rem=9
TRCE [ 32.712180s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::server Handshake::poll(); state=Handshaking::Flushing(_);
TRCE [ 32.712185s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write flush
TRCE [ 32.712188s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write -> not a queued data frame
TRCE [ 32.712229s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write flushing buffer
TRCE [ 32.712237s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::server Handshake::poll(); flush.poll()=Ready
TRCE [ 32.712246s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 32.712249s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 32.712252s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize Prioritize::new; flow=FlowControl { window_size: Window(65535), available: Window(65535) }
TRCE [ 32.712260s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::server Handshake::poll(); connection established!
TRCE [ 32.712268s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.712272s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll
TRCE [ 32.712278s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll; bytes=27B
TRCE [ 32.712281s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read decoding frame from 27B
TRCE [ 32.712283s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read -> kind=Settings
DBUG [ 32.712288s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read received; frame=Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 }
TRCE [ 32.712293s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::connection recv SETTINGS; frame=Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 }
TRCE [ 32.712297s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings send_pending_ack; pending=Some(Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 })
DBUG [ 32.712301s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write send; frame=Settings { flags: (0x1: ACK) }
TRCE [ 32.712304s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 32.712306s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write encoded settings; rem=9
TRCE [ 32.712309s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings ACK sent; applying settings
TRCE [ 32.712312s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll
TRCE [ 32.712315s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll; bytes=13B
TRCE [ 32.712318s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read decoding frame from 13B
TRCE [ 32.712322s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read -> kind=WindowUpdate
DBUG [ 32.712326s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read received; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 2147418112 }
TRCE [ 32.712331s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::connection recv WINDOW_UPDATE; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 2147418112 }
TRCE [ 32.712335s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::flow_control inc_window; sz=2147418112; old=65535; new=2147483647
TRCE [ 32.712338s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize assign_connection_capacity; inc=2147418112
TRCE [ 32.712342s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.712357s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll
TRCE [ 32.712359s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll; bytes=382B
TRCE [ 32.712361s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read decoding frame from 382B
TRCE [ 32.712364s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read -> kind=Headers
TRCE [ 32.712367s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::frame::headers loading headers; flags=(0x4: END_HEADERS)
TRCE [ 32.712373s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder decode
TRCE [ 32.712376s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder Indexed; rem=373
TRCE [ 32.712382s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder Indexed; rem=372
TRCE [ 32.712389s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithIndexing; rem=371
TRCE [ 32.712440s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=324
TRCE [ 32.712458s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=286
TRCE [ 32.712472s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=251
TRCE [ 32.712476s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=234
TRCE [ 32.712480s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=213
TRCE [ 32.712485s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=166
TRCE [ 32.712489s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=148
TRCE [ 32.712495s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=128
TRCE [ 32.712503s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=70
TRCE [ 32.712507s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=57
TRCE [ 32.712511s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=46
TRCE [ 32.712520s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::hpack::decoder LiteralWithoutIndexing; rem=23
DBUG [ 32.712524s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read received; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 32.712530s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::connection recv HEADERS; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 32.712538s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 32.712546s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::flow_control inc_window; sz=2147483647; old=0; new=2147483647
TRCE [ 32.712552s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::streams recv_headers; stream=StreamId(1); state=State { inner: Idle }
TRCE [ 32.712560s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::recv opening stream; init_window=65535
TRCE [ 32.712571s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::send send_reset(..., reason=PROTOCOL_ERROR, stream=StreamId(1), ..., is_reset=false; is_closed=false; pending_send.is_empty=true; state=State { inner: Open { local: AwaitingHeaders, remote: Streaming } }
TRCE [ 32.712581s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize clear_queue; stream=StreamId(1)
TRCE [ 32.712584s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::send send_reset -- queueing; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 32.712590s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize schedule_send; StreamId(1)
TRCE [ 32.712594s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::store Queue::push
TRCE [ 32.712597s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::store -> first entry
TRCE [ 32.712600s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize assign_connection_capacity; inc=0
TRCE [ 32.712604s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 32.712611s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.712614s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll
TRCE [ 32.712618s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll; bytes=49B
TRCE [ 32.712626s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read decoding frame from 49B
TRCE [ 32.712629s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read -> kind=Data
DBUG [ 32.712633s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read received; frame=Data { stream_id: StreamId(1) }
TRCE [ 32.712636s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::connection recv DATA; frame=Data { stream_id: StreamId(1) }
TRCE [ 32.712646s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::recv recv_data; size=40; connection=65535; stream=65535
TRCE [ 32.712649s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::flow_control send_data; sz=40; window=65535; available=65535
TRCE [ 32.712653s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::recv recv_data frame ignored on locally reset StreamId(1) for some time
TRCE [ 32.712656s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::recv release_connection_capacity; size=40, connection in_flight_data=40
TRCE [ 32.712660s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 32.712668s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.712671s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll
TRCE [ 32.712679s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll; bytes=9B
TRCE [ 32.712687s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read decoding frame from 9B
TRCE [ 32.712689s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read -> kind=Settings
DBUG [ 32.712692s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read received; frame=Settings { flags: (0x1: ACK) }
TRCE [ 32.712696s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::connection recv SETTINGS; frame=Settings { flags: (0x1: ACK) }
DBUG [ 32.712703s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings received remote settings ack
TRCE [ 32.712705s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.712708s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll
TRCE [ 32.712716s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize try reclaim frame
TRCE [ 32.712724s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize poll_complete
TRCE [ 32.712727s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 32.712730s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize pop_frame
TRCE [ 32.712734s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize pop_frame; stream=StreamId(1); stream.state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }
TRCE [ 32.712742s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize --> stream=StreamId(1); is_pending_reset=false;
TRCE [ 32.712746s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize pop_frame; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 32.712755s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=true; pending_send_empty=true; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 32.712759s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::counts dec_num_streams; stream=StreamId(1)
TRCE [ 32.712763s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize writing frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
DBUG [ 32.712770s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write send; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 32.712774s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::frame::reset encoding RESET; id=StreamId(1) code=PROTOCOL_ERROR
TRCE [ 32.712794s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write encoded reset; rem=22
TRCE [ 32.712798s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize try reclaim frame
TRCE [ 32.712802s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 32.712805s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize pop_frame
TRCE [ 32.712809s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write flush
TRCE [ 32.712812s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write -> not a queued data frame
TRCE [ 32.712923s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write flushing buffer
TRCE [ 32.712934s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::prioritize try reclaim frame
TRCE [ 32.713085s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.713094s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_read poll
TRCE [ 32.713101s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::connection codec closed
TRCE [ 32.713104s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::streams Streams::recv_eof
TRCE [ 32.713108s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::connection connection closing after flush
TRCE [ 32.713111s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write flush
TRCE [ 32.713113s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::codec::framed_write flushing buffer
TRCE [ 32.713173s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33606} h2::proto::streams::streams Streams::recv_eof
DBUG [ 32.745109s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write send; frame=Settings { flags: (0x0) }
TRCE [ 32.745162s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 32.745168s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write encoded settings; rem=9
TRCE [ 32.745177s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::server Handshake::poll(); state=Handshaking::Flushing(_);
TRCE [ 32.745181s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write flush
TRCE [ 32.745184s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write -> not a queued data frame
TRCE [ 32.745221s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write flushing buffer
TRCE [ 32.745233s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::server Handshake::poll(); flush.poll()=Ready
TRCE [ 32.745244s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 32.745255s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 32.745257s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize Prioritize::new; flow=FlowControl { window_size: Window(65535), available: Window(65535) }
TRCE [ 32.745265s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::server Handshake::poll(); connection established!
TRCE [ 32.745278s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.745287s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll
TRCE [ 32.745292s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll; bytes=27B
TRCE [ 32.745295s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read decoding frame from 27B
TRCE [ 32.745298s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read -> kind=Settings
DBUG [ 32.745302s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read received; frame=Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 }
TRCE [ 32.745311s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::connection recv SETTINGS; frame=Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 }
TRCE [ 32.745315s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::settings send_pending_ack; pending=Some(Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 })
DBUG [ 32.745323s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write send; frame=Settings { flags: (0x1: ACK) }
TRCE [ 32.745326s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 32.745328s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write encoded settings; rem=9
TRCE [ 32.745331s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::settings ACK sent; applying settings
TRCE [ 32.745335s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll
TRCE [ 32.745341s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll; bytes=13B
TRCE [ 32.745344s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read decoding frame from 13B
TRCE [ 32.745347s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read -> kind=WindowUpdate
DBUG [ 32.745350s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read received; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 2147418112 }
TRCE [ 32.745354s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::connection recv WINDOW_UPDATE; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 2147418112 }
TRCE [ 32.745359s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::flow_control inc_window; sz=2147418112; old=65535; new=2147483647
TRCE [ 32.745362s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize assign_connection_capacity; inc=2147418112
TRCE [ 32.745366s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.745369s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll
TRCE [ 32.745372s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll; bytes=382B
TRCE [ 32.745376s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read decoding frame from 382B
TRCE [ 32.745379s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read -> kind=Headers
TRCE [ 32.745382s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::frame::headers loading headers; flags=(0x4: END_HEADERS)
TRCE [ 32.745387s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder decode
TRCE [ 32.745390s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder Indexed; rem=373
TRCE [ 32.745395s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder Indexed; rem=372
TRCE [ 32.745400s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithIndexing; rem=371
TRCE [ 32.745413s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=324
TRCE [ 32.745425s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=286
TRCE [ 32.745437s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=251
TRCE [ 32.745441s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=234
TRCE [ 32.745487s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=213
TRCE [ 32.745496s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=166
TRCE [ 32.745503s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=148
TRCE [ 32.745510s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=128
TRCE [ 32.745515s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=70
TRCE [ 32.745521s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=57
TRCE [ 32.745525s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=46
TRCE [ 32.745530s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::hpack::decoder LiteralWithoutIndexing; rem=23
DBUG [ 32.745539s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read received; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 32.745545s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::connection recv HEADERS; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 32.745552s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 32.745560s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::flow_control inc_window; sz=2147483647; old=0; new=2147483647
TRCE [ 32.745565s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::streams recv_headers; stream=StreamId(1); state=State { inner: Idle }
TRCE [ 32.745574s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::recv opening stream; init_window=65535
TRCE [ 32.745583s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::send send_reset(..., reason=PROTOCOL_ERROR, stream=StreamId(1), ..., is_reset=false; is_closed=false; pending_send.is_empty=true; state=State { inner: Open { local: AwaitingHeaders, remote: Streaming } }
TRCE [ 32.745590s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize clear_queue; stream=StreamId(1)
TRCE [ 32.745595s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::send send_reset -- queueing; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 32.745600s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize schedule_send; StreamId(1)
TRCE [ 32.745606s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::store Queue::push
TRCE [ 32.745608s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::store -> first entry
TRCE [ 32.745613s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize assign_connection_capacity; inc=0
TRCE [ 32.745616s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 32.745627s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.745631s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll
TRCE [ 32.745634s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll; bytes=49B
TRCE [ 32.745636s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read decoding frame from 49B
TRCE [ 32.745639s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read -> kind=Data
DBUG [ 32.745643s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read received; frame=Data { stream_id: StreamId(1) }
TRCE [ 32.745647s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::connection recv DATA; frame=Data { stream_id: StreamId(1) }
TRCE [ 32.745665s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::recv recv_data; size=40; connection=65535; stream=65535
TRCE [ 32.745673s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::flow_control send_data; sz=40; window=65535; available=65535
TRCE [ 32.745675s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::recv recv_data frame ignored on locally reset StreamId(1) for some time
TRCE [ 32.745678s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::recv release_connection_capacity; size=40, connection in_flight_data=40
TRCE [ 32.745682s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 32.745687s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.745689s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll
TRCE [ 32.745699s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize try reclaim frame
TRCE [ 32.745706s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize poll_complete
TRCE [ 32.745709s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 32.745712s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize pop_frame
TRCE [ 32.745715s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize pop_frame; stream=StreamId(1); stream.state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }
TRCE [ 32.745724s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize --> stream=StreamId(1); is_pending_reset=false;
TRCE [ 32.745728s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize pop_frame; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 32.745737s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=true; pending_send_empty=true; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 32.745741s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::counts dec_num_streams; stream=StreamId(1)
TRCE [ 32.745745s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize writing frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
DBUG [ 32.745754s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write send; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 32.745758s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::frame::reset encoding RESET; id=StreamId(1) code=PROTOCOL_ERROR
TRCE [ 32.745762s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write encoded reset; rem=22
TRCE [ 32.745770s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize try reclaim frame
TRCE [ 32.745772s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 32.745774s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize pop_frame
TRCE [ 32.745777s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write flush
TRCE [ 32.745779s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write -> not a queued data frame
TRCE [ 32.745809s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write flushing buffer
TRCE [ 32.745819s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::prioritize try reclaim frame
TRCE [ 32.746870s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::settings send_pending_ack; pending=None
TRCE [ 32.746887s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_read poll
TRCE [ 32.746898s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::connection codec closed
TRCE [ 32.746901s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::streams Streams::recv_eof
TRCE [ 32.746919s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::connection connection closing after flush
TRCE [ 32.746929s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write flush
TRCE [ 32.746931s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::codec::framed_write flushing buffer
TRCE [ 32.746986s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33608} h2::proto::streams::streams Streams::recv_eof
DBUG [ 76.896623s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write send; frame=Settings { flags: (0x0) }
TRCE [ 76.896665s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 76.896670s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write encoded settings; rem=9
TRCE [ 76.896684s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::server Handshake::poll(); state=Handshaking::Flushing(_);
TRCE [ 76.896688s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write flush
TRCE [ 76.896691s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write -> not a queued data frame
TRCE [ 76.896727s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write flushing buffer
TRCE [ 76.896742s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::server Handshake::poll(); flush.poll()=Ready
TRCE [ 76.896757s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 76.896766s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 76.896769s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize Prioritize::new; flow=FlowControl { window_size: Window(65535), available: Window(65535) }
TRCE [ 76.896777s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::server Handshake::poll(); connection established!
TRCE [ 76.896789s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.896798s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll
TRCE [ 76.896805s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll; bytes=27B
TRCE [ 76.896812s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read decoding frame from 27B
TRCE [ 76.896815s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read -> kind=Settings
DBUG [ 76.896836s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read received; frame=Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 }
TRCE [ 76.896849s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::connection recv SETTINGS; frame=Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 }
TRCE [ 76.896854s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::settings send_pending_ack; pending=Some(Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 })
DBUG [ 76.896863s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write send; frame=Settings { flags: (0x1: ACK) }
TRCE [ 76.896867s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 76.896870s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write encoded settings; rem=9
TRCE [ 76.896874s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::settings ACK sent; applying settings
TRCE [ 76.896884s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll
TRCE [ 76.896888s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll; bytes=13B
TRCE [ 76.896892s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read decoding frame from 13B
TRCE [ 76.896895s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read -> kind=WindowUpdate
DBUG [ 76.896899s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read received; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 2147418112 }
TRCE [ 76.896909s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::connection recv WINDOW_UPDATE; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 2147418112 }
TRCE [ 76.896919s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::flow_control inc_window; sz=2147418112; old=65535; new=2147483647
TRCE [ 76.896923s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize assign_connection_capacity; inc=2147418112
TRCE [ 76.896926s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.896929s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll
TRCE [ 76.896933s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll; bytes=382B
TRCE [ 76.896941s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read decoding frame from 382B
TRCE [ 76.896945s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read -> kind=Headers
TRCE [ 76.896948s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::frame::headers loading headers; flags=(0x4: END_HEADERS)
TRCE [ 76.896958s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder decode
TRCE [ 76.896961s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder Indexed; rem=373
TRCE [ 76.896966s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder Indexed; rem=372
TRCE [ 76.896970s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithIndexing; rem=371
TRCE [ 76.896988s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=324
TRCE [ 76.896997s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=286
TRCE [ 76.897004s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=251
TRCE [ 76.897009s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=234
TRCE [ 76.897018s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=213
TRCE [ 76.897023s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=166
TRCE [ 76.897031s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=148
TRCE [ 76.897038s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=128
TRCE [ 76.897047s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=70
TRCE [ 76.897050s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=57
TRCE [ 76.897055s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=46
TRCE [ 76.897058s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::hpack::decoder LiteralWithoutIndexing; rem=23
DBUG [ 76.897068s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read received; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 76.897078s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::connection recv HEADERS; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 76.897084s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 76.897087s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::flow_control inc_window; sz=2147483647; old=0; new=2147483647
TRCE [ 76.897092s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::streams recv_headers; stream=StreamId(1); state=State { inner: Idle }
TRCE [ 76.897101s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::recv opening stream; init_window=65535
TRCE [ 76.897111s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::send send_reset(..., reason=PROTOCOL_ERROR, stream=StreamId(1), ..., is_reset=false; is_closed=false; pending_send.is_empty=true; state=State { inner: Open { local: AwaitingHeaders, remote: Streaming } }
TRCE [ 76.897122s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize clear_queue; stream=StreamId(1)
TRCE [ 76.897126s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::send send_reset -- queueing; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 76.897136s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize schedule_send; StreamId(1)
TRCE [ 76.897140s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::store Queue::push
TRCE [ 76.897143s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::store -> first entry
TRCE [ 76.897151s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize assign_connection_capacity; inc=0
TRCE [ 76.897155s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 76.897165s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.897168s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll
TRCE [ 76.897171s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll; bytes=49B
TRCE [ 76.897174s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read decoding frame from 49B
TRCE [ 76.897177s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read -> kind=Data
DBUG [ 76.897180s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read received; frame=Data { stream_id: StreamId(1) }
TRCE [ 76.897185s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::connection recv DATA; frame=Data { stream_id: StreamId(1) }
TRCE [ 76.897194s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::recv recv_data; size=40; connection=65535; stream=65535
TRCE [ 76.897197s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::flow_control send_data; sz=40; window=65535; available=65535
TRCE [ 76.897200s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::recv recv_data frame ignored on locally reset StreamId(1) for some time
TRCE [ 76.897203s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::recv release_connection_capacity; size=40, connection in_flight_data=40
TRCE [ 76.897207s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 76.897216s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.897219s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll
TRCE [ 76.897228s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize try reclaim frame
TRCE [ 76.897236s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize poll_complete
TRCE [ 76.897239s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 76.897242s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize pop_frame
TRCE [ 76.897251s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize pop_frame; stream=StreamId(1); stream.state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }
TRCE [ 76.897255s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize --> stream=StreamId(1); is_pending_reset=false;
TRCE [ 76.897260s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize pop_frame; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 76.897268s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=true; pending_send_empty=true; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 76.897272s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::counts dec_num_streams; stream=StreamId(1)
TRCE [ 76.897276s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize writing frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
DBUG [ 76.897283s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write send; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 76.897287s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::frame::reset encoding RESET; id=StreamId(1) code=PROTOCOL_ERROR
TRCE [ 76.897292s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write encoded reset; rem=22
TRCE [ 76.897299s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize try reclaim frame
TRCE [ 76.897301s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 76.897304s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize pop_frame
TRCE [ 76.897307s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write flush
TRCE [ 76.897310s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write -> not a queued data frame
TRCE [ 76.897336s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write flushing buffer
TRCE [ 76.897345s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::prioritize try reclaim frame
TRCE [ 76.898009s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.898030s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_read poll
TRCE [ 76.898038s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::connection codec closed
TRCE [ 76.898041s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::streams Streams::recv_eof
TRCE [ 76.898047s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::connection connection closing after flush
TRCE [ 76.898057s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write flush
TRCE [ 76.898060s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::codec::framed_write flushing buffer
TRCE [ 76.898102s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33802} h2::proto::streams::streams Streams::recv_eof
DBUG [ 76.923592s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write send; frame=Settings { flags: (0x0) }
TRCE [ 76.923641s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 76.923692s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write encoded settings; rem=9
TRCE [ 76.923739s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::server Handshake::poll(); state=Handshaking::Flushing(_);
TRCE [ 76.923772s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write flush
TRCE [ 76.923794s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write -> not a queued data frame
TRCE [ 76.923886s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write flushing buffer
TRCE [ 76.923927s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::server Handshake::poll(); flush.poll()=Ready
TRCE [ 76.923993s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 76.924019s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 76.924049s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize Prioritize::new; flow=FlowControl { window_size: Window(65535), available: Window(65535) }
TRCE [ 76.924080s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::server Handshake::poll(); connection established!
TRCE [ 76.924153s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.924183s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll
TRCE [ 76.924204s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll; bytes=27B
TRCE [ 76.924250s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read decoding frame from 27B
TRCE [ 76.924319s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read -> kind=Settings
DBUG [ 76.924338s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read received; frame=Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 }
TRCE [ 76.924354s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::connection recv SETTINGS; frame=Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 }
TRCE [ 76.924383s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings send_pending_ack; pending=Some(Settings { flags: (0x0), header_table_size: 0, enable_push: 0, initial_window_size: 2147483647 })
DBUG [ 76.924403s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write send; frame=Settings { flags: (0x1: ACK) }
TRCE [ 76.924417s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::frame::settings encoding SETTINGS; len=0
TRCE [ 76.924429s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write encoded settings; rem=9
TRCE [ 76.924443s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings ACK sent; applying settings
TRCE [ 76.924461s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll
TRCE [ 76.924589s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll; bytes=13B
TRCE [ 76.924630s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read decoding frame from 13B
TRCE [ 76.924650s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read -> kind=WindowUpdate
DBUG [ 76.924715s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read received; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 2147418112 }
TRCE [ 76.924737s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::connection recv WINDOW_UPDATE; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 2147418112 }
TRCE [ 76.924742s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::flow_control inc_window; sz=2147418112; old=65535; new=2147483647
TRCE [ 76.924748s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize assign_connection_capacity; inc=2147418112
TRCE [ 76.924752s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.924755s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll
TRCE [ 76.924758s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll; bytes=382B
TRCE [ 76.924761s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read decoding frame from 382B
TRCE [ 76.924763s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read -> kind=Headers
TRCE [ 76.924766s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::frame::headers loading headers; flags=(0x4: END_HEADERS)
TRCE [ 76.924773s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder decode
TRCE [ 76.924809s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder Indexed; rem=373
TRCE [ 76.925044s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder Indexed; rem=372
TRCE [ 76.925118s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithIndexing; rem=371
TRCE [ 76.925261s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=324
TRCE [ 76.925287s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=286
TRCE [ 76.925294s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=251
TRCE [ 76.925301s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=234
TRCE [ 76.925308s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=213
TRCE [ 76.925312s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=166
TRCE [ 76.925316s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=148
TRCE [ 76.925408s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=128
TRCE [ 76.925428s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=70
TRCE [ 76.925433s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=57
TRCE [ 76.925473s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=46
TRCE [ 76.925513s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::hpack::decoder LiteralWithoutIndexing; rem=23
DBUG [ 76.925558s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read received; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 76.925619s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::connection recv HEADERS; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
TRCE [ 76.925704s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::flow_control inc_window; sz=65535; old=0; new=65535
TRCE [ 76.925737s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::flow_control inc_window; sz=2147483647; old=0; new=2147483647
TRCE [ 76.925748s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::streams recv_headers; stream=StreamId(1); state=State { inner: Idle }
TRCE [ 76.925757s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::recv opening stream; init_window=65535
TRCE [ 76.925771s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::send send_reset(..., reason=PROTOCOL_ERROR, stream=StreamId(1), ..., is_reset=false; is_closed=false; pending_send.is_empty=true; state=State { inner: Open { local: AwaitingHeaders, remote: Streaming } }
TRCE [ 76.925786s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize clear_queue; stream=StreamId(1)
TRCE [ 76.925798s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::send send_reset -- queueing; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 76.925863s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize schedule_send; StreamId(1)
TRCE [ 76.925901s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::store Queue::push
TRCE [ 76.925946s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::store -> first entry
TRCE [ 76.925987s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize assign_connection_capacity; inc=0
TRCE [ 76.926070s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 76.926122s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.926163s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll
TRCE [ 76.926191s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll; bytes=49B
TRCE [ 76.926215s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read decoding frame from 49B
TRCE [ 76.926234s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read -> kind=Data
DBUG [ 76.926247s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read received; frame=Data { stream_id: StreamId(1) }
TRCE [ 76.926285s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::connection recv DATA; frame=Data { stream_id: StreamId(1) }
TRCE [ 76.926341s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::recv recv_data; size=40; connection=65535; stream=65535
TRCE [ 76.926367s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::flow_control send_data; sz=40; window=65535; available=65535
TRCE [ 76.926381s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::recv recv_data frame ignored on locally reset StreamId(1) for some time
TRCE [ 76.926396s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::recv release_connection_capacity; size=40, connection in_flight_data=40
TRCE [ 76.926410s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=false; pending_send_empty=false; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 76.926433s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.926466s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll
TRCE [ 76.926495s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll; bytes=9B
TRCE [ 76.926571s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read decoding frame from 9B
TRCE [ 76.926592s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read -> kind=Settings
DBUG [ 76.926605s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read received; frame=Settings { flags: (0x1: ACK) }
TRCE [ 76.926618s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::connection recv SETTINGS; frame=Settings { flags: (0x1: ACK) }
DBUG [ 76.926649s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings received remote settings ack
TRCE [ 76.926694s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.926710s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll
TRCE [ 76.926725s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize try reclaim frame
TRCE [ 76.926737s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize poll_complete
TRCE [ 76.926869s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 76.926894s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize pop_frame
TRCE [ 76.926911s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize pop_frame; stream=StreamId(1); stream.state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }
TRCE [ 76.926931s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize --> stream=StreamId(1); is_pending_reset=false;
TRCE [ 76.926999s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize pop_frame; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 76.927074s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::counts transition_after; stream=StreamId(1); state=State { inner: Closed(LocallyReset(PROTOCOL_ERROR)) }; is_closed=true; pending_send_empty=true; buffered_send_data=0; num_recv=1; num_send=0
TRCE [ 76.927110s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::counts dec_num_streams; stream=StreamId(1)
TRCE [ 76.927143s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize writing frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
DBUG [ 76.927221s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write send; frame=Reset { stream_id: StreamId(1), error_code: PROTOCOL_ERROR }
TRCE [ 76.927260s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::frame::reset encoding RESET; id=StreamId(1) code=PROTOCOL_ERROR
TRCE [ 76.927286s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write encoded reset; rem=22
TRCE [ 76.927350s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize try reclaim frame
TRCE [ 76.927364s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize schedule_pending_open
TRCE [ 76.927367s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize pop_frame
TRCE [ 76.927375s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write flush
TRCE [ 76.927378s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write -> not a queued data frame
TRCE [ 76.927427s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write flushing buffer
TRCE [ 76.927516s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::prioritize try reclaim frame
TRCE [ 76.927865s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::settings send_pending_ack; pending=None
TRCE [ 76.928050s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_read poll
TRCE [ 76.928141s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::connection codec closed
TRCE [ 76.928227s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::streams Streams::recv_eof
TRCE [ 76.928309s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::connection connection closing after flush
TRCE [ 76.928327s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write flush
TRCE [ 76.928330s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::codec::framed_write flushing buffer
TRCE [ 76.928632s] proxy={server=out listen=127.0.0.1:4140 remote=10.8.29.40:33806} h2::proto::streams::streams Streams::recv_eof
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.