Skip to content

Instantly share code, notes, and snippets.

@infiniteregrets

infiniteregrets/pod.log Secret

Created May 16, 2022 05:10
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save infiniteregrets/7c42aeae359e3a55c756f2ba9c7e1213 to your computer and use it in GitHub Desktop.
Save infiniteregrets/7c42aeae359e3a55c756f2ba9c7e1213 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
pod.log
mehula@mehul-machine:~$ kubectl logs mirrord-agent-nq9azhcwqa-8wgkx
[2022-05-16T05:07:03Z DEBUG mirrord_agent] mirrord-agent starting with args Args { container_id: Some("75dcd7510d48f80aa1d229639dba48aed3fd52c49dcfcc4dfa8281c0ea69ab5f"), communicate_port: 61337, communication_timeout: 30, interface: "eth0" }
[2022-05-16T05:07:03Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE | WRITABLE
[2022-05-16T05:07:03Z DEBUG mirrord_agent::sniffer] setting namespace
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; idle
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; connecting
[2022-05-16T05:07:03Z TRACE mio::poll] registering event source with poller: token=Token(2), interests=READABLE | WRITABLE
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; not ready
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; connecting
[2022-05-16T05:07:03Z TRACE hyper::client::conn] client handshake Http2
[2022-05-16T05:07:03Z DEBUG h2::client] binding client connection
[2022-05-16T05:07:03Z DEBUG h2::client] client connection bound
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] FramedWrite::buffer; frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384 }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_write] send frame=Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384 }
[2022-05-16T05:07:03Z TRACE h2::frame::settings] encoding SETTINGS; len=18
[2022-05-16T05:07:03Z TRACE h2::frame::settings] encoding setting; val=EnablePush(0)
[2022-05-16T05:07:03Z TRACE h2::frame::settings] encoding setting; val=InitialWindowSize(2097152)
[2022-05-16T05:07:03Z TRACE h2::frame::settings] encoding setting; val=MaxFrameSize(16384)
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] encoded settings rem=27
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] inc_window; sz=65535; old=0; new=65535
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] inc_window; sz=65535; old=0; new=65535
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] Prioritize::new; flow=FlowControl { window_size: Window(65535), available: Window(65535) }
[2022-05-16T05:07:03Z DEBUG h2::proto::connection] Connection; peer=Client
[2022-05-16T05:07:03Z TRACE h2::proto::streams::recv] set_target_connection_window; target=5242880; available=65535, reserved=0
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; connected
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; not ready
[2022-05-16T05:07:03Z TRACE want] signal: Want
[2022-05-16T05:07:03Z TRACE want] signal found waiting giver, notifying
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; connected
[2022-05-16T05:07:03Z TRACE want] poll_want: taker wants!
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; ready
[2022-05-16T05:07:03Z TRACE tower::buffer::service] sending request to buffer worker
[2022-05-16T05:07:03Z TRACE tower::buffer::worker] worker polling for next message
[2022-05-16T05:07:03Z TRACE tower::buffer::worker] processing new request
[2022-05-16T05:07:03Z TRACE tower::buffer::worker] resumed=false message=worker received request; waiting for service readiness
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; connected
[2022-05-16T05:07:03Z TRACE want] poll_want: taker wants!
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] poll_ready; ready
[2022-05-16T05:07:03Z DEBUG tower::buffer::worker] service.ready=true message=processing request
[2022-05-16T05:07:03Z TRACE tonic::transport::service::reconnect] Reconnect::call
[2022-05-16T05:07:03Z TRACE tower::buffer::worker] returning response future
[2022-05-16T05:07:03Z TRACE tower::buffer::worker] worker polling for next message
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection.state=Open
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] FramedWrite::buffer; frame=WindowUpdate { stream_id: StreamId(0), size_increment: 5177345 }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_write] send frame=WindowUpdate { stream_id: StreamId(0), size_increment: 5177345 }
[2022-05-16T05:07:03Z TRACE h2::frame::window_update] encoding WINDOW_UPDATE; id=StreamId(0)
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] encoded window_update rem=40
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] inc_window; sz=5177345; old=65535; new=5242880
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] poll_complete
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_pending_open
[2022-05-16T05:07:03Z TRACE tracing::span] pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] queued_data_frame=false
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] flushing buffer
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Connection;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] inc_window; sz=2097152; old=0; new=2097152
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] inc_window; sz=65535; old=0; new=65535
[2022-05-16T05:07:03Z TRACE h2::proto::streams::send] send_headers; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }; init_window=65535
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] Prioritize::queue_frame; stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_send stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] Queue::push
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] -> first entry
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] reserve_capacity; stream.id=StreamId(1) requested=1 effective=1 curr=0
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> reserve_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] try_assign_capacity; stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_assign_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] requested=1 additional=1 buffered=0 window=65535 conn=65535
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] assigning capacity=1
[2022-05-16T05:07:03Z TRACE h2::proto::streams::stream] assigned capacity to stream; available=1; buffered=0; id=StreamId(1); max_buffer_size=1048576
[2022-05-16T05:07:03Z TRACE h2::proto::streams::stream] notifying task
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] available=1 requested=1 buffered=0 has_unavailable=true
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_assign_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_assign_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- reserve_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- reserve_capacity;
[2022-05-16T05:07:03Z TRACE hyper::proto::h2] send body chunk: 71 bytes, eos=false
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] send_data; sz=71 requested=1
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> send_data;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] buffered=71
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] try_assign_capacity; stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_assign_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] requested=71 additional=70 buffered=71 window=65535 conn=65534
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] assigning capacity=70
[2022-05-16T05:07:03Z TRACE h2::proto::streams::stream] assigned capacity to stream; available=71; buffered=71; id=StreamId(1); max_buffer_size=1048576
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] available=71 requested=71 buffered=71 has_unavailable=true
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] Queue::push
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] -> already queued
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_assign_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_assign_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] available=71 buffered=71
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] Prioritize::queue_frame; stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_send stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] Queue::push
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] -> already queued
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- send_data;
[2022-05-16T05:07:03Z TRACE tracing::span] -- send_data;
[2022-05-16T05:07:03Z TRACE 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=71; num_recv=0; num_send=1
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] reserve_capacity; stream.id=StreamId(1) requested=1 effective=72 curr=71
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> reserve_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] try_assign_capacity; stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_assign_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] requested=72 additional=1 buffered=71 window=65535 conn=65464
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] assigning capacity=1
[2022-05-16T05:07:03Z TRACE h2::proto::streams::stream] assigned capacity to stream; available=72; buffered=71; id=StreamId(1); max_buffer_size=1048576
[2022-05-16T05:07:03Z TRACE h2::proto::streams::stream] notifying task
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] available=72 requested=72 buffered=71 has_unavailable=true
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] Queue::push
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] -> already queued
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_assign_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_assign_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- reserve_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- reserve_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] reserve_capacity; stream.id=StreamId(1) requested=0 effective=71 curr=72
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> reserve_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] assign_connection_capacity; inc=1
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> assign_connection_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- assign_connection_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- assign_connection_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- reserve_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- reserve_capacity;
[2022-05-16T05:07:03Z TRACE hyper::proto::h2] send body eos
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] send_data; sz=0 requested=71
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> send_data;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] buffered=71
[2022-05-16T05:07:03Z TRACE h2::proto::streams::state] send_close: Open => HalfClosedLocal(AwaitingHeaders)
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] reserve_capacity; stream.id=StreamId(1) requested=0 effective=71 curr=71
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> reserve_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- reserve_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- reserve_capacity;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] available=71 buffered=71
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] Prioritize::queue_frame; stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_send stream.id=StreamId(1)
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] Queue::push
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] -> already queued
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Prioritize::queue_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- send_data;
[2022-05-16T05:07:03Z TRACE tracing::span] -- send_data;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::counts] transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }; is_closed=false; pending_send_empty=false; buffered_send_data=71; num_recv=0; num_send=1
[2022-05-16T05:07:03Z TRACE 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(71) }, requested_send_capacity: 71, buffered_send_data: 71, send_task: Some(Waker { data: 0x7effe000c030, vtable: 0x565241349ee8 }), 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, is_pending_push: false, next_pending_accept: None, is_pending_accept: false, recv_flow: FlowControl { window_size: Window(2097152), available: Window(2097152) }, 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 }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::counts] transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }; is_closed=false; pending_send_empty=false; buffered_send_data=71; num_recv=0; num_send=1
[2022-05-16T05:07:03Z TRACE want] signal: Want
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection.state=Open
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] poll_complete
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_pending_open
[2022-05-16T05:07:03Z TRACE tracing::span] pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> pop_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] popped; stream.id=StreamId(1) stream.state=State { inner: HalfClosedLocal(AwaitingHeaders) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> popped;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] is_pending_reset=false
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] pop_frame; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] Queue::push
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] -> first entry
[2022-05-16T05:07:03Z TRACE h2::proto::streams::counts] transition_after; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }; is_closed=false; pending_send_empty=false; buffered_send_data=71; num_recv=0; num_send=1
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- popped;
[2022-05-16T05:07:03Z TRACE tracing::span] -- popped;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- pop_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] writing frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] FramedWrite::buffer; frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_write] send frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
[2022-05-16T05:07:03Z TRACE tracing::span] hpack::encode;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> hpack::encode;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- hpack::encode;
[2022-05-16T05:07:03Z TRACE tracing::span] -- hpack::encode;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_pending_open
[2022-05-16T05:07:03Z TRACE tracing::span] pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> pop_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] popped; stream.id=StreamId(1) stream.state=State { inner: HalfClosedLocal(AwaitingHeaders) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> popped;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] is_pending_reset=false
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] data frame sz=71 eos=false window=71 available=71 requested=71 buffered=71
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] sending data frame len=71
[2022-05-16T05:07:03Z TRACE tracing::span] updating stream flow;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> updating stream flow;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] send_data; sz=71; window=65535; available=71
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- updating stream flow;
[2022-05-16T05:07:03Z TRACE tracing::span] -- updating stream flow;
[2022-05-16T05:07:03Z TRACE tracing::span] updating connection flow;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> updating connection flow;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] send_data; sz=71; window=65535; available=65535
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- updating connection flow;
[2022-05-16T05:07:03Z TRACE tracing::span] -- updating connection flow;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] pop_frame; frame=Data { stream_id: StreamId(1) }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] Queue::push
[2022-05-16T05:07:03Z TRACE h2::proto::streams::store] -> first entry
[2022-05-16T05:07:03Z TRACE 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
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- popped;
[2022-05-16T05:07:03Z TRACE tracing::span] -- popped;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- pop_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] writing frame=Data { stream_id: StreamId(1) }
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] FramedWrite::buffer; frame=Data { stream_id: StreamId(1) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_write] send frame=Data { stream_id: StreamId(1) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] reclaimed frame=Data { stream_id: StreamId(1) } sz=0
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_pending_open
[2022-05-16T05:07:03Z TRACE tracing::span] pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> pop_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] popped; stream.id=StreamId(1) stream.state=State { inner: HalfClosedLocal(AwaitingHeaders) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> popped;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] is_pending_reset=false
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] data frame sz=0 eos=true window=0 available=0 requested=0 buffered=0
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] sending data frame len=0
[2022-05-16T05:07:03Z TRACE tracing::span] updating stream flow;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> updating stream flow;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] send_data; sz=0; window=65464; available=0
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- updating stream flow;
[2022-05-16T05:07:03Z TRACE tracing::span] -- updating stream flow;
[2022-05-16T05:07:03Z TRACE tracing::span] updating connection flow;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> updating connection flow;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] send_data; sz=0; window=65464; available=65464
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- updating connection flow;
[2022-05-16T05:07:03Z TRACE tracing::span] -- updating connection flow;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] pop_frame; frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) }
[2022-05-16T05:07:03Z TRACE 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
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- popped;
[2022-05-16T05:07:03Z TRACE tracing::span] -- popped;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- pop_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] writing frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) }
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] FramedWrite::buffer; frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_write] send frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] reclaimed frame=Data { stream_id: StreamId(1), flags: (0x1: END_STREAM) } sz=0
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_pending_open
[2022-05-16T05:07:03Z TRACE tracing::span] pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] queued_data_frame=false
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] flushing buffer
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Connection;
[2022-05-16T05:07:03Z TRACE want] signal: Want
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection.state=Open
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] frame decoded from buffer
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] read.bytes=15
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] FramedRead::decode_frame; offset=15
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] decoding frame from 15B
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] frame.kind=Settings
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::decode_frame;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_read] received frame=Settings { flags: (0x0), max_frame_size: 16384 }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] recv SETTINGS frame=Settings { flags: (0x0), max_frame_size: 16384 }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] FramedWrite::buffer; frame=Settings { flags: (0x1: ACK) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_write] send frame=Settings { flags: (0x1: ACK) }
[2022-05-16T05:07:03Z TRACE h2::frame::settings] encoding SETTINGS; len=0
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] encoded settings rem=9
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE h2::proto::settings] ACK sent; applying settings
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] poll_complete
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_pending_open
[2022-05-16T05:07:03Z TRACE tracing::span] pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] queued_data_frame=false
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] flushing buffer
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection.state=Open
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] frame decoded from buffer
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] read.bytes=9
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] FramedRead::decode_frame; offset=9
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] decoding frame from 9B
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] frame.kind=Settings
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::decode_frame;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_read] received frame=Settings { flags: (0x1: ACK) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] recv SETTINGS frame=Settings { flags: (0x1: ACK) }
[2022-05-16T05:07:03Z DEBUG h2::proto::settings] received settings ACK; applying Settings { flags: (0x0), enable_push: 0, initial_window_size: 2097152, max_frame_size: 16384 }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::recv] update_initial_window_size; new=2097152; old=2097152
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] frame decoded from buffer
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] read.bytes=13
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] FramedRead::decode_frame; offset=13
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] decoding frame from 13B
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] frame.kind=WindowUpdate
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::decode_frame;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_read] received frame=WindowUpdate { stream_id: StreamId(0), size_increment: 71 }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] recv WINDOW_UPDATE frame=WindowUpdate { stream_id: StreamId(0), size_increment: 71 }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] inc_window; sz=71; old=65464; new=65535
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] assign_connection_capacity; inc=71
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> assign_connection_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- assign_connection_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- assign_connection_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] frame decoded from buffer
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] read.bytes=17
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] FramedRead::decode_frame; offset=17
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] decoding frame from 17B
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] frame.kind=Ping
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::decode_frame;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_read] received frame=Ping { ack: false, payload: [2, 4, 16, 16, 9, 14, 7, 7] }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] recv PING frame=Ping { ack: false, payload: [2, 4, 16, 16, 9, 14, 7, 7] }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] FramedWrite::buffer; frame=Ping { ack: true, payload: [2, 4, 16, 16, 9, 14, 7, 7] }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_write] send frame=Ping { ack: true, payload: [2, 4, 16, 16, 9, 14, 7, 7] }
[2022-05-16T05:07:03Z TRACE h2::frame::ping] encoding PING; ack=true len=8
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] encoded ping rem=17
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] frame decoded from buffer
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] read.bytes=23
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] FramedRead::decode_frame; offset=23
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] decoding frame from 23B
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] frame.kind=Headers
[2022-05-16T05:07:03Z TRACE h2::frame::headers] loading headers; flags=(0x4: END_HEADERS)
[2022-05-16T05:07:03Z TRACE tracing::span] hpack::decode;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> hpack::decode;
[2022-05-16T05:07:03Z TRACE h2::hpack::decoder] decode
[2022-05-16T05:07:03Z TRACE h2::hpack::decoder] rem=14 kind=Indexed
[2022-05-16T05:07:03Z TRACE h2::hpack::decoder] rem=13 kind=LiteralWithIndexing
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- hpack::decode;
[2022-05-16T05:07:03Z TRACE tracing::span] -- hpack::decode;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::decode_frame;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_read] received frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] recv HEADERS frame=Headers { stream_id: StreamId(1), flags: (0x4: END_HEADERS) }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::streams] recv_headers; stream=StreamId(1); state=State { inner: HalfClosedLocal(AwaitingHeaders) }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::recv] opening stream; init_window=2097152
[2022-05-16T05:07:03Z TRACE 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
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] frame decoded from buffer
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] read.bytes=8272
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] FramedRead::decode_frame; offset=8272
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] decoding frame from 8272B
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] frame.kind=Data
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::decode_frame;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_read] received frame=Data { stream_id: StreamId(1) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] recv DATA frame=Data { stream_id: StreamId(1) }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::recv] recv_data; size=8263; connection=5242880; stream=2097152
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] send_data; sz=8263; window=5242880; available=5242880
[2022-05-16T05:07:03Z TRACE h2::proto::streams::flow_control] send_data; sz=8263; window=2097152; available=2097152
[2022-05-16T05:07:03Z TRACE 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
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] frame decoded from buffer
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] read.bytes=33
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] FramedRead::decode_frame; offset=33
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] decoding frame from 33B
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] frame.kind=Headers
[2022-05-16T05:07:03Z TRACE h2::frame::headers] loading headers; flags=(0x5: END_HEADERS | END_STREAM)
[2022-05-16T05:07:03Z TRACE tracing::span] hpack::decode;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> hpack::decode;
[2022-05-16T05:07:03Z TRACE h2::hpack::decoder] decode
[2022-05-16T05:07:03Z TRACE h2::hpack::decoder] rem=24 kind=LiteralWithIndexing
[2022-05-16T05:07:03Z TRACE h2::hpack::decoder] rem=12 kind=LiteralWithIndexing
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- hpack::decode;
[2022-05-16T05:07:03Z TRACE tracing::span] -- hpack::decode;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::decode_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::decode_frame;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_read] received frame=Headers { stream_id: StreamId(1), flags: (0x5: END_HEADERS | END_STREAM) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] recv HEADERS frame=Headers { stream_id: StreamId(1), flags: (0x5: END_HEADERS | END_STREAM) }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::streams] recv_headers; stream=StreamId(1); state=State { inner: HalfClosedLocal(Streaming) }
[2022-05-16T05:07:03Z TRACE h2::proto::streams::state] recv_close: HalfClosedLocal => Closed
[2022-05-16T05:07:03Z TRACE 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
[2022-05-16T05:07:03Z TRACE h2::proto::streams::counts] dec_num_streams; stream=StreamId(1)
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tokio_util::codec::framed_impl] attempting to decode a frame
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] poll_complete
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_pending_open
[2022-05-16T05:07:03Z TRACE tracing::span] pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] queued_data_frame=false
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] flushing buffer
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Connection;
[2022-05-16T05:07:03Z TRACE 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(65464), available: Window(0) }, requested_send_capacity: 0, buffered_send_data: 0, send_task: Some(Waker { data: 0x7effe000c030, vtable: 0x565241349ee8 }), 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, is_pending_push: false, next_pending_accept: None, is_pending_accept: false, recv_flow: FlowControl { window_size: Window(2088889), available: Window(2088889) }, in_flight_recv_data: 8263, 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 }
[2022-05-16T05:07:03Z TRACE 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
[2022-05-16T05:07:03Z TRACE h2::proto::streams::recv] release_capacity; size=8263
[2022-05-16T05:07:03Z TRACE h2::proto::streams::recv] release_connection_capacity; size=8263, connection in_flight_data=8263
[2022-05-16T05:07:03Z TRACE 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(65464), available: Window(0) }, requested_send_capacity: 0, buffered_send_data: 0, send_task: Some(Waker { data: 0x7effe000c030, vtable: 0x565241349ee8 }), 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, is_pending_push: false, next_pending_accept: None, is_pending_accept: false, recv_flow: FlowControl { window_size: Window(2088889), available: Window(2097152) }, 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 }
[2022-05-16T05:07:03Z TRACE 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
[2022-05-16T05:07:03Z DEBUG mirrord_agent::sniffer] Found namespace to attach to "/proc/1320/ns/net"
[2022-05-16T05:07:03Z DEBUG mirrord_agent::sniffer] preparing sniffer
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection.state=Open
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll_ready;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_read] poll
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedRead::poll_next;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] poll_complete
[2022-05-16T05:07:03Z TRACE h2::proto::streams::prioritize] schedule_pending_open
[2022-05-16T05:07:03Z TRACE tracing::span] pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- pop_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] flushing buffer
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span] -- try_reclaim_frame;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Connection;
[2022-05-16T05:07:03Z DEBUG mirrord_agent::sniffer] done prepare sniffer
[2022-05-16T05:07:03Z TRACE mio::poll] registering event source with poller: token=Token(3), interests=READABLE
[2022-05-16T05:07:03Z TRACE tower::buffer::worker] worker polling for next message
[2022-05-16T05:07:03Z TRACE tower::buffer::worker] buffer already closed
[2022-05-16T05:07:03Z TRACE hyper::proto::h2::client] client::dispatch::Sender dropped
[2022-05-16T05:07:03Z TRACE want] signal: Closed
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> poll;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection.state=Open
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] FramedWrite::buffer; frame=GoAway { error_code: NO_ERROR, last_stream_id: StreamId(0) }
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::codec::framed_write] send frame=GoAway { error_code: NO_ERROR, last_stream_id: StreamId(0) }
[2022-05-16T05:07:03Z TRACE h2::frame::go_away] encoding GO_AWAY; code=NO_ERROR
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] encoded go_away rem=17
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::buffer;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::buffer;
[2022-05-16T05:07:03Z DEBUG h2::proto::connection] Connection::poll; connection error error=GoAway(b"", NO_ERROR, Library)
[2022-05-16T05:07:03Z TRACE h2::proto::connection] -> already going away
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection.state=Closing(NO_ERROR, Library)
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection closing after flush
[2022-05-16T05:07:03Z TRACE tracing::span] FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] queued_data_frame=false
[2022-05-16T05:07:03Z TRACE h2::codec::framed_write] flushing buffer
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE tracing::span] -- FramedWrite::flush;
[2022-05-16T05:07:03Z TRACE h2::proto::connection] connection.state=Closed(NO_ERROR, Library)
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- poll;
[2022-05-16T05:07:03Z TRACE tracing::span] -- poll;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- Connection;
[2022-05-16T05:07:03Z TRACE tracing::span] -- Connection;
[2022-05-16T05:07:03Z TRACE h2::proto::streams::streams] Streams::recv_eof
[2022-05-16T05:07:03Z TRACE tracing::span] clear_pending_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] -> clear_pending_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span::active] <- clear_pending_capacity;
[2022-05-16T05:07:03Z TRACE tracing::span] -- clear_pending_capacity;
[2022-05-16T05:07:03Z TRACE mio::poll] deregistering event source from poller
[2022-05-16T05:07:03Z TRACE tracing::span] -- Connection;
[2022-05-16T05:07:07Z TRACE mio::poll] registering event source with poller: token=Token(16777218), interests=READABLE | WRITABLE
[2022-05-16T05:07:07Z DEBUG mirrord_agent] Connection accepeted from 127.0.0.1:45192
[2022-05-16T05:07:12Z TRACE actix_codec::framed] attempting to decode a frame
[2022-05-16T05:07:12Z TRACE actix_codec::framed] frame decoded from buffer
[2022-05-16T05:07:12Z DEBUG mirrord_agent] client sent message PeerMessage { msg: PortSubscribe([3689]), peer_id: 0 }
[2022-05-16T05:07:12Z TRACE actix_codec::framed] attempting to decode a frame
[2022-05-16T05:07:12Z DEBUG mirrord_agent] peer id 0 asked to subscribe to [3689]
[2022-05-16T05:07:12Z DEBUG mirrord_agent::sniffer] setting ports [3689]
[2022-05-16T05:07:12Z DEBUG mirrord_agent::sniffer] setting bpf to "tcp port (3689)"
mehula@mehul-machine:~$ curl http://127.0.0.1:3689
curl: (7) Failed to connect to 127.0.0.1 port 3689: Connection refused
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment