{"level":"warn","ts":"2026-02-23T01:03:35.416097Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-23T01:03:35.417216Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-02-23T01:03:35.417386Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-23T01:03:35.417408Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-23T01:03:35.417437Z","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-02-23T01:03:35.418445Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-02-23T01:03:35.418547Z","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.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-02-23T01:03:35.432631Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.67717ms"} {"level":"info","ts":"2026-02-23T01:03:35.46116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-02-23T01:03:35.461307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-02-23T01:03:35.46136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-02-23T01:03:35.461434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-23T01:03:35.461454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-02-23T01:03:35.461501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-02-23T01:03:35.4763Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-23T01:03:35.478678Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-23T01:03:35.482405Z","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-02-23T01:03:35.486197Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-23T01:03:35.486314Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-23T01:03:35.486558Z","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-02-23T01:03:35.486662Z","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-02-23T01:03:35.48669Z","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-02-23T01:03:35.488169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-02-23T01:03:35.488435Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-23T01:03:35.493909Z","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-02-23T01:03:35.493998Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-23T01:03:35.497498Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-23T01:03:35.497659Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-23T01:03:35.497724Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-23T01:03:35.962672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-02-23T01:03:35.962849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-23T01:03:35.962895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-02-23T01:03:35.962928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-02-23T01:03:35.962946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-23T01:03:35.962965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-02-23T01:03:35.962987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-23T01:03:35.968596Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-23T01:03:35.968863Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T01:03:35.969012Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T01:03:35.969038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T01:03:35.969372Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-23T01:03:35.969867Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-23T01:03:35.972597Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T01:03:35.97273Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T01:03:35.972768Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T01:03:35.973505Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-23T01:03:35.975155Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"warn","ts":"2026-02-23T01:03:40.335738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582775589512944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T01:03:40.832411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29395365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:40.832513Z","caller":"traceutil/trace.go:171","msg":"trace[193328742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:203; }","duration":"1.294114795s","start":"2026-02-23T01:03:39.538377Z","end":"2026-02-23T01:03:40.832492Z","steps":["trace[193328742] 'range keys from in-memory index tree' (duration: 1.293876839s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:40.832587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:39.538358Z","time spent":"1.294203379s","remote":"127.0.0.1:49334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-23T01:03:40.836288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582775589512944,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T01:03:40.879782Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156085431s","expected-duration":"1s"} {"level":"info","ts":"2026-02-23T01:03:40.88015Z","caller":"traceutil/trace.go:171","msg":"trace[2008678464] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:208; }","duration":"1.045083322s","start":"2026-02-23T01:03:39.835031Z","end":"2026-02-23T01:03:40.880114Z","steps":["trace[2008678464] 'read index received' (duration: 1.044973739s)","trace[2008678464] 'applied index is now lower than readState.Index' (duration: 108.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:40.880241Z","caller":"traceutil/trace.go:171","msg":"trace[916989245] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"1.156661507s","start":"2026-02-23T01:03:39.723554Z","end":"2026-02-23T01:03:40.880215Z","steps":["trace[916989245] 'process raft request' (duration: 1.15641384s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:40.880355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04531173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:40.880402Z","caller":"traceutil/trace.go:171","msg":"trace[894613220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:204; }","duration":"1.045417022s","start":"2026-02-23T01:03:39.834974Z","end":"2026-02-23T01:03:40.880391Z","steps":["trace[894613220] 'agreement among raft nodes before linearized reading' (duration: 1.045270008s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:40.880439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:39.834951Z","time spent":"1.045479953s","remote":"127.0.0.1:49334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-23T01:03:40.880763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:39.723525Z","time spent":"1.156767851s","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3937,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T01:03:40.897737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024631058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3181"} {"level":"info","ts":"2026-02-23T01:03:40.897797Z","caller":"traceutil/trace.go:171","msg":"trace[390754557] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:205; }","duration":"1.024738591s","start":"2026-02-23T01:03:39.873043Z","end":"2026-02-23T01:03:40.897781Z","steps":["trace[390754557] 'agreement among raft nodes before linearized reading' (duration: 1.024553906s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:40.897864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:39.873014Z","time spent":"1.024841164s","remote":"127.0.0.1:49252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3205,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-23T01:03:41.063263Z","caller":"traceutil/trace.go:171","msg":"trace[806025418] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"148.202919ms","start":"2026-02-23T01:03:40.915013Z","end":"2026-02-23T01:03:41.063216Z","steps":["trace[806025418] 'read index received' (duration: 101.80829ms)","trace[806025418] 'applied index is now lower than readState.Index' (duration: 46.393529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:41.063398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.359923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-23T01:03:41.063368Z","caller":"traceutil/trace.go:171","msg":"trace[1076936044] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"157.82719ms","start":"2026-02-23T01:03:40.905507Z","end":"2026-02-23T01:03:41.063334Z","steps":["trace[1076936044] 'process raft request' (duration: 111.231876ms)","trace[1076936044] 'compare' (duration: 46.334908ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:41.063442Z","caller":"traceutil/trace.go:171","msg":"trace[1697534942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:207; }","duration":"148.460646ms","start":"2026-02-23T01:03:40.91497Z","end":"2026-02-23T01:03:41.063431Z","steps":["trace[1697534942] 'agreement among raft nodes before linearized reading' (duration: 148.365483ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:41.063392Z","caller":"traceutil/trace.go:171","msg":"trace[248200143] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"146.790722ms","start":"2026-02-23T01:03:40.91659Z","end":"2026-02-23T01:03:41.06338Z","steps":["trace[248200143] 'process raft request' (duration: 146.71415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:41.347818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.261186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:41.348347Z","caller":"traceutil/trace.go:171","msg":"trace[2007560126] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"277.661131ms","start":"2026-02-23T01:03:41.070522Z","end":"2026-02-23T01:03:41.348183Z","steps":["trace[2007560126] 'process raft request' (duration: 116.813028ms)","trace[2007560126] 'compare' (duration: 160.089012ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:41.540194Z","caller":"traceutil/trace.go:171","msg":"trace[1792167153] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"167.739449ms","start":"2026-02-23T01:03:41.372425Z","end":"2026-02-23T01:03:41.540165Z","steps":["trace[1792167153] 'process raft request' (duration: 157.449439ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:41.587984Z","caller":"traceutil/trace.go:171","msg":"trace[282322411] linearizableReadLoop","detail":"{readStateIndex:219; appliedIndex:216; }","duration":"205.842222ms","start":"2026-02-23T01:03:41.382121Z","end":"2026-02-23T01:03:41.587963Z","steps":["trace[282322411] 'read index received' (duration: 147.799908ms)","trace[282322411] 'applied index is now lower than readState.Index' (duration: 58.041164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:41.588095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.949915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:41.588151Z","caller":"traceutil/trace.go:171","msg":"trace[898448954] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:213; }","duration":"206.045958ms","start":"2026-02-23T01:03:41.382086Z","end":"2026-02-23T01:03:41.588132Z","steps":["trace[898448954] 'agreement among raft nodes before linearized reading' (duration: 205.956855ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:41.588137Z","caller":"traceutil/trace.go:171","msg":"trace[1383871301] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"211.162646ms","start":"2026-02-23T01:03:41.376938Z","end":"2026-02-23T01:03:41.588101Z","steps":["trace[1383871301] 'process raft request' (duration: 210.93287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:41.85312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.122471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:41.853495Z","caller":"traceutil/trace.go:171","msg":"trace[646372477] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"263.681188ms","start":"2026-02-23T01:03:41.589751Z","end":"2026-02-23T01:03:41.853432Z","steps":["trace[646372477] 'process raft request' (duration: 147.969491ms)","trace[646372477] 'compare' (duration: 114.854504ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:41.853694Z","caller":"traceutil/trace.go:171","msg":"trace[239641434] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"212.648676ms","start":"2026-02-23T01:03:41.640991Z","end":"2026-02-23T01:03:41.85364Z","steps":["trace[239641434] 'process raft request' (duration: 212.465271ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:41.853747Z","caller":"traceutil/trace.go:171","msg":"trace[546761942] linearizableReadLoop","detail":"{readStateIndex:221; appliedIndex:219; }","duration":"262.698522ms","start":"2026-02-23T01:03:41.590995Z","end":"2026-02-23T01:03:41.853693Z","steps":["trace[546761942] 'read index received' (duration: 46.323516ms)","trace[546761942] 'applied index is now lower than readState.Index' (duration: 216.370126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:41.853939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.926808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-23T01:03:41.853987Z","caller":"traceutil/trace.go:171","msg":"trace[728324204] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:216; }","duration":"263.018421ms","start":"2026-02-23T01:03:41.590957Z","end":"2026-02-23T01:03:41.853975Z","steps":["trace[728324204] 'agreement among raft nodes before linearized reading' (duration: 262.849356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:41.853963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.287872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:41.854044Z","caller":"traceutil/trace.go:171","msg":"trace[7386377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:216; }","duration":"158.415925ms","start":"2026-02-23T01:03:41.695607Z","end":"2026-02-23T01:03:41.854023Z","steps":["trace[7386377] 'agreement among raft nodes before linearized reading' (duration: 158.285812ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:42.100875Z","caller":"traceutil/trace.go:171","msg":"trace[1375691326] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"242.861246ms","start":"2026-02-23T01:03:41.857977Z","end":"2026-02-23T01:03:42.100838Z","steps":["trace[1375691326] 'process raft request' (duration: 167.479521ms)","trace[1375691326] 'compare' (duration: 75.028906ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:42.101002Z","caller":"traceutil/trace.go:171","msg":"trace[1075126674] linearizableReadLoop","detail":"{readStateIndex:224; appliedIndex:223; }","duration":"179.625482ms","start":"2026-02-23T01:03:41.92133Z","end":"2026-02-23T01:03:42.100955Z","steps":["trace[1075126674] 'read index received' (duration: 104.133605ms)","trace[1075126674] 'applied index is now lower than readState.Index' (duration: 75.488427ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:42.10122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.894699ms","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-02-23T01:03:42.101274Z","caller":"traceutil/trace.go:171","msg":"trace[149530578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:218; }","duration":"180.013152ms","start":"2026-02-23T01:03:41.921248Z","end":"2026-02-23T01:03:42.101261Z","steps":["trace[149530578] 'agreement among raft nodes before linearized reading' (duration: 179.839168ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:42.10154Z","caller":"traceutil/trace.go:171","msg":"trace[1823991363] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"179.621382ms","start":"2026-02-23T01:03:41.921894Z","end":"2026-02-23T01:03:42.101515Z","steps":["trace[1823991363] 'process raft request' (duration: 179.494429ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:42.319076Z","caller":"traceutil/trace.go:171","msg":"trace[559327507] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"199.830639ms","start":"2026-02-23T01:03:42.119212Z","end":"2026-02-23T01:03:42.319043Z","steps":["trace[559327507] 'process raft request' (duration: 126.477319ms)","trace[559327507] 'compare' (duration: 73.225086ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:42.507921Z","caller":"traceutil/trace.go:171","msg":"trace[1252586495] linearizableReadLoop","detail":"{readStateIndex:229; appliedIndex:228; }","duration":"116.406197ms","start":"2026-02-23T01:03:42.39149Z","end":"2026-02-23T01:03:42.507896Z","steps":["trace[1252586495] 'read index received' (duration: 89.278451ms)","trace[1252586495] 'applied index is now lower than readState.Index' (duration: 27.126306ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:42.508002Z","caller":"traceutil/trace.go:171","msg":"trace[162879616] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"116.677234ms","start":"2026-02-23T01:03:42.391275Z","end":"2026-02-23T01:03:42.507952Z","steps":["trace[162879616] 'process raft request' (duration: 89.587229ms)","trace[162879616] 'compare' (duration: 26.885449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:42.508049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.53668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-23T01:03:42.508082Z","caller":"traceutil/trace.go:171","msg":"trace[867677315] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:223; }","duration":"116.633473ms","start":"2026-02-23T01:03:42.391438Z","end":"2026-02-23T01:03:42.508071Z","steps":["trace[867677315] 'agreement among raft nodes before linearized reading' (duration: 116.549491ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:42.878871Z","caller":"traceutil/trace.go:171","msg":"trace[425476940] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"139.607437ms","start":"2026-02-23T01:03:42.739234Z","end":"2026-02-23T01:03:42.878842Z","steps":["trace[425476940] 'process raft request' (duration: 139.289088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:43.048497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.233779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-23T01:03:43.049298Z","caller":"traceutil/trace.go:171","msg":"trace[654903595] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:233; }","duration":"135.103084ms","start":"2026-02-23T01:03:42.914167Z","end":"2026-02-23T01:03:43.04927Z","steps":["trace[654903595] 'range keys from in-memory index tree' (duration: 130.544979ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:43.071108Z","caller":"traceutil/trace.go:171","msg":"trace[926989849] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"154.651594ms","start":"2026-02-23T01:03:42.916438Z","end":"2026-02-23T01:03:43.07109Z","steps":["trace[926989849] 'process raft request' (duration: 154.437138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:43.294805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.634388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:43.295027Z","caller":"traceutil/trace.go:171","msg":"trace[551835487] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"165.059925ms","start":"2026-02-23T01:03:43.129943Z","end":"2026-02-23T01:03:43.295002Z","steps":["trace[551835487] 'process raft request' (duration: 164.995183ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:43.295164Z","caller":"traceutil/trace.go:171","msg":"trace[670004791] linearizableReadLoop","detail":"{readStateIndex:243; appliedIndex:241; }","duration":"203.000916ms","start":"2026-02-23T01:03:43.092068Z","end":"2026-02-23T01:03:43.295069Z","steps":["trace[670004791] 'read index received' (duration: 33.846908ms)","trace[670004791] 'applied index is now lower than readState.Index' (duration: 169.150438ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:43.295116Z","caller":"traceutil/trace.go:171","msg":"trace[44195122] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"203.152459ms","start":"2026-02-23T01:03:43.091848Z","end":"2026-02-23T01:03:43.295001Z","steps":["trace[44195122] 'process raft request' (duration: 67.013817ms)","trace[44195122] 'compare' (duration: 135.499315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:43.295355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.247792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:43.295436Z","caller":"traceutil/trace.go:171","msg":"trace[1603472118] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:238; }","duration":"203.376106ms","start":"2026-02-23T01:03:43.092038Z","end":"2026-02-23T01:03:43.295414Z","steps":["trace[1603472118] 'agreement among raft nodes before linearized reading' (duration: 203.179561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:43.295673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.066951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-23T01:03:43.295738Z","caller":"traceutil/trace.go:171","msg":"trace[2038093448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:238; }","duration":"198.191775ms","start":"2026-02-23T01:03:43.097526Z","end":"2026-02-23T01:03:43.295718Z","steps":["trace[2038093448] 'agreement among raft nodes before linearized reading' (duration: 197.99753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:43.583133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.390321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:43.583226Z","caller":"traceutil/trace.go:171","msg":"trace[1179152481] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"280.742303ms","start":"2026-02-23T01:03:43.302466Z","end":"2026-02-23T01:03:43.583209Z","steps":["trace[1179152481] 'process raft request' (duration: 114.204067ms)","trace[1179152481] 'compare' (duration: 166.266938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:44.06937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.094868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:44.069542Z","caller":"traceutil/trace.go:171","msg":"trace[593614157] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:252; }","duration":"269.002195ms","start":"2026-02-23T01:03:43.800511Z","end":"2026-02-23T01:03:44.069513Z","steps":["trace[593614157] 'read index received' (duration: 145.790633ms)","trace[593614157] 'applied index is now lower than readState.Index' (duration: 123.210172ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:44.069592Z","caller":"traceutil/trace.go:171","msg":"trace[333287545] transaction","detail":"{read_only:false; number_of_response:0; response_revision:247; }","duration":"271.531714ms","start":"2026-02-23T01:03:43.798029Z","end":"2026-02-23T01:03:44.069561Z","steps":["trace[333287545] 'process raft request' (duration: 271.440831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.069637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.288002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-23T01:03:44.069615Z","caller":"traceutil/trace.go:171","msg":"trace[293073755] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"295.304958ms","start":"2026-02-23T01:03:43.774282Z","end":"2026-02-23T01:03:44.069587Z","steps":["trace[293073755] 'process raft request' (duration: 171.922462ms)","trace[293073755] 'compare' (duration: 122.965215ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:44.069671Z","caller":"traceutil/trace.go:171","msg":"trace[897903985] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:247; }","duration":"297.359374ms","start":"2026-02-23T01:03:43.772301Z","end":"2026-02-23T01:03:44.06966Z","steps":["trace[897903985] 'agreement among raft nodes before linearized reading' (duration: 297.273982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.06973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.878301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-23T01:03:44.069873Z","caller":"traceutil/trace.go:171","msg":"trace[1093559951] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:247; }","duration":"297.052406ms","start":"2026-02-23T01:03:43.772798Z","end":"2026-02-23T01:03:44.06985Z","steps":["trace[1093559951] 'agreement among raft nodes before linearized reading' (duration: 296.82548ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.149923Z","caller":"traceutil/trace.go:171","msg":"trace[1311608733] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"345.614144ms","start":"2026-02-23T01:03:43.804291Z","end":"2026-02-23T01:03:44.149905Z","steps":["trace[1311608733] 'process raft request' (duration: 345.48571ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.149959Z","caller":"traceutil/trace.go:171","msg":"trace[1986984262] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"345.83824ms","start":"2026-02-23T01:03:43.804102Z","end":"2026-02-23T01:03:44.14994Z","steps":["trace[1986984262] 'process raft request' (duration: 345.557932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.150035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:43.804263Z","time spent":"345.709656ms","remote":"127.0.0.1:49334","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-02-23T01:03:44.150064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:43.804091Z","time spent":"345.920552ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T01:03:44.150105Z","caller":"traceutil/trace.go:171","msg":"trace[728472604] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"336.851206ms","start":"2026-02-23T01:03:43.813236Z","end":"2026-02-23T01:03:44.150087Z","steps":["trace[728472604] 'process raft request' (duration: 336.621099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.150201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:43.813228Z","time spent":"336.917978ms","remote":"127.0.0.1:49254","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-02-23T01:03:44.150156Z","caller":"traceutil/trace.go:171","msg":"trace[1898066220] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"337.034161ms","start":"2026-02-23T01:03:43.813022Z","end":"2026-02-23T01:03:44.150056Z","steps":["trace[1898066220] 'process raft request' (duration: 336.795954ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.150366Z","caller":"traceutil/trace.go:171","msg":"trace[2062883170] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"336.396613ms","start":"2026-02-23T01:03:43.813915Z","end":"2026-02-23T01:03:44.150312Z","steps":["trace[2062883170] 'process raft request' (duration: 335.970761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.150445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:43.813012Z","time spent":"337.323118ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5855,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T01:03:44.150554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:43.813907Z","time spent":"336.560547ms","remote":"127.0.0.1:49254","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-02-23T01:03:44.150632Z","caller":"traceutil/trace.go:171","msg":"trace[349805062] transaction","detail":"{read_only:false; number_of_response:0; response_revision:250; }","duration":"336.614089ms","start":"2026-02-23T01:03:43.814004Z","end":"2026-02-23T01:03:44.150618Z","steps":["trace[349805062] 'process raft request' (duration: 336.403963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.150701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:43.813993Z","time spent":"336.67673ms","remote":"127.0.0.1:49254","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-02-23T01:03:44.151076Z","caller":"traceutil/trace.go:171","msg":"trace[1958667457] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"336.29809ms","start":"2026-02-23T01:03:43.814362Z","end":"2026-02-23T01:03:44.15066Z","steps":["trace[1958667457] 'process raft request' (duration: 336.198277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.151171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:43.814348Z","time spent":"336.792434ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T01:03:44.289639Z","caller":"traceutil/trace.go:171","msg":"trace[1734085421] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"135.037752ms","start":"2026-02-23T01:03:44.154587Z","end":"2026-02-23T01:03:44.289625Z","steps":["trace[1734085421] 'process raft request' (duration: 135.000021ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.289754Z","caller":"traceutil/trace.go:171","msg":"trace[2001842206] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"216.012858ms","start":"2026-02-23T01:03:44.073719Z","end":"2026-02-23T01:03:44.289732Z","steps":["trace[2001842206] 'process raft request' (duration: 215.827183ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.289795Z","caller":"traceutil/trace.go:171","msg":"trace[607641527] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:261; }","duration":"139.144343ms","start":"2026-02-23T01:03:44.15063Z","end":"2026-02-23T01:03:44.289774Z","steps":["trace[607641527] 'read index received' (duration: 61.307443ms)","trace[607641527] 'applied index is now lower than readState.Index' (duration: 77.83407ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:44.289937Z","caller":"traceutil/trace.go:171","msg":"trace[1506176203] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"216.400138ms","start":"2026-02-23T01:03:44.073523Z","end":"2026-02-23T01:03:44.289923Z","steps":["trace[1506176203] 'process raft request' (duration: 138.404093ms)","trace[1506176203] 'compare' (duration: 77.498001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:44.290006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.181212ms","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-02-23T01:03:44.29004Z","caller":"traceutil/trace.go:171","msg":"trace[1760307965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:254; }","duration":"171.284174ms","start":"2026-02-23T01:03:44.118746Z","end":"2026-02-23T01:03:44.29003Z","steps":["trace[1760307965] 'agreement among raft nodes before linearized reading' (duration: 171.14329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.290092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.908136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3952"} {"level":"info","ts":"2026-02-23T01:03:44.290144Z","caller":"traceutil/trace.go:171","msg":"trace[1759055188] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:254; }","duration":"139.00881ms","start":"2026-02-23T01:03:44.15112Z","end":"2026-02-23T01:03:44.290129Z","steps":["trace[1759055188] 'agreement among raft nodes before linearized reading' (duration: 138.903167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.29025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.333564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2898"} {"level":"info","ts":"2026-02-23T01:03:44.290279Z","caller":"traceutil/trace.go:171","msg":"trace[913594576] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:254; }","duration":"137.376355ms","start":"2026-02-23T01:03:44.152894Z","end":"2026-02-23T01:03:44.29027Z","steps":["trace[913594576] 'agreement among raft nodes before linearized reading' (duration: 137.318843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.290325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.644862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3706"} {"level":"warn","ts":"2026-02-23T01:03:44.290363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.411886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-23T01:03:44.290401Z","caller":"traceutil/trace.go:171","msg":"trace[2053195775] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:254; }","duration":"137.466257ms","start":"2026-02-23T01:03:44.152929Z","end":"2026-02-23T01:03:44.290395Z","steps":["trace[2053195775] 'agreement among raft nodes before linearized reading' (duration: 137.402105ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.290404Z","caller":"traceutil/trace.go:171","msg":"trace[400289183] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:254; }","duration":"137.743094ms","start":"2026-02-23T01:03:44.152622Z","end":"2026-02-23T01:03:44.290365Z","steps":["trace[400289183] 'agreement among raft nodes before linearized reading' (duration: 137.649272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.56566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.315999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:44.565737Z","caller":"traceutil/trace.go:171","msg":"trace[1565092688] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"270.67997ms","start":"2026-02-23T01:03:44.295045Z","end":"2026-02-23T01:03:44.565725Z","steps":["trace[1565092688] 'read index received' (duration: 156.260248ms)","trace[1565092688] 'applied index is now lower than readState.Index' (duration: 114.418652ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:44.565778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.733591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:44.565791Z","caller":"traceutil/trace.go:171","msg":"trace[1400207199] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:255; }","duration":"270.779312ms","start":"2026-02-23T01:03:44.295006Z","end":"2026-02-23T01:03:44.565785Z","steps":["trace[1400207199] 'agreement among raft nodes before linearized reading' (duration: 270.750042ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.565785Z","caller":"traceutil/trace.go:171","msg":"trace[565596318] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"271.609954ms","start":"2026-02-23T01:03:44.29414Z","end":"2026-02-23T01:03:44.56575Z","steps":["trace[565596318] 'process raft request' (duration: 157.131051ms)","trace[565596318] 'compare' (duration: 114.206646ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:44.597429Z","caller":"traceutil/trace.go:171","msg":"trace[1146048829] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"301.458793ms","start":"2026-02-23T01:03:44.29596Z","end":"2026-02-23T01:03:44.597419Z","steps":["trace[1146048829] 'process raft request' (duration: 301.308479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.597477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:44.295943Z","time spent":"301.513025ms","remote":"127.0.0.1:49152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T01:03:44.597498Z","caller":"traceutil/trace.go:171","msg":"trace[16494766] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"295.174123ms","start":"2026-02-23T01:03:44.302293Z","end":"2026-02-23T01:03:44.597467Z","steps":["trace[16494766] 'process raft request' (duration: 295.04681ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.597673Z","caller":"traceutil/trace.go:171","msg":"trace[1199656734] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"290.757782ms","start":"2026-02-23T01:03:44.306906Z","end":"2026-02-23T01:03:44.597664Z","steps":["trace[1199656734] 'process raft request' (duration: 290.488095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.950731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.980521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:44.950933Z","caller":"traceutil/trace.go:171","msg":"trace[228154436] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"223.07619ms","start":"2026-02-23T01:03:44.727841Z","end":"2026-02-23T01:03:44.950917Z","steps":["trace[228154436] 'process raft request' (duration: 223.032669ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:44.950975Z","caller":"traceutil/trace.go:171","msg":"trace[1952684915] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"300.282344ms","start":"2026-02-23T01:03:44.650663Z","end":"2026-02-23T01:03:44.950946Z","steps":["trace[1952684915] 'read index received' (duration: 68.009215ms)","trace[1952684915] 'applied index is now lower than readState.Index' (duration: 232.270329ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:44.951102Z","caller":"traceutil/trace.go:171","msg":"trace[1129493420] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"301.675091ms","start":"2026-02-23T01:03:44.649201Z","end":"2026-02-23T01:03:44.950876Z","steps":["trace[1129493420] 'process raft request' (duration: 69.471994ms)","trace[1129493420] 'compare' (duration: 231.828076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:44.951246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:44.649186Z","time spent":"301.994459ms","remote":"127.0.0.1:49270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1196,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T01:03:44.951436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.715255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-23T01:03:44.951535Z","caller":"traceutil/trace.go:171","msg":"trace[1502595713] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:261; }","duration":"300.89261ms","start":"2026-02-23T01:03:44.650625Z","end":"2026-02-23T01:03:44.951517Z","steps":["trace[1502595713] 'agreement among raft nodes before linearized reading' (duration: 300.576002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:44.951628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:44.650613Z","time spent":"300.998343ms","remote":"127.0.0.1:49200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-23T01:03:45.21338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.753371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-02-23T01:03:45.213557Z","caller":"traceutil/trace.go:171","msg":"trace[121503727] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:272; }","duration":"262.397217ms","start":"2026-02-23T01:03:44.95114Z","end":"2026-02-23T01:03:45.213538Z","steps":["trace[121503727] 'read index received' (duration: 108.4056ms)","trace[121503727] 'applied index is now lower than readState.Index' (duration: 153.990157ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:45.213607Z","caller":"traceutil/trace.go:171","msg":"trace[1391772189] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"485.425126ms","start":"2026-02-23T01:03:44.728147Z","end":"2026-02-23T01:03:45.213572Z","steps":["trace[1391772189] 'process raft request' (duration: 331.414338ms)","trace[1391772189] 'compare' (duration: 153.71432ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:45.213632Z","caller":"traceutil/trace.go:171","msg":"trace[1427111270] transaction","detail":"{read_only:false; number_of_response:0; response_revision:261; }","duration":"484.533442ms","start":"2026-02-23T01:03:44.729077Z","end":"2026-02-23T01:03:45.213611Z","steps":["trace[1427111270] 'process raft request' (duration: 484.397488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.21372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.063159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5870"} {"level":"warn","ts":"2026-02-23T01:03:45.213744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:44.728137Z","time spent":"485.548799ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T01:03:45.213752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:44.728996Z","time spent":"484.693096ms","remote":"127.0.0.1:49254","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-02-23T01:03:45.213784Z","caller":"traceutil/trace.go:171","msg":"trace[192709018] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:261; }","duration":"487.179452ms","start":"2026-02-23T01:03:44.72659Z","end":"2026-02-23T01:03:45.21377Z","steps":["trace[192709018] 'agreement among raft nodes before linearized reading' (duration: 487.041008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.213821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:03:44.726568Z","time spent":"487.244964ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5894,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-23T01:03:45.221197Z","caller":"traceutil/trace.go:171","msg":"trace[961381746] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"265.298216ms","start":"2026-02-23T01:03:44.955882Z","end":"2026-02-23T01:03:45.22118Z","steps":["trace[961381746] 'process raft request' (duration: 265.148592ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:45.221223Z","caller":"traceutil/trace.go:171","msg":"trace[718868810] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"263.665411ms","start":"2026-02-23T01:03:44.957542Z","end":"2026-02-23T01:03:45.221208Z","steps":["trace[718868810] 'process raft request' (duration: 263.590579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.221285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.329155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-23T01:03:45.221342Z","caller":"traceutil/trace.go:171","msg":"trace[553190808] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:263; }","duration":"269.435618ms","start":"2026-02-23T01:03:44.951892Z","end":"2026-02-23T01:03:45.221327Z","steps":["trace[553190808] 'agreement among raft nodes before linearized reading' (duration: 269.313655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.490875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.457448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:45.491028Z","caller":"traceutil/trace.go:171","msg":"trace[1322267670] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:277; }","duration":"192.051546ms","start":"2026-02-23T01:03:45.298959Z","end":"2026-02-23T01:03:45.49101Z","steps":["trace[1322267670] 'read index received' (duration: 14.01656ms)","trace[1322267670] 'applied index is now lower than readState.Index' (duration: 178.033926ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:45.491061Z","caller":"traceutil/trace.go:171","msg":"trace[684992895] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"245.866246ms","start":"2026-02-23T01:03:45.24517Z","end":"2026-02-23T01:03:45.491037Z","steps":["trace[684992895] 'process raft request' (duration: 245.795704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.491162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.203001ms","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-02-23T01:03:45.491207Z","caller":"traceutil/trace.go:171","msg":"trace[1023943450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:267; }","duration":"192.280553ms","start":"2026-02-23T01:03:45.298917Z","end":"2026-02-23T01:03:45.491197Z","steps":["trace[1023943450] 'agreement among raft nodes before linearized reading' (duration: 192.17947ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:45.491213Z","caller":"traceutil/trace.go:171","msg":"trace[1406196885] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"247.258794ms","start":"2026-02-23T01:03:45.243944Z","end":"2026-02-23T01:03:45.491203Z","steps":["trace[1406196885] 'process raft request' (duration: 96.400705ms)","trace[1406196885] 'compare' (duration: 150.322105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:45.491322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.145711ms","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-02-23T01:03:45.491344Z","caller":"traceutil/trace.go:171","msg":"trace[1219677167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:267; }","duration":"143.207334ms","start":"2026-02-23T01:03:45.348132Z","end":"2026-02-23T01:03:45.491339Z","steps":["trace[1219677167] 'agreement among raft nodes before linearized reading' (duration: 143.133771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.491398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.636122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5491"} {"level":"info","ts":"2026-02-23T01:03:45.491449Z","caller":"traceutil/trace.go:171","msg":"trace[1681739045] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:267; }","duration":"175.735854ms","start":"2026-02-23T01:03:45.315701Z","end":"2026-02-23T01:03:45.491437Z","steps":["trace[1681739045] 'agreement among raft nodes before linearized reading' (duration: 175.619851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.491345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.535033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:45.491606Z","caller":"traceutil/trace.go:171","msg":"trace[860571197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:267; }","duration":"184.855561ms","start":"2026-02-23T01:03:45.306734Z","end":"2026-02-23T01:03:45.491589Z","steps":["trace[860571197] 'agreement among raft nodes before linearized reading' (duration: 184.558052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.728447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.057933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:45.72855Z","caller":"traceutil/trace.go:171","msg":"trace[1315237701] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"146.280757ms","start":"2026-02-23T01:03:45.582251Z","end":"2026-02-23T01:03:45.728532Z","steps":["trace[1315237701] 'read index received' (duration: 11.07576ms)","trace[1315237701] 'applied index is now lower than readState.Index' (duration: 135.203597ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:45.728633Z","caller":"traceutil/trace.go:171","msg":"trace[1530868959] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"146.467273ms","start":"2026-02-23T01:03:45.582155Z","end":"2026-02-23T01:03:45.728622Z","steps":["trace[1530868959] 'process raft request' (duration: 11.163883ms)","trace[1530868959] 'compare' (duration: 134.900649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:45.728757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.490353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-23T01:03:45.72885Z","caller":"traceutil/trace.go:171","msg":"trace[228010462] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:272; }","duration":"146.587685ms","start":"2026-02-23T01:03:45.582246Z","end":"2026-02-23T01:03:45.728834Z","steps":["trace[228010462] 'agreement among raft nodes before linearized reading' (duration: 146.37596ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:45.777987Z","caller":"traceutil/trace.go:171","msg":"trace[917642563] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"191.469413ms","start":"2026-02-23T01:03:45.586504Z","end":"2026-02-23T01:03:45.777973Z","steps":["trace[917642563] 'process raft request' (duration: 191.38025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:45.778125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.078215ms","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-02-23T01:03:45.778204Z","caller":"traceutil/trace.go:171","msg":"trace[64517734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:273; }","duration":"110.208218ms","start":"2026-02-23T01:03:45.667974Z","end":"2026-02-23T01:03:45.778183Z","steps":["trace[64517734] 'agreement among raft nodes before linearized reading' (duration: 110.030224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:46.045099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.95387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:03:46.045202Z","caller":"traceutil/trace.go:171","msg":"trace[365843984] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:288; }","duration":"243.306349ms","start":"2026-02-23T01:03:45.801879Z","end":"2026-02-23T01:03:46.045186Z","steps":["trace[365843984] 'read index received' (duration: 28.105092ms)","trace[365843984] 'applied index is now lower than readState.Index' (duration: 215.200307ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:46.045334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.762151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:46.045372Z","caller":"traceutil/trace.go:171","msg":"trace[106102823] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:276; }","duration":"239.837253ms","start":"2026-02-23T01:03:45.805521Z","end":"2026-02-23T01:03:46.045358Z","steps":["trace[106102823] 'agreement among raft nodes before linearized reading' (duration: 239.778992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:03:46.045361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.539457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3444"} {"level":"info","ts":"2026-02-23T01:03:46.045375Z","caller":"traceutil/trace.go:171","msg":"trace[1033159138] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"263.28541ms","start":"2026-02-23T01:03:45.782058Z","end":"2026-02-23T01:03:46.045343Z","steps":["trace[1033159138] 'process raft request' (duration: 47.998871ms)","trace[1033159138] 'compare' (duration: 214.801456ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:46.045445Z","caller":"traceutil/trace.go:171","msg":"trace[353614208] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:276; }","duration":"263.66069ms","start":"2026-02-23T01:03:45.781764Z","end":"2026-02-23T01:03:46.045424Z","steps":["trace[353614208] 'agreement among raft nodes before linearized reading' (duration: 263.527866ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:03:46.333407Z","caller":"traceutil/trace.go:171","msg":"trace[832450722] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"241.15923ms","start":"2026-02-23T01:03:46.092222Z","end":"2026-02-23T01:03:46.333381Z","steps":["trace[832450722] 'read index received' (duration: 159.629159ms)","trace[832450722] 'applied index is now lower than readState.Index' (duration: 81.529181ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:03:46.333479Z","caller":"traceutil/trace.go:171","msg":"trace[2130675383] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"278.150673ms","start":"2026-02-23T01:03:46.055296Z","end":"2026-02-23T01:03:46.333447Z","steps":["trace[2130675383] 'process raft request' (duration: 196.634272ms)","trace[2130675383] 'compare' (duration: 81.334266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:03:46.333757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.506739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:03:46.333846Z","caller":"traceutil/trace.go:171","msg":"trace[1245437848] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:278; }","duration":"241.649413ms","start":"2026-02-23T01:03:46.092177Z","end":"2026-02-23T01:03:46.333827Z","steps":["trace[1245437848] 'agreement among raft nodes before linearized reading' (duration: 241.480018ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:04:20.248895Z","caller":"traceutil/trace.go:171","msg":"trace[1221902279] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"292.676797ms","start":"2026-02-23T01:04:19.956187Z","end":"2026-02-23T01:04:20.248864Z","steps":["trace[1221902279] 'read index received' (duration: 292.449651ms)","trace[1221902279] 'applied index is now lower than readState.Index' (duration: 226.176µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:04:20.248969Z","caller":"traceutil/trace.go:171","msg":"trace[1582313799] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"405.64618ms","start":"2026-02-23T01:04:19.843279Z","end":"2026-02-23T01:04:20.248925Z","steps":["trace[1582313799] 'process raft request' (duration: 405.468185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:04:20.249078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.724962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T01:04:20.2491Z","caller":"traceutil/trace.go:171","msg":"trace[1719047936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"225.785123ms","start":"2026-02-23T01:04:20.023309Z","end":"2026-02-23T01:04:20.249094Z","steps":["trace[1719047936] 'agreement among raft nodes before linearized reading' (duration: 225.757752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:04:20.249208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:04:19.843247Z","time spent":"405.834435ms","remote":"127.0.0.1:49254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T01:04:20.249043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.842832ms","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-02-23T01:04:20.249309Z","caller":"traceutil/trace.go:171","msg":"trace[226881377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:550; }","duration":"293.194461ms","start":"2026-02-23T01:04:19.956109Z","end":"2026-02-23T01:04:20.249303Z","steps":["trace[226881377] 'agreement among raft nodes before linearized reading' (duration: 292.874132ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:04:33.049629Z","caller":"traceutil/trace.go:171","msg":"trace[1642898414] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"114.101544ms","start":"2026-02-23T01:04:32.935499Z","end":"2026-02-23T01:04:33.049601Z","steps":["trace[1642898414] 'process raft request' (duration: 113.813216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:04:38.691247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.91664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T01:04:38.691345Z","caller":"traceutil/trace.go:171","msg":"trace[1954885516] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"326.062262ms","start":"2026-02-23T01:04:38.365266Z","end":"2026-02-23T01:04:38.691328Z","steps":["trace[1954885516] 'process raft request' (duration: 197.994368ms)","trace[1954885516] 'compare' (duration: 127.813467ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:04:38.691399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T01:04:38.365251Z","time spent":"326.124913ms","remote":"127.0.0.1:49334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T01:04:39.407956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.102961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-23T01:04:39.408071Z","caller":"traceutil/trace.go:171","msg":"trace[2118298798] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:693; }","duration":"219.268356ms","start":"2026-02-23T01:04:39.188776Z","end":"2026-02-23T01:04:39.408045Z","steps":["trace[2118298798] 'range keys from in-memory index tree' (duration: 218.973158ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:05:02.080729Z","caller":"traceutil/trace.go:171","msg":"trace[691391504] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"106.929502ms","start":"2026-02-23T01:05:01.973779Z","end":"2026-02-23T01:05:02.080708Z","steps":["trace[691391504] 'read index received' (duration: 106.772377ms)","trace[691391504] 'applied index is now lower than readState.Index' (duration: 153.175µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:05:02.080898Z","caller":"traceutil/trace.go:171","msg":"trace[1226522245] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"122.575241ms","start":"2026-02-23T01:05:01.958312Z","end":"2026-02-23T01:05:02.080887Z","steps":["trace[1226522245] 'process raft request' (duration: 122.24631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:05:02.081126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.343645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-23T01:05:02.081167Z","caller":"traceutil/trace.go:171","msg":"trace[1452170177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"107.458339ms","start":"2026-02-23T01:05:01.973696Z","end":"2026-02-23T01:05:02.081155Z","steps":["trace[1452170177] 'agreement among raft nodes before linearized reading' (duration: 107.324975ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:05:15.217251Z","caller":"traceutil/trace.go:171","msg":"trace[1705946180] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"174.457694ms","start":"2026-02-23T01:05:15.042766Z","end":"2026-02-23T01:05:15.217224Z","steps":["trace[1705946180] 'process raft request' (duration: 174.274708ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:05:47.177282Z","caller":"traceutil/trace.go:171","msg":"trace[2040623328] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"115.418503ms","start":"2026-02-23T01:05:47.061828Z","end":"2026-02-23T01:05:47.177246Z","steps":["trace[2040623328] 'read index received' (duration: 115.101304ms)","trace[2040623328] 'applied index is now lower than readState.Index' (duration: 315.869µs)"],"step_count":2} {"level":"warn","ts":"2026-02-23T01:05:47.177606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.752221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-23T01:05:47.17765Z","caller":"traceutil/trace.go:171","msg":"trace[1897289799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"115.874475ms","start":"2026-02-23T01:05:47.061764Z","end":"2026-02-23T01:05:47.177638Z","steps":["trace[1897289799] 'agreement among raft nodes before linearized reading' (duration: 115.629159ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:05:47.177753Z","caller":"traceutil/trace.go:171","msg":"trace[103597997] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"148.729676ms","start":"2026-02-23T01:05:47.028984Z","end":"2026-02-23T01:05:47.177714Z","steps":["trace[103597997] 'process raft request' (duration: 148.065518ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T01:07:32.003894Z","caller":"traceutil/trace.go:171","msg":"trace[1892983787] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1494; }","duration":"273.361352ms","start":"2026-02-23T01:07:31.730482Z","end":"2026-02-23T01:07:32.003844Z","steps":["trace[1892983787] 'read index received' (duration: 273.232668ms)","trace[1892983787] 'applied index is now lower than readState.Index' (duration: 127.083µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T01:07:32.003948Z","caller":"traceutil/trace.go:171","msg":"trace[213869150] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"274.074361ms","start":"2026-02-23T01:07:31.729843Z","end":"2026-02-23T01:07:32.003918Z","steps":["trace[213869150] 'process raft request' (duration: 273.790483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T01:07:32.004203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.69395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-23T01:07:32.004298Z","caller":"traceutil/trace.go:171","msg":"trace[1552679127] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1432; }","duration":"273.838964ms","start":"2026-02-23T01:07:31.730433Z","end":"2026-02-23T01:07:32.004272Z","steps":["trace[1552679127] 'agreement among raft nodes before linearized reading' (duration: 273.516945ms)"],"step_count":1}