Skip to content

Instantly share code, notes, and snippets.

@sjahl

sjahl/linkerd trace logs

Created January 26, 2021 14:32
Show Gist options
  • Save sjahl/f35175a9631de3f1a13f5d9692b0e6f1 to your computer and use it in GitHub Desktop.
Save sjahl/f35175a9631de3f1a13f5d9692b0e6f1 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
linkerd trace logs
[ 110.955704s] TRACE ThreadId(01) outbound: linkerd2_proxy_transport::listen: Accepted local.addr=127.0.0.1:4140 peer.addr=10.48.0.36:48696 orig.addr=Some(10.51.241.58:5432)
[ 110.955769s] DEBUG ThreadId(01) outbound: linkerd2_cache: Caching new service
[ 110.955830s] TRACE ThreadId(01) outbound: linkerd2_cache: Dropping defunct service
[ 110.955857s] TRACE ThreadId(01) outbound: linkerd2_cache: Dropping defunct service
[ 110.955864s] DEBUG ThreadId(01) outbound: linkerd2_cache: services=1 dropped=2
[ 110.955934s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: disconnected
[ 110.955961s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: waiting
[ 110.956003s] TRACE ThreadId(01) outbound: linkerd2_timeout::failfast: Pending
[ 110.956048s] TRACE ThreadId(01) dst: linkerd2_buffer::dispatch: Dispatching request
[ 110.956075s] DEBUG ThreadId(01) dst: linkerd2_dns: resolve_srv name=linkerd-dst-headless.linkerd.svc.cluster.local
[ 110.956312s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: waiting
[ 110.956341s] TRACE ThreadId(01) outbound: linkerd2_timeout::failfast: Pending
[ 110.960012s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: waiting
[ 110.960055s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: streaming
[ 110.960062s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: poll
[ 110.960111s] DEBUG ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: profile received: DestinationProfile { fully_qualified_name: "postgres.gcloud-dev.svc.cluster.local", opaque_protocol: false, routes: [], retry_budget: Some(RetryBudget { retry_ratio: 0.2, min_retries_per_second: 10, ttl: Some(Duration { seconds: 10, nanos: 0 }) }), dst_overrides: [WeightedDst { authority: "postgres.gcloud-dev.svc.cluster.local.:5432", weight: 10000 }], endpoint: None }
[ 110.960165s] TRACE ThreadId(01) outbound: linkerd2_service_profiles::client: daemonizing
[ 110.960236s] TRACE ThreadId(01) outbound: linkerd2_timeout::failfast: Ready
[ 110.960245s] TRACE ThreadId(01) outbound: linkerd2_buffer::dispatch: Dispatching request
[ 110.960250s] DEBUG ThreadId(01) outbound: linkerd2_proxy_transport::prefix: Buffering prefix capacity=8192
[ 110.960273s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: streaming
[ 110.960304s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: poll
[ 110.960317s] DEBUG ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: profile received: DestinationProfile { fully_qualified_name: "postgres.gcloud-dev.svc.cluster.local", opaque_protocol: false, routes: [], retry_budget: Some(RetryBudget { retry_ratio: 0.2, min_retries_per_second: 10, ttl: Some(Duration { seconds: 10, nanos: 0 }) }), dst_overrides: [WeightedDst { authority: "postgres.gcloud-dev.svc.cluster.local.:5432", weight: 10000 }], endpoint: None }
[ 110.960339s] TRACE ThreadId(01) outbound: linkerd2_service_profiles::client: publishing profile=Profile { name: Some("postgres.gcloud-dev.svc.cluster.local"), http_routes: [], targets: [Target { addr: Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 }), weight: 10000 }], opaque_protocol: false, endpoint: None }
[ 110.960356s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: streaming
[ 110.960360s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: poll
[ 110.960406s] TRACE ThreadId(01) outbound:accept{peer.addr=10.48.0.36:48696 target.addr=10.51.241.58:5432}: linkerd2_proxy_transport::prefix: read=8
[ 110.960434s] TRACE ThreadId(01) outbound: linkerd2_buffer::dispatch: Dispatching request
[ 110.960441s] DEBUG ThreadId(01) outbound: linkerd2_proxy_http::version: Not HTTP
[ 110.960445s] TRACE ThreadId(01) outbound: linkerd2_proxy_http::version: bytes=[0, 0, 0, 8, 4, 210, 22, 47]
[ 110.960475s] DEBUG ThreadId(01) outbound: linkerd2_proxy_http::detect: Forwarding TCP
[ 110.960481s] TRACE ThreadId(01) outbound: linkerd2_proxy_http::detect: Building new TCP service
[ 110.960524s] TRACE ThreadId(01) outbound:tcp: linkerd2_stack_tracing: new
[ 110.960532s] TRACE ThreadId(01) outbound:tcp: linkerd2_service_profiles::split: Building split service targets=[Target { addr: Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 }), weight: 10000 }]
[ 110.960574s] TRACE ThreadId(01) outbound:tcp: linkerd2_stack_tracing: service ready=true ok=true
[ 110.960630s] TRACE ThreadId(01) outbound:tcp: linkerd2_stack_tracing: service request=PrefixedIo { prefix: b"\0\0\0\x08\x04\xd2\x16/", io: SensorIo { io: TcpStream { addr: 127.0.0.1:4140, peer: 10.48.0.36:48696, fd: 18 }, sensor: Sensor { metrics: Some(Metrics { open_total: Counter(17, PhantomData), open_connections: Gauge(1), write_bytes_total: Counter(4534465, PhantomData), read_bytes_total: Counter(25591, PhantomData), by_eos: Mutex { data: {Eos(None): EosMetrics { close_total: Counter(13, PhantomData), connection_duration: Histogram { bounds: Bounds([Le(1.0), Le(2.0), Le(3.0), Le(4.0), Le(5.0), Le(10.0), Le(20.0), Le(30.0), Le(40.0), Le(50.0), Le(100.0), Le(200.0), Le(300.0), Le(400.0), Le(500.0), Le(1000.0), Le(2000.0), Le(3000.0), Le(4000.0), Le(5000.0), Le(10000.0), Le(20000.0), Le(30000.0), Le(40000.0), Le(50000.0), Inf]), buckets: [Counter(0, PhantomData), Counter(6, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(1, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(2, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData)], sum: Counter(3014, PhantomData), _p: PhantomData } }, Eos(Some(Errno(ENOTCONN))): EosMetrics { close_total: Counter(3, PhantomData), connection_duration: Histogram { bounds: Bounds([Le(1.0), Le(2.0), Le(3.0), Le(4.0), Le(5.0), Le(10.0), Le(20.0), Le(30.0), Le(40.0), Le(50.0), Le(100.0), Le(200.0), Le(300.0), Le(400.0), Le(500.0), Le(1000.0), Le(2000.0), Le(3000.0), Le(4000.0), Le(5000.0), Le(10000.0), Le(20000.0), Le(30000.0), Le(40000.0), Le(50000.0), Inf]), buckets: [Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(2, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData)], sum: Counter(5064, PhantomData), _p: PhantomData } }} } }), opened_at: Instant { tv_sec: 41623, tv_nsec: 280837106 } } } }
[ 110.960707s] DEBUG ThreadId(01) outbound:tcp: linkerd2_service_profiles::split: Updating targets=[Target { addr: Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 }), weight: 10000 }]
[ 110.960735s] TRACE ThreadId(01) outbound:tcp: linkerd2_service_profiles::split: Target already exists addr=postgres.gcloud-dev.svc.cluster.local:5432
[ 110.960751s] DEBUG ThreadId(01) outbound:tcp: linkerd2_proxy_api_resolve::resolve: Resolving dst=postgres.gcloud-dev.svc.cluster.local:5432 context={"ns":"gcloud-dev", "nodeName":"gke-seqr-cluster-dev-default-pool-480510f5-prvx"}
[ 110.960781s] TRACE ThreadId(01) outbound:tcp: linkerd2_timeout::failfast: Pending
[ 110.960822s] TRACE ThreadId(01) dst: linkerd2_buffer::dispatch: Dispatching request
[ 110.960883s] TRACE ThreadId(01) outbound:tcp: linkerd2_timeout::failfast: Pending
[ 111.056653s] TRACE ThreadId(01) outbound:tcp: linkerd2_proxy_api_resolve::resolve: metadata=MetadataMap { headers: {"content-type": "application/grpc", "date": "Wed, 13 Jan 2021 18:41:55 GMT"} }
[ 111.056702s] TRACE ThreadId(01) outbound:tcp: linkerd2_proxy_resolve::recover: Connected
[ 111.056737s] TRACE ThreadId(01) outbound:tcp: linkerd2_timeout::failfast: Pending
[ 111.056802s] DEBUG ThreadId(01) outbound:tcp: linkerd2_proxy_api_resolve::resolve: Add endpoints=1
[ 111.056820s] TRACE ThreadId(01) outbound:tcp: linkerd2_app_outbound::target: Resolved endpoint addr=10.48.1.14:5432 metadata=Metadata { weight: 10000, labels: {"control_plane_ns": "linkerd", "deployment": "postgres", "namespace": "gcloud-dev", "pod": "postgres-6b48fd96b7-zwgzw", "pod_template_hash": "6b48fd96b7", "service": "postgres", "serviceaccount": "default"}, protocol_hint: Http2, identity: Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local"), authority_override: None } concrete=Concrete { resolve: Some(Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 })), logical: Logical { orig_dst: 10.51.241.58:5432, protocol: (), profile: Some(..) } }
[ 111.056878s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_stack_tracing: new
[ 111.056913s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_stack_tracing: service ready=true ok=true
[ 111.056930s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_stack_tracing: service ready=true ok=true
[ 111.056943s] TRACE ThreadId(01) outbound:tcp: linkerd2_timeout::failfast: Ready
[ 111.056958s] TRACE ThreadId(01) outbound:tcp: linkerd2_buffer::dispatch: Dispatching request
[ 111.056964s] TRACE ThreadId(01) outbound:tcp: linkerd2_service_profiles::split: Dispatching addr=Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 })
[ 111.056976s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_stack_tracing: service request=()
[ 111.056984s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_app_outbound::tcp::connect: PreventLoop addr=10.48.1.14:5432 self.port=4140
[ 111.057008s] DEBUG ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::tls::client: peer.identity=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")
[ 111.057019s] DEBUG ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::connect: Connecting peer.addr=10.48.1.14:5432
[ 111.059042s] DEBUG ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::connect: Connected local.addr=10.48.0.36:52516 keepalive=Some(10s)
[ 111.059083s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::tls::client: initiating TLS peer.id=default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local
[ 111.060075s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::tls::client: established TLS
[ 111.060097s] DEBUG ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::metrics: client connection open
[ 111.060120s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 111.060126s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 111.060132s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 8B
[ 111.060137s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 8B
[ 111.060164s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 8B
<many similar looking lines removed for brevity>
[ 111.615211s] DEBUG ThreadId(01) dst: linkerd2_dns: resolve_a name=linkerd-dst-headless.linkerd.svc.cluster.local
[ 111.900148s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 111.900187s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 111.900194s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 111.900229s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 318B
[ 111.900233s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 318B
[ 111.900278s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 318B
<many similar looking lines removed for brevity>
[ 112.830638s] DEBUG ThreadId(01) dst: linkerd2_proxy_dns_resolve: addrs=[10.48.0.17:8086]
[ 112.895576s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 112.895614s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 112.895622s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 112.895704s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 112.895711s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 112.895778s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 4096B
<many similar looking lines removed for brevity>
[ 113.489543s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 113.489552s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 113.506098s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 113.506171s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 113.506353s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 113.506370s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 113.506377s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 113.506383s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 113.584397s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 113.584441s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 113.584450s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 113.584520s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 202B
[ 113.584526s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 202B
[ 113.584576s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 202B
<many similar looking lines removed for brevity>
[ 115.936330s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 115.936335s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 115.936338s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 115.936346s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 4096B
[ 115.936350s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 115.936354s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 115.936358s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 115.936365s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 4096B
[ 115.936368s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 115.936387s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 115.936391s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 115.936401s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 4096B
[ 115.936405s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 115.961636s] TRACE ThreadId(01) outbound: linkerd2_buffer::dispatch: Idling out inner service error=Service idled out after 5s
[ 115.961855s] TRACE ThreadId(01) outbound: linkerd2_service_profiles::client: profile observation dropped
[ 115.968662s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 115.968708s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 115.968717s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 115.968849s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 115.968858s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 115.968924s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 4096B
<many similar looking lines removed for brevity>
[ 115.972958s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 115.972977s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 115.972995s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 115.973025s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 4096B
[ 115.973042s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 115.973061s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 115.973079s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 115.973110s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 4096B
[ 115.973129s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 117.556099s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:47470 orig.addr=None
[ 117.556530s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47470 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 117.556555s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47470 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 117.556562s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47470 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 117.556709s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47470 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 119.101591s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:47500 orig.addr=None
[ 119.101705s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47500 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 119.101717s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47500 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 119.101723s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47500 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 119.101856s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47500 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 120.003872s] TRACE ThreadId(02) daemon:tap_clean: linkerd2_proxy_tap::registry: retained 0 of 0 taps
[ 123.504195s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 123.504292s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 123.504408s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 123.504419s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 123.504426s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 123.504432s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 127.556154s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:47616 orig.addr=None
[ 127.556436s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47616 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 127.556469s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47616 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 127.556476s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47616 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 127.556698s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47616 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 129.101674s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:47650 orig.addr=None
[ 129.101810s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47650 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 129.101822s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47650 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 129.101828s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47650 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 129.102000s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47650 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 133.504288s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 133.504363s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 133.504467s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 133.504532s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 133.504564s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 133.504570s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 137.556317s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:47766 orig.addr=None
[ 137.556460s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47766 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 137.556473s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47766 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 137.556479s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47766 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 137.556668s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47766 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 139.101468s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:47802 orig.addr=None
[ 139.101607s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47802 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 139.101620s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47802 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 139.101628s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47802 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 139.101773s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47802 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 142.831915s] DEBUG ThreadId(01) dst: linkerd2_dns: resolve_srv name=linkerd-dst-headless.linkerd.svc.cluster.local
[ 143.479290s] DEBUG ThreadId(01) dst: linkerd2_dns: resolve_a name=linkerd-dst-headless.linkerd.svc.cluster.local
[ 143.504309s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 143.504378s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 143.504472s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 143.504529s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 143.504539s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 143.504546s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 144.772818s] DEBUG ThreadId(01) dst: linkerd2_proxy_dns_resolve: addrs=[10.48.0.17:8086]
[ 147.556079s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:47912 orig.addr=None
[ 147.556222s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47912 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 147.556277s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47912 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 147.556285s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47912 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 147.556481s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47912 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 149.101305s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:47944 orig.addr=None
[ 149.101452s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47944 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 149.101472s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47944 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 149.101479s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47944 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 149.101654s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:47944 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 153.504214s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 153.504282s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 153.504392s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 153.504407s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 153.504414s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 153.504424s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 157.556099s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48062 orig.addr=None
[ 157.556355s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48062 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 157.556388s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48062 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 157.556396s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48062 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 157.556617s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48062 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 159.101339s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48104 orig.addr=None
[ 159.101464s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48104 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 159.101479s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48104 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 159.101515s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48104 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 159.101653s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48104 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 163.504216s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 163.504287s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 163.504391s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 163.504408s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 163.504414s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 163.504421s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 167.556068s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48222 orig.addr=None
[ 167.556198s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48222 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 167.556221s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48222 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 167.556227s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48222 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 167.556403s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48222 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 169.101424s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48260 orig.addr=None
[ 169.101587s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48260 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 169.101610s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48260 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 169.101617s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48260 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 169.101778s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48260 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 173.504247s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 173.504320s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 173.504446s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 173.504465s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 173.504474s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 173.504481s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 177.556181s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48376 orig.addr=None
[ 177.556350s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48376 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 177.556361s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48376 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 177.556368s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48376 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 177.556582s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48376 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 179.101385s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48412 orig.addr=None
[ 179.101890s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48412 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 179.101920s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48412 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 179.101927s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48412 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 179.102078s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48412 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 180.004247s] TRACE ThreadId(02) daemon:tap_clean: linkerd2_proxy_tap::registry: retained 0 of 0 taps
[ 183.504393s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 183.504462s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 183.504604s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 183.504643s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 183.504651s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 183.504659s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 187.556041s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48526 orig.addr=None
[ 187.556330s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48526 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 187.556360s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48526 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 187.556367s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48526 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 187.556584s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48526 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 189.101356s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48556 orig.addr=None
[ 189.101619s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48556 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 189.101639s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48556 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 189.101645s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48556 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 189.101803s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48556 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 193.504097s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 193.504162s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 193.504284s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 193.504339s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 193.504354s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 193.504361s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 197.556074s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48676 orig.addr=None
[ 197.556202s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48676 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 197.556233s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48676 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 197.556240s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48676 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 197.556427s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48676 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 199.101595s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48710 orig.addr=None
[ 199.101710s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48710 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=118
[ 199.101727s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48710 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 199.101732s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48710 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 199.101878s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48710 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 203.057807s] TRACE ThreadId(01) outbound: linkerd2_proxy_transport::listen: Accepted local.addr=127.0.0.1:4140 peer.addr=10.48.0.36:50082 orig.addr=Some(10.51.241.58:5432)
[ 203.057877s] DEBUG ThreadId(01) outbound: linkerd2_cache: Caching new service
[ 203.057936s] DEBUG ThreadId(01) outbound: linkerd2_cache: services=1 dropped=0
[ 203.058009s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: disconnected
[ 203.058024s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: waiting
[ 203.058052s] TRACE ThreadId(01) outbound: linkerd2_timeout::failfast: Pending
[ 203.058098s] TRACE ThreadId(01) dst: linkerd2_buffer::dispatch: Dispatching request
[ 203.058130s] DEBUG ThreadId(01) dst: linkerd2_dns: resolve_srv name=linkerd-dst-headless.linkerd.svc.cluster.local
[ 203.058284s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: waiting
[ 203.058305s] TRACE ThreadId(01) outbound: linkerd2_timeout::failfast: Pending
[ 203.060954s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: waiting
[ 203.061004s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: streaming
[ 203.061010s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: poll
[ 203.061032s] DEBUG ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: profile received: DestinationProfile { fully_qualified_name: "postgres.gcloud-dev.svc.cluster.local", opaque_protocol: false, routes: [], retry_budget: Some(RetryBudget { retry_ratio: 0.2, min_retries_per_second: 10, ttl: Some(Duration { seconds: 10, nanos: 0 }) }), dst_overrides: [WeightedDst { authority: "postgres.gcloud-dev.svc.cluster.local.:5432", weight: 10000 }], endpoint: None }
[ 203.061071s] TRACE ThreadId(01) outbound: linkerd2_service_profiles::client: daemonizing
[ 203.061119s] TRACE ThreadId(01) outbound: linkerd2_timeout::failfast: Ready
[ 203.061129s] TRACE ThreadId(01) outbound: linkerd2_buffer::dispatch: Dispatching request
[ 203.061135s] DEBUG ThreadId(01) outbound: linkerd2_proxy_transport::prefix: Buffering prefix capacity=8192
[ 203.061158s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: streaming
[ 203.061168s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: poll
[ 203.061179s] DEBUG ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: profile received: DestinationProfile { fully_qualified_name: "postgres.gcloud-dev.svc.cluster.local", opaque_protocol: false, routes: [], retry_budget: Some(RetryBudget { retry_ratio: 0.2, min_retries_per_second: 10, ttl: Some(Duration { seconds: 10, nanos: 0 }) }), dst_overrides: [WeightedDst { authority: "postgres.gcloud-dev.svc.cluster.local.:5432", weight: 10000 }], endpoint: None }
[ 203.061201s] TRACE ThreadId(01) outbound: linkerd2_service_profiles::client: publishing profile=Profile { name: Some("postgres.gcloud-dev.svc.cluster.local"), http_routes: [], targets: [Target { addr: Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 }), weight: 10000 }], opaque_protocol: false, endpoint: None }
[ 203.061222s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: streaming
[ 203.061234s] TRACE ThreadId(01) outbound:poll_profile: linkerd2_service_profiles::client: poll
[ 203.061260s] TRACE ThreadId(01) outbound:accept{peer.addr=10.48.0.36:50082 target.addr=10.51.241.58:5432}: linkerd2_proxy_transport::prefix: read=16
[ 203.061273s] TRACE ThreadId(01) outbound: linkerd2_buffer::dispatch: Dispatching request
[ 203.061280s] DEBUG ThreadId(01) outbound: linkerd2_proxy_http::version: Not HTTP
[ 203.061285s] TRACE ThreadId(01) outbound: linkerd2_proxy_http::version: bytes=[0, 0, 0, 16, 4, 210, 22, 46, 0, 0, 0, 217, 102, 78, 199, 133]
[ 203.061293s] DEBUG ThreadId(01) outbound: linkerd2_proxy_http::detect: Forwarding TCP
[ 203.061299s] TRACE ThreadId(01) outbound: linkerd2_proxy_http::detect: Building new TCP service
[ 203.061306s] TRACE ThreadId(01) outbound:tcp: linkerd2_stack_tracing: new
[ 203.061316s] TRACE ThreadId(01) outbound:tcp: linkerd2_service_profiles::split: Building split service targets=[Target { addr: Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 }), weight: 10000 }]
[ 203.061378s] TRACE ThreadId(01) outbound:tcp: linkerd2_stack_tracing: service ready=true ok=true
[ 203.061386s] TRACE ThreadId(01) outbound:tcp: linkerd2_stack_tracing: service request=PrefixedIo { prefix: b"\0\0\0\x10\x04\xd2\x16.\0\0\0\xd9fN\xc7\x85", io: SensorIo { io: TcpStream { addr: 127.0.0.1:4140, peer: 10.48.0.36:50082, fd: 23 }, sensor: Sensor { metrics: Some(Metrics { open_total: Counter(18, PhantomData), open_connections: Gauge(2), write_bytes_total: Counter(13345240, PhantomData), read_bytes_total: Counter(247135, PhantomData), by_eos: Mutex { data: {Eos(None): EosMetrics { close_total: Counter(13, PhantomData), connection_duration: Histogram { bounds: Bounds([Le(1.0), Le(2.0), Le(3.0), Le(4.0), Le(5.0), Le(10.0), Le(20.0), Le(30.0), Le(40.0), Le(50.0), Le(100.0), Le(200.0), Le(300.0), Le(400.0), Le(500.0), Le(1000.0), Le(2000.0), Le(3000.0), Le(4000.0), Le(5000.0), Le(10000.0), Le(20000.0), Le(30000.0), Le(40000.0), Le(50000.0), Inf]), buckets: [Counter(0, PhantomData), Counter(6, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(1, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(2, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData)], sum: Counter(3014, PhantomData), _p: PhantomData } }, Eos(Some(Errno(ENOTCONN))): EosMetrics { close_total: Counter(3, PhantomData), connection_duration: Histogram { bounds: Bounds([Le(1.0), Le(2.0), Le(3.0), Le(4.0), Le(5.0), Le(10.0), Le(20.0), Le(30.0), Le(40.0), Le(50.0), Le(100.0), Le(200.0), Le(300.0), Le(400.0), Le(500.0), Le(1000.0), Le(2000.0), Le(3000.0), Le(4000.0), Le(5000.0), Le(10000.0), Le(20000.0), Le(30000.0), Le(40000.0), Le(50000.0), Inf]), buckets: [Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(1, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(2, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData), Counter(0, PhantomData)], sum: Counter(5064, PhantomData), _p: PhantomData } }} } }), opened_at: Instant { tv_sec: 41715, tv_nsec: 382915277 } } } }
[ 203.061456s] DEBUG ThreadId(01) outbound:tcp: linkerd2_service_profiles::split: Updating targets=[Target { addr: Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 }), weight: 10000 }]
[ 203.061474s] TRACE ThreadId(01) outbound:tcp: linkerd2_service_profiles::split: Target already exists addr=postgres.gcloud-dev.svc.cluster.local:5432
[ 203.061505s] DEBUG ThreadId(01) outbound:tcp: linkerd2_proxy_api_resolve::resolve: Resolving dst=postgres.gcloud-dev.svc.cluster.local:5432 context={"ns":"gcloud-dev", "nodeName":"gke-seqr-cluster-dev-default-pool-480510f5-prvx"}
[ 203.061532s] TRACE ThreadId(01) outbound:tcp: linkerd2_timeout::failfast: Pending
[ 203.061563s] TRACE ThreadId(01) dst: linkerd2_buffer::dispatch: Dispatching request
[ 203.061626s] TRACE ThreadId(01) outbound:tcp: linkerd2_timeout::failfast: Pending
[ 203.069260s] TRACE ThreadId(01) outbound:tcp: linkerd2_proxy_api_resolve::resolve: metadata=MetadataMap { headers: {"content-type": "application/grpc", "date": "Wed, 13 Jan 2021 18:43:27 GMT"} }
[ 203.069300s] TRACE ThreadId(01) outbound:tcp: linkerd2_proxy_resolve::recover: Connected
[ 203.069333s] TRACE ThreadId(01) outbound:tcp: linkerd2_timeout::failfast: Pending
[ 203.069395s] DEBUG ThreadId(01) outbound:tcp: linkerd2_proxy_api_resolve::resolve: Add endpoints=1
[ 203.069413s] TRACE ThreadId(01) outbound:tcp: linkerd2_app_outbound::target: Resolved endpoint addr=10.48.1.14:5432 metadata=Metadata { weight: 10000, labels: {"control_plane_ns": "linkerd", "deployment": "postgres", "namespace": "gcloud-dev", "pod": "postgres-6b48fd96b7-zwgzw", "pod_template_hash": "6b48fd96b7", "service": "postgres", "serviceaccount": "default"}, protocol_hint: Http2, identity: Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local"), authority_override: None } concrete=Concrete { resolve: Some(Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 })), logical: Logical { orig_dst: 10.51.241.58:5432, protocol: (), profile: Some(..) } }
[ 203.069470s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_stack_tracing: new
[ 203.069541s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_stack_tracing: service ready=true ok=true
[ 203.069558s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_stack_tracing: service ready=true ok=true
[ 203.069573s] TRACE ThreadId(01) outbound:tcp: linkerd2_timeout::failfast: Ready
[ 203.069581s] TRACE ThreadId(01) outbound:tcp: linkerd2_buffer::dispatch: Dispatching request
[ 203.069588s] TRACE ThreadId(01) outbound:tcp: linkerd2_service_profiles::split: Dispatching addr=Name(NameAddr { name: "postgres.gcloud-dev.svc.cluster.local.", port: 5432 })
[ 203.069606s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_stack_tracing: service request=()
[ 203.069616s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_app_outbound::tcp::connect: PreventLoop addr=10.48.1.14:5432 self.port=4140
[ 203.069644s] DEBUG ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::tls::client: peer.identity=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")
[ 203.069657s] DEBUG ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::connect: Connecting peer.addr=10.48.1.14:5432
[ 203.070227s] DEBUG ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::connect: Connected local.addr=10.48.0.36:53902 keepalive=Some(10s)
[ 203.070257s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::tls::client: initiating TLS peer.id=default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local
[ 203.071041s] TRACE ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::tls::client: established TLS
[ 203.071063s] DEBUG ThreadId(01) outbound:tcp:endpoint{peer.addr=10.48.1.14:5432 peer.id=Some("default.gcloud-dev.serviceaccount.identity.linkerd.cluster.local")}: linkerd2_proxy_transport::metrics: client connection open
[ 203.071110s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 203.071116s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.071122s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 16B
[ 203.071127s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 16B
[ 203.071157s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 16B
[ 203.071162s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.071171s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.074958s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 203.074990s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.074997s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.075021s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 0B
[ 203.075025s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: eof
[ 203.075031s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: shutting down
[ 203.075140s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 203.075148s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.075156s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 0B
[ 203.075160s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: eof
[ 203.075165s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: shutting down
[ 203.075230s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: already shutdown
[ 203.075281s] DEBUG ThreadId(01) outbound:accept{peer.addr=10.48.0.36:50082 target.addr=10.51.241.58:5432}: linkerd2_app_core::serve: Connection closed
[ 203.076632s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 203.076661s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.076674s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 0B
[ 203.076678s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: eof
[ 203.076685s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: shutting down
[ 203.076863s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.081029s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: poll
[ 203.081067s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: already shutdown
[ 203.081074s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.081179s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 203.081192s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 203.081261s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: wrote 4096B
[ 203.081267s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: reading
[ 203.081272s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: read 4096B
[ 203.081276s] TRACE ThreadId(01) outbound:tcp: linkerd2_duplex: writing 4096B
[ 203.081411s] INFO ThreadId(01) outbound:accept{peer.addr=10.48.0.36:48696 target.addr=10.51.241.58:5432}: linkerd2_app_core::serve: Connection closed error=Broken pipe (os error 32)
[ 203.504322s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_metrics::serve: gzipping metrics
[ 203.504392s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="" targets=1 include_latencies=true
[ 203.504534s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route" targets=0 include_latencies=true
[ 203.504550s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::retries: Formatting HTTP retry metrics prfefix=route targets=0
[ 203.504557s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="route_actual" targets=0 include_latencies=false
[ 203.504565s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.20:38096 target.addr=10.48.0.36:4191}: linkerd2_http_metrics::requests::report: Formatting HTTP request metrics prefix="control" targets=2 include_latencies=true
[ 203.711534s] DEBUG ThreadId(01) dst: linkerd2_dns: resolve_a name=linkerd-dst-headless.linkerd.svc.cluster.local
[ 204.920599s] DEBUG ThreadId(01) dst: linkerd2_proxy_dns_resolve: addrs=[10.48.0.17:8086]
[ 207.556196s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}: linkerd2_proxy_transport::listen: Accepted local.addr=10.48.0.36:4191 peer.addr=10.48.0.1:48830 orig.addr=None
[ 207.556338s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48830 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Peeked bytes from TCP stream sz=117
[ 207.556393s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48830 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::conditional_accept: match_client_hello: failed to parse up to SNI
[ 207.556400s] TRACE ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48830 target.addr=10.48.0.36:4191}: linkerd2_proxy_transport::tls::accept: Not a matching TLS ClientHello
[ 207.556593s] DEBUG ThreadId(02) daemon:admin{listen.addr=0.0.0.0:4191}:accept{peer.addr=10.48.0.1:48830 target.addr=10.48.0.36:4191}: linkerd2_app_core::serve: Connection closed
[ 208.062961s] TRACE ThreadId(01) outbound: linkerd2_buffer::dispatch: Idling out inner service error=Service idled out after 5s
[ 208.063217s] TRACE ThreadId(01) outbound: linkerd2_service_profiles::client: profile observation dropped
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment