{"level":"warn","ts":"2026-02-19T05:20:09.813501Z","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-19T05:20:09.813599Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-19T05:20:09.813683Z","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-19T05:20:09.813697Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-19T05:20:09.813721Z","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-19T05:20:09.815118Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-19T05:20:09.815249Z","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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-19T05:20:09.821081Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.429137ms"} {"level":"info","ts":"2026-02-19T05:20:09.826461Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-19T05:20:09.826539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T05:20:09.82656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-19T05:20:09.826573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T05:20:09.826583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-19T05:20:09.82662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-19T05:20:09.836284Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T05:20:09.837361Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T05:20:09.838142Z","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-19T05:20:09.839707Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T05:20:09.839908Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T05:20:09.840091Z","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-19T05:20:09.840271Z","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-19T05:20:09.841512Z","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-19T05:20:09.842515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-19T05:20:09.842802Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-19T05:20:09.845531Z","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-19T05:20:09.845625Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-19T05:20:09.845657Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-19T05:20:09.845829Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T05:20:09.845862Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T05:20:10.527817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T05:20:10.527895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T05:20:10.527931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-19T05:20:10.527953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-19T05:20:10.527971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-19T05:20:10.527992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-19T05:20:10.52801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-19T05:20:10.528974Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:20:10.529708Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T05:20:10.52986Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T05:20:10.529883Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T05:20:10.530253Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:20:10.530437Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:20:10.530488Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T05:20:10.532944Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T05:20:10.53299Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T05:20:10.533017Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-19T05:20:10.533471Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-19T05:20:20.149628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.859353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4015"} {"level":"info","ts":"2026-02-19T05:20:20.149913Z","caller":"traceutil/trace.go:171","msg":"trace[339825677] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:348; }","duration":"167.207892ms","start":"2026-02-19T05:20:19.982674Z","end":"2026-02-19T05:20:20.149882Z","steps":["trace[339825677] 'range keys from in-memory index tree' (duration: 166.70763ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:26.499315Z","caller":"traceutil/trace.go:171","msg":"trace[1370947824] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"261.984673ms","start":"2026-02-19T05:20:26.237312Z","end":"2026-02-19T05:20:26.499297Z","steps":["trace[1370947824] 'read index received' (duration: 261.84239ms)","trace[1370947824] 'applied index is now lower than readState.Index' (duration: 141.783µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:20:26.499425Z","caller":"traceutil/trace.go:171","msg":"trace[1962791883] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"287.914043ms","start":"2026-02-19T05:20:26.211463Z","end":"2026-02-19T05:20:26.499377Z","steps":["trace[1962791883] 'process raft request' (duration: 287.744279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:26.499496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.163618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T05:20:26.499579Z","caller":"traceutil/trace.go:171","msg":"trace[943674533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:404; }","duration":"262.280491ms","start":"2026-02-19T05:20:26.237278Z","end":"2026-02-19T05:20:26.499558Z","steps":["trace[943674533] 'agreement among raft nodes before linearized reading' (duration: 262.115857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:27.053096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.518777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:20:27.053307Z","caller":"traceutil/trace.go:171","msg":"trace[1675372462] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"549.703472ms","start":"2026-02-19T05:20:26.503565Z","end":"2026-02-19T05:20:27.053268Z","steps":["trace[1675372462] 'process raft request' (duration: 68.834972ms)","trace[1675372462] 'compare' (duration: 479.086657ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:27.053427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:26.503541Z","time spent":"549.821395ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:20:27.053463Z","caller":"traceutil/trace.go:171","msg":"trace[599406194] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"549.716842ms","start":"2026-02-19T05:20:26.503708Z","end":"2026-02-19T05:20:27.053425Z","steps":["trace[599406194] 'process raft request' (duration: 549.503497ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:27.053525Z","caller":"traceutil/trace.go:171","msg":"trace[1120470515] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"530.472594ms","start":"2026-02-19T05:20:26.52287Z","end":"2026-02-19T05:20:27.053342Z","steps":["trace[1120470515] 'read index received' (duration: 49.531963ms)","trace[1120470515] 'applied index is now lower than readState.Index' (duration: 480.939851ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:27.053572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.692241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:20:27.053625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:26.503682Z","time spent":"549.861847ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:20:27.053638Z","caller":"traceutil/trace.go:171","msg":"trace[465900124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"530.790673ms","start":"2026-02-19T05:20:26.522834Z","end":"2026-02-19T05:20:27.053625Z","steps":["trace[465900124] 'agreement among raft nodes before linearized reading' (duration: 530.692571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:27.053677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:26.522819Z","time spent":"530.849705ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:20:27.053674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.868845ms","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-02-19T05:20:27.053718Z","caller":"traceutil/trace.go:171","msg":"trace[815495786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"220.948457ms","start":"2026-02-19T05:20:26.832756Z","end":"2026-02-19T05:20:27.053705Z","steps":["trace[815495786] 'agreement among raft nodes before linearized reading' (duration: 220.871405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:27.865494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.551913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:20:27.865604Z","caller":"traceutil/trace.go:171","msg":"trace[1385475995] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"342.346436ms","start":"2026-02-19T05:20:27.52324Z","end":"2026-02-19T05:20:27.865586Z","steps":["trace[1385475995] 'read index received' (duration: 49.201µs)","trace[1385475995] 'applied index is now lower than readState.Index' (duration: 342.296055ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:27.865683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.449558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:20:27.86571Z","caller":"traceutil/trace.go:171","msg":"trace[773029943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"342.510789ms","start":"2026-02-19T05:20:27.52319Z","end":"2026-02-19T05:20:27.8657Z","steps":["trace[773029943] 'agreement among raft nodes before linearized reading' (duration: 342.444168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:27.865742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:27.523168Z","time spent":"342.567131ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:20:27.865938Z","caller":"traceutil/trace.go:171","msg":"trace[613720397] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"808.397207ms","start":"2026-02-19T05:20:27.057527Z","end":"2026-02-19T05:20:27.865924Z","steps":["trace[613720397] 'process raft request' (duration: 362.31186ms)","trace[613720397] 'compare' (duration: 445.458511ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:27.865996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:27.057506Z","time spent":"808.459128ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:20:38.976563Z","caller":"traceutil/trace.go:171","msg":"trace[1279937990] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"106.027729ms","start":"2026-02-19T05:20:38.870512Z","end":"2026-02-19T05:20:38.97654Z","steps":["trace[1279937990] 'process raft request' (duration: 105.855095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:47.014285Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021466860316501,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T05:20:47.032444Z","caller":"traceutil/trace.go:171","msg":"trace[2135441070] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"518.402252ms","start":"2026-02-19T05:20:46.514026Z","end":"2026-02-19T05:20:47.032428Z","steps":["trace[2135441070] 'read index received' (duration: 518.236268ms)","trace[2135441070] 'applied index is now lower than readState.Index' (duration: 165.554µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:47.032533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.499854ms","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-02-19T05:20:47.032558Z","caller":"traceutil/trace.go:171","msg":"trace[1819246600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"518.564626ms","start":"2026-02-19T05:20:46.51398Z","end":"2026-02-19T05:20:47.032545Z","steps":["trace[1819246600] 'agreement among raft nodes before linearized reading' (duration: 518.512514ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:47.032526Z","caller":"traceutil/trace.go:171","msg":"trace[1306004105] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"536.767458ms","start":"2026-02-19T05:20:46.495727Z","end":"2026-02-19T05:20:47.032494Z","steps":["trace[1306004105] 'process raft request' (duration: 536.604454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:47.032599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:46.513959Z","time spent":"518.620268ms","remote":"127.0.0.1:43120","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-02-19T05:20:47.0327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:46.495695Z","time spent":"536.924641ms","remote":"127.0.0.1:43030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:20:47.033285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.647589ms","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-19T05:20:47.033359Z","caller":"traceutil/trace.go:171","msg":"trace[1905196179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"453.765422ms","start":"2026-02-19T05:20:46.579578Z","end":"2026-02-19T05:20:47.033344Z","steps":["trace[1905196179] 'agreement among raft nodes before linearized reading' (duration: 453.123625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:47.033393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:46.579556Z","time spent":"453.829724ms","remote":"127.0.0.1:43120","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-02-19T05:20:47.305851Z","caller":"traceutil/trace.go:171","msg":"trace[1866458412] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"133.069583ms","start":"2026-02-19T05:20:47.17276Z","end":"2026-02-19T05:20:47.30583Z","steps":["trace[1866458412] 'read index received' (duration: 132.9197ms)","trace[1866458412] 'applied index is now lower than readState.Index' (duration: 148.843µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:47.306082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.310639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T05:20:47.306127Z","caller":"traceutil/trace.go:171","msg":"trace[854415457] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:489; }","duration":"133.404541ms","start":"2026-02-19T05:20:47.172712Z","end":"2026-02-19T05:20:47.306116Z","steps":["trace[854415457] 'agreement among raft nodes before linearized reading' (duration: 133.252937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:47.306464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.378533ms","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-19T05:20:47.306002Z","caller":"traceutil/trace.go:171","msg":"trace[1365855488] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"268.715867ms","start":"2026-02-19T05:20:47.037253Z","end":"2026-02-19T05:20:47.305969Z","steps":["trace[1365855488] 'process raft request' (duration: 268.446801ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:47.306572Z","caller":"traceutil/trace.go:171","msg":"trace[1670818903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:489; }","duration":"129.529216ms","start":"2026-02-19T05:20:47.17702Z","end":"2026-02-19T05:20:47.306549Z","steps":["trace[1670818903] 'agreement among raft nodes before linearized reading' (duration: 128.997224ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:47.824323Z","caller":"traceutil/trace.go:171","msg":"trace[397729992] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"513.542584ms","start":"2026-02-19T05:20:47.310753Z","end":"2026-02-19T05:20:47.824295Z","steps":["trace[397729992] 'process raft request' (duration: 507.372154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:47.82454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:47.310733Z","time spent":"513.713858ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:20:47.827946Z","caller":"traceutil/trace.go:171","msg":"trace[515017539] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:507; }","duration":"304.560618ms","start":"2026-02-19T05:20:47.523364Z","end":"2026-02-19T05:20:47.827925Z","steps":["trace[515017539] 'read index received' (duration: 294.731999ms)","trace[515017539] 'applied index is now lower than readState.Index' (duration: 9.827599ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:47.82804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.66854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:20:47.828066Z","caller":"traceutil/trace.go:171","msg":"trace[1075567158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"304.744672ms","start":"2026-02-19T05:20:47.523313Z","end":"2026-02-19T05:20:47.828058Z","steps":["trace[1075567158] 'agreement among raft nodes before linearized reading' (duration: 304.693371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:47.82809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:47.523291Z","time spent":"304.792913ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:20:47.828111Z","caller":"traceutil/trace.go:171","msg":"trace[165615594] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"516.781313ms","start":"2026-02-19T05:20:47.311304Z","end":"2026-02-19T05:20:47.828085Z","steps":["trace[165615594] 'process raft request' (duration: 516.528516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:47.828273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:47.311283Z","time spent":"516.907926ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:20:49.608963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084536374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:20:49.609021Z","caller":"traceutil/trace.go:171","msg":"trace[1275065642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"1.084649346s","start":"2026-02-19T05:20:48.524358Z","end":"2026-02-19T05:20:49.609008Z","steps":["trace[1275065642] 'range keys from in-memory index tree' (duration: 1.084425381s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:49.609053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:48.524332Z","time spent":"1.084714918s","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:20:49.918249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.391509ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T05:20:49.918349Z","caller":"traceutil/trace.go:171","msg":"trace[1334845214] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"817.127947ms","start":"2026-02-19T05:20:49.10121Z","end":"2026-02-19T05:20:49.918338Z","steps":["trace[1334845214] 'read index received' (duration: 471.570854ms)","trace[1334845214] 'applied index is now lower than readState.Index' (duration: 345.556193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:49.918477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.713354ms","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-19T05:20:49.918497Z","caller":"traceutil/trace.go:171","msg":"trace[1216701482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"603.774776ms","start":"2026-02-19T05:20:49.314715Z","end":"2026-02-19T05:20:49.918489Z","steps":["trace[1216701482] 'agreement among raft nodes before linearized reading' (duration: 603.683134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:49.918522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:49.314695Z","time spent":"603.823368ms","remote":"127.0.0.1:43120","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-02-19T05:20:49.91855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.197746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:20:49.918617Z","caller":"traceutil/trace.go:171","msg":"trace[1724407945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"308.306659ms","start":"2026-02-19T05:20:49.610293Z","end":"2026-02-19T05:20:49.9186Z","steps":["trace[1724407945] 'agreement among raft nodes before linearized reading' (duration: 308.185146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:49.918657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:49.610279Z","time spent":"308.37095ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:20:49.918767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.524908ms","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-02-19T05:20:49.918852Z","caller":"traceutil/trace.go:171","msg":"trace[1403769257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"817.664871ms","start":"2026-02-19T05:20:49.101165Z","end":"2026-02-19T05:20:49.91883Z","steps":["trace[1403769257] 'agreement among raft nodes before linearized reading' (duration: 817.20746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:20:49.918895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:20:49.10114Z","time spent":"817.746263ms","remote":"127.0.0.1:43120","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-02-19T05:20:50.704044Z","caller":"traceutil/trace.go:171","msg":"trace[1234198674] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"119.508933ms","start":"2026-02-19T05:20:50.584476Z","end":"2026-02-19T05:20:50.703985Z","steps":["trace[1234198674] 'process raft request' (duration: 92.499216ms)","trace[1234198674] 'compare' (duration: 26.859103ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:20:51.881574Z","caller":"traceutil/trace.go:171","msg":"trace[1100110957] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"218.862456ms","start":"2026-02-19T05:20:51.662687Z","end":"2026-02-19T05:20:51.881549Z","steps":["trace[1100110957] 'process raft request' (duration: 218.709403ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:54.908462Z","caller":"traceutil/trace.go:171","msg":"trace[363172246] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"134.20557ms","start":"2026-02-19T05:20:54.774229Z","end":"2026-02-19T05:20:54.908435Z","steps":["trace[363172246] 'process raft request' (duration: 133.658797ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:59.712438Z","caller":"traceutil/trace.go:171","msg":"trace[1764046258] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"218.067474ms","start":"2026-02-19T05:20:59.494346Z","end":"2026-02-19T05:20:59.712413Z","steps":["trace[1764046258] 'process raft request' (duration: 217.817098ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:59.861039Z","caller":"traceutil/trace.go:171","msg":"trace[1439565134] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"195.681006ms","start":"2026-02-19T05:20:59.665338Z","end":"2026-02-19T05:20:59.861019Z","steps":["trace[1439565134] 'process raft request' (duration: 195.561493ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:20:59.861438Z","caller":"traceutil/trace.go:171","msg":"trace[1500436368] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:629; }","duration":"147.743433ms","start":"2026-02-19T05:20:59.713687Z","end":"2026-02-19T05:20:59.86143Z","steps":["trace[1500436368] 'read index received' (duration: 147.150049ms)","trace[1500436368] 'applied index is now lower than readState.Index' (duration: 592.724µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:20:59.861522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.828395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:20:59.861555Z","caller":"traceutil/trace.go:171","msg":"trace[952583336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"147.901467ms","start":"2026-02-19T05:20:59.713644Z","end":"2026-02-19T05:20:59.861546Z","steps":["trace[952583336] 'agreement among raft nodes before linearized reading' (duration: 147.821375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:00.261132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.231484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:21:00.261257Z","caller":"traceutil/trace.go:171","msg":"trace[1040832479] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"215.078902ms","start":"2026-02-19T05:21:00.046158Z","end":"2026-02-19T05:21:00.261237Z","steps":["trace[1040832479] 'process raft request' (duration: 24.624242ms)","trace[1040832479] 'compare' (duration: 190.108871ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:21:00.269395Z","caller":"traceutil/trace.go:171","msg":"trace[1143505495] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"221.867405ms","start":"2026-02-19T05:21:00.047501Z","end":"2026-02-19T05:21:00.269369Z","steps":["trace[1143505495] 'process raft request' (duration: 221.798534ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:00.269829Z","caller":"traceutil/trace.go:171","msg":"trace[353136567] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"223.554806ms","start":"2026-02-19T05:21:00.046264Z","end":"2026-02-19T05:21:00.269819Z","steps":["trace[353136567] 'process raft request' (duration: 222.894309ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:00.285113Z","caller":"traceutil/trace.go:171","msg":"trace[985517788] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"156.454403ms","start":"2026-02-19T05:21:00.128637Z","end":"2026-02-19T05:21:00.285092Z","steps":["trace[985517788] 'process raft request' (duration: 156.371051ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:06.430586Z","caller":"traceutil/trace.go:171","msg":"trace[144487084] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"103.769796ms","start":"2026-02-19T05:21:06.326792Z","end":"2026-02-19T05:21:06.430562Z","steps":["trace[144487084] 'process raft request' (duration: 103.603612ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:06.43073Z","caller":"traceutil/trace.go:171","msg":"trace[611984379] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"101.279955ms","start":"2026-02-19T05:21:06.329431Z","end":"2026-02-19T05:21:06.430711Z","steps":["trace[611984379] 'process raft request' (duration: 101.086771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:07.916104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.837978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:21:07.916209Z","caller":"traceutil/trace.go:171","msg":"trace[845935771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"381.002222ms","start":"2026-02-19T05:21:07.535184Z","end":"2026-02-19T05:21:07.916186Z","steps":["trace[845935771] 'range keys from in-memory index tree' (duration: 380.779826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:07.916259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:07.53517Z","time spent":"381.080514ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:21:07.916514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.367967ms","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-19T05:21:07.917304Z","caller":"traceutil/trace.go:171","msg":"trace[702835332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"134.184597ms","start":"2026-02-19T05:21:07.783085Z","end":"2026-02-19T05:21:07.91727Z","steps":["trace[702835332] 'range keys from in-memory index tree' (duration: 133.059089ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:08.349849Z","caller":"traceutil/trace.go:171","msg":"trace[1321484619] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:699; }","duration":"278.175339ms","start":"2026-02-19T05:21:08.071658Z","end":"2026-02-19T05:21:08.349833Z","steps":["trace[1321484619] 'read index received' (duration: 278.067687ms)","trace[1321484619] 'applied index is now lower than readState.Index' (duration: 106.892µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:21:08.349952Z","caller":"traceutil/trace.go:171","msg":"trace[2086909716] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"428.577806ms","start":"2026-02-19T05:21:07.921332Z","end":"2026-02-19T05:21:08.34991Z","steps":["trace[2086909716] 'process raft request' (duration: 428.402742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:08.350033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.357763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T05:21:08.350063Z","caller":"traceutil/trace.go:171","msg":"trace[1792302649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"278.455836ms","start":"2026-02-19T05:21:08.071598Z","end":"2026-02-19T05:21:08.350054Z","steps":["trace[1792302649] 'agreement among raft nodes before linearized reading' (duration: 278.337983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:08.35014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:07.921307Z","time spent":"428.75264ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:09.111414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.862955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:21:09.111655Z","caller":"traceutil/trace.go:171","msg":"trace[1676178141] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"758.016168ms","start":"2026-02-19T05:21:08.353615Z","end":"2026-02-19T05:21:09.111631Z","steps":["trace[1676178141] 'process raft request' (duration: 757.911616ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:09.111667Z","caller":"traceutil/trace.go:171","msg":"trace[1025505342] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:700; }","duration":"677.736828ms","start":"2026-02-19T05:21:08.433903Z","end":"2026-02-19T05:21:09.11164Z","steps":["trace[1025505342] 'read index received' (duration: 40.708338ms)","trace[1025505342] 'applied index is now lower than readState.Index' (duration: 637.027ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:09.11177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:08.353602Z","time spent":"758.10629ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:09.111925Z","caller":"traceutil/trace.go:171","msg":"trace[1943138554] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"758.815668ms","start":"2026-02-19T05:21:08.352788Z","end":"2026-02-19T05:21:09.111604Z","steps":["trace[1943138554] 'process raft request' (duration: 121.668056ms)","trace[1943138554] 'compare' (duration: 636.760343ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:09.11205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:08.352773Z","time spent":"759.188576ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:09.548772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.170973ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T05:21:09.548864Z","caller":"traceutil/trace.go:171","msg":"trace[935163734] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"437.123021ms","start":"2026-02-19T05:21:09.111728Z","end":"2026-02-19T05:21:09.548851Z","steps":["trace[935163734] 'read index received' (duration: 61.711µs)","trace[935163734] 'applied index is now lower than readState.Index' (duration: 437.05999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:09.549007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115024954s","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-02-19T05:21:09.549036Z","caller":"traceutil/trace.go:171","msg":"trace[802392649] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"1.115104126s","start":"2026-02-19T05:21:08.433923Z","end":"2026-02-19T05:21:09.549027Z","steps":["trace[802392649] 'agreement among raft nodes before linearized reading' (duration: 1.114962432s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:09.549062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:08.433891Z","time spent":"1.115164396s","remote":"127.0.0.1:43120","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-02-19T05:21:09.549345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027260353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:21:09.549374Z","caller":"traceutil/trace.go:171","msg":"trace[312774472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"1.027314615s","start":"2026-02-19T05:21:08.522051Z","end":"2026-02-19T05:21:09.549366Z","steps":["trace[312774472] 'agreement among raft nodes before linearized reading' (duration: 1.027267823s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:09.549397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:08.52203Z","time spent":"1.027361185s","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:21:09.549056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115146107s","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-02-19T05:21:09.549912Z","caller":"traceutil/trace.go:171","msg":"trace[252140135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"1.116023557s","start":"2026-02-19T05:21:08.433855Z","end":"2026-02-19T05:21:09.549879Z","steps":["trace[252140135] 'agreement among raft nodes before linearized reading' (duration: 677.917452ms)","trace[252140135] 'range keys from in-memory index tree' (duration: 437.162193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:09.550019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:08.433817Z","time spent":"1.116183161s","remote":"127.0.0.1:43120","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-02-19T05:21:11.738472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.588914ms","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-02-19T05:21:11.738549Z","caller":"traceutil/trace.go:171","msg":"trace[620444021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"163.699357ms","start":"2026-02-19T05:21:11.574831Z","end":"2026-02-19T05:21:11.738531Z","steps":["trace[620444021] 'range keys from in-memory index tree' (duration: 163.427941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:11.73852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.92859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T05:21:11.738607Z","caller":"traceutil/trace.go:171","msg":"trace[1340830380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"623.044743ms","start":"2026-02-19T05:21:11.115544Z","end":"2026-02-19T05:21:11.738588Z","steps":["trace[1340830380] 'range keys from in-memory index tree' (duration: 622.720075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:11.738624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.997577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:21:11.738718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.9169ms","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-19T05:21:11.738719Z","caller":"traceutil/trace.go:171","msg":"trace[687768450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"214.15447ms","start":"2026-02-19T05:21:11.524542Z","end":"2026-02-19T05:21:11.738696Z","steps":["trace[687768450] 'range keys from in-memory index tree' (duration: 213.863514ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:11.738745Z","caller":"traceutil/trace.go:171","msg":"trace[620208417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"622.94916ms","start":"2026-02-19T05:21:11.115788Z","end":"2026-02-19T05:21:11.738737Z","steps":["trace[620208417] 'range keys from in-memory index tree' (duration: 622.848559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:11.738769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:11.115784Z","time spent":"622.980762ms","remote":"127.0.0.1:43120","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-02-19T05:21:11.738648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:11.115528Z","time spent":"623.111525ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T05:21:11.739133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.997124ms","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-02-19T05:21:11.739179Z","caller":"traceutil/trace.go:171","msg":"trace[528444137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"164.791683ms","start":"2026-02-19T05:21:11.574376Z","end":"2026-02-19T05:21:11.739168Z","steps":["trace[528444137] 'range keys from in-memory index tree' (duration: 163.850181ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:11.947004Z","caller":"traceutil/trace.go:171","msg":"trace[1522153624] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"204.028477ms","start":"2026-02-19T05:21:11.742955Z","end":"2026-02-19T05:21:11.946984Z","steps":["trace[1522153624] 'process raft request' (duration: 203.909694ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:12.204076Z","caller":"traceutil/trace.go:171","msg":"trace[940576829] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:707; }","duration":"201.642689ms","start":"2026-02-19T05:21:12.002417Z","end":"2026-02-19T05:21:12.20406Z","steps":["trace[940576829] 'read index received' (duration: 169.418623ms)","trace[940576829] 'applied index is now lower than readState.Index' (duration: 32.223356ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:21:12.204092Z","caller":"traceutil/trace.go:171","msg":"trace[1824225968] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"460.544876ms","start":"2026-02-19T05:21:11.743527Z","end":"2026-02-19T05:21:12.204072Z","steps":["trace[1824225968] 'process raft request' (duration: 460.474334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:12.204162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.732772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:21:12.204183Z","caller":"traceutil/trace.go:171","msg":"trace[60886908] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:685; }","duration":"201.800833ms","start":"2026-02-19T05:21:12.002375Z","end":"2026-02-19T05:21:12.204176Z","steps":["trace[60886908] 'agreement among raft nodes before linearized reading' (duration: 201.746361ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:12.204167Z","caller":"traceutil/trace.go:171","msg":"trace[1829056298] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"460.530206ms","start":"2026-02-19T05:21:11.743612Z","end":"2026-02-19T05:21:12.204143Z","steps":["trace[1829056298] 'process raft request' (duration: 460.423223ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:12.204173Z","caller":"traceutil/trace.go:171","msg":"trace[1460913044] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"460.926625ms","start":"2026-02-19T05:21:11.743216Z","end":"2026-02-19T05:21:12.204143Z","steps":["trace[1460913044] 'process raft request' (duration: 428.606877ms)","trace[1460913044] 'compare' (duration: 32.091422ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:12.204214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:11.743504Z","time spent":"460.649468ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:12.204285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:11.743603Z","time spent":"460.622408ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:12.20429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:11.743199Z","time spent":"461.030737ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:12.513692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.082242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:21:12.513776Z","caller":"traceutil/trace.go:171","msg":"trace[161432774] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:685; }","duration":"119.192875ms","start":"2026-02-19T05:21:12.394565Z","end":"2026-02-19T05:21:12.513758Z","steps":["trace[161432774] 'count revisions from in-memory index tree' (duration: 118.99323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:12.513804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.868214ms","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-19T05:21:12.513868Z","caller":"traceutil/trace.go:171","msg":"trace[753267035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"127.974056ms","start":"2026-02-19T05:21:12.385881Z","end":"2026-02-19T05:21:12.513855Z","steps":["trace[753267035] 'range keys from in-memory index tree' (duration: 127.674379ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:12.636907Z","caller":"traceutil/trace.go:171","msg":"trace[1917914161] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"119.649835ms","start":"2026-02-19T05:21:12.517234Z","end":"2026-02-19T05:21:12.636883Z","steps":["trace[1917914161] 'process raft request' (duration: 119.476651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:13.11903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.933313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:21:13.119099Z","caller":"traceutil/trace.go:171","msg":"trace[659206720] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:686; }","duration":"315.024645ms","start":"2026-02-19T05:21:12.804059Z","end":"2026-02-19T05:21:13.119084Z","steps":["trace[659206720] 'count revisions from in-memory index tree' (duration: 314.84587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:13.119314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:12.804Z","time spent":"315.306321ms","remote":"127.0.0.1:43300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-19T05:21:13.227458Z","caller":"traceutil/trace.go:171","msg":"trace[1876026857] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"210.377539ms","start":"2026-02-19T05:21:13.016867Z","end":"2026-02-19T05:21:13.227244Z","steps":["trace[1876026857] 'process raft request' (duration: 208.698928ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:15.047723Z","caller":"traceutil/trace.go:171","msg":"trace[229417172] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"379.419933ms","start":"2026-02-19T05:21:14.668284Z","end":"2026-02-19T05:21:15.047704Z","steps":["trace[229417172] 'read index received' (duration: 379.269ms)","trace[229417172] 'applied index is now lower than readState.Index' (duration: 150.263µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:15.047892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.599318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-b4c45\" ","response":"range_response_count:1 size:5012"} {"level":"info","ts":"2026-02-19T05:21:15.047867Z","caller":"traceutil/trace.go:171","msg":"trace[607892053] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"404.096436ms","start":"2026-02-19T05:21:14.643738Z","end":"2026-02-19T05:21:15.047834Z","steps":["trace[607892053] 'process raft request' (duration: 403.86507ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:15.047915Z","caller":"traceutil/trace.go:171","msg":"trace[1981748250] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-b4c45; range_end:; response_count:1; response_revision:696; }","duration":"379.666359ms","start":"2026-02-19T05:21:14.668241Z","end":"2026-02-19T05:21:15.047907Z","steps":["trace[1981748250] 'agreement among raft nodes before linearized reading' (duration: 379.530806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:15.047939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:14.668223Z","time spent":"379.711611ms","remote":"127.0.0.1:43030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5035,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-b4c45\" "} {"level":"warn","ts":"2026-02-19T05:21:15.048028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:14.643716Z","time spent":"404.225819ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:33.968294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021466860317072,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T05:21:33.988348Z","caller":"traceutil/trace.go:171","msg":"trace[271931075] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"825.126642ms","start":"2026-02-19T05:21:33.163191Z","end":"2026-02-19T05:21:33.988318Z","steps":["trace[271931075] 'process raft request' (duration: 824.938228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:33.988566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:33.163164Z","time spent":"825.282596ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:35.783077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.012805ms","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-02-19T05:21:35.783161Z","caller":"traceutil/trace.go:171","msg":"trace[133280965] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:748; }","duration":"458.106277ms","start":"2026-02-19T05:21:35.325043Z","end":"2026-02-19T05:21:35.783149Z","steps":["trace[133280965] 'range keys from in-memory index tree' (duration: 458.001395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:35.783623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167097954s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:21:35.783758Z","caller":"traceutil/trace.go:171","msg":"trace[1269830554] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:777; }","duration":"2.316193496s","start":"2026-02-19T05:21:33.467538Z","end":"2026-02-19T05:21:35.783731Z","steps":["trace[1269830554] 'read index received' (duration: 520.560898ms)","trace[1269830554] 'applied index is now lower than readState.Index' (duration: 1.795630548s)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:21:35.783813Z","caller":"traceutil/trace.go:171","msg":"trace[1318186558] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"2.308712686s","start":"2026-02-19T05:21:33.475055Z","end":"2026-02-19T05:21:35.783768Z","steps":["trace[1318186558] 'process raft request' (duration: 1.141384156s)","trace[1318186558] 'compare' (duration: 1.166264165s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:35.783931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.293463642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T05:21:35.783937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258743677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:21:35.783956Z","caller":"traceutil/trace.go:171","msg":"trace[646671582] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:749; }","duration":"1.293514433s","start":"2026-02-19T05:21:34.490435Z","end":"2026-02-19T05:21:35.78395Z","steps":["trace[646671582] 'agreement among raft nodes before linearized reading' (duration: 1.293468922s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:35.783993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:34.490423Z","time spent":"1.293564044s","remote":"127.0.0.1:43238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-19T05:21:35.783987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752221764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T05:21:35.784002Z","caller":"traceutil/trace.go:171","msg":"trace[406236431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"1.258808018s","start":"2026-02-19T05:21:34.525171Z","end":"2026-02-19T05:21:35.783979Z","steps":["trace[406236431] 'agreement among raft nodes before linearized reading' (duration: 1.258718226s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:35.783998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:33.475016Z","time spent":"2.30888554s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:35.784037Z","caller":"traceutil/trace.go:171","msg":"trace[278955555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:749; }","duration":"1.752321616s","start":"2026-02-19T05:21:34.031705Z","end":"2026-02-19T05:21:35.784027Z","steps":["trace[278955555] 'agreement among raft nodes before linearized reading' (duration: 1.752172843s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:35.784042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:34.525166Z","time spent":"1.25886908s","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:21:35.784044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351143398s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T05:21:35.784038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.316478113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-19T05:21:35.784111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401750235s","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-02-19T05:21:35.784106Z","caller":"traceutil/trace.go:171","msg":"trace[100761069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"1.35121701s","start":"2026-02-19T05:21:34.432875Z","end":"2026-02-19T05:21:35.784092Z","steps":["trace[100761069] 'agreement among raft nodes before linearized reading' (duration: 1.351011365s)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:35.78413Z","caller":"traceutil/trace.go:171","msg":"trace[261697079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"1.401795546s","start":"2026-02-19T05:21:34.38233Z","end":"2026-02-19T05:21:35.784125Z","steps":["trace[261697079] 'agreement among raft nodes before linearized reading' (duration: 1.401748645s)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:35.784126Z","caller":"traceutil/trace.go:171","msg":"trace[540092502] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:749; }","duration":"2.316592686s","start":"2026-02-19T05:21:33.46751Z","end":"2026-02-19T05:21:35.784103Z","steps":["trace[540092502] 'agreement among raft nodes before linearized reading' (duration: 2.31634405s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:35.784147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:34.38231Z","time spent":"1.401833907s","remote":"127.0.0.1:43120","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-02-19T05:21:35.784155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:34.432869Z","time spent":"1.351274111s","remote":"127.0.0.1:43120","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-02-19T05:21:35.78417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:33.467495Z","time spent":"2.316667027s","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-02-19T05:21:35.784145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395373651s","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-02-19T05:21:35.784064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:34.031684Z","time spent":"1.752375757s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-19T05:21:35.784227Z","caller":"traceutil/trace.go:171","msg":"trace[443602603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:749; }","duration":"1.395461943s","start":"2026-02-19T05:21:34.388746Z","end":"2026-02-19T05:21:35.784208Z","steps":["trace[443602603] 'agreement among raft nodes before linearized reading' (duration: 1.395260479s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:35.784279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:34.388739Z","time spent":"1.395529276s","remote":"127.0.0.1:43120","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-02-19T05:21:36.291003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021466860317077,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T05:21:36.898231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.121102ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-02-19T05:21:36.898745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:35.785343Z","time spent":"1.113350043s","remote":"127.0.0.1:42892","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-19T05:21:36.898854Z","caller":"traceutil/trace.go:171","msg":"trace[1997693137] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.111992871s","start":"2026-02-19T05:21:35.786783Z","end":"2026-02-19T05:21:36.898776Z","steps":["trace[1997693137] 'process raft request' (duration: 1.111673484s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:36.898991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:35.786756Z","time spent":"1.112180415s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:36.899104Z","caller":"traceutil/trace.go:171","msg":"trace[59039949] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.109998882s","start":"2026-02-19T05:21:35.788993Z","end":"2026-02-19T05:21:36.898992Z","steps":["trace[59039949] 'process raft request' (duration: 1.109716926s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:36.899237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:35.788974Z","time spent":"1.110194117s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:36.898746Z","caller":"traceutil/trace.go:171","msg":"trace[357999041] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"1.111770005s","start":"2026-02-19T05:21:35.786962Z","end":"2026-02-19T05:21:36.898732Z","steps":["trace[357999041] 'process raft request' (duration: 1.111720364s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:36.899495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:35.786948Z","time spent":"1.112515793s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:36.900223Z","caller":"traceutil/trace.go:171","msg":"trace[1797536844] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:779; }","duration":"1.11028935s","start":"2026-02-19T05:21:35.789904Z","end":"2026-02-19T05:21:36.900193Z","steps":["trace[1797536844] 'read index received' (duration: 501.341985ms)","trace[1797536844] 'applied index is now lower than readState.Index' (duration: 608.945794ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:21:36.90034Z","caller":"traceutil/trace.go:171","msg":"trace[1021765173] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.110438514s","start":"2026-02-19T05:21:35.789881Z","end":"2026-02-19T05:21:36.90032Z","steps":["trace[1021765173] 'process raft request' (duration: 1.109038109s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:36.900455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:35.789858Z","time spent":"1.110535786s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:36.900489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110587077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:21:36.900649Z","caller":"traceutil/trace.go:171","msg":"trace[1031403786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"1.110760561s","start":"2026-02-19T05:21:35.789873Z","end":"2026-02-19T05:21:36.900633Z","steps":["trace[1031403786] 'agreement among raft nodes before linearized reading' (duration: 1.110584897s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:36.900794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:35.789861Z","time spent":"1.110818853s","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:21:36.900382Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":15490021466860317085,"received-request-id":15490021466860317077} {"level":"warn","ts":"2026-02-19T05:21:37.401119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021466860317085,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T05:21:37.535927Z","caller":"traceutil/trace.go:171","msg":"trace[402746743] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.001694737s","start":"2026-02-19T05:21:36.534208Z","end":"2026-02-19T05:21:37.535902Z","steps":["trace[402746743] 'process raft request' (duration: 1.001334379s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:37.536312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:36.534181Z","time spent":"1.002004035s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:37.540209Z","caller":"traceutil/trace.go:171","msg":"trace[706386755] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"639.875885ms","start":"2026-02-19T05:21:36.900297Z","end":"2026-02-19T05:21:37.540173Z","steps":["trace[706386755] 'read index received' (duration: 635.567631ms)","trace[706386755] 'applied index is now lower than readState.Index' (duration: 4.307024ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:37.540354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707418288s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:21:37.540413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547130013s","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-19T05:21:37.540389Z","caller":"traceutil/trace.go:171","msg":"trace[1176389705] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:755; }","duration":"1.707496979s","start":"2026-02-19T05:21:35.832882Z","end":"2026-02-19T05:21:37.540379Z","steps":["trace[1176389705] 'agreement among raft nodes before linearized reading' (duration: 1.707414817s)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:37.540483Z","caller":"traceutil/trace.go:171","msg":"trace[1423756389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"1.547240785s","start":"2026-02-19T05:21:35.993228Z","end":"2026-02-19T05:21:37.540469Z","steps":["trace[1423756389] 'agreement among raft nodes before linearized reading' (duration: 1.54703645s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:37.540524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:35.993207Z","time spent":"1.547306187s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T05:21:37.540528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:35.832868Z","time spent":"1.707643443s","remote":"127.0.0.1:42990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-02-19T05:21:37.540221Z","caller":"traceutil/trace.go:171","msg":"trace[926708489] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"639.283941ms","start":"2026-02-19T05:21:36.900915Z","end":"2026-02-19T05:21:37.540199Z","steps":["trace[926708489] 'process raft request' (duration: 639.142577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:37.54081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.660617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:21:37.540777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.923003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:21:37.540843Z","caller":"traceutil/trace.go:171","msg":"trace[1954538459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"601.727308ms","start":"2026-02-19T05:21:36.939106Z","end":"2026-02-19T05:21:37.540833Z","steps":["trace[1954538459] 'agreement among raft nodes before linearized reading' (duration: 601.672077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:37.540873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:36.939088Z","time spent":"601.77862ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:21:37.540853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:36.900893Z","time spent":"639.865865ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:37.540899Z","caller":"traceutil/trace.go:171","msg":"trace[417094437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"639.057155ms","start":"2026-02-19T05:21:36.901799Z","end":"2026-02-19T05:21:37.540856Z","steps":["trace[417094437] 'agreement among raft nodes before linearized reading' (duration: 638.865861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:37.541019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:36.901787Z","time spent":"639.152568ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:21:37.753994Z","caller":"traceutil/trace.go:171","msg":"trace[1700343723] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"208.187716ms","start":"2026-02-19T05:21:37.545778Z","end":"2026-02-19T05:21:37.753966Z","steps":["trace[1700343723] 'read index received' (duration: 128.530691ms)","trace[1700343723] 'applied index is now lower than readState.Index' (duration: 79.655915ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:21:37.754147Z","caller":"traceutil/trace.go:171","msg":"trace[717667376] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"209.636261ms","start":"2026-02-19T05:21:37.544492Z","end":"2026-02-19T05:21:37.754129Z","steps":["trace[717667376] 'process raft request' (duration: 129.852323ms)","trace[717667376] 'compare' (duration: 79.486711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:37.754272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.486413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-19T05:21:37.754307Z","caller":"traceutil/trace.go:171","msg":"trace[1356844122] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:756; }","duration":"208.566485ms","start":"2026-02-19T05:21:37.545732Z","end":"2026-02-19T05:21:37.754299Z","steps":["trace[1356844122] 'agreement among raft nodes before linearized reading' (duration: 208.435002ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:39.30243Z","caller":"traceutil/trace.go:171","msg":"trace[760872819] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"307.626977ms","start":"2026-02-19T05:21:38.994745Z","end":"2026-02-19T05:21:39.302372Z","steps":["trace[760872819] 'process raft request' (duration: 307.537295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:39.302586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:38.994725Z","time spent":"307.777911ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:39.30274Z","caller":"traceutil/trace.go:171","msg":"trace[1926781057] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"307.855043ms","start":"2026-02-19T05:21:38.994828Z","end":"2026-02-19T05:21:39.302683Z","steps":["trace[1926781057] 'process raft request' (duration: 307.494834ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:39.302819Z","caller":"traceutil/trace.go:171","msg":"trace[31320220] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"308.449688ms","start":"2026-02-19T05:21:38.994357Z","end":"2026-02-19T05:21:39.302807Z","steps":["trace[31320220] 'process raft request' (duration: 307.783772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:39.302877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:38.994321Z","time spent":"308.526179ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:39.303015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:38.994819Z","time spent":"308.031388ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:39.906914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.271763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:21:39.906944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.620504ms","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":"warn","ts":"2026-02-19T05:21:39.906959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.256867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:21:39.906994Z","caller":"traceutil/trace.go:171","msg":"trace[1902869666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"382.429376ms","start":"2026-02-19T05:21:39.524548Z","end":"2026-02-19T05:21:39.906977Z","steps":["trace[1902869666] 'range keys from in-memory index tree' (duration: 382.186161ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:39.906999Z","caller":"traceutil/trace.go:171","msg":"trace[1161113500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"148.736437ms","start":"2026-02-19T05:21:39.758249Z","end":"2026-02-19T05:21:39.906986Z","steps":["trace[1161113500] 'range keys from in-memory index tree' (duration: 148.42263ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:39.907024Z","caller":"traceutil/trace.go:171","msg":"trace[1218205841] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:760; }","duration":"141.35682ms","start":"2026-02-19T05:21:39.765651Z","end":"2026-02-19T05:21:39.907008Z","steps":["trace[1218205841] 'count revisions from in-memory index tree' (duration: 141.196136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:39.907043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:39.524527Z","time spent":"382.501919ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:21:48.182684Z","caller":"traceutil/trace.go:171","msg":"trace[1076788535] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"127.949087ms","start":"2026-02-19T05:21:48.054716Z","end":"2026-02-19T05:21:48.182665Z","steps":["trace[1076788535] 'process raft request' (duration: 127.811534ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:53.599442Z","caller":"traceutil/trace.go:171","msg":"trace[1257743592] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:884; }","duration":"111.810268ms","start":"2026-02-19T05:21:53.487605Z","end":"2026-02-19T05:21:53.599416Z","steps":["trace[1257743592] 'read index received' (duration: 111.08452ms)","trace[1257743592] 'applied index is now lower than readState.Index' (duration: 724.228µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:53.599642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.015653ms","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-19T05:21:53.599706Z","caller":"traceutil/trace.go:171","msg":"trace[1695336803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:848; }","duration":"112.117746ms","start":"2026-02-19T05:21:53.487574Z","end":"2026-02-19T05:21:53.599691Z","steps":["trace[1695336803] 'agreement among raft nodes before linearized reading' (duration: 111.937481ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:54.416515Z","caller":"traceutil/trace.go:171","msg":"trace[1326701211] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"207.874669ms","start":"2026-02-19T05:21:54.208622Z","end":"2026-02-19T05:21:54.416497Z","steps":["trace[1326701211] 'process raft request' (duration: 207.768127ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:55.721072Z","caller":"traceutil/trace.go:171","msg":"trace[473722930] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"566.433291ms","start":"2026-02-19T05:21:55.154628Z","end":"2026-02-19T05:21:55.721061Z","steps":["trace[473722930] 'process raft request' (duration: 566.285928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:55.72117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:55.154608Z","time spent":"566.509413ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:55.721208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.82657ms","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-02-19T05:21:55.721067Z","caller":"traceutil/trace.go:171","msg":"trace[362261070] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"309.672807ms","start":"2026-02-19T05:21:55.411371Z","end":"2026-02-19T05:21:55.721044Z","steps":["trace[362261070] 'read index received' (duration: 309.449392ms)","trace[362261070] 'applied index is now lower than readState.Index' (duration: 222.595µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:21:55.721256Z","caller":"traceutil/trace.go:171","msg":"trace[454170439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"309.904922ms","start":"2026-02-19T05:21:55.41133Z","end":"2026-02-19T05:21:55.721235Z","steps":["trace[454170439] 'agreement among raft nodes before linearized reading' (duration: 309.777259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:55.721389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:55.411311Z","time spent":"310.061947ms","remote":"127.0.0.1:43120","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-02-19T05:21:55.721406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.112404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:21:55.721427Z","caller":"traceutil/trace.go:171","msg":"trace[400257396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"193.156205ms","start":"2026-02-19T05:21:55.528264Z","end":"2026-02-19T05:21:55.721421Z","steps":["trace[400257396] 'agreement among raft nodes before linearized reading' (duration: 193.120294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:55.721484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.267399ms","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-02-19T05:21:55.721557Z","caller":"traceutil/trace.go:171","msg":"trace[529869247] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"265.367402ms","start":"2026-02-19T05:21:55.456175Z","end":"2026-02-19T05:21:55.721542Z","steps":["trace[529869247] 'agreement among raft nodes before linearized reading' (duration: 265.203028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:55.721584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.238873ms","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-19T05:21:55.721647Z","caller":"traceutil/trace.go:171","msg":"trace[2061489595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"113.336915ms","start":"2026-02-19T05:21:55.608291Z","end":"2026-02-19T05:21:55.721628Z","steps":["trace[2061489595] 'agreement among raft nodes before linearized reading' (duration: 113.079529ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:56.315449Z","caller":"traceutil/trace.go:171","msg":"trace[1945147152] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"589.967077ms","start":"2026-02-19T05:21:55.72546Z","end":"2026-02-19T05:21:56.315427Z","steps":["trace[1945147152] 'process raft request' (duration: 587.231461ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:56.315467Z","caller":"traceutil/trace.go:171","msg":"trace[438933935] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"589.620328ms","start":"2026-02-19T05:21:55.725823Z","end":"2026-02-19T05:21:56.315443Z","steps":["trace[438933935] 'process raft request' (duration: 589.538646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:56.315551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:55.725443Z","time spent":"590.055109ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:21:56.315591Z","caller":"traceutil/trace.go:171","msg":"trace[725695671] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:889; }","duration":"265.700579ms","start":"2026-02-19T05:21:56.049868Z","end":"2026-02-19T05:21:56.315569Z","steps":["trace[725695671] 'read index received' (duration: 262.83542ms)","trace[725695671] 'applied index is now lower than readState.Index' (duration: 2.864319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:21:56.315634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:55.72581Z","time spent":"589.738181ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:21:56.315721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.839673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:21:56.315755Z","caller":"traceutil/trace.go:171","msg":"trace[1386694362] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:856; }","duration":"265.931455ms","start":"2026-02-19T05:21:56.049814Z","end":"2026-02-19T05:21:56.315745Z","steps":["trace[1386694362] 'agreement among raft nodes before linearized reading' (duration: 265.847783ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:56.315758Z","caller":"traceutil/trace.go:171","msg":"trace[1021833945] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"140.026847ms","start":"2026-02-19T05:21:56.175715Z","end":"2026-02-19T05:21:56.315741Z","steps":["trace[1021833945] 'process raft request' (duration: 139.781841ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:21:56.315758Z","caller":"traceutil/trace.go:171","msg":"trace[962987864] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"589.873254ms","start":"2026-02-19T05:21:55.72586Z","end":"2026-02-19T05:21:56.315733Z","steps":["trace[962987864] 'process raft request' (duration: 589.549376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:21:56.315873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:21:55.725838Z","time spent":"589.971346ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:09.966061Z","caller":"traceutil/trace.go:171","msg":"trace[1320546176] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"114.282274ms","start":"2026-02-19T05:22:09.851757Z","end":"2026-02-19T05:22:09.966039Z","steps":["trace[1320546176] 'process raft request' (duration: 114.149051ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:10.698659Z","caller":"traceutil/trace.go:171","msg":"trace[1612688660] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"159.849677ms","start":"2026-02-19T05:22:10.538789Z","end":"2026-02-19T05:22:10.698639Z","steps":["trace[1612688660] 'read index received' (duration: 159.670793ms)","trace[1612688660] 'applied index is now lower than readState.Index' (duration: 178.174µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:10.698848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.042762ms","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-19T05:22:10.698874Z","caller":"traceutil/trace.go:171","msg":"trace[69805098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:893; }","duration":"160.131955ms","start":"2026-02-19T05:22:10.538732Z","end":"2026-02-19T05:22:10.698863Z","steps":["trace[69805098] 'agreement among raft nodes before linearized reading' (duration: 160.000431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:10.699139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.341758ms","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-19T05:22:10.699201Z","caller":"traceutil/trace.go:171","msg":"trace[1014754242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"142.46242ms","start":"2026-02-19T05:22:10.556726Z","end":"2026-02-19T05:22:10.699188Z","steps":["trace[1014754242] 'agreement among raft nodes before linearized reading' (duration: 142.212834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:10.699072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.11525ms","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-02-19T05:22:10.698864Z","caller":"traceutil/trace.go:171","msg":"trace[379220777] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"160.422441ms","start":"2026-02-19T05:22:10.538346Z","end":"2026-02-19T05:22:10.698768Z","steps":["trace[379220777] 'process raft request' (duration: 160.177415ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:10.699565Z","caller":"traceutil/trace.go:171","msg":"trace[2074860167] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:893; }","duration":"159.671233ms","start":"2026-02-19T05:22:10.539876Z","end":"2026-02-19T05:22:10.699547Z","steps":["trace[2074860167] 'agreement among raft nodes before linearized reading' (duration: 158.965197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:11.82329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.416573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:22:11.823529Z","caller":"traceutil/trace.go:171","msg":"trace[449114577] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.11974329s","start":"2026-02-19T05:22:10.703766Z","end":"2026-02-19T05:22:11.823509Z","steps":["trace[449114577] 'process raft request' (duration: 891.02265ms)","trace[449114577] 'compare' (duration: 228.028064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:11.82364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:10.703738Z","time spent":"1.119866114s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:11.823744Z","caller":"traceutil/trace.go:171","msg":"trace[1737655136] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"1.119353932s","start":"2026-02-19T05:22:10.704371Z","end":"2026-02-19T05:22:11.823725Z","steps":["trace[1737655136] 'process raft request' (duration: 1.119080725s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:11.823834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:10.704345Z","time spent":"1.119443963s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:11.823945Z","caller":"traceutil/trace.go:171","msg":"trace[2099018506] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.116834361s","start":"2026-02-19T05:22:10.707076Z","end":"2026-02-19T05:22:11.823911Z","steps":["trace[2099018506] 'process raft request' (duration: 1.116598165s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:11.82411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:10.707064Z","time spent":"1.116961574s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:12.147442Z","caller":"traceutil/trace.go:171","msg":"trace[1151197842] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"124.258423ms","start":"2026-02-19T05:22:12.023164Z","end":"2026-02-19T05:22:12.147422Z","steps":["trace[1151197842] 'process raft request' (duration: 124.12343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:12.774178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.795644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:12.774272Z","caller":"traceutil/trace.go:171","msg":"trace[1954230606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"251.927198ms","start":"2026-02-19T05:22:12.522317Z","end":"2026-02-19T05:22:12.774244Z","steps":["trace[1954230606] 'range keys from in-memory index tree' (duration: 251.721033ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:13.340048Z","caller":"traceutil/trace.go:171","msg":"trace[1196723841] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"562.086713ms","start":"2026-02-19T05:22:12.777942Z","end":"2026-02-19T05:22:13.340029Z","steps":["trace[1196723841] 'process raft request' (duration: 561.98463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:13.340161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:12.777921Z","time spent":"562.185115ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:15.407899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256361449s","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-02-19T05:22:15.407967Z","caller":"traceutil/trace.go:171","msg":"trace[1709073090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"1.256472563s","start":"2026-02-19T05:22:14.151479Z","end":"2026-02-19T05:22:15.407952Z","steps":["trace[1709073090] 'range keys from in-memory index tree' (duration: 1.256204215s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:15.408001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:14.151464Z","time spent":"1.256530194s","remote":"127.0.0.1:43120","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-02-19T05:22:15.408217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.885701378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:15.408308Z","caller":"traceutil/trace.go:171","msg":"trace[526110961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"1.885847102s","start":"2026-02-19T05:22:13.522434Z","end":"2026-02-19T05:22:15.408281Z","steps":["trace[526110961] 'range keys from in-memory index tree' (duration: 1.885603246s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:15.408343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579668211s","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":"warn","ts":"2026-02-19T05:22:15.40837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:13.522413Z","time spent":"1.885937853s","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:22:15.408399Z","caller":"traceutil/trace.go:171","msg":"trace[1860338498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"1.579765003s","start":"2026-02-19T05:22:13.828618Z","end":"2026-02-19T05:22:15.408383Z","steps":["trace[1860338498] 'range keys from in-memory index tree' (duration: 1.579533528s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:15.408435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579572818s","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-19T05:22:15.40846Z","caller":"traceutil/trace.go:171","msg":"trace[32790463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"1.57961567s","start":"2026-02-19T05:22:13.828836Z","end":"2026-02-19T05:22:15.408452Z","steps":["trace[32790463] 'range keys from in-memory index tree' (duration: 1.579477366s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:15.408479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:13.828826Z","time spent":"1.57964839s","remote":"127.0.0.1:43120","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-02-19T05:22:15.408452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:13.828565Z","time spent":"1.579875605s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T05:22:15.408363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343631715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:22:15.408236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579555538s","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-02-19T05:22:15.408617Z","caller":"traceutil/trace.go:171","msg":"trace[650354897] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:898; }","duration":"1.343907841s","start":"2026-02-19T05:22:14.064664Z","end":"2026-02-19T05:22:15.408572Z","steps":["trace[650354897] 'count revisions from in-memory index tree' (duration: 1.343515433s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:15.408664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:14.064642Z","time spent":"1.344003564s","remote":"127.0.0.1:35234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-19T05:22:15.408686Z","caller":"traceutil/trace.go:171","msg":"trace[1536108275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"1.58004502s","start":"2026-02-19T05:22:13.828626Z","end":"2026-02-19T05:22:15.408671Z","steps":["trace[1536108275] 'range keys from in-memory index tree' (duration: 1.579307652s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:15.408704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939009827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.217\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-19T05:22:15.408741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:13.828565Z","time spent":"1.580163092s","remote":"127.0.0.1:43120","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-02-19T05:22:15.408783Z","caller":"traceutil/trace.go:171","msg":"trace[15703297] range","detail":"{range_begin:/registry/masterleases/162.253.55.217; range_end:; response_count:1; response_revision:898; }","duration":"1.9391332s","start":"2026-02-19T05:22:13.469626Z","end":"2026-02-19T05:22:15.408759Z","steps":["trace[15703297] 'range keys from in-memory index tree' (duration: 1.938782801s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:15.408826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:13.469606Z","time spent":"1.939210311s","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.217\" "} {"level":"warn","ts":"2026-02-19T05:22:15.408846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321438962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:22:15.408917Z","caller":"traceutil/trace.go:171","msg":"trace[2146125649] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:898; }","duration":"1.321570385s","start":"2026-02-19T05:22:14.087327Z","end":"2026-02-19T05:22:15.408897Z","steps":["trace[2146125649] 'count revisions from in-memory index tree' (duration: 1.32135944s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:15.408958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:14.087304Z","time spent":"1.321643196s","remote":"127.0.0.1:43298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-19T05:22:15.770655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.410029Z","time spent":"360.622637ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-19T05:22:16.100073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.545791ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:22:16.100418Z","caller":"traceutil/trace.go:171","msg":"trace[1917659999] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"328.63408ms","start":"2026-02-19T05:22:15.771764Z","end":"2026-02-19T05:22:16.100398Z","steps":["trace[1917659999] 'process raft request' (duration: 328.585329ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:16.100408Z","caller":"traceutil/trace.go:171","msg":"trace[1116037143] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"687.271109ms","start":"2026-02-19T05:22:15.413071Z","end":"2026-02-19T05:22:16.100342Z","steps":["trace[1116037143] 'process raft request' (duration: 537.37243ms)","trace[1116037143] 'compare' (duration: 149.403106ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:22:16.100465Z","caller":"traceutil/trace.go:171","msg":"trace[493272576] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"686.773356ms","start":"2026-02-19T05:22:15.413682Z","end":"2026-02-19T05:22:16.100455Z","steps":["trace[493272576] 'process raft request' (duration: 686.49563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:16.100508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.77172Z","time spent":"328.748203ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:16.100626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.41366Z","time spent":"686.862478ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:16.100673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.41305Z","time spent":"687.475344ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:16.10084Z","caller":"traceutil/trace.go:171","msg":"trace[1887165462] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"686.326596ms","start":"2026-02-19T05:22:15.414499Z","end":"2026-02-19T05:22:16.100826Z","steps":["trace[1887165462] 'process raft request' (duration: 685.776483ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:16.100857Z","caller":"traceutil/trace.go:171","msg":"trace[12264209] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:938; }","duration":"683.336985ms","start":"2026-02-19T05:22:15.417506Z","end":"2026-02-19T05:22:16.100843Z","steps":["trace[12264209] 'read index received' (duration: 353.063456ms)","trace[12264209] 'applied index is now lower than readState.Index' (duration: 330.271709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:16.100904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.414485Z","time spent":"686.384617ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:16.100954Z","caller":"traceutil/trace.go:171","msg":"trace[1509730479] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"686.341416ms","start":"2026-02-19T05:22:15.414602Z","end":"2026-02-19T05:22:16.100943Z","steps":["trace[1509730479] 'process raft request' (duration: 685.704671ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:16.100401Z","caller":"traceutil/trace.go:171","msg":"trace[1466591156] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"686.671214ms","start":"2026-02-19T05:22:15.413694Z","end":"2026-02-19T05:22:16.100365Z","steps":["trace[1466591156] 'process raft request' (duration: 686.551811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:16.101008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.414569Z","time spent":"686.407518ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:16.101034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.413678Z","time spent":"687.33256ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:16.101135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.635251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:16.101167Z","caller":"traceutil/trace.go:171","msg":"trace[2066847104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"683.694283ms","start":"2026-02-19T05:22:15.417464Z","end":"2026-02-19T05:22:16.101159Z","steps":["trace[2066847104] 'agreement among raft nodes before linearized reading' (duration: 683.617491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:16.101195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.417449Z","time spent":"683.740144ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:22:16.101312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.269077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:22:16.101348Z","caller":"traceutil/trace.go:171","msg":"trace[1170322964] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:904; }","duration":"467.354499ms","start":"2026-02-19T05:22:15.633983Z","end":"2026-02-19T05:22:16.101337Z","steps":["trace[1170322964] 'agreement among raft nodes before linearized reading' (duration: 467.250637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:16.101379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:15.633953Z","time spent":"467.418701ms","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-19T05:22:17.241619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021466860317481,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T05:22:17.454425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.291868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:17.454513Z","caller":"traceutil/trace.go:171","msg":"trace[139489090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"929.436012ms","start":"2026-02-19T05:22:16.525059Z","end":"2026-02-19T05:22:17.454495Z","steps":["trace[139489090] 'range keys from in-memory index tree' (duration: 929.207577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:17.454557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:16.525038Z","time spent":"929.509873ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:22:17.454965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084224767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:22:17.455006Z","caller":"traceutil/trace.go:171","msg":"trace[1988484264] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:904; }","duration":"1.084304399s","start":"2026-02-19T05:22:16.370691Z","end":"2026-02-19T05:22:17.454995Z","steps":["trace[1988484264] 'count revisions from in-memory index tree' (duration: 1.084115714s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:17.455036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:16.370637Z","time spent":"1.084390711s","remote":"127.0.0.1:43134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-19T05:22:17.620963Z","caller":"traceutil/trace.go:171","msg":"trace[1814943791] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"880.420744ms","start":"2026-02-19T05:22:16.740521Z","end":"2026-02-19T05:22:17.620941Z","steps":["trace[1814943791] 'read index received' (duration: 880.22985ms)","trace[1814943791] 'applied index is now lower than readState.Index' (duration: 190.254µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:22:17.621026Z","caller":"traceutil/trace.go:171","msg":"trace[878142177] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"886.385297ms","start":"2026-02-19T05:22:16.734611Z","end":"2026-02-19T05:22:17.620996Z","steps":["trace[878142177] 'process raft request' (duration: 886.193133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:17.62113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.5889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:17.621165Z","caller":"traceutil/trace.go:171","msg":"trace[678129860] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:905; }","duration":"880.689292ms","start":"2026-02-19T05:22:16.740468Z","end":"2026-02-19T05:22:17.621158Z","steps":["trace[678129860] 'agreement among raft nodes before linearized reading' (duration: 880.59847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:17.621197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:16.740445Z","time spent":"880.742813ms","remote":"127.0.0.1:43264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-19T05:22:17.621207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:16.734585Z","time spent":"886.511922ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:17.621697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.309149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:17.621754Z","caller":"traceutil/trace.go:171","msg":"trace[20501754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"165.40635ms","start":"2026-02-19T05:22:17.456313Z","end":"2026-02-19T05:22:17.621719Z","steps":["trace[20501754] 'agreement among raft nodes before linearized reading' (duration: 165.313728ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:18.192032Z","caller":"traceutil/trace.go:171","msg":"trace[697805929] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"245.335319ms","start":"2026-02-19T05:22:17.946678Z","end":"2026-02-19T05:22:18.192014Z","steps":["trace[697805929] 'process raft request' (duration: 245.129444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:18.761699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.457095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:22:18.761896Z","caller":"traceutil/trace.go:171","msg":"trace[1625075314] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"564.910802ms","start":"2026-02-19T05:22:18.196972Z","end":"2026-02-19T05:22:18.761883Z","steps":["trace[1625075314] 'process raft request' (duration: 564.82111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:18.761958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.196956Z","time spent":"564.967233ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:18.761962Z","caller":"traceutil/trace.go:171","msg":"trace[2087110705] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"569.980243ms","start":"2026-02-19T05:22:18.191965Z","end":"2026-02-19T05:22:18.761945Z","steps":["trace[2087110705] 'read index received' (duration: 326.14787ms)","trace[2087110705] 'applied index is now lower than readState.Index' (duration: 243.830473ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:22:18.762052Z","caller":"traceutil/trace.go:171","msg":"trace[1233296140] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"627.819161ms","start":"2026-02-19T05:22:18.134221Z","end":"2026-02-19T05:22:18.76204Z","steps":["trace[1233296140] 'process raft request' (duration: 383.928597ms)","trace[1233296140] 'compare' (duration: 242.789327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:18.762113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.134195Z","time spent":"627.879792ms","remote":"127.0.0.1:42940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":852,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:22:18.762311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.324548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T05:22:18.762336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.853764ms","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-02-19T05:22:18.762358Z","caller":"traceutil/trace.go:171","msg":"trace[2069210366] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:908; }","duration":"407.3846ms","start":"2026-02-19T05:22:18.354962Z","end":"2026-02-19T05:22:18.762347Z","steps":["trace[2069210366] 'agreement among raft nodes before linearized reading' (duration: 407.283587ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:18.762364Z","caller":"traceutil/trace.go:171","msg":"trace[1774077763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"655.897095ms","start":"2026-02-19T05:22:18.106459Z","end":"2026-02-19T05:22:18.762356Z","steps":["trace[1774077763] 'agreement among raft nodes before linearized reading' (duration: 655.776522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:18.762331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.786092ms","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-02-19T05:22:18.762388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.106451Z","time spent":"655.932117ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T05:22:18.762396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.175378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:22:18.762387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.354871Z","time spent":"407.510693ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-19T05:22:18.762444Z","caller":"traceutil/trace.go:171","msg":"trace[952195238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"238.24827ms","start":"2026-02-19T05:22:18.524182Z","end":"2026-02-19T05:22:18.76243Z","steps":["trace[952195238] 'agreement among raft nodes before linearized reading' (duration: 238.172358ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:18.762433Z","caller":"traceutil/trace.go:171","msg":"trace[319597072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:908; }","duration":"655.908375ms","start":"2026-02-19T05:22:18.106498Z","end":"2026-02-19T05:22:18.762406Z","steps":["trace[319597072] 'agreement among raft nodes before linearized reading' (duration: 655.556597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:18.76249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.106494Z","time spent":"655.987248ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T05:22:18.762509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.964357ms","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":"warn","ts":"2026-02-19T05:22:18.762477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.530237ms","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-19T05:22:18.762548Z","caller":"traceutil/trace.go:171","msg":"trace[642736370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:908; }","duration":"656.049489ms","start":"2026-02-19T05:22:18.106487Z","end":"2026-02-19T05:22:18.762537Z","steps":["trace[642736370] 'agreement among raft nodes before linearized reading' (duration: 655.941307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:18.76258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.106464Z","time spent":"656.10889ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-19T05:22:18.762578Z","caller":"traceutil/trace.go:171","msg":"trace[1061991645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"655.67131ms","start":"2026-02-19T05:22:18.106882Z","end":"2026-02-19T05:22:18.762554Z","steps":["trace[1061991645] 'agreement among raft nodes before linearized reading' (duration: 655.362502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:18.76263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.106869Z","time spent":"655.750472ms","remote":"127.0.0.1:43120","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-02-19T05:22:19.51721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.535494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:22:19.517382Z","caller":"traceutil/trace.go:171","msg":"trace[892373850] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"740.996287ms","start":"2026-02-19T05:22:18.776359Z","end":"2026-02-19T05:22:19.517355Z","steps":["trace[892373850] 'process raft request' (duration: 168.190857ms)","trace[892373850] 'compare' (duration: 572.219207ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:19.517459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.776336Z","time spent":"741.08818ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:21.527159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000708967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-19T05:22:21.527364Z","caller":"traceutil/trace.go:171","msg":"trace[638345071] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001005075s","start":"2026-02-19T05:22:19.526335Z","end":"2026-02-19T05:22:21.52734Z","steps":["trace[638345071] 'agreement among raft nodes before linearized reading' (duration: 2.000726988s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:21.527421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:19.526313Z","time spent":"2.001096117s","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/19 05:22:21 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-19T05:22:21.887482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.108509615s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:22:21.888767Z","caller":"traceutil/trace.go:171","msg":"trace[1868720077] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"3.110713624s","start":"2026-02-19T05:22:18.778022Z","end":"2026-02-19T05:22:21.888736Z","steps":["trace[1868720077] 'process raft request' (duration: 1.000828715s)","trace[1868720077] 'compare' (duration: 2.108311871s)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:22:21.888842Z","caller":"traceutil/trace.go:171","msg":"trace[2053173373] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"3.110244792s","start":"2026-02-19T05:22:18.778584Z","end":"2026-02-19T05:22:21.888829Z","steps":["trace[2053173373] 'process raft request' (duration: 3.109084684s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:21.88892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.777998Z","time spent":"3.110834417s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:21.888768Z","caller":"traceutil/trace.go:171","msg":"trace[306411004] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"3.110636042s","start":"2026-02-19T05:22:18.778106Z","end":"2026-02-19T05:22:21.888742Z","steps":["trace[306411004] 'process raft request' (duration: 3.109495965s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:21.88895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.778577Z","time spent":"3.110299704s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:21.889044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:18.778102Z","time spent":"3.110892248s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:22.347981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.242858ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T05:22:22.348123Z","caller":"traceutil/trace.go:171","msg":"trace[1924150998] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:950; }","duration":"2.821729126s","start":"2026-02-19T05:22:19.52637Z","end":"2026-02-19T05:22:22.348099Z","steps":["trace[1924150998] 'read index received' (duration: 252.388678ms)","trace[1924150998] 'applied index is now lower than readState.Index' (duration: 2.569338098s)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:22.348353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.518427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:22:22.348405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.184773097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T05:22:22.348368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010045227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T05:22:22.348431Z","caller":"traceutil/trace.go:171","msg":"trace[566093325] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:912; }","duration":"2.184864279s","start":"2026-02-19T05:22:20.163557Z","end":"2026-02-19T05:22:22.348421Z","steps":["trace[566093325] 'agreement among raft nodes before linearized reading' (duration: 2.184703915s)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:22.348435Z","caller":"traceutil/trace.go:171","msg":"trace[1141000072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"905.654821ms","start":"2026-02-19T05:22:21.442759Z","end":"2026-02-19T05:22:22.348414Z","steps":["trace[1141000072] 'agreement among raft nodes before linearized reading' (duration: 905.510587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:22.348461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:20.163526Z","time spent":"2.184927491s","remote":"127.0.0.1:42940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":86,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-19T05:22:22.34846Z","caller":"traceutil/trace.go:171","msg":"trace[1203967426] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:912; }","duration":"1.010155389s","start":"2026-02-19T05:22:21.338283Z","end":"2026-02-19T05:22:22.348438Z","steps":["trace[1203967426] 'agreement among raft nodes before linearized reading' (duration: 1.009995795s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:22.348481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:21.442741Z","time spent":"905.731642ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:22:22.348515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58169909s","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-02-19T05:22:22.348518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:21.338208Z","time spent":"1.010292773s","remote":"127.0.0.1:43316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-19T05:22:22.348546Z","caller":"traceutil/trace.go:171","msg":"trace[173341824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"1.581754191s","start":"2026-02-19T05:22:20.766783Z","end":"2026-02-19T05:22:22.348537Z","steps":["trace[173341824] 'agreement among raft nodes before linearized reading' (duration: 1.581637099s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:22.348536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.14051ms","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":"warn","ts":"2026-02-19T05:22:22.348564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:20.766767Z","time spent":"1.581793952s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T05:22:22.348731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.352947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:22.348766Z","caller":"traceutil/trace.go:171","msg":"trace[715412422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"821.438489ms","start":"2026-02-19T05:22:21.527318Z","end":"2026-02-19T05:22:22.348757Z","steps":["trace[715412422] 'agreement among raft nodes before linearized reading' (duration: 821.378307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:22.348793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:21.527286Z","time spent":"821.50109ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T05:22:22.348627Z","caller":"traceutil/trace.go:171","msg":"trace[261924656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:912; }","duration":"818.248602ms","start":"2026-02-19T05:22:21.530326Z","end":"2026-02-19T05:22:22.348575Z","steps":["trace[261924656] 'agreement among raft nodes before linearized reading' (duration: 818.055938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:22.348854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:21.530301Z","time spent":"818.53471ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-19T05:22:22.529704Z","caller":"traceutil/trace.go:171","msg":"trace[304981322] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"176.548868ms","start":"2026-02-19T05:22:22.353135Z","end":"2026-02-19T05:22:22.529684Z","steps":["trace[304981322] 'process raft request' (duration: 176.404515ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:22.876334Z","caller":"traceutil/trace.go:171","msg":"trace[1781970477] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:954; }","duration":"353.739571ms","start":"2026-02-19T05:22:22.52256Z","end":"2026-02-19T05:22:22.8763Z","steps":["trace[1781970477] 'read index received' (duration: 7.023869ms)","trace[1781970477] 'applied index is now lower than readState.Index' (duration: 346.714652ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:22:22.876396Z","caller":"traceutil/trace.go:171","msg":"trace[1692807804] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"519.677115ms","start":"2026-02-19T05:22:22.356684Z","end":"2026-02-19T05:22:22.876361Z","steps":["trace[1692807804] 'process raft request' (duration: 519.549602ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:22.876431Z","caller":"traceutil/trace.go:171","msg":"trace[202444323] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"521.12783ms","start":"2026-02-19T05:22:22.355264Z","end":"2026-02-19T05:22:22.876392Z","steps":["trace[202444323] 'process raft request' (duration: 520.782691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:22.8765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.906686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:22.876559Z","caller":"traceutil/trace.go:171","msg":"trace[86891190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"354.024879ms","start":"2026-02-19T05:22:22.522518Z","end":"2026-02-19T05:22:22.876543Z","steps":["trace[86891190] 'agreement among raft nodes before linearized reading' (duration: 353.890115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:22.876576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:22.356666Z","time spent":"519.806518ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:22.876637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:22.522499Z","time spent":"354.124111ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:22:22.876955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:22.35523Z","time spent":"521.278604ms","remote":"127.0.0.1:42940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:22:24.79798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.066508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:22:24.798122Z","caller":"traceutil/trace.go:171","msg":"trace[1848070914] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"886.265836ms","start":"2026-02-19T05:22:23.911836Z","end":"2026-02-19T05:22:24.798102Z","steps":["trace[1848070914] 'read index received' (duration: 278.030625ms)","trace[1848070914] 'applied index is now lower than readState.Index' (duration: 608.233581ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:22:24.798275Z","caller":"traceutil/trace.go:171","msg":"trace[2010685231] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"1.259401813s","start":"2026-02-19T05:22:23.53885Z","end":"2026-02-19T05:22:24.798252Z","steps":["trace[2010685231] 'process raft request' (duration: 650.967807ms)","trace[2010685231] 'compare' (duration: 607.889443ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:24.798396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:23.538834Z","time spent":"1.259490875s","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:24.798537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.675186ms","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-02-19T05:22:24.798639Z","caller":"traceutil/trace.go:171","msg":"trace[1581638420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:916; }","duration":"886.83787ms","start":"2026-02-19T05:22:23.911766Z","end":"2026-02-19T05:22:24.798604Z","steps":["trace[1581638420] 'agreement among raft nodes before linearized reading' (duration: 886.523072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:24.798692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:23.911743Z","time spent":"886.935763ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T05:22:25.31288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.958838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:22:25.313092Z","caller":"traceutil/trace.go:171","msg":"trace[1581316389] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"506.729574ms","start":"2026-02-19T05:22:24.806341Z","end":"2026-02-19T05:22:25.313071Z","steps":["trace[1581316389] 'process raft request' (duration: 506.655002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.313192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:24.806326Z","time spent":"506.821246ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:25.313481Z","caller":"traceutil/trace.go:171","msg":"trace[728448798] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"515.145085ms","start":"2026-02-19T05:22:24.798266Z","end":"2026-02-19T05:22:25.313411Z","steps":["trace[728448798] 'read index received' (duration: 132.711655ms)","trace[728448798] 'applied index is now lower than readState.Index' (duration: 382.42919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:25.313612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40173848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-19T05:22:25.313623Z","caller":"traceutil/trace.go:171","msg":"trace[951245502] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"754.179034ms","start":"2026-02-19T05:22:24.559431Z","end":"2026-02-19T05:22:25.31361Z","steps":["trace[951245502] 'process raft request' (duration: 371.397086ms)","trace[951245502] 'compare' (duration: 381.659831ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:22:25.313642Z","caller":"traceutil/trace.go:171","msg":"trace[687995577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"1.401836752s","start":"2026-02-19T05:22:23.911797Z","end":"2026-02-19T05:22:25.313633Z","steps":["trace[687995577] 'agreement among raft nodes before linearized reading' (duration: 1.40176942s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.313678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:24.559417Z","time spent":"754.235697ms","remote":"127.0.0.1:42940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":786,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:22:25.313699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:23.911774Z","time spent":"1.401885114s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T05:22:25.313678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.156552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T05:22:25.31358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.016214ms","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-02-19T05:22:25.313774Z","caller":"traceutil/trace.go:171","msg":"trace[396769107] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:918; }","duration":"686.294214ms","start":"2026-02-19T05:22:24.627461Z","end":"2026-02-19T05:22:25.313756Z","steps":["trace[396769107] 'agreement among raft nodes before linearized reading' (duration: 686.15665ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:25.313782Z","caller":"traceutil/trace.go:171","msg":"trace[1143767482] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"780.264191ms","start":"2026-02-19T05:22:24.533511Z","end":"2026-02-19T05:22:25.313775Z","steps":["trace[1143767482] 'agreement among raft nodes before linearized reading' (duration: 779.928152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.313809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:24.533491Z","time spent":"780.311902ms","remote":"127.0.0.1:43120","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-02-19T05:22:25.313818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:24.627445Z","time spent":"686.361396ms","remote":"127.0.0.1:43266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-19T05:22:25.313541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.01025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:25.313893Z","caller":"traceutil/trace.go:171","msg":"trace[1113988727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"784.39812ms","start":"2026-02-19T05:22:24.52949Z","end":"2026-02-19T05:22:25.313888Z","steps":["trace[1113988727] 'agreement among raft nodes before linearized reading' (duration: 784.00162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.313911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:24.529474Z","time spent":"784.432191ms","remote":"127.0.0.1:42850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:22:25.314017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40215198s","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-19T05:22:25.314058Z","caller":"traceutil/trace.go:171","msg":"trace[1902288302] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"1.402230012s","start":"2026-02-19T05:22:23.911817Z","end":"2026-02-19T05:22:25.314047Z","steps":["trace[1902288302] 'agreement among raft nodes before linearized reading' (duration: 1.40212794s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.314096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:23.911798Z","time spent":"1.402289593s","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T05:22:25.314136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.027585ms","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-19T05:22:25.314156Z","caller":"traceutil/trace.go:171","msg":"trace[178800677] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:918; }","duration":"433.084726ms","start":"2026-02-19T05:22:24.881066Z","end":"2026-02-19T05:22:25.31415Z","steps":["trace[178800677] 'agreement among raft nodes before linearized reading' (duration: 433.014025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.314179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:24.881045Z","time spent":"433.129578ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T05:22:25.314224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.725833ms","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-02-19T05:22:25.314314Z","caller":"traceutil/trace.go:171","msg":"trace[343617800] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:918; }","duration":"514.01883ms","start":"2026-02-19T05:22:24.800281Z","end":"2026-02-19T05:22:25.3143Z","steps":["trace[343617800] 'agreement among raft nodes before linearized reading' (duration: 513.674692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.314393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:24.800264Z","time spent":"514.116152ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-19T05:22:25.691195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.681554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T05:22:25.691467Z","caller":"traceutil/trace.go:171","msg":"trace[836745205] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:962; }","duration":"166.593779ms","start":"2026-02-19T05:22:25.524855Z","end":"2026-02-19T05:22:25.691449Z","steps":["trace[836745205] 'read index received' (duration: 45.441µs)","trace[836745205] 'applied index is now lower than readState.Index' (duration: 166.547138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:25.691544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.684991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:25.691569Z","caller":"traceutil/trace.go:171","msg":"trace[1325950073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"166.747393ms","start":"2026-02-19T05:22:25.524813Z","end":"2026-02-19T05:22:25.69156Z","steps":["trace[1325950073] 'agreement among raft nodes before linearized reading' (duration: 166.687911ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:25.691766Z","caller":"traceutil/trace.go:171","msg":"trace[1632432153] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"372.315436ms","start":"2026-02-19T05:22:25.319438Z","end":"2026-02-19T05:22:25.691754Z","steps":["trace[1632432153] 'process raft request' (duration: 96.961226ms)","trace[1632432153] 'compare' (duration: 274.560311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:22:25.691845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:25.31943Z","time spent":"372.360008ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:25.691946Z","caller":"traceutil/trace.go:171","msg":"trace[147286980] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"371.040277ms","start":"2026-02-19T05:22:25.320879Z","end":"2026-02-19T05:22:25.69192Z","steps":["trace[147286980] 'process raft request' (duration: 370.479683ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:25.692054Z","caller":"traceutil/trace.go:171","msg":"trace[117748968] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"368.64567ms","start":"2026-02-19T05:22:25.323394Z","end":"2026-02-19T05:22:25.69204Z","steps":["trace[117748968] 'process raft request' (duration: 368.010574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.69207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:25.320837Z","time spent":"371.17272ms","remote":"127.0.0.1:42940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":786,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T05:22:25.692123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:25.323387Z","time spent":"368.694551ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:25.691977Z","caller":"traceutil/trace.go:171","msg":"trace[474940463] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"371.115739ms","start":"2026-02-19T05:22:25.320835Z","end":"2026-02-19T05:22:25.691951Z","steps":["trace[474940463] 'process raft request' (duration: 370.462963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:25.692259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:25.320829Z","time spent":"371.346065ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T05:22:29.829384Z","caller":"traceutil/trace.go:171","msg":"trace[1187825925] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"460.270318ms","start":"2026-02-19T05:22:29.369088Z","end":"2026-02-19T05:22:29.829358Z","steps":["trace[1187825925] 'read index received' (duration: 460.018822ms)","trace[1187825925] 'applied index is now lower than readState.Index' (duration: 250.326µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:22:29.830299Z","caller":"traceutil/trace.go:171","msg":"trace[126371801] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"492.096473ms","start":"2026-02-19T05:22:29.337392Z","end":"2026-02-19T05:22:29.829488Z","steps":["trace[126371801] 'process raft request' (duration: 491.811496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:29.830349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.456124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:22:29.830568Z","caller":"traceutil/trace.go:171","msg":"trace[910269576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"306.477838ms","start":"2026-02-19T05:22:29.524043Z","end":"2026-02-19T05:22:29.830521Z","steps":["trace[910269576] 'agreement among raft nodes before linearized reading' (duration: 305.476124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:29.830618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:29.337383Z","time spent":"492.988684ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T05:22:29.830621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:29.524014Z","time spent":"306.587131ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T05:22:29.832203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.051765ms","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-02-19T05:22:29.832288Z","caller":"traceutil/trace.go:171","msg":"trace[1246407115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:943; }","duration":"463.20865ms","start":"2026-02-19T05:22:29.369044Z","end":"2026-02-19T05:22:29.832253Z","steps":["trace[1246407115] 'agreement among raft nodes before linearized reading' (duration: 460.463484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T05:22:29.832331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:22:29.369022Z","time spent":"463.296972ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T05:22:33.699598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.694241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T05:22:33.699634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.214302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-19T05:22:33.699689Z","caller":"traceutil/trace.go:171","msg":"trace[67754117] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"171.829715ms","start":"2026-02-19T05:22:33.52784Z","end":"2026-02-19T05:22:33.69967Z","steps":["trace[67754117] 'range keys from in-memory index tree' (duration: 171.62472ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:22:33.699717Z","caller":"traceutil/trace.go:171","msg":"trace[1398389477] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:972; }","duration":"169.337654ms","start":"2026-02-19T05:22:33.530359Z","end":"2026-02-19T05:22:33.699697Z","steps":["trace[1398389477] 'range keys from in-memory index tree' (duration: 169.031127ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:23:14.249008Z","caller":"traceutil/trace.go:171","msg":"trace[2077292927] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"110.03815ms","start":"2026-02-19T05:23:14.138938Z","end":"2026-02-19T05:23:14.248976Z","steps":["trace[2077292927] 'process raft request' (duration: 109.974839ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:23:14.249042Z","caller":"traceutil/trace.go:171","msg":"trace[405385205] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"110.188354ms","start":"2026-02-19T05:23:14.138814Z","end":"2026-02-19T05:23:14.249003Z","steps":["trace[405385205] 'process raft request' (duration: 42.308101ms)","trace[405385205] 'compare' (duration: 67.690668ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:23:26.322431Z","caller":"traceutil/trace.go:171","msg":"trace[136892814] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"102.123481ms","start":"2026-02-19T05:23:26.220278Z","end":"2026-02-19T05:23:26.322401Z","steps":["trace[136892814] 'read index received' (duration: 27.734383ms)","trace[136892814] 'applied index is now lower than readState.Index' (duration: 74.388038ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:23:26.322491Z","caller":"traceutil/trace.go:171","msg":"trace[2058577301] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"112.905649ms","start":"2026-02-19T05:23:26.20955Z","end":"2026-02-19T05:23:26.322456Z","steps":["trace[2058577301] 'process raft request' (duration: 38.472449ms)","trace[2058577301] 'compare' (duration: 74.243205ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:23:26.322675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.350386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T05:23:26.322768Z","caller":"traceutil/trace.go:171","msg":"trace[1809649130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"102.617743ms","start":"2026-02-19T05:23:26.22013Z","end":"2026-02-19T05:23:26.322747Z","steps":["trace[1809649130] 'agreement among raft nodes before linearized reading' (duration: 102.425788ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:23:48.695718Z","caller":"traceutil/trace.go:171","msg":"trace[1758733364] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"115.747046ms","start":"2026-02-19T05:23:48.579934Z","end":"2026-02-19T05:23:48.695681Z","steps":["trace[1758733364] 'process raft request' (duration: 115.565192ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:23:48.876727Z","caller":"traceutil/trace.go:171","msg":"trace[245166261] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"295.764229ms","start":"2026-02-19T05:23:48.580936Z","end":"2026-02-19T05:23:48.8767Z","steps":["trace[245166261] 'process raft request' (duration: 295.507183ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:23:48.877001Z","caller":"traceutil/trace.go:171","msg":"trace[1307116511] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"295.167874ms","start":"2026-02-19T05:23:48.58182Z","end":"2026-02-19T05:23:48.876988Z","steps":["trace[1307116511] 'process raft request' (duration: 294.831376ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:23:48.877083Z","caller":"traceutil/trace.go:171","msg":"trace[797690697] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"294.57624ms","start":"2026-02-19T05:23:48.582388Z","end":"2026-02-19T05:23:48.876964Z","steps":["trace[797690697] 'process raft request' (duration: 294.534079ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:23:49.185638Z","caller":"traceutil/trace.go:171","msg":"trace[843030784] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"294.682962ms","start":"2026-02-19T05:23:48.890925Z","end":"2026-02-19T05:23:49.185607Z","steps":["trace[843030784] 'process raft request' (duration: 197.500779ms)","trace[843030784] 'compare' (duration: 97.037739ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:23:49.18571Z","caller":"traceutil/trace.go:171","msg":"trace[743431722] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"269.194543ms","start":"2026-02-19T05:23:48.916454Z","end":"2026-02-19T05:23:49.185649Z","steps":["trace[743431722] 'read index received' (duration: 171.989899ms)","trace[743431722] 'applied index is now lower than readState.Index' (duration: 97.199114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:23:49.280139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.671901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-19T05:23:49.280222Z","caller":"traceutil/trace.go:171","msg":"trace[1770232281] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1303; }","duration":"363.790454ms","start":"2026-02-19T05:23:48.916408Z","end":"2026-02-19T05:23:49.280198Z","steps":["trace[1770232281] 'agreement among raft nodes before linearized reading' (duration: 269.428858ms)","trace[1770232281] 'range keys from in-memory index tree' (duration: 94.170961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T05:23:49.280267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T05:23:48.916389Z","time spent":"363.869206ms","remote":"127.0.0.1:43120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-19T05:23:49.450688Z","caller":"traceutil/trace.go:171","msg":"trace[1144895720] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"114.130728ms","start":"2026-02-19T05:23:49.336537Z","end":"2026-02-19T05:23:49.450668Z","steps":["trace[1144895720] 'process raft request' (duration: 113.997085ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T05:24:36.960125Z","caller":"traceutil/trace.go:171","msg":"trace[2094970060] transaction","detail":"{read_only:false; response_revision:1496; number_of_response:1; }","duration":"102.373729ms","start":"2026-02-19T05:24:36.857719Z","end":"2026-02-19T05:24:36.960093Z","steps":["trace[2094970060] 'process raft request' (duration: 25.235769ms)","trace[2094970060] 'compare' (duration: 77.041098ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T05:25:41.123547Z","caller":"traceutil/trace.go:171","msg":"trace[722871052] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"103.112801ms","start":"2026-02-19T05:25:41.020408Z","end":"2026-02-19T05:25:41.12352Z","steps":["trace[722871052] 'process raft request' (duration: 102.947637ms)"],"step_count":1}