{"level":"warn","ts":"2026-02-24T08:09:55.430577Z","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-24T08:09:55.430724Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-24T08:09:55.430827Z","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-24T08:09:55.430842Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-24T08:09:55.430864Z","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-24T08:09:55.432212Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-24T08:09:55.432303Z","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.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-24T08:09:55.437332Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.744156ms"} {"level":"info","ts":"2026-02-24T08:09:55.450718Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-24T08:09:55.451669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T08:09:55.451716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-24T08:09:55.451733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T08:09:55.451748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-24T08:09:55.451814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-24T08:09:55.462609Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T08:09:55.46449Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T08:09:55.468186Z","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-24T08:09:55.471535Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T08:09:55.471906Z","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-24T08:09:55.471984Z","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-24T08:09:55.471996Z","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-24T08:09:55.471773Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T08:09:55.476611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-24T08:09:55.476741Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-24T08:09:55.479669Z","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-24T08:09:55.479787Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-24T08:09:55.479836Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-24T08:09:55.479947Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T08:09:55.479986Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T08:09:55.852927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T08:09:55.85303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T08:09:55.853062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-24T08:09:55.853086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-24T08:09:55.8531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-24T08:09:55.853359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-24T08:09:55.853379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-24T08:09:55.858503Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T08:09:55.859669Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T08:09:55.859988Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T08:09:55.860036Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T08:09:55.860315Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T08:09:55.860402Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T08:09:55.860457Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T08:09:55.861253Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T08:09:55.861398Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T08:09:55.863122Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T08:09:55.863914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"warn","ts":"2026-02-24T08:10:00.307195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.668278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:10:00.307397Z","caller":"traceutil/trace.go:171","msg":"trace[1509259457] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"302.142884ms","start":"2026-02-24T08:10:00.005225Z","end":"2026-02-24T08:10:00.307368Z","steps":["trace[1509259457] 'process raft request' (duration: 62.366911ms)","trace[1509259457] 'compare' (duration: 238.451763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:00.307495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:00.005201Z","time spent":"302.255066ms","remote":"127.0.0.1:57600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":714,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T08:10:00.557159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.818286ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-24T08:10:00.557318Z","caller":"traceutil/trace.go:171","msg":"trace[1905594882] linearizableReadLoop","detail":"{readStateIndex:239; appliedIndex:238; }","duration":"108.036064ms","start":"2026-02-24T08:10:00.449252Z","end":"2026-02-24T08:10:00.557288Z","steps":["trace[1905594882] 'read index received' (duration: 40.731µs)","trace[1905594882] 'applied index is now lower than readState.Index' (duration: 107.993683ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:00.557405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.155797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:10:00.557429Z","caller":"traceutil/trace.go:171","msg":"trace[1091302199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:233; }","duration":"108.240529ms","start":"2026-02-24T08:10:00.449182Z","end":"2026-02-24T08:10:00.557422Z","steps":["trace[1091302199] 'agreement among raft nodes before linearized reading' (duration: 108.161667ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:01.328098Z","caller":"traceutil/trace.go:171","msg":"trace[658237641] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:263; }","duration":"110.248053ms","start":"2026-02-24T08:10:01.217813Z","end":"2026-02-24T08:10:01.328061Z","steps":["trace[658237641] 'read index received' (duration: 20.589725ms)","trace[658237641] 'applied index is now lower than readState.Index' (duration: 89.656738ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:01.328247Z","caller":"traceutil/trace.go:171","msg":"trace[642254865] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"114.832857ms","start":"2026-02-24T08:10:01.213378Z","end":"2026-02-24T08:10:01.328211Z","steps":["trace[642254865] 'process raft request' (duration: 25.17006ms)","trace[642254865] 'compare' (duration: 89.34907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:01.328501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.641921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-24T08:10:01.328636Z","caller":"traceutil/trace.go:171","msg":"trace[519976285] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:258; }","duration":"110.862516ms","start":"2026-02-24T08:10:01.217746Z","end":"2026-02-24T08:10:01.328609Z","steps":["trace[519976285] 'agreement among raft nodes before linearized reading' (duration: 110.444457ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:01.554119Z","caller":"traceutil/trace.go:171","msg":"trace[1118354205] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:265; }","duration":"135.842332ms","start":"2026-02-24T08:10:01.418252Z","end":"2026-02-24T08:10:01.554095Z","steps":["trace[1118354205] 'read index received' (duration: 100.234247ms)","trace[1118354205] 'applied index is now lower than readState.Index' (duration: 35.607345ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:01.554599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.313824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-24T08:10:01.554712Z","caller":"traceutil/trace.go:171","msg":"trace[1791360735] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:260; }","duration":"136.459587ms","start":"2026-02-24T08:10:01.418222Z","end":"2026-02-24T08:10:01.554682Z","steps":["trace[1791360735] 'agreement among raft nodes before linearized reading' (duration: 136.039277ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:01.554181Z","caller":"traceutil/trace.go:171","msg":"trace[175071754] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"152.784926ms","start":"2026-02-24T08:10:01.401364Z","end":"2026-02-24T08:10:01.554149Z","steps":["trace[175071754] 'process raft request' (duration: 117.18432ms)","trace[175071754] 'compare' (duration: 35.423752ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:02.11386Z","caller":"traceutil/trace.go:171","msg":"trace[1449832894] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"193.858565ms","start":"2026-02-24T08:10:01.919957Z","end":"2026-02-24T08:10:02.113816Z","steps":["trace[1449832894] 'process raft request' (duration: 193.682951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:02.55181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.844402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:10:02.552067Z","caller":"traceutil/trace.go:171","msg":"trace[1006745010] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"189.092096ms","start":"2026-02-24T08:10:02.362945Z","end":"2026-02-24T08:10:02.552037Z","steps":["trace[1006745010] 'process raft request' (duration: 83.639231ms)","trace[1006745010] 'compare' (duration: 104.680538ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:02.552492Z","caller":"traceutil/trace.go:171","msg":"trace[120454267] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"184.140464ms","start":"2026-02-24T08:10:02.368335Z","end":"2026-02-24T08:10:02.552475Z","steps":["trace[120454267] 'process raft request' (duration: 184.026672ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:02.552531Z","caller":"traceutil/trace.go:171","msg":"trace[1960115321] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"184.718958ms","start":"2026-02-24T08:10:02.367758Z","end":"2026-02-24T08:10:02.552477Z","steps":["trace[1960115321] 'process raft request' (duration: 184.241718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:02.83872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.3501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:10:02.838805Z","caller":"traceutil/trace.go:171","msg":"trace[604937395] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:288; }","duration":"167.380915ms","start":"2026-02-24T08:10:02.671411Z","end":"2026-02-24T08:10:02.838792Z","steps":["trace[604937395] 'read index received' (duration: 16.948854ms)","trace[604937395] 'applied index is now lower than readState.Index' (duration: 150.431231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:02.838855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.461708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-24T08:10:02.838874Z","caller":"traceutil/trace.go:171","msg":"trace[1412946838] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:283; }","duration":"167.582491ms","start":"2026-02-24T08:10:02.671283Z","end":"2026-02-24T08:10:02.838866Z","steps":["trace[1412946838] 'agreement among raft nodes before linearized reading' (duration: 167.54008ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:02.838888Z","caller":"traceutil/trace.go:171","msg":"trace[1451446257] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"190.873358ms","start":"2026-02-24T08:10:02.647958Z","end":"2026-02-24T08:10:02.838831Z","steps":["trace[1451446257] 'process raft request' (duration: 40.349933ms)","trace[1451446257] 'compare' (duration: 150.250578ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:03.207617Z","caller":"traceutil/trace.go:171","msg":"trace[1551264694] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:297; }","duration":"146.8451ms","start":"2026-02-24T08:10:03.060735Z","end":"2026-02-24T08:10:03.20758Z","steps":["trace[1551264694] 'read index received' (duration: 93.402812ms)","trace[1551264694] 'applied index is now lower than readState.Index' (duration: 53.440858ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:03.207771Z","caller":"traceutil/trace.go:171","msg":"trace[2142946576] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"166.911864ms","start":"2026-02-24T08:10:03.040844Z","end":"2026-02-24T08:10:03.207756Z","steps":["trace[2142946576] 'process raft request' (duration: 113.273361ms)","trace[2142946576] 'compare' (duration: 53.225394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:03.208038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.127726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-24T08:10:03.208175Z","caller":"traceutil/trace.go:171","msg":"trace[422717740] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:292; }","duration":"173.32822ms","start":"2026-02-24T08:10:03.034822Z","end":"2026-02-24T08:10:03.20815Z","steps":["trace[422717740] 'agreement among raft nodes before linearized reading' (duration: 173.054444ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:03.20973Z","caller":"traceutil/trace.go:171","msg":"trace[387182040] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"142.788099ms","start":"2026-02-24T08:10:03.066907Z","end":"2026-02-24T08:10:03.209695Z","steps":["trace[387182040] 'process raft request' (duration: 142.499363ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:03.209872Z","caller":"traceutil/trace.go:171","msg":"trace[696525291] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"140.522528ms","start":"2026-02-24T08:10:03.069326Z","end":"2026-02-24T08:10:03.209848Z","steps":["trace[696525291] 'process raft request' (duration: 140.276782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:03.210032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.869189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:10:03.210094Z","caller":"traceutil/trace.go:171","msg":"trace[518967523] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:297; }","duration":"141.983021ms","start":"2026-02-24T08:10:03.068095Z","end":"2026-02-24T08:10:03.210078Z","steps":["trace[518967523] 'agreement among raft nodes before linearized reading' (duration: 141.893859ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:03.210228Z","caller":"traceutil/trace.go:171","msg":"trace[1183883875] transaction","detail":"{read_only:false; number_of_response:0; response_revision:294; }","duration":"105.170668ms","start":"2026-02-24T08:10:03.105047Z","end":"2026-02-24T08:10:03.210218Z","steps":["trace[1183883875] 'process raft request' (duration: 104.610666ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.68142Z","caller":"traceutil/trace.go:171","msg":"trace[1407363225] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"206.120662ms","start":"2026-02-24T08:10:05.47527Z","end":"2026-02-24T08:10:05.681391Z","steps":["trace[1407363225] 'process raft request' (duration: 185.382273ms)","trace[1407363225] 'compare' (duration: 20.502313ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:05.68205Z","caller":"traceutil/trace.go:171","msg":"trace[370746414] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"206.702454ms","start":"2026-02-24T08:10:05.475337Z","end":"2026-02-24T08:10:05.682039Z","steps":["trace[370746414] 'process raft request' (duration: 206.579301ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.682215Z","caller":"traceutil/trace.go:171","msg":"trace[147805982] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"206.894079ms","start":"2026-02-24T08:10:05.47527Z","end":"2026-02-24T08:10:05.682164Z","steps":["trace[147805982] 'process raft request' (duration: 206.595112ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.682482Z","caller":"traceutil/trace.go:171","msg":"trace[366030443] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"194.215343ms","start":"2026-02-24T08:10:05.488239Z","end":"2026-02-24T08:10:05.682454Z","steps":["trace[366030443] 'process raft request' (duration: 193.707231ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.68265Z","caller":"traceutil/trace.go:171","msg":"trace[1785700799] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"194.258984ms","start":"2026-02-24T08:10:05.488377Z","end":"2026-02-24T08:10:05.682636Z","steps":["trace[1785700799] 'process raft request' (duration: 193.604989ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.682756Z","caller":"traceutil/trace.go:171","msg":"trace[1360093938] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"191.931331ms","start":"2026-02-24T08:10:05.490795Z","end":"2026-02-24T08:10:05.682726Z","steps":["trace[1360093938] 'process raft request' (duration: 191.215004ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.68221Z","caller":"traceutil/trace.go:171","msg":"trace[887450605] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:353; }","duration":"163.661041ms","start":"2026-02-24T08:10:05.518526Z","end":"2026-02-24T08:10:05.682187Z","steps":["trace[887450605] 'read index received' (duration: 142.137925ms)","trace[887450605] 'applied index is now lower than readState.Index' (duration: 21.521876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:05.68313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.567532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-24T08:10:05.683225Z","caller":"traceutil/trace.go:171","msg":"trace[1704845544] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:347; }","duration":"164.724456ms","start":"2026-02-24T08:10:05.518481Z","end":"2026-02-24T08:10:05.683205Z","steps":["trace[1704845544] 'agreement among raft nodes before linearized reading' (duration: 164.501721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:05.685418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.550359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-24T08:10:05.685488Z","caller":"traceutil/trace.go:171","msg":"trace[1302634415] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:347; }","duration":"117.670491ms","start":"2026-02-24T08:10:05.567798Z","end":"2026-02-24T08:10:05.685468Z","steps":["trace[1302634415] 'agreement among raft nodes before linearized reading' (duration: 115.468571ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.876248Z","caller":"traceutil/trace.go:171","msg":"trace[1958673569] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"100.443862ms","start":"2026-02-24T08:10:05.775707Z","end":"2026-02-24T08:10:05.87615Z","steps":["trace[1958673569] 'read index received' (duration: 6.966477ms)","trace[1958673569] 'applied index is now lower than readState.Index' (duration: 93.475565ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:05.87649Z","caller":"traceutil/trace.go:171","msg":"trace[874536859] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"116.71528ms","start":"2026-02-24T08:10:05.75974Z","end":"2026-02-24T08:10:05.876455Z","steps":["trace[874536859] 'process raft request' (duration: 23.00671ms)","trace[874536859] 'compare' (duration: 93.203938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:05.876731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.515154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-24T08:10:05.876835Z","caller":"traceutil/trace.go:171","msg":"trace[148285103] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:353; }","duration":"108.653307ms","start":"2026-02-24T08:10:05.768152Z","end":"2026-02-24T08:10:05.876806Z","steps":["trace[148285103] 'agreement among raft nodes before linearized reading' (duration: 108.417502ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.88175Z","caller":"traceutil/trace.go:171","msg":"trace[720601623] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"100.091384ms","start":"2026-02-24T08:10:05.781642Z","end":"2026-02-24T08:10:05.881734Z","steps":["trace[720601623] 'process raft request' (duration: 100.052403ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.881829Z","caller":"traceutil/trace.go:171","msg":"trace[627168101] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"101.866924ms","start":"2026-02-24T08:10:05.779943Z","end":"2026-02-24T08:10:05.881809Z","steps":["trace[627168101] 'process raft request' (duration: 101.597228ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:05.881824Z","caller":"traceutil/trace.go:171","msg":"trace[107437563] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"100.733509ms","start":"2026-02-24T08:10:05.78106Z","end":"2026-02-24T08:10:05.881793Z","steps":["trace[107437563] 'process raft request' (duration: 100.591925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.257476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.698134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:10:06.257721Z","caller":"traceutil/trace.go:171","msg":"trace[72966887] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:365; }","duration":"381.163321ms","start":"2026-02-24T08:10:05.876535Z","end":"2026-02-24T08:10:06.257698Z","steps":["trace[72966887] 'read index received' (duration: 4.995024ms)","trace[72966887] 'applied index is now lower than readState.Index' (duration: 376.167067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:06.257808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.664583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-24T08:10:06.257864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.626417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T08:10:06.257882Z","caller":"traceutil/trace.go:171","msg":"trace[506610091] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:358; }","duration":"389.786846ms","start":"2026-02-24T08:10:05.86808Z","end":"2026-02-24T08:10:06.257867Z","steps":["trace[506610091] 'agreement among raft nodes before linearized reading' (duration: 389.645533ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:06.257922Z","caller":"traceutil/trace.go:171","msg":"trace[1612193382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:358; }","duration":"391.70583ms","start":"2026-02-24T08:10:05.866199Z","end":"2026-02-24T08:10:06.257905Z","steps":["trace[1612193382] 'agreement among raft nodes before linearized reading' (duration: 391.610557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.257976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.866183Z","time spent":"391.780881ms","remote":"127.0.0.1:57532","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-24T08:10:06.257924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.868051Z","time spent":"389.864997ms","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-24T08:10:06.258257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.382209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-24T08:10:06.258284Z","caller":"traceutil/trace.go:171","msg":"trace[549098502] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"471.385151ms","start":"2026-02-24T08:10:05.786885Z","end":"2026-02-24T08:10:06.258271Z","steps":["trace[549098502] 'process raft request' (duration: 336.785077ms)","trace[549098502] 'compare' (duration: 133.565391ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:06.258316Z","caller":"traceutil/trace.go:171","msg":"trace[388764759] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:358; }","duration":"436.467101ms","start":"2026-02-24T08:10:05.821835Z","end":"2026-02-24T08:10:06.258302Z","steps":["trace[388764759] 'agreement among raft nodes before linearized reading' (duration: 436.371599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.258365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.821823Z","time spent":"436.531263ms","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":207,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-02-24T08:10:06.25837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.786869Z","time spent":"471.443593ms","remote":"127.0.0.1:57516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:10:06.258543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.964771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-24T08:10:06.258591Z","caller":"traceutil/trace.go:171","msg":"trace[1158634011] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:358; }","duration":"440.047272ms","start":"2026-02-24T08:10:05.818529Z","end":"2026-02-24T08:10:06.258577Z","steps":["trace[1158634011] 'agreement among raft nodes before linearized reading' (duration: 439.976361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.258642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.818521Z","time spent":"440.108874ms","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-24T08:10:06.258806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.650008ms","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-24T08:10:06.258863Z","caller":"traceutil/trace.go:171","msg":"trace[245346683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:358; }","duration":"441.74381ms","start":"2026-02-24T08:10:05.817106Z","end":"2026-02-24T08:10:06.258849Z","steps":["trace[245346683] 'agreement among raft nodes before linearized reading' (duration: 441.654728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.258904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.817084Z","time spent":"441.809512ms","remote":"127.0.0.1:57532","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":"info","ts":"2026-02-24T08:10:06.258599Z","caller":"traceutil/trace.go:171","msg":"trace[408725739] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"471.78767ms","start":"2026-02-24T08:10:05.786801Z","end":"2026-02-24T08:10:06.258589Z","steps":["trace[408725739] 'process raft request' (duration: 470.810248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.259212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.78678Z","time spent":"472.363413ms","remote":"127.0.0.1:57710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3980,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:10:06.261464Z","caller":"traceutil/trace.go:171","msg":"trace[1579584847] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"373.273782ms","start":"2026-02-24T08:10:05.888178Z","end":"2026-02-24T08:10:06.261451Z","steps":["trace[1579584847] 'process raft request' (duration: 373.055227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.261521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.888159Z","time spent":"373.337044ms","remote":"127.0.0.1:57360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T08:10:06.261689Z","caller":"traceutil/trace.go:171","msg":"trace[1401187505] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"371.783968ms","start":"2026-02-24T08:10:05.889872Z","end":"2026-02-24T08:10:06.261656Z","steps":["trace[1401187505] 'process raft request' (duration: 371.542753ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:06.261762Z","caller":"traceutil/trace.go:171","msg":"trace[1600194343] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"373.555238ms","start":"2026-02-24T08:10:05.888195Z","end":"2026-02-24T08:10:06.26175Z","steps":["trace[1600194343] 'process raft request' (duration: 373.16889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.261832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.888177Z","time spent":"373.629719ms","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T08:10:06.262009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.889858Z","time spent":"371.972102ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:10:06.262132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.632852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-24T08:10:06.262216Z","caller":"traceutil/trace.go:171","msg":"trace[12531471] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:361; }","duration":"343.702063ms","start":"2026-02-24T08:10:05.918463Z","end":"2026-02-24T08:10:06.262165Z","steps":["trace[12531471] 'agreement among raft nodes before linearized reading' (duration: 343.58683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:06.262249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:05.918451Z","time spent":"343.791166ms","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-02-24T08:10:13.492274Z","caller":"traceutil/trace.go:171","msg":"trace[674383430] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"385.332763ms","start":"2026-02-24T08:10:13.106914Z","end":"2026-02-24T08:10:13.492247Z","steps":["trace[674383430] 'process raft request' (duration: 379.909441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:13.492448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:13.106884Z","time spent":"385.470325ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:10:19.091948Z","caller":"traceutil/trace.go:171","msg":"trace[405453232] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"137.840257ms","start":"2026-02-24T08:10:18.954088Z","end":"2026-02-24T08:10:19.091929Z","steps":["trace[405453232] 'process raft request' (duration: 137.707044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:19.681201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.354581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:10:19.681316Z","caller":"traceutil/trace.go:171","msg":"trace[1874849124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:459; }","duration":"395.542826ms","start":"2026-02-24T08:10:19.285748Z","end":"2026-02-24T08:10:19.681291Z","steps":["trace[1874849124] 'range keys from in-memory index tree' (duration: 395.240899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:19.681785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:19.285729Z","time spent":"395.972415ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T08:10:28.951659Z","caller":"traceutil/trace.go:171","msg":"trace[1800603348] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"238.002866ms","start":"2026-02-24T08:10:28.713633Z","end":"2026-02-24T08:10:28.951636Z","steps":["trace[1800603348] 'read index received' (duration: 237.830932ms)","trace[1800603348] 'applied index is now lower than readState.Index' (duration: 171.044µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:28.951817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.161989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T08:10:28.951809Z","caller":"traceutil/trace.go:171","msg":"trace[656784195] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"306.323868ms","start":"2026-02-24T08:10:28.645434Z","end":"2026-02-24T08:10:28.951758Z","steps":["trace[656784195] 'process raft request' (duration: 306.078873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:28.952006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:28.645409Z","time spent":"306.500703ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:10:28.951852Z","caller":"traceutil/trace.go:171","msg":"trace[275747492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"238.239561ms","start":"2026-02-24T08:10:28.713602Z","end":"2026-02-24T08:10:28.951841Z","steps":["trace[275747492] 'agreement among raft nodes before linearized reading' (duration: 238.159289ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:36.61857Z","caller":"traceutil/trace.go:171","msg":"trace[137878607] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"143.502527ms","start":"2026-02-24T08:10:36.475043Z","end":"2026-02-24T08:10:36.618546Z","steps":["trace[137878607] 'read index received' (duration: 143.318903ms)","trace[137878607] 'applied index is now lower than readState.Index' (duration: 182.283µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:36.618713Z","caller":"traceutil/trace.go:171","msg":"trace[619141476] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"459.780764ms","start":"2026-02-24T08:10:36.158893Z","end":"2026-02-24T08:10:36.618673Z","steps":["trace[619141476] 'process raft request' (duration: 459.503188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:36.618878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:36.158864Z","time spent":"459.933428ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:10:36.618954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.864304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T08:10:36.619083Z","caller":"traceutil/trace.go:171","msg":"trace[721405607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:537; }","duration":"144.088409ms","start":"2026-02-24T08:10:36.474968Z","end":"2026-02-24T08:10:36.619056Z","steps":["trace[721405607] 'agreement among raft nodes before linearized reading' (duration: 143.737661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:36.620181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.64629ms","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-24T08:10:36.620289Z","caller":"traceutil/trace.go:171","msg":"trace[1695766217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"123.810734ms","start":"2026-02-24T08:10:36.496452Z","end":"2026-02-24T08:10:36.620263Z","steps":["trace[1695766217] 'agreement among raft nodes before linearized reading' (duration: 122.324241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:36.796124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.323589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:10:36.796268Z","caller":"traceutil/trace.go:171","msg":"trace[1768376856] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"164.457787ms","start":"2026-02-24T08:10:36.631784Z","end":"2026-02-24T08:10:36.796241Z","steps":["trace[1768376856] 'process raft request' (duration: 62.808521ms)","trace[1768376856] 'compare' (duration: 101.076464ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:36.814087Z","caller":"traceutil/trace.go:171","msg":"trace[1868027187] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"181.578235ms","start":"2026-02-24T08:10:36.632471Z","end":"2026-02-24T08:10:36.814049Z","steps":["trace[1868027187] 'process raft request' (duration: 181.466172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:37.778647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088667213004681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:10:38.27944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088667213004681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:10:38.72443Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.724311811s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T08:10:38.724885Z","caller":"traceutil/trace.go:171","msg":"trace[544055761] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"1.446788006s","start":"2026-02-24T08:10:37.278074Z","end":"2026-02-24T08:10:38.724862Z","steps":["trace[544055761] 'read index received' (duration: 1.446604672s)","trace[544055761] 'applied index is now lower than readState.Index' (duration: 181.744µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:38.725042Z","caller":"traceutil/trace.go:171","msg":"trace[1651757014] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.725034957s","start":"2026-02-24T08:10:36.999972Z","end":"2026-02-24T08:10:38.725007Z","steps":["trace[1651757014] 'process raft request' (duration: 1.724731461s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:38.725183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411588442s","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-24T08:10:38.725219Z","caller":"traceutil/trace.go:171","msg":"trace[25591583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"1.411633573s","start":"2026-02-24T08:10:37.313577Z","end":"2026-02-24T08:10:38.72521Z","steps":["trace[25591583] 'agreement among raft nodes before linearized reading' (duration: 1.41148899s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:38.725048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446969411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T08:10:38.72524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:37.313571Z","time spent":"1.411664043s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T08:10:38.725289Z","caller":"traceutil/trace.go:171","msg":"trace[1278469023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.447235557s","start":"2026-02-24T08:10:37.278019Z","end":"2026-02-24T08:10:38.725255Z","steps":["trace[1278469023] 'agreement among raft nodes before linearized reading' (duration: 1.446984471s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:38.725404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:37.277999Z","time spent":"1.447359099s","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T08:10:38.726046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:36.999945Z","time spent":"1.725220742s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:10:40.667265Z","caller":"traceutil/trace.go:171","msg":"trace[1925749367] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"281.255816ms","start":"2026-02-24T08:10:40.385964Z","end":"2026-02-24T08:10:40.66722Z","steps":["trace[1925749367] 'read index received' (duration: 280.816277ms)","trace[1925749367] 'applied index is now lower than readState.Index' (duration: 438.099µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:40.667566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.591084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T08:10:40.667625Z","caller":"traceutil/trace.go:171","msg":"trace[281788878] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:553; }","duration":"281.696306ms","start":"2026-02-24T08:10:40.385916Z","end":"2026-02-24T08:10:40.667612Z","steps":["trace[281788878] 'agreement among raft nodes before linearized reading' (duration: 281.463201ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:40.667319Z","caller":"traceutil/trace.go:171","msg":"trace[1123829631] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"297.396182ms","start":"2026-02-24T08:10:40.369846Z","end":"2026-02-24T08:10:40.667242Z","steps":["trace[1123829631] 'process raft request' (duration: 297.001283ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:51.147972Z","caller":"traceutil/trace.go:171","msg":"trace[2035584006] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"130.403248ms","start":"2026-02-24T08:10:51.017551Z","end":"2026-02-24T08:10:51.147954Z","steps":["trace[2035584006] 'process raft request' (duration: 130.288275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:51.556397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.985575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-cb2hh\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-24T08:10:51.556461Z","caller":"traceutil/trace.go:171","msg":"trace[2095572332] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-cb2hh; range_end:; response_count:1; response_revision:686; }","duration":"298.094347ms","start":"2026-02-24T08:10:51.258352Z","end":"2026-02-24T08:10:51.556446Z","steps":["trace[2095572332] 'range keys from in-memory index tree' (duration: 297.827302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:51.556556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.500103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:10:51.556642Z","caller":"traceutil/trace.go:171","msg":"trace[1402448593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"276.637336ms","start":"2026-02-24T08:10:51.279981Z","end":"2026-02-24T08:10:51.556618Z","steps":["trace[1402448593] 'range keys from in-memory index tree' (duration: 276.403821ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:57.174411Z","caller":"traceutil/trace.go:171","msg":"trace[738759388] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"320.092112ms","start":"2026-02-24T08:10:56.854293Z","end":"2026-02-24T08:10:57.174385Z","steps":["trace[738759388] 'read index received' (duration: 319.917168ms)","trace[738759388] 'applied index is now lower than readState.Index' (duration: 174.014µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:57.174513Z","caller":"traceutil/trace.go:171","msg":"trace[963637902] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"342.575507ms","start":"2026-02-24T08:10:56.831913Z","end":"2026-02-24T08:10:57.174488Z","steps":["trace[963637902] 'process raft request' (duration: 342.27816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:57.174644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:56.831896Z","time spent":"342.677909ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:10:57.174683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.26384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T08:10:57.174685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.372428ms","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-24T08:10:57.174738Z","caller":"traceutil/trace.go:171","msg":"trace[744440568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"267.361372ms","start":"2026-02-24T08:10:56.907359Z","end":"2026-02-24T08:10:57.17472Z","steps":["trace[744440568] 'agreement among raft nodes before linearized reading' (duration: 267.182579ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:57.174785Z","caller":"traceutil/trace.go:171","msg":"trace[1770827926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:725; }","duration":"320.511502ms","start":"2026-02-24T08:10:56.854258Z","end":"2026-02-24T08:10:57.174769Z","steps":["trace[1770827926] 'agreement among raft nodes before linearized reading' (duration: 320.280947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:57.174811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.604689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T08:10:57.17483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:56.854242Z","time spent":"320.573453ms","remote":"127.0.0.1:57532","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":"info","ts":"2026-02-24T08:10:57.174857Z","caller":"traceutil/trace.go:171","msg":"trace[1354446795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:725; }","duration":"263.681371ms","start":"2026-02-24T08:10:56.911163Z","end":"2026-02-24T08:10:57.174844Z","steps":["trace[1354446795] 'agreement among raft nodes before linearized reading' (duration: 263.566929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:57.679316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088667213005036,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:10:59.262327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.624214ms","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-24T08:10:59.26247Z","caller":"traceutil/trace.go:171","msg":"trace[170974409] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:725; }","duration":"726.776247ms","start":"2026-02-24T08:10:58.535677Z","end":"2026-02-24T08:10:59.262453Z","steps":["trace[170974409] 'range keys from in-memory index tree' (duration: 726.608043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:59.262814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497553112s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:10:59.262893Z","caller":"traceutil/trace.go:171","msg":"trace[1529099432] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"2.083697637s","start":"2026-02-24T08:10:57.179182Z","end":"2026-02-24T08:10:59.26288Z","steps":["trace[1529099432] 'process raft request' (duration: 585.985951ms)","trace[1529099432] 'compare' (duration: 1.497058382s)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:59.262947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:57.179158Z","time spent":"2.083761639s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:10:59.301853Z","caller":"traceutil/trace.go:171","msg":"trace[396913845] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"2.122143533s","start":"2026-02-24T08:10:57.179693Z","end":"2026-02-24T08:10:59.301836Z","steps":["trace[396913845] 'process raft request' (duration: 2.12198744s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:59.301956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:57.179682Z","time spent":"2.122225445s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:10:59.301987Z","caller":"traceutil/trace.go:171","msg":"trace[1681216495] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:750; }","duration":"2.122732226s","start":"2026-02-24T08:10:57.179225Z","end":"2026-02-24T08:10:59.301957Z","steps":["trace[1681216495] 'read index received' (duration: 585.982291ms)","trace[1681216495] 'applied index is now lower than readState.Index' (duration: 1.536748145s)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:59.302096Z","caller":"traceutil/trace.go:171","msg":"trace[422151722] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"2.122259125s","start":"2026-02-24T08:10:57.179827Z","end":"2026-02-24T08:10:59.302087Z","steps":["trace[422151722] 'process raft request' (duration: 2.121952548s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:59.302201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:57.179822Z","time spent":"2.122299845s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:10:59.30219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902689304s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T08:10:59.302265Z","caller":"traceutil/trace.go:171","msg":"trace[1999227358] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:728; }","duration":"1.902834358s","start":"2026-02-24T08:10:57.399413Z","end":"2026-02-24T08:10:59.302247Z","steps":["trace[1999227358] 'agreement among raft nodes before linearized reading' (duration: 1.902690595s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:59.302304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:57.39938Z","time spent":"1.902915019s","remote":"127.0.0.1:57438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-24T08:10:59.302375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.749819ms","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-24T08:10:59.302419Z","caller":"traceutil/trace.go:171","msg":"trace[180031440] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:728; }","duration":"567.83017ms","start":"2026-02-24T08:10:58.734576Z","end":"2026-02-24T08:10:59.302406Z","steps":["trace[180031440] 'agreement among raft nodes before linearized reading' (duration: 567.737298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:59.302451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:58.734556Z","time spent":"567.887052ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-24T08:10:59.3025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023012164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:10:59.302529Z","caller":"traceutil/trace.go:171","msg":"trace[428099754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:728; }","duration":"1.023088605s","start":"2026-02-24T08:10:58.279431Z","end":"2026-02-24T08:10:59.302519Z","steps":["trace[428099754] 'agreement among raft nodes before linearized reading' (duration: 1.023044704s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:59.302555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:58.279403Z","time spent":"1.023147807s","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T08:10:59.302585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.573561373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T08:10:59.302629Z","caller":"traceutil/trace.go:171","msg":"trace[1175918273] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:728; }","duration":"1.573638105s","start":"2026-02-24T08:10:57.728977Z","end":"2026-02-24T08:10:59.302615Z","steps":["trace[1175918273] 'agreement among raft nodes before linearized reading' (duration: 1.573562724s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:59.302661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:57.728958Z","time spent":"1.573694776s","remote":"127.0.0.1:57516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-24T08:10:59.302692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.123466602s","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-24T08:10:59.302725Z","caller":"traceutil/trace.go:171","msg":"trace[1398087615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:728; }","duration":"2.123556934s","start":"2026-02-24T08:10:57.179159Z","end":"2026-02-24T08:10:59.302716Z","steps":["trace[1398087615] 'agreement among raft nodes before linearized reading' (duration: 2.123440022s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:10:59.302782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:10:57.179125Z","time spent":"2.123648016s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T08:10:59.302995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.180532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T08:10:59.30309Z","caller":"traceutil/trace.go:171","msg":"trace[1939044084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"124.297804ms","start":"2026-02-24T08:10:59.178778Z","end":"2026-02-24T08:10:59.303076Z","steps":["trace[1939044084] 'agreement among raft nodes before linearized reading' (duration: 124.12412ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:59.481396Z","caller":"traceutil/trace.go:171","msg":"trace[161760897] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"174.033079ms","start":"2026-02-24T08:10:59.307336Z","end":"2026-02-24T08:10:59.481369Z","steps":["trace[161760897] 'process raft request' (duration: 125.789798ms)","trace[161760897] 'compare' (duration: 48.039607ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:10:59.481546Z","caller":"traceutil/trace.go:171","msg":"trace[1670973971] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:753; }","duration":"167.788112ms","start":"2026-02-24T08:10:59.313739Z","end":"2026-02-24T08:10:59.481527Z","steps":["trace[1670973971] 'read index received' (duration: 119.404647ms)","trace[1670973971] 'applied index is now lower than readState.Index' (duration: 48.382475ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:10:59.481652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.900074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:10:59.481674Z","caller":"traceutil/trace.go:171","msg":"trace[1115381351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"167.972596ms","start":"2026-02-24T08:10:59.313694Z","end":"2026-02-24T08:10:59.481667Z","steps":["trace[1115381351] 'agreement among raft nodes before linearized reading' (duration: 167.895664ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:10:59.481824Z","caller":"traceutil/trace.go:171","msg":"trace[30298134] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"173.929367ms","start":"2026-02-24T08:10:59.307888Z","end":"2026-02-24T08:10:59.481817Z","steps":["trace[30298134] 'process raft request' (duration: 173.438966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:00.783963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.715796ms","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-24T08:11:00.784039Z","caller":"traceutil/trace.go:171","msg":"trace[192605029] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:731; }","duration":"746.84012ms","start":"2026-02-24T08:11:00.03718Z","end":"2026-02-24T08:11:00.78402Z","steps":["trace[192605029] 'count revisions from in-memory index tree' (duration: 746.624115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:00.78406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.631621ms","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":"warn","ts":"2026-02-24T08:11:00.784084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:00.037161Z","time spent":"746.908951ms","remote":"127.0.0.1:57710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-24T08:11:00.784095Z","caller":"traceutil/trace.go:171","msg":"trace[1133552523] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:731; }","duration":"669.701583ms","start":"2026-02-24T08:11:00.114384Z","end":"2026-02-24T08:11:00.784085Z","steps":["trace[1133552523] 'count revisions from in-memory index tree' (duration: 669.566189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:00.784117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:00.114367Z","time spent":"669.744453ms","remote":"127.0.0.1:57416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-24T08:11:00.784528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.737161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T08:11:00.784553Z","caller":"traceutil/trace.go:171","msg":"trace[1816247994] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:731; }","duration":"563.765822ms","start":"2026-02-24T08:11:00.22078Z","end":"2026-02-24T08:11:00.784546Z","steps":["trace[1816247994] 'count revisions from in-memory index tree' (duration: 563.68527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:00.784571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:00.22074Z","time spent":"563.825573ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-24T08:11:00.786437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.800585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T08:11:00.786549Z","caller":"traceutil/trace.go:171","msg":"trace[313478390] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:731; }","duration":"399.977319ms","start":"2026-02-24T08:11:00.386542Z","end":"2026-02-24T08:11:00.786519Z","steps":["trace[313478390] 'range keys from in-memory index tree' (duration: 399.559921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:00.786614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:00.38651Z","time spent":"400.089432ms","remote":"127.0.0.1:57286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-24T08:11:25.307465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088667213005265,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T08:11:25.313206Z","caller":"traceutil/trace.go:171","msg":"trace[1914658645] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"506.763548ms","start":"2026-02-24T08:11:24.80641Z","end":"2026-02-24T08:11:25.313173Z","steps":["trace[1914658645] 'read index received' (duration: 506.455141ms)","trace[1914658645] 'applied index is now lower than readState.Index' (duration: 307.277µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:11:25.313391Z","caller":"traceutil/trace.go:171","msg":"trace[1048413734] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"513.693331ms","start":"2026-02-24T08:11:24.799651Z","end":"2026-02-24T08:11:25.313344Z","steps":["trace[1048413734] 'process raft request' (duration: 513.310382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:25.313523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.993463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:11:25.313628Z","caller":"traceutil/trace.go:171","msg":"trace[806350923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"507.250869ms","start":"2026-02-24T08:11:24.806359Z","end":"2026-02-24T08:11:25.313609Z","steps":["trace[806350923] 'agreement among raft nodes before linearized reading' (duration: 506.979933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:25.313629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:24.799615Z","time spent":"513.864805ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:11:25.313672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:24.806332Z","time spent":"507.329411ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T08:11:25.313966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.605623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T08:11:25.314345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.990691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T08:11:25.314396Z","caller":"traceutil/trace.go:171","msg":"trace[435095526] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:805; }","duration":"175.104123ms","start":"2026-02-24T08:11:25.139281Z","end":"2026-02-24T08:11:25.314386Z","steps":["trace[435095526] 'agreement among raft nodes before linearized reading' (duration: 175.002861ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:11:25.315095Z","caller":"traceutil/trace.go:171","msg":"trace[844600250] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:805; }","duration":"420.787218ms","start":"2026-02-24T08:11:24.893287Z","end":"2026-02-24T08:11:25.314074Z","steps":["trace[844600250] 'agreement among raft nodes before linearized reading' (duration: 420.610473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:25.315151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:24.893258Z","time spent":"421.881622ms","remote":"127.0.0.1:57448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-24T08:11:25.736167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.650472ms","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-24T08:11:25.736271Z","caller":"traceutil/trace.go:171","msg":"trace[869492716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"234.819466ms","start":"2026-02-24T08:11:25.501426Z","end":"2026-02-24T08:11:25.736246Z","steps":["trace[869492716] 'range keys from in-memory index tree' (duration: 234.453238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:25.736266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.576507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T08:11:25.736363Z","caller":"traceutil/trace.go:171","msg":"trace[1553867273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"132.74365ms","start":"2026-02-24T08:11:25.603596Z","end":"2026-02-24T08:11:25.73634Z","steps":["trace[1553867273] 'range keys from in-memory index tree' (duration: 132.352772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:25.736376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.372664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T08:11:25.736493Z","caller":"traceutil/trace.go:171","msg":"trace[1040806828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"263.522717ms","start":"2026-02-24T08:11:25.472941Z","end":"2026-02-24T08:11:25.736464Z","steps":["trace[1040806828] 'range keys from in-memory index tree' (duration: 263.076607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:25.736551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.403816ms","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-24T08:11:25.736587Z","caller":"traceutil/trace.go:171","msg":"trace[1638074145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"234.440327ms","start":"2026-02-24T08:11:25.502137Z","end":"2026-02-24T08:11:25.736578Z","steps":["trace[1638074145] 'range keys from in-memory index tree' (duration: 234.260893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:25.736169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.535417ms","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-24T08:11:25.736685Z","caller":"traceutil/trace.go:171","msg":"trace[1539956283] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"134.078249ms","start":"2026-02-24T08:11:25.602581Z","end":"2026-02-24T08:11:25.736659Z","steps":["trace[1539956283] 'range keys from in-memory index tree' (duration: 133.377364ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:11:25.90103Z","caller":"traceutil/trace.go:171","msg":"trace[1579761351] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"157.190248ms","start":"2026-02-24T08:11:25.743628Z","end":"2026-02-24T08:11:25.900818Z","steps":["trace[1579761351] 'process raft request' (duration: 157.034205ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:11:25.901306Z","caller":"traceutil/trace.go:171","msg":"trace[1677738064] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"157.533185ms","start":"2026-02-24T08:11:25.743749Z","end":"2026-02-24T08:11:25.901282Z","steps":["trace[1677738064] 'process raft request' (duration: 157.482434ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:11:25.901462Z","caller":"traceutil/trace.go:171","msg":"trace[1615079455] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"157.73361ms","start":"2026-02-24T08:11:25.743717Z","end":"2026-02-24T08:11:25.90145Z","steps":["trace[1615079455] 'process raft request' (duration: 157.072795ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:11:25.901572Z","caller":"traceutil/trace.go:171","msg":"trace[737729165] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"157.831712ms","start":"2026-02-24T08:11:25.743717Z","end":"2026-02-24T08:11:25.901548Z","steps":["trace[737729165] 'process raft request' (duration: 157.427363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:28.278227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.171866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:11:28.278405Z","caller":"traceutil/trace.go:171","msg":"trace[1810033738] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"367.392893ms","start":"2026-02-24T08:11:27.910986Z","end":"2026-02-24T08:11:28.278379Z","steps":["trace[1810033738] 'process raft request' (duration: 50.92666ms)","trace[1810033738] 'compare' (duration: 315.982892ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:11:28.27855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:27.910953Z","time spent":"367.497584ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:11:28.360402Z","caller":"traceutil/trace.go:171","msg":"trace[907486787] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"448.943417ms","start":"2026-02-24T08:11:27.91141Z","end":"2026-02-24T08:11:28.360353Z","steps":["trace[907486787] 'process raft request' (duration: 448.797394ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:11:28.36044Z","caller":"traceutil/trace.go:171","msg":"trace[2129989207] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"448.975267ms","start":"2026-02-24T08:11:27.911425Z","end":"2026-02-24T08:11:28.3604Z","steps":["trace[2129989207] 'process raft request' (duration: 448.825273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:28.360909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:27.911385Z","time spent":"449.219332ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:11:28.360954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:27.911408Z","time spent":"449.362296ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:11:28.360488Z","caller":"traceutil/trace.go:171","msg":"trace[1374405286] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"449.09989ms","start":"2026-02-24T08:11:27.91135Z","end":"2026-02-24T08:11:28.36045Z","steps":["trace[1374405286] 'process raft request' (duration: 448.684981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:28.361142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:27.911328Z","time spent":"449.778694ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:11:42.07445Z","caller":"traceutil/trace.go:171","msg":"trace[2090659992] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"108.189097ms","start":"2026-02-24T08:11:41.966234Z","end":"2026-02-24T08:11:42.074423Z","steps":["trace[2090659992] 'process raft request' (duration: 108.037354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:43.146135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.138419ms","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-24T08:11:43.146191Z","caller":"traceutil/trace.go:171","msg":"trace[779926521] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:903; }","duration":"809.206021ms","start":"2026-02-24T08:11:42.336973Z","end":"2026-02-24T08:11:43.146179Z","steps":["trace[779926521] 'range keys from in-memory index tree' (duration: 809.018397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:43.146218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:42.336931Z","time spent":"809.280322ms","remote":"127.0.0.1:57532","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-24T08:11:43.146389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.467077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T08:11:43.146412Z","caller":"traceutil/trace.go:171","msg":"trace[1871324610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:903; }","duration":"714.542579ms","start":"2026-02-24T08:11:42.431861Z","end":"2026-02-24T08:11:43.146404Z","steps":["trace[1871324610] 'range keys from in-memory index tree' (duration: 714.341504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:43.146437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:42.431841Z","time spent":"714.59165ms","remote":"127.0.0.1:57532","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-24T08:11:43.146555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.061772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T08:11:43.146579Z","caller":"traceutil/trace.go:171","msg":"trace[1184911702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:903; }","duration":"679.101603ms","start":"2026-02-24T08:11:42.467472Z","end":"2026-02-24T08:11:43.146573Z","steps":["trace[1184911702] 'range keys from in-memory index tree' (duration: 679.003821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:43.146595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:42.467468Z","time spent":"679.122854ms","remote":"127.0.0.1:57532","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-24T08:11:43.146769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.332048ms","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-24T08:11:43.146833Z","caller":"traceutil/trace.go:171","msg":"trace[1282389411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:903; }","duration":"679.427279ms","start":"2026-02-24T08:11:42.467392Z","end":"2026-02-24T08:11:43.146819Z","steps":["trace[1282389411] 'range keys from in-memory index tree' (duration: 679.161574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:11:43.146874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:11:42.467372Z","time spent":"679.493141ms","remote":"127.0.0.1:57532","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":"info","ts":"2026-02-24T08:11:59.481313Z","caller":"traceutil/trace.go:171","msg":"trace[937499610] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"115.214492ms","start":"2026-02-24T08:11:59.366078Z","end":"2026-02-24T08:11:59.481292Z","steps":["trace[937499610] 'process raft request' (duration: 115.095239ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:11:59.483364Z","caller":"traceutil/trace.go:171","msg":"trace[644197980] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"115.290593ms","start":"2026-02-24T08:11:59.368052Z","end":"2026-02-24T08:11:59.483342Z","steps":["trace[644197980] 'process raft request' (duration: 115.15524ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:11:59.483571Z","caller":"traceutil/trace.go:171","msg":"trace[1145444949] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"115.412606ms","start":"2026-02-24T08:11:59.368117Z","end":"2026-02-24T08:11:59.48353Z","steps":["trace[1145444949] 'process raft request' (duration: 115.192211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:00.642437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.977839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:12:00.642572Z","caller":"traceutil/trace.go:171","msg":"trace[1267669541] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"211.796356ms","start":"2026-02-24T08:12:00.430741Z","end":"2026-02-24T08:12:00.642538Z","steps":["trace[1267669541] 'process raft request' (duration: 68.615143ms)","trace[1267669541] 'compare' (duration: 142.835856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:12:01.867099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088667213005675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:12:02.012804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.280253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:12:02.012927Z","caller":"traceutil/trace.go:171","msg":"trace[1011376407] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"735.436026ms","start":"2026-02-24T08:12:01.277474Z","end":"2026-02-24T08:12:02.01291Z","steps":["trace[1011376407] 'range keys from in-memory index tree' (duration: 735.090219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.012975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:01.277461Z","time spent":"735.505947ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T08:12:02.367328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088667213005675,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:12:02.620045Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.30947603s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T08:12:02.620435Z","caller":"traceutil/trace.go:171","msg":"trace[294688324] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.30994213s","start":"2026-02-24T08:12:01.310461Z","end":"2026-02-24T08:12:02.620403Z","steps":["trace[294688324] 'process raft request' (duration: 1.309781107s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.620551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:01.310431Z","time spent":"1.310057203s","remote":"127.0.0.1:57342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T08:12:02.6213Z","caller":"traceutil/trace.go:171","msg":"trace[1475550678] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1017; }","duration":"1.254542407s","start":"2026-02-24T08:12:01.366738Z","end":"2026-02-24T08:12:02.62128Z","steps":["trace[1475550678] 'read index received' (duration: 1.254536887s)","trace[1475550678] 'applied index is now lower than readState.Index' (duration: 4.31µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:12:02.621495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.136288529s","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-24T08:12:02.621533Z","caller":"traceutil/trace.go:171","msg":"trace[843200256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"1.136377721s","start":"2026-02-24T08:12:01.485145Z","end":"2026-02-24T08:12:02.621523Z","steps":["trace[843200256] 'agreement among raft nodes before linearized reading' (duration: 1.136220208s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.621563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:01.485124Z","time spent":"1.136432063s","remote":"127.0.0.1:57532","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-24T08:12:02.621848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255102439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T08:12:02.621902Z","caller":"traceutil/trace.go:171","msg":"trace[924556746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"1.255192681s","start":"2026-02-24T08:12:01.366696Z","end":"2026-02-24T08:12:02.621888Z","steps":["trace[924556746] 'agreement among raft nodes before linearized reading' (duration: 1.255078299s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.621926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:01.366675Z","time spent":"1.255244563s","remote":"127.0.0.1:57532","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-24T08:12:02.622036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.345979ms","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-24T08:12:02.622054Z","caller":"traceutil/trace.go:171","msg":"trace[1552032234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"399.40397ms","start":"2026-02-24T08:12:02.222646Z","end":"2026-02-24T08:12:02.62205Z","steps":["trace[1552032234] 'agreement among raft nodes before linearized reading' (duration: 399.357779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.622067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:02.222623Z","time spent":"399.440312ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T08:12:02.622131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.930355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:12:02.622158Z","caller":"traceutil/trace.go:171","msg":"trace[1464944740] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"607.984147ms","start":"2026-02-24T08:12:02.01417Z","end":"2026-02-24T08:12:02.622154Z","steps":["trace[1464944740] 'agreement among raft nodes before linearized reading' (duration: 607.950886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.62217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:02.014156Z","time spent":"608.011157ms","remote":"127.0.0.1:57266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T08:12:02.622271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.096407346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:12:02.622292Z","caller":"traceutil/trace.go:171","msg":"trace[242220449] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:975; }","duration":"1.096463077s","start":"2026-02-24T08:12:01.525822Z","end":"2026-02-24T08:12:02.622286Z","steps":["trace[242220449] 'agreement among raft nodes before linearized reading' (duration: 1.096435106s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.622309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:01.525804Z","time spent":"1.096500038s","remote":"127.0.0.1:57788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-24T08:12:02.622421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132185839s","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-24T08:12:02.622444Z","caller":"traceutil/trace.go:171","msg":"trace[1372824257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"1.13221736s","start":"2026-02-24T08:12:01.490221Z","end":"2026-02-24T08:12:02.622439Z","steps":["trace[1372824257] 'agreement among raft nodes before linearized reading' (duration: 1.132161239s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.622459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:01.490218Z","time spent":"1.13223793s","remote":"127.0.0.1:57532","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-24T08:12:02.622544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132335282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T08:12:02.622561Z","caller":"traceutil/trace.go:171","msg":"trace[1683527091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"1.132367073s","start":"2026-02-24T08:12:01.49019Z","end":"2026-02-24T08:12:02.622557Z","steps":["trace[1683527091] 'agreement among raft nodes before linearized reading' (duration: 1.132325932s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:02.622573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:01.490148Z","time spent":"1.132422485s","remote":"127.0.0.1:57532","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-24T08:12:03.133217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088667213005678,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:12:03.884309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.664989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:12:03.884673Z","caller":"traceutil/trace.go:171","msg":"trace[533832944] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.259009564s","start":"2026-02-24T08:12:02.625632Z","end":"2026-02-24T08:12:03.884641Z","steps":["trace[533832944] 'process raft request' (duration: 910.852615ms)","trace[533832944] 'compare' (duration: 346.626845ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:12:03.884861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:02.625614Z","time spent":"1.259146678s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:12:03.884803Z","caller":"traceutil/trace.go:171","msg":"trace[879744018] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1017; }","duration":"1.25236089s","start":"2026-02-24T08:12:02.632412Z","end":"2026-02-24T08:12:03.884773Z","steps":["trace[879744018] 'read index received' (duration: 904.17967ms)","trace[879744018] 'applied index is now lower than readState.Index' (duration: 348.17924ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:12:03.885156Z","caller":"traceutil/trace.go:171","msg":"trace[1715376943] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.255933357s","start":"2026-02-24T08:12:02.629211Z","end":"2026-02-24T08:12:03.885144Z","steps":["trace[1715376943] 'process raft request' (duration: 1.255509698s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:03.885206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:02.629207Z","time spent":"1.255970228s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:12:03.885309Z","caller":"traceutil/trace.go:171","msg":"trace[311442510] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.258577125s","start":"2026-02-24T08:12:02.626725Z","end":"2026-02-24T08:12:03.885302Z","steps":["trace[311442510] 'process raft request' (duration: 1.257760098s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:03.885343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:02.626718Z","time spent":"1.258605386s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:12:03.885426Z","caller":"traceutil/trace.go:171","msg":"trace[384257758] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.256324876s","start":"2026-02-24T08:12:02.629096Z","end":"2026-02-24T08:12:03.88542Z","steps":["trace[384257758] 'process raft request' (duration: 1.255482038s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:03.885461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:02.629086Z","time spent":"1.256353516s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:12:03.885552Z","caller":"traceutil/trace.go:171","msg":"trace[968795533] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.256366057s","start":"2026-02-24T08:12:02.629178Z","end":"2026-02-24T08:12:03.885544Z","steps":["trace[968795533] 'process raft request' (duration: 1.255490288s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:03.885586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:02.629172Z","time spent":"1.256393757s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:03.885932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.290699ms","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":"info","ts":"2026-02-24T08:12:03.885961Z","caller":"traceutil/trace.go:171","msg":"trace[1813878695] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:980; }","duration":"638.326009ms","start":"2026-02-24T08:12:03.247626Z","end":"2026-02-24T08:12:03.885952Z","steps":["trace[1813878695] 'agreement among raft nodes before linearized reading' (duration: 638.267008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:03.885985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:03.247572Z","time spent":"638.407002ms","remote":"127.0.0.1:46100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-24T08:12:03.886115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25370137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:12:03.886136Z","caller":"traceutil/trace.go:171","msg":"trace[2107985717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"1.25373846s","start":"2026-02-24T08:12:02.63239Z","end":"2026-02-24T08:12:03.886129Z","steps":["trace[2107985717] 'agreement among raft nodes before linearized reading' (duration: 1.253704079s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:03.886153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:02.632383Z","time spent":"1.253766031s","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T08:12:05.189511Z","caller":"traceutil/trace.go:171","msg":"trace[2108740634] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"164.832068ms","start":"2026-02-24T08:12:05.024647Z","end":"2026-02-24T08:12:05.189479Z","steps":["trace[2108740634] 'process raft request' (duration: 107.597325ms)","trace[2108740634] 'compare' (duration: 56.707922ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:12:05.207894Z","caller":"traceutil/trace.go:171","msg":"trace[990151779] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1024; }","duration":"178.465585ms","start":"2026-02-24T08:12:05.029371Z","end":"2026-02-24T08:12:05.207837Z","steps":["trace[990151779] 'read index received' (duration: 102.884001ms)","trace[990151779] 'applied index is now lower than readState.Index' (duration: 75.579374ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:12:05.207934Z","caller":"traceutil/trace.go:171","msg":"trace[659267253] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"178.778852ms","start":"2026-02-24T08:12:05.029123Z","end":"2026-02-24T08:12:05.207902Z","steps":["trace[659267253] 'process raft request' (duration: 178.475375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:05.208254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.837243ms","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-24T08:12:05.208323Z","caller":"traceutil/trace.go:171","msg":"trace[1566102632] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:984; }","duration":"178.952376ms","start":"2026-02-24T08:12:05.029354Z","end":"2026-02-24T08:12:05.208307Z","steps":["trace[1566102632] 'agreement among raft nodes before linearized reading' (duration: 178.638909ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:05.545041Z","caller":"traceutil/trace.go:171","msg":"trace[993656215] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"350.317537ms","start":"2026-02-24T08:12:05.194688Z","end":"2026-02-24T08:12:05.545005Z","steps":["trace[993656215] 'process raft request' (duration: 349.97324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:05.545307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:05.194663Z","time spent":"350.540881ms","remote":"127.0.0.1:57342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-24T08:12:05.55395Z","caller":"traceutil/trace.go:171","msg":"trace[36257925] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1026; }","duration":"272.696627ms","start":"2026-02-24T08:12:05.28123Z","end":"2026-02-24T08:12:05.553927Z","steps":["trace[36257925] 'read index received' (duration: 263.431995ms)","trace[36257925] 'applied index is now lower than readState.Index' (duration: 9.263882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:12:05.554167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.921423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:12:05.554119Z","caller":"traceutil/trace.go:171","msg":"trace[1157898829] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"339.749925ms","start":"2026-02-24T08:12:05.214322Z","end":"2026-02-24T08:12:05.554072Z","steps":["trace[1157898829] 'process raft request' (duration: 339.470159ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:05.554203Z","caller":"traceutil/trace.go:171","msg":"trace[672177868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"273.017715ms","start":"2026-02-24T08:12:05.281175Z","end":"2026-02-24T08:12:05.554193Z","steps":["trace[672177868] 'agreement among raft nodes before linearized reading' (duration: 272.833091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:05.554364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:05.214294Z","time spent":"339.94002ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:12:06.581935Z","caller":"traceutil/trace.go:171","msg":"trace[1690417377] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"672.755714ms","start":"2026-02-24T08:12:05.909149Z","end":"2026-02-24T08:12:06.581905Z","steps":["trace[1690417377] 'process raft request' (duration: 672.56224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:06.582123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:05.909138Z","time spent":"672.889077ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:07.448925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.46826ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:12:07.449273Z","caller":"traceutil/trace.go:171","msg":"trace[2007645491] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1029; }","duration":"991.309555ms","start":"2026-02-24T08:12:06.457946Z","end":"2026-02-24T08:12:07.449255Z","steps":["trace[2007645491] 'read index received' (duration: 123.781529ms)","trace[2007645491] 'applied index is now lower than readState.Index' (duration: 867.527116ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:12:07.449366Z","caller":"traceutil/trace.go:171","msg":"trace[1269561042] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.539675048s","start":"2026-02-24T08:12:05.909681Z","end":"2026-02-24T08:12:07.449356Z","steps":["trace[1269561042] 'process raft request' (duration: 1.539474583s)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:07.449343Z","caller":"traceutil/trace.go:171","msg":"trace[655327907] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.539723498s","start":"2026-02-24T08:12:05.909577Z","end":"2026-02-24T08:12:07.4493Z","steps":["trace[655327907] 'process raft request' (duration: 1.539546015s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:07.449425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:05.909675Z","time spent":"1.539716159s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:07.449552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:05.909571Z","time spent":"1.539868132s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:07.449669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.158255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T08:12:07.449709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.721835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T08:12:07.449799Z","caller":"traceutil/trace.go:171","msg":"trace[1478180306] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:993; }","duration":"991.881869ms","start":"2026-02-24T08:12:06.457893Z","end":"2026-02-24T08:12:07.449775Z","steps":["trace[1478180306] 'agreement among raft nodes before linearized reading' (duration: 991.674154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:07.449852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:06.457865Z","time spent":"991.973561ms","remote":"127.0.0.1:57808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-24T08:12:07.449874Z","caller":"traceutil/trace.go:171","msg":"trace[1401103539] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"1.540530917s","start":"2026-02-24T08:12:05.909322Z","end":"2026-02-24T08:12:07.449853Z","steps":["trace[1401103539] 'process raft request' (duration: 1.046087625s)","trace[1401103539] 'compare' (duration: 492.9968ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:12:07.449916Z","caller":"traceutil/trace.go:171","msg":"trace[358735745] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.540555277s","start":"2026-02-24T08:12:05.909342Z","end":"2026-02-24T08:12:07.449897Z","steps":["trace[358735745] 'process raft request' (duration: 1.539703478s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:07.449942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:05.909311Z","time spent":"1.540595949s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:07.450013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:05.909333Z","time spent":"1.540630839s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:07.45008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.266075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:12:07.450111Z","caller":"traceutil/trace.go:171","msg":"trace[1287253895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"169.316127ms","start":"2026-02-24T08:12:07.280783Z","end":"2026-02-24T08:12:07.450099Z","steps":["trace[1287253895] 'agreement among raft nodes before linearized reading' (duration: 169.274276ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:07.450292Z","caller":"traceutil/trace.go:171","msg":"trace[1572712703] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.114755728s","start":"2026-02-24T08:12:06.335516Z","end":"2026-02-24T08:12:07.450272Z","steps":["trace[1572712703] 'process raft request' (duration: 1.113671254s)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:07.450365Z","caller":"traceutil/trace.go:171","msg":"trace[579131130] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.031827623s","start":"2026-02-24T08:12:06.418528Z","end":"2026-02-24T08:12:07.450355Z","steps":["trace[579131130] 'process raft request' (duration: 1.030693388s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:07.450398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:06.335482Z","time spent":"1.114848951s","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5282,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:07.450422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:06.418495Z","time spent":"1.031895314s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:12:07.450656Z","caller":"traceutil/trace.go:171","msg":"trace[187231557] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:993; }","duration":"234.222027ms","start":"2026-02-24T08:12:07.21547Z","end":"2026-02-24T08:12:07.449692Z","steps":["trace[187231557] 'agreement among raft nodes before linearized reading' (duration: 234.135105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:07.450703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.240981ms","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":"info","ts":"2026-02-24T08:12:07.450758Z","caller":"traceutil/trace.go:171","msg":"trace[1440560657] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:993; }","duration":"178.342414ms","start":"2026-02-24T08:12:07.2724Z","end":"2026-02-24T08:12:07.450743Z","steps":["trace[1440560657] 'agreement among raft nodes before linearized reading' (duration: 178.16249ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:08.810099Z","caller":"traceutil/trace.go:171","msg":"trace[1853166389] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"220.338601ms","start":"2026-02-24T08:12:08.589736Z","end":"2026-02-24T08:12:08.810075Z","steps":["trace[1853166389] 'process raft request' (duration: 220.156267ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:09.593516Z","caller":"traceutil/trace.go:171","msg":"trace[1491509246] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"134.035353ms","start":"2026-02-24T08:12:09.459383Z","end":"2026-02-24T08:12:09.593418Z","steps":["trace[1491509246] 'process raft request' (duration: 133.791208ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:10.174429Z","caller":"traceutil/trace.go:171","msg":"trace[247155636] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"714.442117ms","start":"2026-02-24T08:12:09.459961Z","end":"2026-02-24T08:12:10.174403Z","steps":["trace[247155636] 'process raft request' (duration: 714.14884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:10.174646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:09.45994Z","time spent":"714.565939ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:12:10.174688Z","caller":"traceutil/trace.go:171","msg":"trace[160402439] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1038; }","duration":"119.346651ms","start":"2026-02-24T08:12:10.055324Z","end":"2026-02-24T08:12:10.174671Z","steps":["trace[160402439] 'read index received' (duration: 118.684447ms)","trace[160402439] 'applied index is now lower than readState.Index' (duration: 661.594µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:12:10.174785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.442983ms","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-24T08:12:10.174797Z","caller":"traceutil/trace.go:171","msg":"trace[1363039343] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"714.318684ms","start":"2026-02-24T08:12:09.460462Z","end":"2026-02-24T08:12:10.174781Z","steps":["trace[1363039343] 'process raft request' (duration: 713.891524ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:10.174836Z","caller":"traceutil/trace.go:171","msg":"trace[934310272] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"714.048067ms","start":"2026-02-24T08:12:09.46078Z","end":"2026-02-24T08:12:10.174828Z","steps":["trace[934310272] 'process raft request' (duration: 713.808192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:10.174856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:09.460447Z","time spent":"714.376975ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:12:10.174807Z","caller":"traceutil/trace.go:171","msg":"trace[673885705] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:998; }","duration":"119.510155ms","start":"2026-02-24T08:12:10.055289Z","end":"2026-02-24T08:12:10.174799Z","steps":["trace[673885705] 'agreement among raft nodes before linearized reading' (duration: 119.445954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:10.174908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:09.460755Z","time spent":"714.107039ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:12.097514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088667213005738,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T08:12:12.563928Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.189278828s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T08:12:12.564333Z","caller":"traceutil/trace.go:171","msg":"trace[924829216] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"967.086675ms","start":"2026-02-24T08:12:11.597223Z","end":"2026-02-24T08:12:12.56431Z","steps":["trace[924829216] 'read index received' (duration: 966.8515ms)","trace[924829216] 'applied index is now lower than readState.Index' (duration: 234.225µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:12:12.564595Z","caller":"traceutil/trace.go:171","msg":"trace[396176581] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.190028104s","start":"2026-02-24T08:12:11.374554Z","end":"2026-02-24T08:12:12.564582Z","steps":["trace[396176581] 'process raft request' (duration: 1.189613945s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:12.564751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:11.374535Z","time spent":"1.190120667s","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:12:12.564782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.546347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T08:12:12.564923Z","caller":"traceutil/trace.go:171","msg":"trace[1256824803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"967.767601ms","start":"2026-02-24T08:12:11.597133Z","end":"2026-02-24T08:12:12.5649Z","steps":["trace[1256824803] 'agreement among raft nodes before linearized reading' (duration: 967.469574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:12.564925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.011847ms","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-24T08:12:12.564983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:11.597114Z","time spent":"967.858713ms","remote":"127.0.0.1:57532","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-24T08:12:12.565024Z","caller":"traceutil/trace.go:171","msg":"trace[40575413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"385.18269ms","start":"2026-02-24T08:12:12.179822Z","end":"2026-02-24T08:12:12.565005Z","steps":["trace[40575413] 'agreement among raft nodes before linearized reading' (duration: 384.845302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:12.565078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:12.179811Z","time spent":"385.257663ms","remote":"127.0.0.1:57532","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-24T08:12:12.565165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.904558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:12:12.565204Z","caller":"traceutil/trace.go:171","msg":"trace[373169852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"285.977959ms","start":"2026-02-24T08:12:12.279216Z","end":"2026-02-24T08:12:12.565194Z","steps":["trace[373169852] 'agreement among raft nodes before linearized reading' (duration: 285.922068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:12.565113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.883996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T08:12:12.565364Z","caller":"traceutil/trace.go:171","msg":"trace[759298101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"386.207003ms","start":"2026-02-24T08:12:12.179129Z","end":"2026-02-24T08:12:12.565336Z","steps":["trace[759298101] 'agreement among raft nodes before linearized reading' (duration: 385.6268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:12.565427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:12.179107Z","time spent":"386.306735ms","remote":"127.0.0.1:57532","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-24T08:12:12.565836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.901958ms","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-24T08:12:12.566111Z","caller":"traceutil/trace.go:171","msg":"trace[789147555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"387.212384ms","start":"2026-02-24T08:12:12.178809Z","end":"2026-02-24T08:12:12.566022Z","steps":["trace[789147555] 'agreement among raft nodes before linearized reading' (duration: 385.851924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:12.566246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:12.178789Z","time spent":"387.4403ms","remote":"127.0.0.1:57532","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":"info","ts":"2026-02-24T08:12:16.80306Z","caller":"traceutil/trace.go:171","msg":"trace[559907737] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"203.272918ms","start":"2026-02-24T08:12:16.599723Z","end":"2026-02-24T08:12:16.802996Z","steps":["trace[559907737] 'process raft request' (duration: 203.234578ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:16.80308Z","caller":"traceutil/trace.go:171","msg":"trace[474661046] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"203.534064ms","start":"2026-02-24T08:12:16.599515Z","end":"2026-02-24T08:12:16.803049Z","steps":["trace[474661046] 'process raft request' (duration: 201.475779ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:12:16.803285Z","caller":"traceutil/trace.go:171","msg":"trace[1503643437] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"203.568696ms","start":"2026-02-24T08:12:16.599686Z","end":"2026-02-24T08:12:16.803255Z","steps":["trace[1503643437] 'process raft request' (duration: 203.227338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:18.96078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.696431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:12:18.960964Z","caller":"traceutil/trace.go:171","msg":"trace[135497039] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"152.255793ms","start":"2026-02-24T08:12:18.808639Z","end":"2026-02-24T08:12:18.960895Z","steps":["trace[135497039] 'read index received' (duration: 5.237955ms)","trace[135497039] 'applied index is now lower than readState.Index' (duration: 147.016558ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:12:18.9611Z","caller":"traceutil/trace.go:171","msg":"trace[1200289367] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"329.696296ms","start":"2026-02-24T08:12:18.631355Z","end":"2026-02-24T08:12:18.961051Z","steps":["trace[1200289367] 'process raft request' (duration: 182.654798ms)","trace[1200289367] 'compare' (duration: 146.530917ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T08:12:18.961199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:12:18.631342Z","time spent":"329.791848ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-24T08:12:18.961422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.657561ms","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-24T08:12:18.961565Z","caller":"traceutil/trace.go:171","msg":"trace[1119756402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"152.849806ms","start":"2026-02-24T08:12:18.80868Z","end":"2026-02-24T08:12:18.96153Z","steps":["trace[1119756402] 'agreement among raft nodes before linearized reading' (duration: 152.417966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:18.961816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.791304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-24T08:12:18.961943Z","caller":"traceutil/trace.go:171","msg":"trace[331790676] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"153.006389ms","start":"2026-02-24T08:12:18.808907Z","end":"2026-02-24T08:12:18.961913Z","steps":["trace[331790676] 'agreement among raft nodes before linearized reading' (duration: 152.61607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:12:18.962038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.342276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-24T08:12:18.962154Z","caller":"traceutil/trace.go:171","msg":"trace[702710644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"153.535331ms","start":"2026-02-24T08:12:18.808593Z","end":"2026-02-24T08:12:18.962129Z","steps":["trace[702710644] 'agreement among raft nodes before linearized reading' (duration: 153.169503ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:15:31.350046Z","caller":"traceutil/trace.go:171","msg":"trace[1820089191] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"226.107079ms","start":"2026-02-24T08:15:31.123923Z","end":"2026-02-24T08:15:31.35003Z","steps":["trace[1820089191] 'process raft request' (duration: 221.963466ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:15:48.202286Z","caller":"traceutil/trace.go:171","msg":"trace[408112262] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"110.71408ms","start":"2026-02-24T08:15:48.09154Z","end":"2026-02-24T08:15:48.202254Z","steps":["trace[408112262] 'process raft request' (duration: 110.337581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:15:48.408361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.644823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T08:15:48.408485Z","caller":"traceutil/trace.go:171","msg":"trace[1958471753] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"201.657556ms","start":"2026-02-24T08:15:48.206805Z","end":"2026-02-24T08:15:48.408463Z","steps":["trace[1958471753] 'process raft request' (duration: 39.824798ms)","trace[1958471753] 'compare' (duration: 161.509811ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:15:48.536739Z","caller":"traceutil/trace.go:171","msg":"trace[1564526333] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2036; }","duration":"257.597288ms","start":"2026-02-24T08:15:48.279116Z","end":"2026-02-24T08:15:48.536713Z","steps":["trace[1564526333] 'read index received' (duration: 234.907998ms)","trace[1564526333] 'applied index is now lower than readState.Index' (duration: 22.68816ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:15:48.537242Z","caller":"traceutil/trace.go:171","msg":"trace[1713946477] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"329.926503ms","start":"2026-02-24T08:15:48.207289Z","end":"2026-02-24T08:15:48.537215Z","steps":["trace[1713946477] 'process raft request' (duration: 306.795483ms)","trace[1713946477] 'compare' (duration: 21.050574ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:15:48.537274Z","caller":"traceutil/trace.go:171","msg":"trace[934648782] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"328.842449ms","start":"2026-02-24T08:15:48.208397Z","end":"2026-02-24T08:15:48.53724Z","steps":["trace[934648782] 'process raft request' (duration: 328.184905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:15:48.537356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:15:48.207267Z","time spent":"330.015285ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T08:15:48.537329Z","caller":"traceutil/trace.go:171","msg":"trace[500378976] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"328.84735ms","start":"2026-02-24T08:15:48.208402Z","end":"2026-02-24T08:15:48.537249Z","steps":["trace[500378976] 'process raft request' (duration: 328.254627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:15:48.537467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:15:48.208386Z","time spent":"328.966062ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:15:48.538109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T08:15:48.208392Z","time spent":"329.238598ms","remote":"127.0.0.1:57532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T08:15:48.538859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.739145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:15:48.538958Z","caller":"traceutil/trace.go:171","msg":"trace[637829243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1950; }","duration":"259.836437ms","start":"2026-02-24T08:15:48.2791Z","end":"2026-02-24T08:15:48.538937Z","steps":["trace[637829243] 'agreement among raft nodes before linearized reading' (duration: 257.729062ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T08:15:50.379825Z","caller":"traceutil/trace.go:171","msg":"trace[82812728] linearizableReadLoop","detail":"{readStateIndex:2043; appliedIndex:2042; }","duration":"122.624754ms","start":"2026-02-24T08:15:50.25718Z","end":"2026-02-24T08:15:50.379804Z","steps":["trace[82812728] 'read index received' (duration: 122.448469ms)","trace[82812728] 'applied index is now lower than readState.Index' (duration: 175.525µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T08:15:50.380053Z","caller":"traceutil/trace.go:171","msg":"trace[289587244] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"170.587621ms","start":"2026-02-24T08:15:50.209453Z","end":"2026-02-24T08:15:50.380041Z","steps":["trace[289587244] 'process raft request' (duration: 170.239653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:15:50.3803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.107394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-24T08:15:50.380338Z","caller":"traceutil/trace.go:171","msg":"trace[1778178434] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1954; }","duration":"123.158685ms","start":"2026-02-24T08:15:50.257169Z","end":"2026-02-24T08:15:50.380327Z","steps":["trace[1778178434] 'agreement among raft nodes before linearized reading' (duration: 123.030612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:15:50.38049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.233247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-02-24T08:15:50.380524Z","caller":"traceutil/trace.go:171","msg":"trace[398712075] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1954; }","duration":"113.303638ms","start":"2026-02-24T08:15:50.267212Z","end":"2026-02-24T08:15:50.380516Z","steps":["trace[398712075] 'agreement among raft nodes before linearized reading' (duration: 113.243537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T08:15:50.380732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.108913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T08:15:50.380765Z","caller":"traceutil/trace.go:171","msg":"trace[116499986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1954; }","duration":"103.163135ms","start":"2026-02-24T08:15:50.277594Z","end":"2026-02-24T08:15:50.380757Z","steps":["trace[116499986] 'agreement among raft nodes before linearized reading' (duration: 103.108753ms)"],"step_count":1}