{"level":"warn","ts":"2026-03-31T20:11:48.174148Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T20:11:48.174231Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-31T20:11:48.174305Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-31T20:11:48.174316Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-31T20:11:48.174332Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-31T20:11:48.174972Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-03-31T20:11:48.17505Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-31T20:11:48.182299Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.018294ms"} {"level":"info","ts":"2026-03-31T20:11:48.193754Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-03-31T20:11:48.193877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-03-31T20:11:48.193927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-03-31T20:11:48.193955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-31T20:11:48.193971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-03-31T20:11:48.194027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-03-31T20:11:48.212794Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-31T20:11:48.214693Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-31T20:11:48.216326Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-31T20:11:48.218789Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-31T20:11:48.218925Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-31T20:11:48.218986Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-31T20:11:48.219072Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-31T20:11:48.219086Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-31T20:11:48.220394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-03-31T20:11:48.221719Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-31T20:11:48.223733Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-31T20:11:48.223895Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-31T20:11:48.223942Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-31T20:11:48.224697Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-31T20:11:48.224729Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-31T20:11:48.69461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-03-31T20:11:48.694689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-31T20:11:48.694706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-03-31T20:11:48.694738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-03-31T20:11:48.694747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-31T20:11:48.694759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-03-31T20:11:48.694768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-31T20:11:48.69718Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-03-31T20:11:48.697269Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T20:11:48.697242Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:11:48.697446Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-31T20:11:48.697493Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-31T20:11:48.698964Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:11:48.69896Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-31T20:11:48.699083Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:11:48.699127Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-31T20:11:48.701786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-03-31T20:11:48.703183Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-31T20:11:56.783117Z","caller":"traceutil/trace.go:171","msg":"trace[1247086003] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"109.078159ms","start":"2026-03-31T20:11:56.674015Z","end":"2026-03-31T20:11:56.783093Z","steps":["trace[1247086003] 'process raft request' (duration: 108.951589ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:11:57.501384Z","caller":"traceutil/trace.go:171","msg":"trace[1268913671] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"127.448435ms","start":"2026-03-31T20:11:57.373908Z","end":"2026-03-31T20:11:57.501356Z","steps":["trace[1268913671] 'process raft request' (duration: 127.144133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:57.66529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.62003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:11:57.665435Z","caller":"traceutil/trace.go:171","msg":"trace[1387113675] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:306; }","duration":"158.966812ms","start":"2026-03-31T20:11:57.50645Z","end":"2026-03-31T20:11:57.665417Z","steps":["trace[1387113675] 'read index received' (duration: 41.550767ms)","trace[1387113675] 'applied index is now lower than readState.Index' (duration: 117.414865ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:11:57.66552Z","caller":"traceutil/trace.go:171","msg":"trace[1248255924] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"159.810236ms","start":"2026-03-31T20:11:57.505681Z","end":"2026-03-31T20:11:57.665491Z","steps":["trace[1248255924] 'process raft request' (duration: 42.380541ms)","trace[1248255924] 'compare' (duration: 116.494509ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:11:57.665541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.089972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-31T20:11:57.665598Z","caller":"traceutil/trace.go:171","msg":"trace[1520857607] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:297; }","duration":"159.198583ms","start":"2026-03-31T20:11:57.506388Z","end":"2026-03-31T20:11:57.665586Z","steps":["trace[1520857607] 'agreement among raft nodes before linearized reading' (duration: 159.107662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:58.16665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.846529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:11:58.166788Z","caller":"traceutil/trace.go:171","msg":"trace[569256362] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"237.029458ms","start":"2026-03-31T20:11:57.929736Z","end":"2026-03-31T20:11:58.166765Z","steps":["trace[569256362] 'read index received' (duration: 17.883106ms)","trace[569256362] 'applied index is now lower than readState.Index' (duration: 219.144712ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:11:58.166997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.259939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T20:11:58.167123Z","caller":"traceutil/trace.go:171","msg":"trace[1767595701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:299; }","duration":"237.403759ms","start":"2026-03-31T20:11:57.929659Z","end":"2026-03-31T20:11:58.167063Z","steps":["trace[1767595701] 'agreement among raft nodes before linearized reading' (duration: 237.229268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:58.167085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.803561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T20:11:58.167207Z","caller":"traceutil/trace.go:171","msg":"trace[1766520280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:299; }","duration":"121.039093ms","start":"2026-03-31T20:11:58.04615Z","end":"2026-03-31T20:11:58.167189Z","steps":["trace[1766520280] 'agreement among raft nodes before linearized reading' (duration: 120.795332ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:11:58.166886Z","caller":"traceutil/trace.go:171","msg":"trace[29841977] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"400.402794ms","start":"2026-03-31T20:11:57.766421Z","end":"2026-03-31T20:11:58.166824Z","steps":["trace[29841977] 'process raft request' (duration: 181.265022ms)","trace[29841977] 'compare' (duration: 218.632428ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:11:58.167882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:57.76638Z","time spent":"401.31952ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":197,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:11:59.12284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.759242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:11:59.123145Z","caller":"traceutil/trace.go:171","msg":"trace[998845620] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"950.91759ms","start":"2026-03-31T20:11:58.172206Z","end":"2026-03-31T20:11:59.123124Z","steps":["trace[998845620] 'process raft request' (duration: 437.796986ms)","trace[998845620] 'compare' (duration: 512.623561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:11:59.123227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.172181Z","time spent":"951.013751ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:11:59.123282Z","caller":"traceutil/trace.go:171","msg":"trace[604060680] linearizableReadLoop","detail":"{readStateIndex:311; appliedIndex:309; }","duration":"923.159768ms","start":"2026-03-31T20:11:58.200108Z","end":"2026-03-31T20:11:59.123268Z","steps":["trace[604060680] 'read index received' (duration: 409.883593ms)","trace[604060680] 'applied index is now lower than readState.Index' (duration: 513.275135ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:11:59.123388Z","caller":"traceutil/trace.go:171","msg":"trace[1620269882] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"951.142731ms","start":"2026-03-31T20:11:58.172205Z","end":"2026-03-31T20:11:59.123348Z","steps":["trace[1620269882] 'process raft request' (duration: 950.92975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.123522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.172179Z","time spent":"951.270903ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:11:59.123514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.392449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-31T20:11:59.123616Z","caller":"traceutil/trace.go:171","msg":"trace[1340256342] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:301; }","duration":"923.500889ms","start":"2026-03-31T20:11:58.200075Z","end":"2026-03-31T20:11:59.123575Z","steps":["trace[1340256342] 'agreement among raft nodes before linearized reading' (duration: 923.356419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.123712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.200058Z","time spent":"923.60447ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-03-31T20:11:59.12416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.112377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-03-31T20:11:59.124344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.566396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-31T20:11:59.124365Z","caller":"traceutil/trace.go:171","msg":"trace[1080404990] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:301; }","duration":"892.600256ms","start":"2026-03-31T20:11:58.231759Z","end":"2026-03-31T20:11:59.124359Z","steps":["trace[1080404990] 'agreement among raft nodes before linearized reading' (duration: 892.561416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.12438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.231753Z","time spent":"892.624276ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-31T20:11:59.124423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.720457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-03-31T20:11:59.124521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.30824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-31T20:11:59.124532Z","caller":"traceutil/trace.go:171","msg":"trace[568296500] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:301; }","duration":"892.868498ms","start":"2026-03-31T20:11:58.231649Z","end":"2026-03-31T20:11:59.124518Z","steps":["trace[568296500] 'agreement among raft nodes before linearized reading' (duration: 892.688607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.124588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.550224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-03-31T20:11:59.124603Z","caller":"traceutil/trace.go:171","msg":"trace[1219878924] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:301; }","duration":"890.589174ms","start":"2026-03-31T20:11:58.23401Z","end":"2026-03-31T20:11:59.124599Z","steps":["trace[1219878924] 'agreement among raft nodes before linearized reading' (duration: 890.565644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.124602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.231627Z","time spent":"892.962788ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-03-31T20:11:59.124738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.369603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-31T20:11:59.124483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.913037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-io5jcm\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-03-31T20:11:59.124759Z","caller":"traceutil/trace.go:171","msg":"trace[2063752503] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:301; }","duration":"907.420403ms","start":"2026-03-31T20:11:58.217334Z","end":"2026-03-31T20:11:59.124754Z","steps":["trace[2063752503] 'agreement among raft nodes before linearized reading' (duration: 907.384183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.124774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.217319Z","time spent":"907.452364ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-03-31T20:11:59.124568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.372065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-31T20:11:59.124841Z","caller":"traceutil/trace.go:171","msg":"trace[116380989] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:301; }","duration":"887.654027ms","start":"2026-03-31T20:11:58.237182Z","end":"2026-03-31T20:11:59.124836Z","steps":["trace[116380989] 'agreement among raft nodes before linearized reading' (duration: 887.371455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.124858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.237174Z","time spent":"887.677187ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-03-31T20:11:59.124617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.233994Z","time spent":"890.619854ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-03-31T20:11:59.124544Z","caller":"traceutil/trace.go:171","msg":"trace[288998310] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:301; }","duration":"886.33972ms","start":"2026-03-31T20:11:58.238197Z","end":"2026-03-31T20:11:59.124537Z","steps":["trace[288998310] 'agreement among raft nodes before linearized reading' (duration: 886.29938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.124929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.23819Z","time spent":"886.735783ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-03-31T20:11:59.124808Z","caller":"traceutil/trace.go:171","msg":"trace[469534595] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-io5jcm; range_end:; response_count:1; response_revision:301; }","duration":"298.249561ms","start":"2026-03-31T20:11:58.82652Z","end":"2026-03-31T20:11:59.12477Z","steps":["trace[469534595] 'agreement among raft nodes before linearized reading' (duration: 297.937338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.124965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.313521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-31T20:11:59.125025Z","caller":"traceutil/trace.go:171","msg":"trace[1528187575] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:301; }","duration":"893.367831ms","start":"2026-03-31T20:11:58.231638Z","end":"2026-03-31T20:11:59.125005Z","steps":["trace[1528187575] 'agreement among raft nodes before linearized reading' (duration: 893.16828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.125056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.231618Z","time spent":"893.431402ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-03-31T20:11:59.125044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.947126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-31T20:11:59.12513Z","caller":"traceutil/trace.go:171","msg":"trace[1918302964] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:301; }","duration":"913.054997ms","start":"2026-03-31T20:11:58.212059Z","end":"2026-03-31T20:11:59.125114Z","steps":["trace[1918302964] 'agreement among raft nodes before linearized reading' (duration: 912.733235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.125195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.212053Z","time spent":"913.131827ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-31T20:11:59.125477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.502214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-31T20:11:59.125527Z","caller":"traceutil/trace.go:171","msg":"trace[1851999198] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:301; }","duration":"895.588725ms","start":"2026-03-31T20:11:58.229924Z","end":"2026-03-31T20:11:59.125513Z","steps":["trace[1851999198] 'agreement among raft nodes before linearized reading' (duration: 895.503225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.125619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.229918Z","time spent":"895.663015ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-03-31T20:11:59.12422Z","caller":"traceutil/trace.go:171","msg":"trace[1554117877] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:301; }","duration":"923.183567ms","start":"2026-03-31T20:11:58.201023Z","end":"2026-03-31T20:11:59.124206Z","steps":["trace[1554117877] 'agreement among raft nodes before linearized reading' (duration: 923.085626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:11:59.125905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:58.201018Z","time spent":"924.874557ms","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-03-31T20:12:00.169748Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495411443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:00.628323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15671192s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:00.628473Z","caller":"traceutil/trace.go:171","msg":"trace[688584599] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"1.489453045s","start":"2026-03-31T20:11:59.13899Z","end":"2026-03-31T20:12:00.628443Z","steps":["trace[688584599] 'process raft request' (duration: 332.498293ms)","trace[688584599] 'compare' (duration: 1.156229778s)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:00.628568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.138974Z","time spent":"1.489547035s","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:00.670721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495411443,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-31T20:12:00.705691Z","caller":"traceutil/trace.go:171","msg":"trace[1115304264] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"1.566225082s","start":"2026-03-31T20:11:59.13943Z","end":"2026-03-31T20:12:00.705655Z","steps":["trace[1115304264] 'process raft request' (duration: 1.56594488s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.705943Z","caller":"traceutil/trace.go:171","msg":"trace[2011532251] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"1.564929364s","start":"2026-03-31T20:11:59.140985Z","end":"2026-03-31T20:12:00.705914Z","steps":["trace[2011532251] 'process raft request' (duration: 1.564695333s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.706024Z","caller":"traceutil/trace.go:171","msg":"trace[1704010753] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"1.566466273s","start":"2026-03-31T20:11:59.139547Z","end":"2026-03-31T20:12:00.706013Z","steps":["trace[1704010753] 'process raft request' (duration: 1.56605172s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.706068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.140978Z","time spent":"1.565035015s","remote":"127.0.0.1:43272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:12:00.706141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.139541Z","time spent":"1.566529753s","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:00.706127Z","caller":"traceutil/trace.go:171","msg":"trace[1028737699] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"1.564643292s","start":"2026-03-31T20:11:59.141463Z","end":"2026-03-31T20:12:00.706106Z","steps":["trace[1028737699] 'process raft request' (duration: 1.56425618s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.706225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.141458Z","time spent":"1.564724043s","remote":"127.0.0.1:43338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:12:00.705994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.139422Z","time spent":"1.566492933s","remote":"127.0.0.1:43240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:00.705807Z","caller":"traceutil/trace.go:171","msg":"trace[1938778969] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"1.565468537s","start":"2026-03-31T20:11:59.140327Z","end":"2026-03-31T20:12:00.705796Z","steps":["trace[1938778969] 'process raft request' (duration: 1.565311037s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.706403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.14032Z","time spent":"1.566053451s","remote":"127.0.0.1:43656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:00.706475Z","caller":"traceutil/trace.go:171","msg":"trace[2023856990] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"1.563969788s","start":"2026-03-31T20:11:59.142497Z","end":"2026-03-31T20:12:00.706467Z","steps":["trace[2023856990] 'process raft request' (duration: 1.563759267s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.705727Z","caller":"traceutil/trace.go:171","msg":"trace[1324470888] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"1.566193901s","start":"2026-03-31T20:11:59.139493Z","end":"2026-03-31T20:12:00.705687Z","steps":["trace[1324470888] 'process raft request' (duration: 1.56602577s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.706549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.14249Z","time spent":"1.564013588s","remote":"127.0.0.1:43500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:00.706506Z","caller":"traceutil/trace.go:171","msg":"trace[1758350918] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"1.562109108s","start":"2026-03-31T20:11:59.144362Z","end":"2026-03-31T20:12:00.706471Z","steps":["trace[1758350918] 'process raft request' (duration: 1.562054568s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.706615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.139486Z","time spent":"1.567072566s","remote":"127.0.0.1:43272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:00.706661Z","caller":"traceutil/trace.go:171","msg":"trace[178461852] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"1.565077395s","start":"2026-03-31T20:11:59.141566Z","end":"2026-03-31T20:12:00.706643Z","steps":["trace[178461852] 'process raft request' (duration: 1.56418179s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.706729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.144356Z","time spent":"1.562257669s","remote":"127.0.0.1:43500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:00.706752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.141546Z","time spent":"1.565173755s","remote":"127.0.0.1:43432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:00.706826Z","caller":"traceutil/trace.go:171","msg":"trace[722441874] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"1.563935368s","start":"2026-03-31T20:11:59.142875Z","end":"2026-03-31T20:12:00.70681Z","steps":["trace[722441874] 'process raft request' (duration: 1.563435945s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.706696Z","caller":"traceutil/trace.go:171","msg":"trace[679751445] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"1.564876603s","start":"2026-03-31T20:11:59.141808Z","end":"2026-03-31T20:12:00.706685Z","steps":["trace[679751445] 'process raft request' (duration: 1.56429296s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.706925Z","caller":"traceutil/trace.go:171","msg":"trace[1932079459] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"1.563691767s","start":"2026-03-31T20:11:59.143223Z","end":"2026-03-31T20:12:00.706915Z","steps":["trace[1932079459] 'process raft request' (duration: 1.563145114s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.706954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.141804Z","time spent":"1.565117715s","remote":"127.0.0.1:43500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:00.706998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.143217Z","time spent":"1.563754517s","remote":"127.0.0.1:43650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:12:00.706997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.142868Z","time spent":"1.564014888s","remote":"127.0.0.1:43348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:00.804524Z","caller":"traceutil/trace.go:171","msg":"trace[818693843] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:312; }","duration":"1.135080828s","start":"2026-03-31T20:11:59.669375Z","end":"2026-03-31T20:12:00.804456Z","steps":["trace[818693843] 'read index received' (duration: 1.036073962s)","trace[818693843] 'applied index is now lower than readState.Index' (duration: 99.005746ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:00.804646Z","caller":"traceutil/trace.go:171","msg":"trace[1320300161] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"166.91452ms","start":"2026-03-31T20:12:00.637722Z","end":"2026-03-31T20:12:00.804636Z","steps":["trace[1320300161] 'process raft request' (duration: 166.674758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.804768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135381829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T20:12:00.804542Z","caller":"traceutil/trace.go:171","msg":"trace[2359086] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"547.24113ms","start":"2026-03-31T20:12:00.257269Z","end":"2026-03-31T20:12:00.80451Z","steps":["trace[2359086] 'process raft request' (duration: 532.127132ms)","trace[2359086] 'compare' (duration: 14.821506ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:00.804851Z","caller":"traceutil/trace.go:171","msg":"trace[85126666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:316; }","duration":"1.13550162s","start":"2026-03-31T20:11:59.669329Z","end":"2026-03-31T20:12:00.80483Z","steps":["trace[85126666] 'agreement among raft nodes before linearized reading' (duration: 1.135365209s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.804897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.669303Z","time spent":"1.13558503s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:12:00.804926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:00.25724Z","time spent":"547.596832ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:00.827556Z","caller":"traceutil/trace.go:171","msg":"trace[1793653417] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"113.525449ms","start":"2026-03-31T20:12:00.713979Z","end":"2026-03-31T20:12:00.827505Z","steps":["trace[1793653417] 'process raft request' (duration: 113.277528ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.82763Z","caller":"traceutil/trace.go:171","msg":"trace[997750044] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"112.652464ms","start":"2026-03-31T20:12:00.714944Z","end":"2026-03-31T20:12:00.827596Z","steps":["trace[997750044] 'process raft request' (duration: 112.346783ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.827655Z","caller":"traceutil/trace.go:171","msg":"trace[1827009641] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"111.634418ms","start":"2026-03-31T20:12:00.716004Z","end":"2026-03-31T20:12:00.827639Z","steps":["trace[1827009641] 'process raft request' (duration: 111.399716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.827597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063928349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T20:12:00.827723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.75378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-31T20:12:00.82795Z","caller":"traceutil/trace.go:171","msg":"trace[2060961299] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"112.623614ms","start":"2026-03-31T20:12:00.714974Z","end":"2026-03-31T20:12:00.827598Z","steps":["trace[2060961299] 'process raft request' (duration: 112.351563ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.827969Z","caller":"traceutil/trace.go:171","msg":"trace[407086456] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:323; }","duration":"114.041612ms","start":"2026-03-31T20:12:00.713915Z","end":"2026-03-31T20:12:00.827956Z","steps":["trace[407086456] 'agreement among raft nodes before linearized reading' (duration: 113.766201ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.827966Z","caller":"traceutil/trace.go:171","msg":"trace[1472897855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:323; }","duration":"1.064331431s","start":"2026-03-31T20:11:59.76361Z","end":"2026-03-31T20:12:00.827942Z","steps":["trace[1472897855] 'agreement among raft nodes before linearized reading' (duration: 1.063866099s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:00.828042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:11:59.763594Z","time spent":"1.064406143s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T20:12:00.827662Z","caller":"traceutil/trace.go:171","msg":"trace[7197905] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"110.16931ms","start":"2026-03-31T20:12:00.717478Z","end":"2026-03-31T20:12:00.827647Z","steps":["trace[7197905] 'process raft request' (duration: 109.963779ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.82814Z","caller":"traceutil/trace.go:171","msg":"trace[1120153082] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"112.514714ms","start":"2026-03-31T20:12:00.715615Z","end":"2026-03-31T20:12:00.82813Z","steps":["trace[1120153082] 'process raft request' (duration: 111.745169ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:00.828993Z","caller":"traceutil/trace.go:171","msg":"trace[1055352028] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"115.103329ms","start":"2026-03-31T20:12:00.713847Z","end":"2026-03-31T20:12:00.828951Z","steps":["trace[1055352028] 'process raft request' (duration: 113.311438ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:02.212863Z","caller":"traceutil/trace.go:171","msg":"trace[1220592286] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"117.583305ms","start":"2026-03-31T20:12:02.09525Z","end":"2026-03-31T20:12:02.212833Z","steps":["trace[1220592286] 'process raft request' (duration: 117.328104ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:02.419139Z","caller":"traceutil/trace.go:171","msg":"trace[1907858998] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"148.725574ms","start":"2026-03-31T20:12:02.270395Z","end":"2026-03-31T20:12:02.41912Z","steps":["trace[1907858998] 'process raft request' (duration: 148.614983ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:03.589291Z","caller":"traceutil/trace.go:171","msg":"trace[1390419866] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"149.953776ms","start":"2026-03-31T20:12:03.43931Z","end":"2026-03-31T20:12:03.589264Z","steps":["trace[1390419866] 'process raft request' (duration: 149.239223ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:04.514861Z","caller":"traceutil/trace.go:171","msg":"trace[975191815] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:376; }","duration":"239.610632ms","start":"2026-03-31T20:12:04.275227Z","end":"2026-03-31T20:12:04.514838Z","steps":["trace[975191815] 'read index received' (duration: 239.449931ms)","trace[975191815] 'applied index is now lower than readState.Index' (duration: 159.331µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:04.515015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.766903ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:04.515102Z","caller":"traceutil/trace.go:171","msg":"trace[1498556960] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:365; }","duration":"239.870693ms","start":"2026-03-31T20:12:04.275218Z","end":"2026-03-31T20:12:04.515089Z","steps":["trace[1498556960] 'agreement among raft nodes before linearized reading' (duration: 239.711852ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:04.515467Z","caller":"traceutil/trace.go:171","msg":"trace[1264834747] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"243.269322ms","start":"2026-03-31T20:12:04.272106Z","end":"2026-03-31T20:12:04.515375Z","steps":["trace[1264834747] 'process raft request' (duration: 242.547158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:05.424305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495411540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:05.925108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495411540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:06.425709Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495411540,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:06.512782Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.986717947s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:12:06.519813Z","caller":"traceutil/trace.go:171","msg":"trace[1119183368] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"1.595829405s","start":"2026-03-31T20:12:04.923965Z","end":"2026-03-31T20:12:06.519795Z","steps":["trace[1119183368] 'read index received' (duration: 1.589007977s)","trace[1119183368] 'applied index is now lower than readState.Index' (duration: 6.820428ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:06.520101Z","caller":"traceutil/trace.go:171","msg":"trace[1541894025] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.994097307s","start":"2026-03-31T20:12:04.525991Z","end":"2026-03-31T20:12:06.520088Z","steps":["trace[1541894025] 'process raft request' (duration: 1.987077558s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:06.520186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596213147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:12:06.520199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:04.52598Z","time spent":"1.994151557s","remote":"127.0.0.1:43358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:06.520238Z","caller":"traceutil/trace.go:171","msg":"trace[951759950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"1.596327258s","start":"2026-03-31T20:12:04.923898Z","end":"2026-03-31T20:12:06.520225Z","steps":["trace[951759950] 'agreement among raft nodes before linearized reading' (duration: 1.596240237s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:06.520237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274767559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-31T20:12:06.520279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.467568398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:12:06.520315Z","caller":"traceutil/trace.go:171","msg":"trace[2055162431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:366; }","duration":"1.467621708s","start":"2026-03-31T20:12:05.052678Z","end":"2026-03-31T20:12:06.520299Z","steps":["trace[2055162431] 'agreement among raft nodes before linearized reading' (duration: 1.467578567s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:06.520307Z","caller":"traceutil/trace.go:171","msg":"trace[1572526198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:366; }","duration":"1.27487968s","start":"2026-03-31T20:12:05.24541Z","end":"2026-03-31T20:12:06.52029Z","steps":["trace[1572526198] 'agreement among raft nodes before linearized reading' (duration: 1.274749249s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:06.520274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239855211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T20:12:06.520347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:05.052661Z","time spent":"1.46768018s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:12:06.520368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:05.245389Z","time spent":"1.274970331s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T20:12:06.520389Z","caller":"traceutil/trace.go:171","msg":"trace[360305194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:366; }","duration":"1.239997803s","start":"2026-03-31T20:12:05.280367Z","end":"2026-03-31T20:12:06.520365Z","steps":["trace[360305194] 'agreement among raft nodes before linearized reading' (duration: 1.239811951s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:06.520268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:04.923872Z","time spent":"1.596390448s","remote":"127.0.0.1:43164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:12:06.520222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.553659645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T20:12:06.521267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:05.280355Z","time spent":"1.240478695s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:12:06.521313Z","caller":"traceutil/trace.go:171","msg":"trace[277944499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:366; }","duration":"1.554793003s","start":"2026-03-31T20:12:04.966506Z","end":"2026-03-31T20:12:06.521299Z","steps":["trace[277944499] 'agreement among raft nodes before linearized reading' (duration: 1.553636075s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:06.521351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:04.966493Z","time spent":"1.554847483s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T20:12:06.700137Z","caller":"traceutil/trace.go:171","msg":"trace[1763387454] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"173.168514ms","start":"2026-03-31T20:12:06.526938Z","end":"2026-03-31T20:12:06.700107Z","steps":["trace[1763387454] 'process raft request' (duration: 172.956923ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:06.700343Z","caller":"traceutil/trace.go:171","msg":"trace[59847525] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"173.310454ms","start":"2026-03-31T20:12:06.527013Z","end":"2026-03-31T20:12:06.700323Z","steps":["trace[59847525] 'process raft request' (duration: 173.033452ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:06.700716Z","caller":"traceutil/trace.go:171","msg":"trace[330765494] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"172.794302ms","start":"2026-03-31T20:12:06.527904Z","end":"2026-03-31T20:12:06.700699Z","steps":["trace[330765494] 'process raft request' (duration: 172.35158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:06.700843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.895751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:06.700899Z","caller":"traceutil/trace.go:171","msg":"trace[1127413050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:371; }","duration":"171.066252ms","start":"2026-03-31T20:12:06.529821Z","end":"2026-03-31T20:12:06.700887Z","steps":["trace[1127413050] 'agreement among raft nodes before linearized reading' (duration: 170.942181ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:06.700718Z","caller":"traceutil/trace.go:171","msg":"trace[779461535] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:378; }","duration":"170.75418ms","start":"2026-03-31T20:12:06.529941Z","end":"2026-03-31T20:12:06.700695Z","steps":["trace[779461535] 'read index received' (duration: 52.581756ms)","trace[779461535] 'applied index is now lower than readState.Index' (duration: 118.171434ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:06.700808Z","caller":"traceutil/trace.go:171","msg":"trace[1837075373] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"168.367676ms","start":"2026-03-31T20:12:06.532417Z","end":"2026-03-31T20:12:06.700785Z","steps":["trace[1837075373] 'process raft request' (duration: 168.116585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:09.111012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.741803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:09.111235Z","caller":"traceutil/trace.go:171","msg":"trace[1697612265] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"402.320563ms","start":"2026-03-31T20:12:08.708886Z","end":"2026-03-31T20:12:09.111206Z","steps":["trace[1697612265] 'process raft request' (duration: 31.299964ms)","trace[1697612265] 'compare' (duration: 370.588755ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:09.111267Z","caller":"traceutil/trace.go:171","msg":"trace[1289851391] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:385; }","duration":"184.00038ms","start":"2026-03-31T20:12:08.927244Z","end":"2026-03-31T20:12:09.111244Z","steps":["trace[1289851391] 'read index received' (duration: 3.67757ms)","trace[1289851391] 'applied index is now lower than readState.Index' (duration: 180.32037ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:09.111378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:08.70887Z","time spent":"402.427938ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:09.111495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.217222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:09.111532Z","caller":"traceutil/trace.go:171","msg":"trace[1886336950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"184.361917ms","start":"2026-03-31T20:12:08.92716Z","end":"2026-03-31T20:12:09.111521Z","steps":["trace[1886336950] 'agreement among raft nodes before linearized reading' (duration: 184.198577ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:09.111659Z","caller":"traceutil/trace.go:171","msg":"trace[669464417] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"402.757109ms","start":"2026-03-31T20:12:08.708891Z","end":"2026-03-31T20:12:09.111648Z","steps":["trace[669464417] 'process raft request' (duration: 402.242719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:09.111721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:08.708873Z","time spent":"402.817601ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:09.111799Z","caller":"traceutil/trace.go:171","msg":"trace[882650471] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"401.797869ms","start":"2026-03-31T20:12:08.709949Z","end":"2026-03-31T20:12:09.111747Z","steps":["trace[882650471] 'process raft request' (duration: 401.260978ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:09.112079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:08.709936Z","time spent":"401.974109ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:11.564285Z","caller":"traceutil/trace.go:171","msg":"trace[708815717] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"448.82813ms","start":"2026-03-31T20:12:11.115436Z","end":"2026-03-31T20:12:11.564264Z","steps":["trace[708815717] 'read index received' (duration: 448.675281ms)","trace[708815717] 'applied index is now lower than readState.Index' (duration: 151.759µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:11.564404Z","caller":"traceutil/trace.go:171","msg":"trace[824500325] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"466.785994ms","start":"2026-03-31T20:12:11.097579Z","end":"2026-03-31T20:12:11.564365Z","steps":["trace[824500325] 'process raft request' (duration: 466.552761ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:11.564478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.030521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T20:12:11.564483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.360215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:12:11.564505Z","caller":"traceutil/trace.go:171","msg":"trace[1106254773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"449.110525ms","start":"2026-03-31T20:12:11.115386Z","end":"2026-03-31T20:12:11.564496Z","steps":["trace[1106254773] 'agreement among raft nodes before linearized reading' (duration: 448.989519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:11.564495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.038851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T20:12:11.564541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:11.115368Z","time spent":"449.166678ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T20:12:11.564546Z","caller":"traceutil/trace.go:171","msg":"trace[1843612680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:377; }","duration":"448.444999ms","start":"2026-03-31T20:12:11.116082Z","end":"2026-03-31T20:12:11.564527Z","steps":["trace[1843612680] 'agreement among raft nodes before linearized reading' (duration: 448.339813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:11.564576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:11.097527Z","time spent":"466.960934ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:11.564593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:11.116068Z","time spent":"448.514213ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:12:11.564586Z","caller":"traceutil/trace.go:171","msg":"trace[506928691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"449.168529ms","start":"2026-03-31T20:12:11.11539Z","end":"2026-03-31T20:12:11.564559Z","steps":["trace[506928691] 'agreement among raft nodes before linearized reading' (duration: 448.967118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:11.564651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:11.115376Z","time spent":"449.264974ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T20:12:11.906704Z","caller":"traceutil/trace.go:171","msg":"trace[199316336] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"332.93126ms","start":"2026-03-31T20:12:11.573743Z","end":"2026-03-31T20:12:11.906675Z","steps":["trace[199316336] 'process raft request' (duration: 332.825164ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:11.9068Z","caller":"traceutil/trace.go:171","msg":"trace[94906726] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:390; }","duration":"314.695111ms","start":"2026-03-31T20:12:11.592079Z","end":"2026-03-31T20:12:11.906775Z","steps":["trace[94906726] 'read index received' (duration: 291.119229ms)","trace[94906726] 'applied index is now lower than readState.Index' (duration: 23.574032ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:11.906902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:11.573696Z","time spent":"333.10518ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:11.906984Z","caller":"traceutil/trace.go:171","msg":"trace[1536587274] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"336.96235ms","start":"2026-03-31T20:12:11.569973Z","end":"2026-03-31T20:12:11.906935Z","steps":["trace[1536587274] 'process raft request' (duration: 313.21251ms)","trace[1536587274] 'compare' (duration: 23.218291ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:11.906926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.846839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T20:12:11.907006Z","caller":"traceutil/trace.go:171","msg":"trace[1311522270] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"333.216246ms","start":"2026-03-31T20:12:11.573776Z","end":"2026-03-31T20:12:11.906992Z","steps":["trace[1311522270] 'process raft request' (duration: 332.855707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:11.907134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:11.569956Z","time spent":"337.119939ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:11.907284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:11.573752Z","time spent":"333.4783ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:11.907222Z","caller":"traceutil/trace.go:171","msg":"trace[508029558] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:380; }","duration":"315.133625ms","start":"2026-03-31T20:12:11.592018Z","end":"2026-03-31T20:12:11.907152Z","steps":["trace[508029558] 'agreement among raft nodes before linearized reading' (duration: 314.825878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:11.907536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:11.592Z","time spent":"315.443103ms","remote":"127.0.0.1:43198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"info","ts":"2026-03-31T20:12:12.962627Z","caller":"traceutil/trace.go:171","msg":"trace[1879782297] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"164.504649ms","start":"2026-03-31T20:12:12.798083Z","end":"2026-03-31T20:12:12.962587Z","steps":["trace[1879782297] 'process raft request' (duration: 164.211173ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:17.628537Z","caller":"traceutil/trace.go:171","msg":"trace[1394513615] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"138.128906ms","start":"2026-03-31T20:12:17.490383Z","end":"2026-03-31T20:12:17.628512Z","steps":["trace[1394513615] 'process raft request' (duration: 137.892304ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:17.899608Z","caller":"traceutil/trace.go:171","msg":"trace[1974980710] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"182.269287ms","start":"2026-03-31T20:12:17.717322Z","end":"2026-03-31T20:12:17.899591Z","steps":["trace[1974980710] 'process raft request' (duration: 182.243986ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:17.899606Z","caller":"traceutil/trace.go:171","msg":"trace[465589629] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"197.69219ms","start":"2026-03-31T20:12:17.701877Z","end":"2026-03-31T20:12:17.899569Z","steps":["trace[465589629] 'process raft request' (duration: 197.600766ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:17.899677Z","caller":"traceutil/trace.go:171","msg":"trace[1161798037] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"196.086589ms","start":"2026-03-31T20:12:17.703556Z","end":"2026-03-31T20:12:17.899642Z","steps":["trace[1161798037] 'process raft request' (duration: 195.946332ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:17.899907Z","caller":"traceutil/trace.go:171","msg":"trace[2093312004] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"194.053027ms","start":"2026-03-31T20:12:17.705847Z","end":"2026-03-31T20:12:17.8999Z","steps":["trace[2093312004] 'process raft request' (duration: 193.686897ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:17.899947Z","caller":"traceutil/trace.go:171","msg":"trace[1895486138] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"198.528474ms","start":"2026-03-31T20:12:17.701405Z","end":"2026-03-31T20:12:17.899934Z","steps":["trace[1895486138] 'process raft request' (duration: 102.880355ms)","trace[1895486138] 'compare' (duration: 95.061868ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:21.30962Z","caller":"traceutil/trace.go:171","msg":"trace[1502348868] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"193.834237ms","start":"2026-03-31T20:12:21.115757Z","end":"2026-03-31T20:12:21.309591Z","steps":["trace[1502348868] 'process raft request' (duration: 193.651358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:22.42652Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495411670,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:22.482141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.896949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-31T20:12:22.4822Z","caller":"traceutil/trace.go:171","msg":"trace[774650829] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:430; }","duration":"889.983074ms","start":"2026-03-31T20:12:21.592202Z","end":"2026-03-31T20:12:22.482185Z","steps":["trace[774650829] 'range keys from in-memory index tree' (duration: 889.827897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:22.482227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:21.592188Z","time spent":"890.032346ms","remote":"127.0.0.1:43198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"info","ts":"2026-03-31T20:12:22.482532Z","caller":"traceutil/trace.go:171","msg":"trace[888653081] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"556.910447ms","start":"2026-03-31T20:12:21.925613Z","end":"2026-03-31T20:12:22.482524Z","steps":["trace[888653081] 'read index received' (duration: 546.379015ms)","trace[888653081] 'applied index is now lower than readState.Index' (duration: 10.530682ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:22.482649Z","caller":"traceutil/trace.go:171","msg":"trace[291017849] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"631.770916ms","start":"2026-03-31T20:12:21.850872Z","end":"2026-03-31T20:12:22.482642Z","steps":["trace[291017849] 'process raft request' (duration: 621.093957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:22.482699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:21.850855Z","time spent":"631.806528ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:22.482794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.17679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:22.482824Z","caller":"traceutil/trace.go:171","msg":"trace[60572087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"557.238283ms","start":"2026-03-31T20:12:21.925578Z","end":"2026-03-31T20:12:22.482817Z","steps":["trace[60572087] 'agreement among raft nodes before linearized reading' (duration: 557.185241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:22.482833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.962619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T20:12:22.482844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:21.925535Z","time spent":"557.304266ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:12:22.482869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.783894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T20:12:22.482881Z","caller":"traceutil/trace.go:171","msg":"trace[1213059326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"425.816176ms","start":"2026-03-31T20:12:22.057061Z","end":"2026-03-31T20:12:22.482878Z","steps":["trace[1213059326] 'agreement among raft nodes before linearized reading' (duration: 425.796695ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:22.482893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:22.057051Z","time spent":"425.839637ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T20:12:22.482849Z","caller":"traceutil/trace.go:171","msg":"trace[761138816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:431; }","duration":"445.013652ms","start":"2026-03-31T20:12:22.037831Z","end":"2026-03-31T20:12:22.482844Z","steps":["trace[761138816] 'agreement among raft nodes before linearized reading' (duration: 444.98538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:22.482943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:22.037812Z","time spent":"445.124718ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:12:22.482957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.535604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-31T20:12:22.482972Z","caller":"traceutil/trace.go:171","msg":"trace[204275786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:431; }","duration":"438.572185ms","start":"2026-03-31T20:12:22.044361Z","end":"2026-03-31T20:12:22.482969Z","steps":["trace[204275786] 'agreement among raft nodes before linearized reading' (duration: 438.548184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:22.482984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:22.044351Z","time spent":"438.630638ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T20:12:28.767863Z","caller":"traceutil/trace.go:171","msg":"trace[2081209704] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"206.685432ms","start":"2026-03-31T20:12:28.56115Z","end":"2026-03-31T20:12:28.767836Z","steps":["trace[2081209704] 'read index received' (duration: 206.451861ms)","trace[2081209704] 'applied index is now lower than readState.Index' (duration: 230.45µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:28.76798Z","caller":"traceutil/trace.go:171","msg":"trace[418549526] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"217.928009ms","start":"2026-03-31T20:12:28.550041Z","end":"2026-03-31T20:12:28.767969Z","steps":["trace[418549526] 'process raft request' (duration: 217.639797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:28.768178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.629888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:12:28.768288Z","caller":"traceutil/trace.go:171","msg":"trace[971404667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:446; }","duration":"199.762153ms","start":"2026-03-31T20:12:28.568499Z","end":"2026-03-31T20:12:28.768261Z","steps":["trace[971404667] 'agreement among raft nodes before linearized reading' (duration: 199.613407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:28.76831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.144602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-03-31T20:12:28.768368Z","caller":"traceutil/trace.go:171","msg":"trace[1366562964] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:446; }","duration":"207.247506ms","start":"2026-03-31T20:12:28.561103Z","end":"2026-03-31T20:12:28.76835Z","steps":["trace[1366562964] 'agreement among raft nodes before linearized reading' (duration: 207.1081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:28.768415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.094224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T20:12:28.768497Z","caller":"traceutil/trace.go:171","msg":"trace[319441950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:446; }","duration":"199.23423ms","start":"2026-03-31T20:12:28.569244Z","end":"2026-03-31T20:12:28.768478Z","steps":["trace[319441950] 'agreement among raft nodes before linearized reading' (duration: 198.988759ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:28.928865Z","caller":"traceutil/trace.go:171","msg":"trace[1557854099] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"156.514274ms","start":"2026-03-31T20:12:28.772319Z","end":"2026-03-31T20:12:28.928833Z","steps":["trace[1557854099] 'process raft request' (duration: 136.219404ms)","trace[1557854099] 'compare' (duration: 20.174384ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:28.932579Z","caller":"traceutil/trace.go:171","msg":"trace[1601120139] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"158.692911ms","start":"2026-03-31T20:12:28.773861Z","end":"2026-03-31T20:12:28.932554Z","steps":["trace[1601120139] 'process raft request' (duration: 158.504043ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:30.914568Z","caller":"traceutil/trace.go:171","msg":"trace[799307292] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"140.192383ms","start":"2026-03-31T20:12:30.774349Z","end":"2026-03-31T20:12:30.914541Z","steps":["trace[799307292] 'process raft request' (duration: 140.020096ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.588514Z","caller":"traceutil/trace.go:171","msg":"trace[1216310976] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"116.985918ms","start":"2026-03-31T20:12:31.471497Z","end":"2026-03-31T20:12:31.588483Z","steps":["trace[1216310976] 'process raft request' (duration: 116.617042ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.662823Z","caller":"traceutil/trace.go:171","msg":"trace[1848455260] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"185.937949ms","start":"2026-03-31T20:12:31.476857Z","end":"2026-03-31T20:12:31.662795Z","steps":["trace[1848455260] 'process raft request' (duration: 185.754902ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.662961Z","caller":"traceutil/trace.go:171","msg":"trace[1215474411] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"185.913038ms","start":"2026-03-31T20:12:31.477032Z","end":"2026-03-31T20:12:31.662945Z","steps":["trace[1215474411] 'process raft request' (duration: 185.686898ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.663071Z","caller":"traceutil/trace.go:171","msg":"trace[104969117] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"177.204856ms","start":"2026-03-31T20:12:31.48586Z","end":"2026-03-31T20:12:31.663065Z","steps":["trace[104969117] 'process raft request' (duration: 176.923414ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.663213Z","caller":"traceutil/trace.go:171","msg":"trace[1957945413] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"174.991312ms","start":"2026-03-31T20:12:31.488212Z","end":"2026-03-31T20:12:31.663204Z","steps":["trace[1957945413] 'process raft request' (duration: 174.764961ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.663476Z","caller":"traceutil/trace.go:171","msg":"trace[1742964442] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"163.419307ms","start":"2026-03-31T20:12:31.500018Z","end":"2026-03-31T20:12:31.663437Z","steps":["trace[1742964442] 'process raft request' (duration: 163.126603ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.663662Z","caller":"traceutil/trace.go:171","msg":"trace[1300142542] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"149.683918ms","start":"2026-03-31T20:12:31.513956Z","end":"2026-03-31T20:12:31.66364Z","steps":["trace[1300142542] 'process raft request' (duration: 149.298722ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.663746Z","caller":"traceutil/trace.go:171","msg":"trace[1977828489] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"175.345186ms","start":"2026-03-31T20:12:31.488368Z","end":"2026-03-31T20:12:31.663713Z","steps":["trace[1977828489] 'process raft request' (duration: 174.659336ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.663788Z","caller":"traceutil/trace.go:171","msg":"trace[1035802644] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"134.79315ms","start":"2026-03-31T20:12:31.528981Z","end":"2026-03-31T20:12:31.663774Z","steps":["trace[1035802644] 'process raft request' (duration: 134.346511ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.663815Z","caller":"traceutil/trace.go:171","msg":"trace[730686570] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"175.19891ms","start":"2026-03-31T20:12:31.488609Z","end":"2026-03-31T20:12:31.663808Z","steps":["trace[730686570] 'process raft request' (duration: 174.480239ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:31.663726Z","caller":"traceutil/trace.go:171","msg":"trace[1622679533] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"175.526854ms","start":"2026-03-31T20:12:31.488188Z","end":"2026-03-31T20:12:31.663715Z","steps":["trace[1622679533] 'process raft request' (duration: 174.683158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.162278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.634831ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-31T20:12:32.162742Z","caller":"traceutil/trace.go:171","msg":"trace[1037050937] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"488.867415ms","start":"2026-03-31T20:12:31.673851Z","end":"2026-03-31T20:12:32.162719Z","steps":["trace[1037050937] 'process raft request' (duration: 488.810903ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.162863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.673838Z","time spent":"488.956499ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:32.162967Z","caller":"traceutil/trace.go:171","msg":"trace[927519068] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"494.821409ms","start":"2026-03-31T20:12:31.668128Z","end":"2026-03-31T20:12:32.162949Z","steps":["trace[927519068] 'process raft request' (duration: 494.3557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.163153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.666086Z","time spent":"497.064585ms","remote":"127.0.0.1:43198","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-31T20:12:32.16313Z","caller":"traceutil/trace.go:171","msg":"trace[708833545] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"493.543594ms","start":"2026-03-31T20:12:31.66952Z","end":"2026-03-31T20:12:32.163064Z","steps":["trace[708833545] 'process raft request' (duration: 493.107016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.163192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.668116Z","time spent":"495.031928ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:12:32.163402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.669509Z","time spent":"493.776604ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:32.197716Z","caller":"traceutil/trace.go:171","msg":"trace[1880894908] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"519.755278ms","start":"2026-03-31T20:12:31.677917Z","end":"2026-03-31T20:12:32.197672Z","steps":["trace[1880894908] 'process raft request' (duration: 519.489317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.197889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.67791Z","time spent":"519.872923ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:32.198136Z","caller":"traceutil/trace.go:171","msg":"trace[162576568] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"520.26998ms","start":"2026-03-31T20:12:31.677852Z","end":"2026-03-31T20:12:32.198122Z","steps":["trace[162576568] 'process raft request' (duration: 519.460886ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:32.198137Z","caller":"traceutil/trace.go:171","msg":"trace[1707925284] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"461.953144ms","start":"2026-03-31T20:12:31.736159Z","end":"2026-03-31T20:12:32.198112Z","steps":["trace[1707925284] 'process raft request' (duration: 461.817848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.198199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.677835Z","time spent":"520.337452ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:32.198248Z","caller":"traceutil/trace.go:171","msg":"trace[1947997944] transaction","detail":"{read_only:false; number_of_response:0; response_revision:506; }","duration":"516.366964ms","start":"2026-03-31T20:12:31.681874Z","end":"2026-03-31T20:12:32.198241Z","steps":["trace[1947997944] 'process raft request' (duration: 515.57252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.198352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.736127Z","time spent":"462.120161ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-31T20:12:32.198359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.681858Z","time spent":"516.461738ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:32.198586Z","caller":"traceutil/trace.go:171","msg":"trace[1771327865] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"512.190225ms","start":"2026-03-31T20:12:31.686383Z","end":"2026-03-31T20:12:32.198573Z","steps":["trace[1771327865] 'process raft request' (duration: 511.335968ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:32.198705Z","caller":"traceutil/trace.go:171","msg":"trace[880314605] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"509.732969ms","start":"2026-03-31T20:12:31.688962Z","end":"2026-03-31T20:12:32.198695Z","steps":["trace[880314605] 'process raft request' (duration: 508.907853ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:32.198746Z","caller":"traceutil/trace.go:171","msg":"trace[1138823762] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"506.305083ms","start":"2026-03-31T20:12:31.692431Z","end":"2026-03-31T20:12:32.198736Z","steps":["trace[1138823762] 'process raft request' (duration: 505.485907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.198829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.69242Z","time spent":"506.350675ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:32.19882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.688935Z","time spent":"509.826303ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:32.19815Z","caller":"traceutil/trace.go:171","msg":"trace[1494549722] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"513.01647ms","start":"2026-03-31T20:12:31.685064Z","end":"2026-03-31T20:12:32.198081Z","steps":["trace[1494549722] 'process raft request' (duration: 512.427026ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:32.198822Z","caller":"traceutil/trace.go:171","msg":"trace[1504136186] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"512.192934ms","start":"2026-03-31T20:12:31.686533Z","end":"2026-03-31T20:12:32.198726Z","steps":["trace[1504136186] 'process raft request' (duration: 511.262104ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.198701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.686371Z","time spent":"512.251507ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:32.199036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.686522Z","time spent":"512.443135ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:32.199091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:31.682311Z","time spent":"516.607654ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:32.445941Z","caller":"traceutil/trace.go:171","msg":"trace[1336804332] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:535; }","duration":"246.256537ms","start":"2026-03-31T20:12:32.199662Z","end":"2026-03-31T20:12:32.445918Z","steps":["trace[1336804332] 'read index received' (duration: 209.853107ms)","trace[1336804332] 'applied index is now lower than readState.Index' (duration: 36.40244ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:32.44594Z","caller":"traceutil/trace.go:171","msg":"trace[286103810] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"281.520819ms","start":"2026-03-31T20:12:32.16437Z","end":"2026-03-31T20:12:32.445891Z","steps":["trace[286103810] 'process raft request' (duration: 244.970402ms)","trace[286103810] 'compare' (duration: 36.262624ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:32.446022Z","caller":"traceutil/trace.go:171","msg":"trace[1417893063] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"262.772346ms","start":"2026-03-31T20:12:32.183239Z","end":"2026-03-31T20:12:32.446012Z","steps":["trace[1417893063] 'process raft request' (duration: 262.601638ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:32.446035Z","caller":"traceutil/trace.go:171","msg":"trace[572764225] transaction","detail":"{read_only:false; number_of_response:0; response_revision:513; }","duration":"279.502723ms","start":"2026-03-31T20:12:32.166501Z","end":"2026-03-31T20:12:32.446004Z","steps":["trace[572764225] 'process raft request' (duration: 279.286164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.446324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.643764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-03-31T20:12:32.446363Z","caller":"traceutil/trace.go:171","msg":"trace[504200353] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:514; }","duration":"246.734217ms","start":"2026-03-31T20:12:32.199615Z","end":"2026-03-31T20:12:32.446349Z","steps":["trace[504200353] 'agreement among raft nodes before linearized reading' (duration: 246.56668ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:32.574105Z","caller":"traceutil/trace.go:171","msg":"trace[2063014974] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"362.999535ms","start":"2026-03-31T20:12:32.211076Z","end":"2026-03-31T20:12:32.574075Z","steps":["trace[2063014974] 'process raft request' (duration: 357.025892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.57427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:32.211051Z","time spent":"363.130841ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:32.574544Z","caller":"traceutil/trace.go:171","msg":"trace[727681879] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"356.189857ms","start":"2026-03-31T20:12:32.21832Z","end":"2026-03-31T20:12:32.57451Z","steps":["trace[727681879] 'process raft request' (duration: 355.532239ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:32.574585Z","caller":"traceutil/trace.go:171","msg":"trace[1006407752] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:538; }","duration":"126.452058ms","start":"2026-03-31T20:12:32.448113Z","end":"2026-03-31T20:12:32.574565Z","steps":["trace[1006407752] 'read index received' (duration: 119.901051ms)","trace[1006407752] 'applied index is now lower than readState.Index' (duration: 6.549907ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:32.574631Z","caller":"traceutil/trace.go:171","msg":"trace[250681847] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"313.746572ms","start":"2026-03-31T20:12:32.260872Z","end":"2026-03-31T20:12:32.574619Z","steps":["trace[250681847] 'process raft request' (duration: 313.24105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.574832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:32.260842Z","time spent":"313.878907ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:32.574767Z","caller":"traceutil/trace.go:171","msg":"trace[1143418445] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"354.031445ms","start":"2026-03-31T20:12:32.220719Z","end":"2026-03-31T20:12:32.57475Z","steps":["trace[1143418445] 'process raft request' (duration: 353.209731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.575079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.951859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"warn","ts":"2026-03-31T20:12:32.575128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:32.220708Z","time spent":"354.232213ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:32.575151Z","caller":"traceutil/trace.go:171","msg":"trace[193969442] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:520; }","duration":"127.045213ms","start":"2026-03-31T20:12:32.448087Z","end":"2026-03-31T20:12:32.575132Z","steps":["trace[193969442] 'agreement among raft nodes before linearized reading' (duration: 126.816234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.574847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:32.218302Z","time spent":"356.325982ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:32.574562Z","caller":"traceutil/trace.go:171","msg":"trace[1146536419] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"356.538711ms","start":"2026-03-31T20:12:32.218003Z","end":"2026-03-31T20:12:32.574541Z","steps":["trace[1146536419] 'process raft request' (duration: 355.78492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.575286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:32.217976Z","time spent":"357.279142ms","remote":"127.0.0.1:43706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:32.574818Z","caller":"traceutil/trace.go:171","msg":"trace[1729496770] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"295.942318ms","start":"2026-03-31T20:12:32.278858Z","end":"2026-03-31T20:12:32.574801Z","steps":["trace[1729496770] 'process raft request' (duration: 295.570913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.575288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.278971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-31T20:12:32.575391Z","caller":"traceutil/trace.go:171","msg":"trace[1798530929] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:520; }","duration":"126.412577ms","start":"2026-03-31T20:12:32.448961Z","end":"2026-03-31T20:12:32.575373Z","steps":["trace[1798530929] 'agreement among raft nodes before linearized reading' (duration: 126.041102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.955526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.092653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:32.955666Z","caller":"traceutil/trace.go:171","msg":"trace[1222105338] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"349.6459ms","start":"2026-03-31T20:12:32.606Z","end":"2026-03-31T20:12:32.955646Z","steps":["trace[1222105338] 'process raft request' (duration: 71.333128ms)","trace[1222105338] 'compare' (duration: 277.880914ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:32.955822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:32.605975Z","time spent":"349.779125ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:32.961885Z","caller":"traceutil/trace.go:171","msg":"trace[2028221026] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"349.842988ms","start":"2026-03-31T20:12:32.612015Z","end":"2026-03-31T20:12:32.961858Z","steps":["trace[2028221026] 'process raft request' (duration: 349.256234ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:32.961967Z","caller":"traceutil/trace.go:171","msg":"trace[455927225] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"330.871435ms","start":"2026-03-31T20:12:32.631055Z","end":"2026-03-31T20:12:32.961926Z","steps":["trace[455927225] 'process raft request' (duration: 330.727199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:32.962023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:32.611996Z","time spent":"349.948393ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:32.962165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:32.631026Z","time spent":"331.039193ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:32.975682Z","caller":"traceutil/trace.go:171","msg":"trace[167113343] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"200.964071ms","start":"2026-03-31T20:12:32.774699Z","end":"2026-03-31T20:12:32.975663Z","steps":["trace[167113343] 'process raft request' (duration: 200.697249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:33.03701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.938322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:33.037068Z","caller":"traceutil/trace.go:171","msg":"trace[1970041678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"114.026206ms","start":"2026-03-31T20:12:32.923028Z","end":"2026-03-31T20:12:33.037055Z","steps":["trace[1970041678] 'agreement among raft nodes before linearized reading' (duration: 113.949283ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:33.227882Z","caller":"traceutil/trace.go:171","msg":"trace[52227599] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"250.813876ms","start":"2026-03-31T20:12:32.977021Z","end":"2026-03-31T20:12:33.227835Z","steps":["trace[52227599] 'process raft request' (duration: 182.097654ms)","trace[52227599] 'compare' (duration: 68.43544ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:33.22814Z","caller":"traceutil/trace.go:171","msg":"trace[678775353] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"249.276532ms","start":"2026-03-31T20:12:32.978846Z","end":"2026-03-31T20:12:33.228123Z","steps":["trace[678775353] 'process raft request' (duration: 248.903116ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:33.228144Z","caller":"traceutil/trace.go:171","msg":"trace[1234625763] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:549; }","duration":"190.002423ms","start":"2026-03-31T20:12:33.038118Z","end":"2026-03-31T20:12:33.22812Z","steps":["trace[1234625763] 'read index received' (duration: 121.109033ms)","trace[1234625763] 'applied index is now lower than readState.Index' (duration: 68.89229ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:33.228278Z","caller":"traceutil/trace.go:171","msg":"trace[1724411041] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"192.57313ms","start":"2026-03-31T20:12:33.035695Z","end":"2026-03-31T20:12:33.228268Z","steps":["trace[1724411041] 'process raft request' (duration: 192.304469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:33.228349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.205401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:33.228402Z","caller":"traceutil/trace.go:171","msg":"trace[779335536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"190.290505ms","start":"2026-03-31T20:12:33.038098Z","end":"2026-03-31T20:12:33.228388Z","steps":["trace[779335536] 'agreement among raft nodes before linearized reading' (duration: 190.18099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:33.229859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.012238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T20:12:33.22993Z","caller":"traceutil/trace.go:171","msg":"trace[1795071530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"159.136243ms","start":"2026-03-31T20:12:33.070775Z","end":"2026-03-31T20:12:33.229912Z","steps":["trace[1795071530] 'agreement among raft nodes before linearized reading' (duration: 158.990117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:33.230324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.718685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:12:33.230641Z","caller":"traceutil/trace.go:171","msg":"trace[1833451405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"142.07952ms","start":"2026-03-31T20:12:33.088548Z","end":"2026-03-31T20:12:33.230628Z","steps":["trace[1833451405] 'agreement among raft nodes before linearized reading' (duration: 141.731165ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:33.229864Z","caller":"traceutil/trace.go:171","msg":"trace[1801046862] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"180.942664ms","start":"2026-03-31T20:12:33.048897Z","end":"2026-03-31T20:12:33.229839Z","steps":["trace[1801046862] 'process raft request' (duration: 180.733885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:35.699988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.728389392s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:35.700097Z","caller":"traceutil/trace.go:171","msg":"trace[1021403666] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"1.942419259s","start":"2026-03-31T20:12:33.757657Z","end":"2026-03-31T20:12:35.700077Z","steps":["trace[1021403666] 'process raft request' (duration: 213.866981ms)","trace[1021403666] 'compare' (duration: 1.727691933s)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:35.700159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:33.757639Z","time spent":"1.942489292s","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:35.807276Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.835755246s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-31T20:12:35.925049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00030392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-31T20:12:35.925202Z","caller":"traceutil/trace.go:171","msg":"trace[682544304] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000509768s","start":"2026-03-31T20:12:33.924651Z","end":"2026-03-31T20:12:35.925161Z","steps":["trace[682544304] 'agreement among raft nodes before linearized reading' (duration: 2.00033211s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:35.925264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:33.924603Z","time spent":"2.000647633s","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/31 20:12:35 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-31T20:12:35.96974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.043136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:35.969957Z","caller":"traceutil/trace.go:171","msg":"trace[37102544] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"2.191903646s","start":"2026-03-31T20:12:33.778019Z","end":"2026-03-31T20:12:35.969923Z","steps":["trace[37102544] 'process raft request' (duration: 2.029582758s)","trace[37102544] 'compare' (duration: 161.764325ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:35.970115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:33.778Z","time spent":"2.192036241s","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:35.970196Z","caller":"traceutil/trace.go:171","msg":"trace[1075785577] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"193.019761ms","start":"2026-03-31T20:12:35.777157Z","end":"2026-03-31T20:12:35.970177Z","steps":["trace[1075785577] 'process raft request' (duration: 192.889906ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:35.970278Z","caller":"traceutil/trace.go:171","msg":"trace[1552578635] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"2.045474933s","start":"2026-03-31T20:12:33.924709Z","end":"2026-03-31T20:12:35.970184Z","steps":["trace[1552578635] 'read index received' (duration: 46.823308ms)","trace[1552578635] 'applied index is now lower than readState.Index' (duration: 1.998646845s)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:35.970341Z","caller":"traceutil/trace.go:171","msg":"trace[1365033882] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"246.792016ms","start":"2026-03-31T20:12:35.723526Z","end":"2026-03-31T20:12:35.970318Z","steps":["trace[1365033882] 'process raft request' (duration: 246.420201ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:35.970424Z","caller":"traceutil/trace.go:171","msg":"trace[1772029632] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"2.033551716s","start":"2026-03-31T20:12:33.936782Z","end":"2026-03-31T20:12:35.970334Z","steps":["trace[1772029632] 'process raft request' (duration: 2.033070996s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:35.970592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.387816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T20:12:35.970632Z","caller":"traceutil/trace.go:171","msg":"trace[1204541709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"446.49099ms","start":"2026-03-31T20:12:35.524127Z","end":"2026-03-31T20:12:35.970618Z","steps":["trace[1204541709] 'agreement among raft nodes before linearized reading' (duration: 446.344334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:35.970643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:33.936768Z","time spent":"2.033762463s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:35.970663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:35.524099Z","time spent":"446.555553ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:12:35.970673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.78002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T20:12:35.97071Z","caller":"traceutil/trace.go:171","msg":"trace[1145995163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"673.899615ms","start":"2026-03-31T20:12:35.296797Z","end":"2026-03-31T20:12:35.970697Z","steps":["trace[1145995163] 'agreement among raft nodes before linearized reading' (duration: 673.747019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:35.970672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.944119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-31T20:12:35.970752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.225589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T20:12:35.970803Z","caller":"traceutil/trace.go:171","msg":"trace[1396445856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"737.158058ms","start":"2026-03-31T20:12:35.233613Z","end":"2026-03-31T20:12:35.970771Z","steps":["trace[1396445856] 'agreement among raft nodes before linearized reading' (duration: 736.862696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:35.970864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:35.233525Z","time spent":"737.330015ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:12:35.970742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:35.296773Z","time spent":"673.960528ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:12:35.971956Z","caller":"traceutil/trace.go:171","msg":"trace[1831472953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"712.342434ms","start":"2026-03-31T20:12:35.258475Z","end":"2026-03-31T20:12:35.970817Z","steps":["trace[1831472953] 'agreement among raft nodes before linearized reading' (duration: 712.120955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:35.972081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:35.258459Z","time spent":"713.586186ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T20:12:36.111324Z","caller":"traceutil/trace.go:171","msg":"trace[328213448] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"134.335704ms","start":"2026-03-31T20:12:35.976955Z","end":"2026-03-31T20:12:36.111291Z","steps":["trace[328213448] 'process raft request' (duration: 134.214229ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:36.111356Z","caller":"traceutil/trace.go:171","msg":"trace[1764569165] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"134.872535ms","start":"2026-03-31T20:12:35.976445Z","end":"2026-03-31T20:12:36.111318Z","steps":["trace[1764569165] 'process raft request' (duration: 100.145084ms)","trace[1764569165] 'compare' (duration: 34.373677ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:36.111769Z","caller":"traceutil/trace.go:171","msg":"trace[1949508963] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"134.795761ms","start":"2026-03-31T20:12:35.976963Z","end":"2026-03-31T20:12:36.111759Z","steps":["trace[1949508963] 'process raft request' (duration: 134.286031ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:36.112211Z","caller":"traceutil/trace.go:171","msg":"trace[643575350] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"122.354343ms","start":"2026-03-31T20:12:35.98983Z","end":"2026-03-31T20:12:36.112185Z","steps":["trace[643575350] 'process raft request' (duration: 122.203127ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:36.112306Z","caller":"traceutil/trace.go:171","msg":"trace[423891401] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:577; }","duration":"133.605153ms","start":"2026-03-31T20:12:35.97868Z","end":"2026-03-31T20:12:36.112285Z","steps":["trace[423891401] 'read index received' (duration: 58.149888ms)","trace[423891401] 'applied index is now lower than readState.Index' (duration: 75.454445ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:36.112395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.699617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:36.112422Z","caller":"traceutil/trace.go:171","msg":"trace[1010507032] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"133.77181ms","start":"2026-03-31T20:12:35.978643Z","end":"2026-03-31T20:12:36.112415Z","steps":["trace[1010507032] 'agreement among raft nodes before linearized reading' (duration: 133.695887ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:36.112516Z","caller":"traceutil/trace.go:171","msg":"trace[1137728699] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"114.369708ms","start":"2026-03-31T20:12:35.998047Z","end":"2026-03-31T20:12:36.112417Z","steps":["trace[1137728699] 'process raft request' (duration: 114.074616ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:36.112491Z","caller":"traceutil/trace.go:171","msg":"trace[690090317] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"132.634384ms","start":"2026-03-31T20:12:35.979831Z","end":"2026-03-31T20:12:36.112465Z","steps":["trace[690090317] 'process raft request' (duration: 131.652133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:36.380859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.369396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:36.381083Z","caller":"traceutil/trace.go:171","msg":"trace[683364500] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"261.091767ms","start":"2026-03-31T20:12:36.119968Z","end":"2026-03-31T20:12:36.38106Z","steps":["trace[683364500] 'process raft request' (duration: 143.442019ms)","trace[683364500] 'compare' (duration: 117.090314ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:36.551773Z","caller":"traceutil/trace.go:171","msg":"trace[1689322371] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"412.697265ms","start":"2026-03-31T20:12:36.139041Z","end":"2026-03-31T20:12:36.551739Z","steps":["trace[1689322371] 'process raft request' (duration: 407.581628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:36.551981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:36.139015Z","time spent":"412.854981ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:36.552491Z","caller":"traceutil/trace.go:171","msg":"trace[1838405528] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:585; }","duration":"136.189276ms","start":"2026-03-31T20:12:36.41628Z","end":"2026-03-31T20:12:36.552469Z","steps":["trace[1838405528] 'read index received' (duration: 130.412643ms)","trace[1838405528] 'applied index is now lower than readState.Index' (duration: 5.775603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:36.552741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.444387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-03-31T20:12:36.552784Z","caller":"traceutil/trace.go:171","msg":"trace[2119969235] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:563; }","duration":"136.550121ms","start":"2026-03-31T20:12:36.416222Z","end":"2026-03-31T20:12:36.552773Z","steps":["trace[2119969235] 'agreement among raft nodes before linearized reading' (duration: 136.331622ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:36.552992Z","caller":"traceutil/trace.go:171","msg":"trace[527637133] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"401.599896ms","start":"2026-03-31T20:12:36.151354Z","end":"2026-03-31T20:12:36.552954Z","steps":["trace[527637133] 'process raft request' (duration: 400.293844ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:36.553169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:36.151323Z","time spent":"401.746582ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:37.410971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.626172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:37.411085Z","caller":"traceutil/trace.go:171","msg":"trace[1347386502] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"819.719018ms","start":"2026-03-31T20:12:36.591346Z","end":"2026-03-31T20:12:37.411065Z","steps":["trace[1347386502] 'process raft request' (duration: 63.907938ms)","trace[1347386502] 'compare' (duration: 755.363922ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:37.411153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:36.591327Z","time spent":"819.790431ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:37.421608Z","caller":"traceutil/trace.go:171","msg":"trace[1986612883] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"829.952986ms","start":"2026-03-31T20:12:36.591627Z","end":"2026-03-31T20:12:37.42158Z","steps":["trace[1986612883] 'process raft request' (duration: 829.117883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:37.421815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:36.591616Z","time spent":"830.125783ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:37.421869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.827296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-03-31T20:12:37.421938Z","caller":"traceutil/trace.go:171","msg":"trace[1379071397] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:565; }","duration":"814.93249ms","start":"2026-03-31T20:12:36.60699Z","end":"2026-03-31T20:12:37.421923Z","steps":["trace[1379071397] 'agreement among raft nodes before linearized reading' (duration: 814.648068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:37.421972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:36.606976Z","time spent":"814.990232ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80727,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-03-31T20:12:37.422118Z","caller":"traceutil/trace.go:171","msg":"trace[2064693789] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:587; }","duration":"815.060345ms","start":"2026-03-31T20:12:36.607029Z","end":"2026-03-31T20:12:37.42209Z","steps":["trace[2064693789] 'read index received' (duration: 48.220416ms)","trace[2064693789] 'applied index is now lower than readState.Index' (duration: 766.221285ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:37.422281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.527042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:37.42232Z","caller":"traceutil/trace.go:171","msg":"trace[19954059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"167.611326ms","start":"2026-03-31T20:12:37.254699Z","end":"2026-03-31T20:12:37.42231Z","steps":["trace[19954059] 'agreement among raft nodes before linearized reading' (duration: 167.532503ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:37.694992Z","caller":"traceutil/trace.go:171","msg":"trace[3026383] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"238.131818ms","start":"2026-03-31T20:12:37.456836Z","end":"2026-03-31T20:12:37.694967Z","steps":["trace[3026383] 'process raft request' (duration: 237.92408ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:37.696811Z","caller":"traceutil/trace.go:171","msg":"trace[683538076] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:591; }","duration":"163.883037ms","start":"2026-03-31T20:12:37.532913Z","end":"2026-03-31T20:12:37.696796Z","steps":["trace[683538076] 'read index received' (duration: 163.876376ms)","trace[683538076] 'applied index is now lower than readState.Index' (duration: 5.351µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:37.69697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.036213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-03-31T20:12:37.697009Z","caller":"traceutil/trace.go:171","msg":"trace[1347910605] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"164.114096ms","start":"2026-03-31T20:12:37.532883Z","end":"2026-03-31T20:12:37.696997Z","steps":["trace[1347910605] 'agreement among raft nodes before linearized reading' (duration: 163.97725ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:37.842183Z","caller":"traceutil/trace.go:171","msg":"trace[1307268062] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"135.151911ms","start":"2026-03-31T20:12:37.707002Z","end":"2026-03-31T20:12:37.842154Z","steps":["trace[1307268062] 'read index received' (duration: 134.762505ms)","trace[1307268062] 'applied index is now lower than readState.Index' (duration: 388.196µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:37.842282Z","caller":"traceutil/trace.go:171","msg":"trace[931228981] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"204.486956ms","start":"2026-03-31T20:12:37.637762Z","end":"2026-03-31T20:12:37.842249Z","steps":["trace[931228981] 'process raft request' (duration: 204.048729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:37.842381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.350628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-03-31T20:12:37.842426Z","caller":"traceutil/trace.go:171","msg":"trace[1882414766] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:568; }","duration":"135.441062ms","start":"2026-03-31T20:12:37.706973Z","end":"2026-03-31T20:12:37.842414Z","steps":["trace[1882414766] 'agreement among raft nodes before linearized reading' (duration: 135.273655ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:37.898082Z","caller":"traceutil/trace.go:171","msg":"trace[617146945] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"184.063362ms","start":"2026-03-31T20:12:37.713987Z","end":"2026-03-31T20:12:37.89805Z","steps":["trace[617146945] 'process raft request' (duration: 183.814463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:38.005615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.58177ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-31T20:12:38.005797Z","caller":"traceutil/trace.go:171","msg":"trace[1922603440] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"122.116769ms","start":"2026-03-31T20:12:37.883672Z","end":"2026-03-31T20:12:38.005789Z","steps":["trace[1922603440] 'process raft request' (duration: 122.015705ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:38.005804Z","caller":"traceutil/trace.go:171","msg":"trace[171661636] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:592; }","duration":"151.802404ms","start":"2026-03-31T20:12:37.853978Z","end":"2026-03-31T20:12:38.005781Z","steps":["trace[171661636] 'read index received' (duration: 43.795187ms)","trace[171661636] 'applied index is now lower than readState.Index' (duration: 108.004657ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:38.006076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.048363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:65214"} {"level":"info","ts":"2026-03-31T20:12:38.00612Z","caller":"traceutil/trace.go:171","msg":"trace[1671986072] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tlsroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:570; }","duration":"152.162937ms","start":"2026-03-31T20:12:37.853944Z","end":"2026-03-31T20:12:38.006107Z","steps":["trace[1671986072] 'agreement among raft nodes before linearized reading' (duration: 151.946059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:38.498129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.526304ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:38.498253Z","caller":"traceutil/trace.go:171","msg":"trace[260231452] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:595; }","duration":"420.26162ms","start":"2026-03-31T20:12:38.077951Z","end":"2026-03-31T20:12:38.498213Z","steps":["trace[260231452] 'read index received' (duration: 13.842495ms)","trace[260231452] 'applied index is now lower than readState.Index' (duration: 406.417954ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:38.498274Z","caller":"traceutil/trace.go:171","msg":"trace[1533609628] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"439.426395ms","start":"2026-03-31T20:12:38.058826Z","end":"2026-03-31T20:12:38.498252Z","steps":["trace[1533609628] 'process raft request' (duration: 131.702693ms)","trace[1533609628] 'compare' (duration: 307.290125ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:38.49837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:38.058816Z","time spent":"439.508148ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:38.498405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.452898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-03-31T20:12:38.498443Z","caller":"traceutil/trace.go:171","msg":"trace[2103729973] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:572; }","duration":"420.52496ms","start":"2026-03-31T20:12:38.077908Z","end":"2026-03-31T20:12:38.498433Z","steps":["trace[2103729973] 'agreement among raft nodes before linearized reading' (duration: 420.399525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:38.498549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:38.077892Z","time spent":"420.574592ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65460,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-03-31T20:12:38.580092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.174783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:12:38.580134Z","caller":"traceutil/trace.go:171","msg":"trace[176524360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"462.250045ms","start":"2026-03-31T20:12:38.117874Z","end":"2026-03-31T20:12:38.580124Z","steps":["trace[176524360] 'agreement among raft nodes before linearized reading' (duration: 462.097019ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:38.58013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.297346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-31T20:12:38.580173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.045218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T20:12:38.580196Z","caller":"traceutil/trace.go:171","msg":"trace[1976002903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"461.09116ms","start":"2026-03-31T20:12:38.119098Z","end":"2026-03-31T20:12:38.580189Z","steps":["trace[1976002903] 'agreement among raft nodes before linearized reading' (duration: 461.022478ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:38.580198Z","caller":"traceutil/trace.go:171","msg":"trace[187792636] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"464.39949ms","start":"2026-03-31T20:12:38.115776Z","end":"2026-03-31T20:12:38.580175Z","steps":["trace[187792636] 'agreement among raft nodes before linearized reading' (duration: 464.222473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:38.580212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:38.119087Z","time spent":"461.120771ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:12:38.580157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:38.117863Z","time spent":"462.286967ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:12:38.58024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:38.115756Z","time spent":"464.473223ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T20:12:38.580073Z","caller":"traceutil/trace.go:171","msg":"trace[1566520671] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"427.049718ms","start":"2026-03-31T20:12:38.152991Z","end":"2026-03-31T20:12:38.58004Z","steps":["trace[1566520671] 'process raft request' (duration: 426.837998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:38.58025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.932612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T20:12:38.580309Z","caller":"traceutil/trace.go:171","msg":"trace[1372736408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"464.028355ms","start":"2026-03-31T20:12:38.116266Z","end":"2026-03-31T20:12:38.580294Z","steps":["trace[1372736408] 'agreement among raft nodes before linearized reading' (duration: 463.858449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:38.580348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:38.116257Z","time spent":"464.080138ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:12:38.580378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:38.152975Z","time spent":"427.316528ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:38.726928Z","caller":"traceutil/trace.go:171","msg":"trace[1104989805] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:599; }","duration":"131.234355ms","start":"2026-03-31T20:12:38.595671Z","end":"2026-03-31T20:12:38.726906Z","steps":["trace[1104989805] 'read index received' (duration: 100.616217ms)","trace[1104989805] 'applied index is now lower than readState.Index' (duration: 30.617408ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:38.726972Z","caller":"traceutil/trace.go:171","msg":"trace[1045811875] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"141.156905ms","start":"2026-03-31T20:12:38.585776Z","end":"2026-03-31T20:12:38.726933Z","steps":["trace[1045811875] 'process raft request' (duration: 141.057751ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:38.727008Z","caller":"traceutil/trace.go:171","msg":"trace[594457337] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"140.00602ms","start":"2026-03-31T20:12:38.586968Z","end":"2026-03-31T20:12:38.726974Z","steps":["trace[594457337] 'process raft request' (duration: 139.903206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:38.727114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.417092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-03-31T20:12:38.727137Z","caller":"traceutil/trace.go:171","msg":"trace[583475213] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"141.77677ms","start":"2026-03-31T20:12:38.585348Z","end":"2026-03-31T20:12:38.727125Z","steps":["trace[583475213] 'process raft request' (duration: 110.842651ms)","trace[583475213] 'compare' (duration: 30.514183ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:38.727149Z","caller":"traceutil/trace.go:171","msg":"trace[582010649] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:577; }","duration":"131.490005ms","start":"2026-03-31T20:12:38.595647Z","end":"2026-03-31T20:12:38.727137Z","steps":["trace[582010649] 'agreement among raft nodes before linearized reading' (duration: 131.344559ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:38.732664Z","caller":"traceutil/trace.go:171","msg":"trace[857711168] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"110.973406ms","start":"2026-03-31T20:12:38.621668Z","end":"2026-03-31T20:12:38.732642Z","steps":["trace[857711168] 'process raft request' (duration: 110.733316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:43.099044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.835243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:43.099111Z","caller":"traceutil/trace.go:171","msg":"trace[46617012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"119.925077ms","start":"2026-03-31T20:12:42.979172Z","end":"2026-03-31T20:12:43.099097Z","steps":["trace[46617012] 'range keys from in-memory index tree' (duration: 119.802503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:43.099247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.020424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T20:12:43.099247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.946421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T20:12:43.099326Z","caller":"traceutil/trace.go:171","msg":"trace[1449166369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"486.129637ms","start":"2026-03-31T20:12:42.613175Z","end":"2026-03-31T20:12:43.099305Z","steps":["trace[1449166369] 'range keys from in-memory index tree' (duration: 485.893839ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:43.099335Z","caller":"traceutil/trace.go:171","msg":"trace[786103218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"123.066615ms","start":"2026-03-31T20:12:42.97625Z","end":"2026-03-31T20:12:43.099317Z","steps":["trace[786103218] 'range keys from in-memory index tree' (duration: 122.794185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:43.099363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:42.613162Z","time spent":"486.19294ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:12:43.100006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.233871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:12:43.100082Z","caller":"traceutil/trace.go:171","msg":"trace[400106883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"123.311724ms","start":"2026-03-31T20:12:42.976748Z","end":"2026-03-31T20:12:43.10006Z","steps":["trace[400106883] 'range keys from in-memory index tree' (duration: 123.144687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:43.101544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.150643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:12:43.101689Z","caller":"traceutil/trace.go:171","msg":"trace[2142663379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"125.300288ms","start":"2026-03-31T20:12:42.976369Z","end":"2026-03-31T20:12:43.101669Z","steps":["trace[2142663379] 'range keys from in-memory index tree' (duration: 125.06733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:44.878833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495411998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:45.397988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495411998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:45.823357Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.61668305s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:12:45.824955Z","caller":"traceutil/trace.go:171","msg":"trace[1618867534] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"1.44665547s","start":"2026-03-31T20:12:44.378279Z","end":"2026-03-31T20:12:45.824934Z","steps":["trace[1618867534] 'read index received' (duration: 1.445197616s)","trace[1618867534] 'applied index is now lower than readState.Index' (duration: 1.456874ms)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:12:45.825104Z","caller":"traceutil/trace.go:171","msg":"trace[2022315748] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.61967637s","start":"2026-03-31T20:12:44.205388Z","end":"2026-03-31T20:12:45.825064Z","steps":["trace[2022315748] 'process raft request' (duration: 1.619048707s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:45.825151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446856738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-d8mcb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-31T20:12:45.825193Z","caller":"traceutil/trace.go:171","msg":"trace[2034912502] range","detail":"{range_begin:/registry/pods/kube-system/cilium-d8mcb; range_end:; response_count:1; response_revision:608; }","duration":"1.446956521s","start":"2026-03-31T20:12:44.378224Z","end":"2026-03-31T20:12:45.82518Z","steps":["trace[2034912502] 'agreement among raft nodes before linearized reading' (duration: 1.446804915s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:45.825227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:44.378204Z","time spent":"1.447014753s","remote":"127.0.0.1:43358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-d8mcb\" "} {"level":"warn","ts":"2026-03-31T20:12:45.825257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:44.205354Z","time spent":"1.619826195s","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:45.825329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.73885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-31T20:12:45.825334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.412929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:12:45.825395Z","caller":"traceutil/trace.go:171","msg":"trace[92216998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"714.847434ms","start":"2026-03-31T20:12:45.110525Z","end":"2026-03-31T20:12:45.825373Z","steps":["trace[92216998] 'agreement among raft nodes before linearized reading' (duration: 714.693898ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:45.825399Z","caller":"traceutil/trace.go:171","msg":"trace[1654234228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"714.515172ms","start":"2026-03-31T20:12:45.110865Z","end":"2026-03-31T20:12:45.82538Z","steps":["trace[1654234228] 'agreement among raft nodes before linearized reading' (duration: 714.298694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:45.825429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.110492Z","time spent":"714.929367ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:12:45.825436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.110845Z","time spent":"714.582695ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:12:45.825445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.947912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-31T20:12:45.825555Z","caller":"traceutil/trace.go:171","msg":"trace[240357186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"714.089107ms","start":"2026-03-31T20:12:45.111434Z","end":"2026-03-31T20:12:45.825523Z","steps":["trace[240357186] 'agreement among raft nodes before linearized reading' (duration: 713.769336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:45.825631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.111422Z","time spent":"714.198451ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:12:45.825657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.168275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T20:12:45.825693Z","caller":"traceutil/trace.go:171","msg":"trace[2097616350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"715.204697ms","start":"2026-03-31T20:12:45.110477Z","end":"2026-03-31T20:12:45.825682Z","steps":["trace[2097616350] 'agreement among raft nodes before linearized reading' (duration: 715.041541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:45.825721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.110413Z","time spent":"715.30166ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:12:45.825787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.872329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:45.826018Z","caller":"traceutil/trace.go:171","msg":"trace[1170762645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"901.73713ms","start":"2026-03-31T20:12:44.924255Z","end":"2026-03-31T20:12:45.825993Z","steps":["trace[1170762645] 'agreement among raft nodes before linearized reading' (duration: 900.870649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:45.826101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:44.924221Z","time spent":"901.864844ms","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:12:46.234341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.427187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-31T20:12:46.234614Z","caller":"traceutil/trace.go:171","msg":"trace[1502064063] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"403.641381ms","start":"2026-03-31T20:12:45.83092Z","end":"2026-03-31T20:12:46.234562Z","steps":["trace[1502064063] 'process raft request' (duration: 131.899842ms)","trace[1502064063] 'compare' (duration: 271.253912ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:46.234841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.830895Z","time spent":"403.842367ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:46.234917Z","caller":"traceutil/trace.go:171","msg":"trace[1178255708] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"400.587039ms","start":"2026-03-31T20:12:45.834307Z","end":"2026-03-31T20:12:46.234894Z","steps":["trace[1178255708] 'process raft request' (duration: 400.34796ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:46.235032Z","caller":"traceutil/trace.go:171","msg":"trace[1593486605] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"401.003044ms","start":"2026-03-31T20:12:45.833996Z","end":"2026-03-31T20:12:46.234999Z","steps":["trace[1593486605] 'process raft request' (duration: 400.456084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:46.235098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.834292Z","time spent":"400.712604ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:46.235187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.833977Z","time spent":"401.133988ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:12:47.424755Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495412002,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:47.925575Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495412002,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:48.426637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495412002,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:12:48.845942Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.882996979s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-31T20:12:48.876989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.176051833s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:48.877103Z","caller":"traceutil/trace.go:171","msg":"trace[1421665106] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:612; }","duration":"1.176178378s","start":"2026-03-31T20:12:47.700906Z","end":"2026-03-31T20:12:48.877084Z","steps":["trace[1421665106] 'range keys from in-memory index tree' (duration: 1.176035873s)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:48.87731Z","caller":"traceutil/trace.go:171","msg":"trace[1476920071] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"3.017394294s","start":"2026-03-31T20:12:45.85988Z","end":"2026-03-31T20:12:48.877275Z","steps":["trace[1476920071] 'process raft request' (duration: 2.986396133s)","trace[1476920071] 'compare' (duration: 30.296247ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:48.877401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.859864Z","time spent":"3.017480337s","remote":"127.0.0.1:43358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:48.877524Z","caller":"traceutil/trace.go:171","msg":"trace[1410884083] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"2.979568826s","start":"2026-03-31T20:12:45.897847Z","end":"2026-03-31T20:12:48.877416Z","steps":["trace[1410884083] 'process raft request' (duration: 2.979220064s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:48.877702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:45.897829Z","time spent":"2.979813265s","remote":"127.0.0.1:43400","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:48.877691Z","caller":"traceutil/trace.go:171","msg":"trace[1515616291] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"2.734941237s","start":"2026-03-31T20:12:46.142725Z","end":"2026-03-31T20:12:48.877666Z","steps":["trace[1515616291] 'process raft request' (duration: 2.734420709s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:48.878117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:46.142697Z","time spent":"2.735376023s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:12:48.885184Z","caller":"traceutil/trace.go:171","msg":"trace[316158391] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:638; }","duration":"1.961174549s","start":"2026-03-31T20:12:46.923994Z","end":"2026-03-31T20:12:48.885168Z","steps":["trace[316158391] 'read index received' (duration: 1.92227237s)","trace[316158391] 'applied index is now lower than readState.Index' (duration: 38.901179ms)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:12:48.885293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.961299133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:12:48.885363Z","caller":"traceutil/trace.go:171","msg":"trace[641921402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"1.961370165s","start":"2026-03-31T20:12:46.923969Z","end":"2026-03-31T20:12:48.885339Z","steps":["trace[641921402] 'agreement among raft nodes before linearized reading' (duration: 1.961276302s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:48.885375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018308155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-31T20:12:48.885391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:46.923894Z","time spent":"1.96149053s","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-31T20:12:48.885392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.125337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:12:48.885425Z","caller":"traceutil/trace.go:171","msg":"trace[1386191060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:615; }","duration":"642.22593ms","start":"2026-03-31T20:12:48.24319Z","end":"2026-03-31T20:12:48.885416Z","steps":["trace[1386191060] 'agreement among raft nodes before linearized reading' (duration: 642.141757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:48.885452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.354686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-31T20:12:48.885463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:48.243148Z","time spent":"642.309223ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-31T20:12:48.885466Z","caller":"traceutil/trace.go:171","msg":"trace[264677661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:615; }","duration":"646.408438ms","start":"2026-03-31T20:12:48.239054Z","end":"2026-03-31T20:12:48.885462Z","steps":["trace[264677661] 'agreement among raft nodes before linearized reading' (duration: 646.351686ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:48.885396Z","caller":"traceutil/trace.go:171","msg":"trace[1652740678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"1.018361967s","start":"2026-03-31T20:12:47.867027Z","end":"2026-03-31T20:12:48.885389Z","steps":["trace[1652740678] 'agreement among raft nodes before linearized reading' (duration: 1.018257803s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:48.885483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:48.239032Z","time spent":"646.447689ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:12:48.885545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:47.867Z","time spent":"1.018501112s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:12:48.885753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.535027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-31T20:12:48.88586Z","caller":"traceutil/trace.go:171","msg":"trace[1621816982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:615; }","duration":"645.678662ms","start":"2026-03-31T20:12:48.240161Z","end":"2026-03-31T20:12:48.88584Z","steps":["trace[1621816982] 'agreement among raft nodes before linearized reading' (duration: 645.270468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:48.885948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:48.240138Z","time spent":"645.793446ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-31T20:12:50.979359Z","caller":"traceutil/trace.go:171","msg":"trace[1122133011] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"397.26371ms","start":"2026-03-31T20:12:50.582068Z","end":"2026-03-31T20:12:50.979332Z","steps":["trace[1122133011] 'process raft request' (duration: 397.113845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:12:50.979681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:12:50.582043Z","time spent":"397.389665ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-31T20:12:50.986129Z","caller":"traceutil/trace.go:171","msg":"trace[1634615721] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"202.725531ms","start":"2026-03-31T20:12:50.783379Z","end":"2026-03-31T20:12:50.986105Z","steps":["trace[1634615721] 'process raft request' (duration: 202.495073ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:12:53.88172Z","caller":"traceutil/trace.go:171","msg":"trace[1059878753] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"296.045816ms","start":"2026-03-31T20:12:53.585653Z","end":"2026-03-31T20:12:53.881698Z","steps":["trace[1059878753] 'process raft request' (duration: 295.85256ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:13:02.094097Z","caller":"traceutil/trace.go:171","msg":"trace[1968720784] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"169.694918ms","start":"2026-03-31T20:13:01.924379Z","end":"2026-03-31T20:13:02.094074Z","steps":["trace[1968720784] 'read index received' (duration: 169.488731ms)","trace[1968720784] 'applied index is now lower than readState.Index' (duration: 204.917µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:13:02.094234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.833042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:13:02.094274Z","caller":"traceutil/trace.go:171","msg":"trace[1632094395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"169.916324ms","start":"2026-03-31T20:13:01.924346Z","end":"2026-03-31T20:13:02.094262Z","steps":["trace[1632094395] 'agreement among raft nodes before linearized reading' (duration: 169.833712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:02.094238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:01.596944Z","time spent":"497.281028ms","remote":"127.0.0.1:43198","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-31T20:13:05.023508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495412405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:13:05.524201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495412405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:13:06.025218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812890495412405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-31T20:13:06.20763Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.73777419s","expected-duration":"1s"} {"level":"info","ts":"2026-03-31T20:13:06.20799Z","caller":"traceutil/trace.go:171","msg":"trace[35615284] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"1.684968912s","start":"2026-03-31T20:13:04.522998Z","end":"2026-03-31T20:13:06.207966Z","steps":["trace[35615284] 'read index received' (duration: 1.684776107s)","trace[35615284] 'applied index is now lower than readState.Index' (duration: 192.095µs)"],"step_count":2} {"level":"warn","ts":"2026-03-31T20:13:06.208407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138095755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-31T20:13:06.208373Z","caller":"traceutil/trace.go:171","msg":"trace[926470983] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"1.738572183s","start":"2026-03-31T20:13:04.469727Z","end":"2026-03-31T20:13:06.208299Z","steps":["trace[926470983] 'process raft request' (duration: 1.738105739s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:06.20853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110071811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-31T20:13:06.208558Z","caller":"traceutil/trace.go:171","msg":"trace[616860667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"1.110133233s","start":"2026-03-31T20:13:05.098417Z","end":"2026-03-31T20:13:06.208551Z","steps":["trace[616860667] 'agreement among raft nodes before linearized reading' (duration: 1.110053211s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:06.208588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:05.098398Z","time spent":"1.110182515s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-31T20:13:06.208772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:04.469714Z","time spent":"1.738785379s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-31T20:13:06.208449Z","caller":"traceutil/trace.go:171","msg":"trace[774328941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"1.138174558s","start":"2026-03-31T20:13:05.070258Z","end":"2026-03-31T20:13:06.208433Z","steps":["trace[774328941] 'agreement among raft nodes before linearized reading' (duration: 1.138031313s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:06.208947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:05.070244Z","time spent":"1.138694652s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-31T20:13:06.208888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.639427309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-31T20:13:06.209204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139546977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-31T20:13:06.20924Z","caller":"traceutil/trace.go:171","msg":"trace[585757724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.139637s","start":"2026-03-31T20:13:05.069593Z","end":"2026-03-31T20:13:06.20923Z","steps":["trace[585757724] 'agreement among raft nodes before linearized reading' (duration: 1.139543998s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:06.209267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:05.069537Z","time spent":"1.139722462s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-31T20:13:06.209129Z","caller":"traceutil/trace.go:171","msg":"trace[805973785] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:777; }","duration":"1.639711327s","start":"2026-03-31T20:13:04.569401Z","end":"2026-03-31T20:13:06.209113Z","steps":["trace[805973785] 'agreement among raft nodes before linearized reading' (duration: 1.63877122s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:06.208791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134782808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-31T20:13:06.209353Z","caller":"traceutil/trace.go:171","msg":"trace[1420658628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"1.135361465s","start":"2026-03-31T20:13:05.073982Z","end":"2026-03-31T20:13:06.209343Z","steps":["trace[1420658628] 'agreement among raft nodes before linearized reading' (duration: 1.134743577s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:06.209355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:04.56938Z","time spent":"1.639962905s","remote":"127.0.0.1:43446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-03-31T20:13:06.209381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:05.073971Z","time spent":"1.135403286s","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-31T20:13:06.209422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686428794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vh9xm\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-03-31T20:13:06.209467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287716376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-31T20:13:06.209503Z","caller":"traceutil/trace.go:171","msg":"trace[2133364579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"1.287788148s","start":"2026-03-31T20:13:04.921705Z","end":"2026-03-31T20:13:06.209493Z","steps":["trace[2133364579] 'agreement among raft nodes before linearized reading' (duration: 1.287735447s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:06.209527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:04.921682Z","time spent":"1.28783794s","remote":"127.0.0.1:43168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-31T20:13:06.209461Z","caller":"traceutil/trace.go:171","msg":"trace[1994988214] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vh9xm; range_end:; response_count:1; response_revision:777; }","duration":"1.686521517s","start":"2026-03-31T20:13:04.522923Z","end":"2026-03-31T20:13:06.209445Z","steps":["trace[1994988214] 'agreement among raft nodes before linearized reading' (duration: 1.685160918s)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:13:06.209716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:13:04.522892Z","time spent":"1.686814215s","remote":"127.0.0.1:43358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vh9xm\" "} {"level":"info","ts":"2026-03-31T20:14:50.92406Z","caller":"traceutil/trace.go:171","msg":"trace[2082359159] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"111.488199ms","start":"2026-03-31T20:14:50.812541Z","end":"2026-03-31T20:14:50.92403Z","steps":["trace[2082359159] 'read index received' (duration: 111.226015ms)","trace[2082359159] 'applied index is now lower than readState.Index' (duration: 260.814µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:50.924123Z","caller":"traceutil/trace.go:171","msg":"trace[946312953] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"121.588749ms","start":"2026-03-31T20:14:50.802497Z","end":"2026-03-31T20:14:50.924085Z","steps":["trace[946312953] 'process raft request' (duration: 121.331186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:50.924336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.762212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T20:14:50.924401Z","caller":"traceutil/trace.go:171","msg":"trace[1484006847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1283; }","duration":"111.867394ms","start":"2026-03-31T20:14:50.812514Z","end":"2026-03-31T20:14:50.924381Z","steps":["trace[1484006847] 'agreement among raft nodes before linearized reading' (duration: 111.687801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:50.924515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.108792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T20:14:50.92462Z","caller":"traceutil/trace.go:171","msg":"trace[1418491977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1283; }","duration":"111.238104ms","start":"2026-03-31T20:14:50.813353Z","end":"2026-03-31T20:14:50.924591Z","steps":["trace[1418491977] 'agreement among raft nodes before linearized reading' (duration: 110.977881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:50.924683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.67007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:14:50.924732Z","caller":"traceutil/trace.go:171","msg":"trace[1467402072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1283; }","duration":"106.778522ms","start":"2026-03-31T20:14:50.81794Z","end":"2026-03-31T20:14:50.924719Z","steps":["trace[1467402072] 'agreement among raft nodes before linearized reading' (duration: 106.574269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:50.92487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.929796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T20:14:50.924923Z","caller":"traceutil/trace.go:171","msg":"trace[1555208243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"110.002337ms","start":"2026-03-31T20:14:50.814905Z","end":"2026-03-31T20:14:50.924908Z","steps":["trace[1555208243] 'agreement among raft nodes before linearized reading' (duration: 109.860775ms)"],"step_count":1} {"level":"info","ts":"2026-03-31T20:14:59.458075Z","caller":"traceutil/trace.go:171","msg":"trace[1214777597] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"480.53651ms","start":"2026-03-31T20:14:58.977512Z","end":"2026-03-31T20:14:59.458049Z","steps":["trace[1214777597] 'read index received' (duration: 480.389617ms)","trace[1214777597] 'applied index is now lower than readState.Index' (duration: 145.743µs)"],"step_count":2} {"level":"info","ts":"2026-03-31T20:14:59.458135Z","caller":"traceutil/trace.go:171","msg":"trace[1933136094] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"480.724033ms","start":"2026-03-31T20:14:58.97738Z","end":"2026-03-31T20:14:59.458104Z","steps":["trace[1933136094] 'process raft request' (duration: 480.511519ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:59.458287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:58.977365Z","time spent":"480.838134ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:14:59.458329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.801744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-31T20:14:59.458365Z","caller":"traceutil/trace.go:171","msg":"trace[880379587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1317; }","duration":"480.893875ms","start":"2026-03-31T20:14:58.97746Z","end":"2026-03-31T20:14:59.458354Z","steps":["trace[880379587] 'agreement among raft nodes before linearized reading' (duration: 480.716293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:59.458404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:58.977435Z","time spent":"480.959346ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-31T20:14:59.470663Z","caller":"traceutil/trace.go:171","msg":"trace[1521846397] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"340.803081ms","start":"2026-03-31T20:14:59.12984Z","end":"2026-03-31T20:14:59.470643Z","steps":["trace[1521846397] 'process raft request' (duration: 340.69542ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:59.470801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:59.129817Z","time spent":"340.909232ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-31T20:14:59.470952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.363317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-31T20:14:59.471005Z","caller":"traceutil/trace.go:171","msg":"trace[1485728034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1318; }","duration":"491.468668ms","start":"2026-03-31T20:14:58.979525Z","end":"2026-03-31T20:14:59.470993Z","steps":["trace[1485728034] 'agreement among raft nodes before linearized reading' (duration: 491.335816ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:59.471038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:58.979499Z","time spent":"491.532079ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-31T20:14:59.471036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.223655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-31T20:14:59.471091Z","caller":"traceutil/trace.go:171","msg":"trace[1385754212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"491.303846ms","start":"2026-03-31T20:14:58.979773Z","end":"2026-03-31T20:14:59.471077Z","steps":["trace[1385754212] 'agreement among raft nodes before linearized reading' (duration: 491.151804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:59.471125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:58.979764Z","time spent":"491.355206ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-31T20:14:59.471107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.179234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-31T20:14:59.471245Z","caller":"traceutil/trace.go:171","msg":"trace[1774623474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1318; }","duration":"491.377197ms","start":"2026-03-31T20:14:58.979853Z","end":"2026-03-31T20:14:59.47123Z","steps":["trace[1774623474] 'agreement among raft nodes before linearized reading' (duration: 491.038352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-31T20:14:59.471318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-31T20:14:58.979841Z","time spent":"491.464258ms","remote":"127.0.0.1:43416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}