{"level":"warn","ts":"2026-04-16T09:14:16.339897Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T09:14:16.340023Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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://199.204.45.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-04-16T09:14:16.340105Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T09:14:16.340119Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-16T09:14:16.340139Z","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-04-16T09:14:16.341023Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-16T09:14:16.341245Z","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://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-04-16T09:14:16.344808Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.273156ms"} {"level":"info","ts":"2026-04-16T09:14:16.351754Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-16T09:14:16.351849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T09:14:16.351875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-16T09:14:16.3519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T09:14:16.351923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-16T09:14:16.351982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-16T09:14:16.359494Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T09:14:16.361011Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T09:14:16.362009Z","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-04-16T09:14:16.364212Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T09:14:16.368902Z","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-04-16T09:14:16.36979Z","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-04-16T09:14:16.369928Z","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-04-16T09:14:16.370055Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T09:14:16.372449Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-16T09:14:16.372479Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-16T09:14:16.372654Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T09:14:16.372715Z","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-04-16T09:14:16.373594Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T09:14:16.373863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-16T09:14:16.376933Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-16T09:14:16.652265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T09:14:16.652349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T09:14:16.652384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-16T09:14:16.652401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-16T09:14:16.652407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-16T09:14:16.652526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-16T09:14:16.652542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-16T09:14:16.658337Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T09:14:16.658372Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T09:14:16.658527Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T09:14:16.658757Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T09:14:16.658967Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T09:14:16.659005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T09:14:16.66006Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T09:14:16.660245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T09:14:16.660289Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T09:14:16.661905Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-16T09:14:16.663757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-16T09:14:26.033908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.383432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T09:14:26.034188Z","caller":"traceutil/trace.go:171","msg":"trace[1772714030] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:334; }","duration":"201.377319ms","start":"2026-04-16T09:14:25.832785Z","end":"2026-04-16T09:14:26.034163Z","steps":["trace[1772714030] 'read index received' (duration: 18.004082ms)","trace[1772714030] 'applied index is now lower than readState.Index' (duration: 183.371157ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:14:26.034218Z","caller":"traceutil/trace.go:171","msg":"trace[1494422100] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"238.946532ms","start":"2026-04-16T09:14:25.795237Z","end":"2026-04-16T09:14:26.034184Z","steps":["trace[1494422100] 'process raft request' (duration: 55.566194ms)","trace[1494422100] 'compare' (duration: 182.216967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:14:26.034563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.749629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-16T09:14:26.034655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.841532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-16T09:14:26.034563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.68003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-16T09:14:26.034739Z","caller":"traceutil/trace.go:171","msg":"trace[739307717] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:321; }","duration":"152.012677ms","start":"2026-04-16T09:14:25.882708Z","end":"2026-04-16T09:14:26.03472Z","steps":["trace[739307717] 'agreement among raft nodes before linearized reading' (duration: 151.725789ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:26.034646Z","caller":"traceutil/trace.go:171","msg":"trace[1619526063] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:321; }","duration":"201.880252ms","start":"2026-04-16T09:14:25.832747Z","end":"2026-04-16T09:14:26.034628Z","steps":["trace[1619526063] 'agreement among raft nodes before linearized reading' (duration: 201.524453ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:26.034785Z","caller":"traceutil/trace.go:171","msg":"trace[880188009] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:321; }","duration":"101.915167ms","start":"2026-04-16T09:14:25.932817Z","end":"2026-04-16T09:14:26.034732Z","steps":["trace[880188009] 'agreement among raft nodes before linearized reading' (duration: 101.633659ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:26.3244Z","caller":"traceutil/trace.go:171","msg":"trace[1588577980] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"282.585417ms","start":"2026-04-16T09:14:26.041789Z","end":"2026-04-16T09:14:26.324374Z","steps":["trace[1588577980] 'process raft request' (duration: 257.324476ms)","trace[1588577980] 'compare' (duration: 25.141307ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:14:26.353135Z","caller":"traceutil/trace.go:171","msg":"trace[1461531918] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"308.803322ms","start":"2026-04-16T09:14:26.044298Z","end":"2026-04-16T09:14:26.353101Z","steps":["trace[1461531918] 'process raft request' (duration: 308.592717ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:26.353248Z","caller":"traceutil/trace.go:171","msg":"trace[662215688] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"308.168826ms","start":"2026-04-16T09:14:26.045058Z","end":"2026-04-16T09:14:26.353226Z","steps":["trace[662215688] 'process raft request' (duration: 308.018221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:26.353269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.795378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T09:14:26.353324Z","caller":"traceutil/trace.go:171","msg":"trace[1517546487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:325; }","duration":"257.880171ms","start":"2026-04-16T09:14:26.095422Z","end":"2026-04-16T09:14:26.353302Z","steps":["trace[1517546487] 'agreement among raft nodes before linearized reading' (duration: 257.769038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:26.353331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:26.045044Z","time spent":"308.246237ms","remote":"127.0.0.1:36540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T09:14:26.353287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:26.044281Z","time spent":"308.937406ms","remote":"127.0.0.1:42706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T09:14:26.353146Z","caller":"traceutil/trace.go:171","msg":"trace[1568244869] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:335; }","duration":"257.650924ms","start":"2026-04-16T09:14:26.095471Z","end":"2026-04-16T09:14:26.353122Z","steps":["trace[1568244869] 'read index received' (duration: 203.651283ms)","trace[1568244869] 'applied index is now lower than readState.Index' (duration: 53.998611ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:14:26.353278Z","caller":"traceutil/trace.go:171","msg":"trace[726377065] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"308.898935ms","start":"2026-04-16T09:14:26.044367Z","end":"2026-04-16T09:14:26.353266Z","steps":["trace[726377065] 'process raft request' (duration: 308.622887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:26.353449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:26.044349Z","time spent":"309.036179ms","remote":"127.0.0.1:42588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T09:14:26.496804Z","caller":"traceutil/trace.go:171","msg":"trace[1157766571] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"131.401055ms","start":"2026-04-16T09:14:26.365348Z","end":"2026-04-16T09:14:26.496749Z","steps":["trace[1157766571] 'process raft request' (duration: 69.875287ms)","trace[1157766571] 'compare' (duration: 61.159168ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:14:26.496897Z","caller":"traceutil/trace.go:171","msg":"trace[336051231] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"130.663426ms","start":"2026-04-16T09:14:26.36622Z","end":"2026-04-16T09:14:26.496883Z","steps":["trace[336051231] 'process raft request' (duration: 130.46273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:26.496983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.605514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-16T09:14:26.496825Z","caller":"traceutil/trace.go:171","msg":"trace[481223061] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"131.361734ms","start":"2026-04-16T09:14:26.365445Z","end":"2026-04-16T09:14:26.496807Z","steps":["trace[481223061] 'process raft request' (duration: 131.127918ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:26.497083Z","caller":"traceutil/trace.go:171","msg":"trace[596149362] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:334; }","duration":"130.732757ms","start":"2026-04-16T09:14:26.366325Z","end":"2026-04-16T09:14:26.497058Z","steps":["trace[596149362] 'agreement among raft nodes before linearized reading' (duration: 130.566303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:28.643119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.923655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-16T09:14:28.643354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.029506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T09:14:28.643414Z","caller":"traceutil/trace.go:171","msg":"trace[1343231203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:362; }","duration":"142.128549ms","start":"2026-04-16T09:14:28.501274Z","end":"2026-04-16T09:14:28.643403Z","steps":["trace[1343231203] 'range keys from in-memory index tree' (duration: 141.820791ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:28.643189Z","caller":"traceutil/trace.go:171","msg":"trace[611596087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"142.033027ms","start":"2026-04-16T09:14:28.501139Z","end":"2026-04-16T09:14:28.643172Z","steps":["trace[611596087] 'range keys from in-memory index tree' (duration: 141.827541ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:34.596018Z","caller":"traceutil/trace.go:171","msg":"trace[925052568] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"195.729225ms","start":"2026-04-16T09:14:34.400264Z","end":"2026-04-16T09:14:34.595993Z","steps":["trace[925052568] 'process raft request' (duration: 195.598202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:35.228868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.189258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-16T09:14:35.228922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.578683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-16T09:14:35.228954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.698866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T09:14:35.229011Z","caller":"traceutil/trace.go:171","msg":"trace[456769489] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"426.403773ms","start":"2026-04-16T09:14:34.80258Z","end":"2026-04-16T09:14:35.228984Z","steps":["trace[456769489] 'range keys from in-memory index tree' (duration: 426.028304ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:35.229041Z","caller":"traceutil/trace.go:171","msg":"trace[1088018261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"487.823038ms","start":"2026-04-16T09:14:34.7412Z","end":"2026-04-16T09:14:35.229024Z","steps":["trace[1088018261] 'range keys from in-memory index tree' (duration: 487.545242ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:35.229038Z","caller":"traceutil/trace.go:171","msg":"trace[2117609646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"482.728637ms","start":"2026-04-16T09:14:34.746277Z","end":"2026-04-16T09:14:35.229006Z","steps":["trace[2117609646] 'range keys from in-memory index tree' (duration: 482.490891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:35.229068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:34.802549Z","time spent":"426.508715ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T09:14:35.229106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:34.74627Z","time spent":"482.820599ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T09:14:35.22908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:34.741181Z","time spent":"487.89028ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T09:14:35.228922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.502942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:14:35.229361Z","caller":"traceutil/trace.go:171","msg":"trace[508787374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:409; }","duration":"453.007675ms","start":"2026-04-16T09:14:34.776339Z","end":"2026-04-16T09:14:35.229346Z","steps":["trace[508787374] 'range keys from in-memory index tree' (duration: 452.419299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:35.229405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:34.77632Z","time spent":"453.073517ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T09:14:35.755353Z","caller":"traceutil/trace.go:171","msg":"trace[850721449] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"522.224708ms","start":"2026-04-16T09:14:35.233096Z","end":"2026-04-16T09:14:35.755321Z","steps":["trace[850721449] 'process raft request' (duration: 522.002411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:35.755773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:35.233069Z","time spent":"522.379581ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T09:14:35.757133Z","caller":"traceutil/trace.go:171","msg":"trace[142691955] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"522.349761ms","start":"2026-04-16T09:14:35.234754Z","end":"2026-04-16T09:14:35.757104Z","steps":["trace[142691955] 'process raft request' (duration: 521.92466ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:35.757133Z","caller":"traceutil/trace.go:171","msg":"trace[1459737629] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"522.74589ms","start":"2026-04-16T09:14:35.234345Z","end":"2026-04-16T09:14:35.757091Z","steps":["trace[1459737629] 'process raft request' (duration: 522.192876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:35.757241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:35.234748Z","time spent":"522.437703ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:14:35.757336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:35.234332Z","time spent":"522.891764ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T09:14:38.026864Z","caller":"traceutil/trace.go:171","msg":"trace[857066016] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"259.076416ms","start":"2026-04-16T09:14:37.767762Z","end":"2026-04-16T09:14:38.026838Z","steps":["trace[857066016] 'process raft request' (duration: 258.88819ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:38.027193Z","caller":"traceutil/trace.go:171","msg":"trace[171748701] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"259.382323ms","start":"2026-04-16T09:14:37.767769Z","end":"2026-04-16T09:14:38.027151Z","steps":["trace[171748701] 'process raft request' (duration: 259.030454ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:38.029845Z","caller":"traceutil/trace.go:171","msg":"trace[1290383098] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"261.129248ms","start":"2026-04-16T09:14:37.768484Z","end":"2026-04-16T09:14:38.029613Z","steps":["trace[1290383098] 'process raft request' (duration: 260.989405ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:52.071615Z","caller":"traceutil/trace.go:171","msg":"trace[2134186925] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"126.031517ms","start":"2026-04-16T09:14:51.945557Z","end":"2026-04-16T09:14:52.071589Z","steps":["trace[2134186925] 'process raft request' (duration: 125.901084ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:52.525349Z","caller":"traceutil/trace.go:171","msg":"trace[268324204] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"415.091819ms","start":"2026-04-16T09:14:52.110194Z","end":"2026-04-16T09:14:52.525285Z","steps":["trace[268324204] 'read index received' (duration: 368.707928ms)","trace[268324204] 'applied index is now lower than readState.Index' (duration: 46.382751ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:14:52.525403Z","caller":"traceutil/trace.go:171","msg":"trace[1010575295] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"433.842661ms","start":"2026-04-16T09:14:52.091517Z","end":"2026-04-16T09:14:52.525359Z","steps":["trace[1010575295] 'process raft request' (duration: 388.736873ms)","trace[1010575295] 'compare' (duration: 44.653047ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:14:52.525516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.305984ms","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-04-16T09:14:52.525547Z","caller":"traceutil/trace.go:171","msg":"trace[2075337022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"415.399866ms","start":"2026-04-16T09:14:52.110137Z","end":"2026-04-16T09:14:52.525537Z","steps":["trace[2075337022] 'agreement among raft nodes before linearized reading' (duration: 415.308984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:52.525576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.110098Z","time spent":"415.470999ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T09:14:52.525573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.091477Z","time spent":"434.005045ms","remote":"127.0.0.1:42522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T09:14:52.596109Z","caller":"traceutil/trace.go:171","msg":"trace[495118353] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"165.404399ms","start":"2026-04-16T09:14:52.430676Z","end":"2026-04-16T09:14:52.596081Z","steps":["trace[495118353] 'process raft request' (duration: 165.157302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:52.596213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.232287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T09:14:52.59627Z","caller":"traceutil/trace.go:171","msg":"trace[1417664000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:576; }","duration":"479.342359ms","start":"2026-04-16T09:14:52.116913Z","end":"2026-04-16T09:14:52.596255Z","steps":["trace[1417664000] 'agreement among raft nodes before linearized reading' (duration: 479.178485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:52.596315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.116894Z","time spent":"479.411972ms","remote":"127.0.0.1:42696","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-04-16T09:14:52.596481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.255073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T09:14:52.596615Z","caller":"traceutil/trace.go:171","msg":"trace[1568160732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"473.449989ms","start":"2026-04-16T09:14:52.123132Z","end":"2026-04-16T09:14:52.596582Z","steps":["trace[1568160732] 'agreement among raft nodes before linearized reading' (duration: 473.187932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:52.596676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.123114Z","time spent":"473.550681ms","remote":"127.0.0.1:42696","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-04-16T09:14:53.375745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.028909ms","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-04-16T09:14:53.375942Z","caller":"traceutil/trace.go:171","msg":"trace[85800358] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:576; }","duration":"356.249184ms","start":"2026-04-16T09:14:53.019665Z","end":"2026-04-16T09:14:53.375915Z","steps":["trace[85800358] 'range keys from in-memory index tree' (duration: 356.003808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:53.376168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.877837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T09:14:53.376612Z","caller":"traceutil/trace.go:171","msg":"trace[450599847] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"842.660027ms","start":"2026-04-16T09:14:52.533926Z","end":"2026-04-16T09:14:53.376586Z","steps":["trace[450599847] 'process raft request' (duration: 521.245725ms)","trace[450599847] 'compare' (duration: 320.364134ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:14:53.37671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.533897Z","time spent":"842.77674ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T09:14:53.376847Z","caller":"traceutil/trace.go:171","msg":"trace[1928566976] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"792.741114ms","start":"2026-04-16T09:14:52.584077Z","end":"2026-04-16T09:14:53.376818Z","steps":["trace[1928566976] 'process raft request' (duration: 792.356694ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:53.376946Z","caller":"traceutil/trace.go:171","msg":"trace[850415760] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"825.327611ms","start":"2026-04-16T09:14:52.551608Z","end":"2026-04-16T09:14:53.376936Z","steps":["trace[850415760] 'process raft request' (duration: 824.696805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:53.377015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.584066Z","time spent":"792.864287ms","remote":"127.0.0.1:36562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T09:14:53.377469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.551595Z","time spent":"825.379932ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T09:14:53.377607Z","caller":"traceutil/trace.go:171","msg":"trace[1810524965] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"776.755373ms","start":"2026-04-16T09:14:52.600839Z","end":"2026-04-16T09:14:53.377595Z","steps":["trace[1810524965] 'process raft request' (duration: 775.889451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:53.377689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.600813Z","time spent":"776.826775ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T09:14:53.377813Z","caller":"traceutil/trace.go:171","msg":"trace[590961413] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:602; }","duration":"674.548288ms","start":"2026-04-16T09:14:52.703234Z","end":"2026-04-16T09:14:53.377782Z","steps":["trace[590961413] 'read index received' (duration: 352.014249ms)","trace[590961413] 'applied index is now lower than readState.Index' (duration: 322.532489ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:14:53.37804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.815926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T09:14:53.37807Z","caller":"traceutil/trace.go:171","msg":"trace[279732413] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"776.537278ms","start":"2026-04-16T09:14:52.60152Z","end":"2026-04-16T09:14:53.378057Z","steps":["trace[279732413] 'process raft request' (duration: 776.033265ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:14:53.378079Z","caller":"traceutil/trace.go:171","msg":"trace[267156190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"674.917708ms","start":"2026-04-16T09:14:52.70315Z","end":"2026-04-16T09:14:53.378068Z","steps":["trace[267156190] 'agreement among raft nodes before linearized reading' (duration: 674.781485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:53.378112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.703117Z","time spent":"674.98844ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T09:14:53.378152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.601494Z","time spent":"776.607799ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:14:53.378149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.598619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:14:53.378248Z","caller":"traceutil/trace.go:171","msg":"trace[1583415231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"600.733853ms","start":"2026-04-16T09:14:52.777488Z","end":"2026-04-16T09:14:53.378222Z","steps":["trace[1583415231] 'agreement among raft nodes before linearized reading' (duration: 600.567569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:14:53.378451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:14:52.777477Z","time spent":"600.961459ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T09:15:07.721973Z","caller":"traceutil/trace.go:171","msg":"trace[1041787484] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"210.531567ms","start":"2026-04-16T09:15:07.5114Z","end":"2026-04-16T09:15:07.721932Z","steps":["trace[1041787484] 'read index received' (duration: 163.124448ms)","trace[1041787484] 'applied index is now lower than readState.Index' (duration: 47.405448ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:15:07.722034Z","caller":"traceutil/trace.go:171","msg":"trace[144521670] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"237.7711ms","start":"2026-04-16T09:15:07.484227Z","end":"2026-04-16T09:15:07.721998Z","steps":["trace[144521670] 'process raft request' (duration: 190.211018ms)","trace[144521670] 'compare' (duration: 47.319426ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:15:07.722265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.666051ms","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-04-16T09:15:07.722356Z","caller":"traceutil/trace.go:171","msg":"trace[691234115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"241.776043ms","start":"2026-04-16T09:15:07.480561Z","end":"2026-04-16T09:15:07.722337Z","steps":["trace[691234115] 'agreement among raft nodes before linearized reading' (duration: 241.526506ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:15:07.750835Z","caller":"traceutil/trace.go:171","msg":"trace[323836665] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"234.135438ms","start":"2026-04-16T09:15:07.51668Z","end":"2026-04-16T09:15:07.750815Z","steps":["trace[323836665] 'process raft request' (duration: 233.999035ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:15:10.505051Z","caller":"traceutil/trace.go:171","msg":"trace[1080627644] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"455.100376ms","start":"2026-04-16T09:15:10.049917Z","end":"2026-04-16T09:15:10.505018Z","steps":["trace[1080627644] 'process raft request' (duration: 454.888271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:15:10.505247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:15:10.049893Z","time spent":"455.25568ms","remote":"127.0.0.1:42600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:15:11.318601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.874841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:15:11.318835Z","caller":"traceutil/trace.go:171","msg":"trace[1213169006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"540.182499ms","start":"2026-04-16T09:15:10.778624Z","end":"2026-04-16T09:15:11.318806Z","steps":["trace[1213169006] 'range keys from in-memory index tree' (duration: 539.692127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:15:11.319029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:15:10.778601Z","time spent":"540.414014ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T09:15:11.318601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.035683ms","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-04-16T09:15:11.319203Z","caller":"traceutil/trace.go:171","msg":"trace[1672450499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"393.658339ms","start":"2026-04-16T09:15:10.925491Z","end":"2026-04-16T09:15:11.319149Z","steps":["trace[1672450499] 'range keys from in-memory index tree' (duration: 392.5945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:15:11.319362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:15:10.925471Z","time spent":"393.843993ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T09:15:32.011732Z","caller":"traceutil/trace.go:171","msg":"trace[1109141670] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"134.14672ms","start":"2026-04-16T09:15:31.877561Z","end":"2026-04-16T09:15:32.011708Z","steps":["trace[1109141670] 'process raft request' (duration: 134.002346ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:15:56.020584Z","caller":"traceutil/trace.go:171","msg":"trace[2090231562] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"152.720747ms","start":"2026-04-16T09:15:55.867838Z","end":"2026-04-16T09:15:56.020559Z","steps":["trace[2090231562] 'process raft request' (duration: 152.567783ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:15:56.274422Z","caller":"traceutil/trace.go:171","msg":"trace[275505232] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"198.649355ms","start":"2026-04-16T09:15:56.075749Z","end":"2026-04-16T09:15:56.274398Z","steps":["trace[275505232] 'read index received' (duration: 198.529082ms)","trace[275505232] 'applied index is now lower than readState.Index' (duration: 119.293µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:15:56.274654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.8825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T09:15:56.274708Z","caller":"traceutil/trace.go:171","msg":"trace[1585433558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:994; }","duration":"198.990183ms","start":"2026-04-16T09:15:56.075703Z","end":"2026-04-16T09:15:56.274693Z","steps":["trace[1585433558] 'agreement among raft nodes before linearized reading' (duration: 198.816879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:15:56.274718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.377363ms","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-04-16T09:15:56.274805Z","caller":"traceutil/trace.go:171","msg":"trace[485560521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:994; }","duration":"115.491976ms","start":"2026-04-16T09:15:56.159297Z","end":"2026-04-16T09:15:56.274789Z","steps":["trace[485560521] 'agreement among raft nodes before linearized reading' (duration: 115.23145ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:15:56.275032Z","caller":"traceutil/trace.go:171","msg":"trace[2120841066] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"213.262242ms","start":"2026-04-16T09:15:56.061734Z","end":"2026-04-16T09:15:56.274997Z","steps":["trace[2120841066] 'process raft request' (duration: 212.532395ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:15:57.026205Z","caller":"traceutil/trace.go:171","msg":"trace[1312309739] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"429.064943ms","start":"2026-04-16T09:15:56.597116Z","end":"2026-04-16T09:15:57.026181Z","steps":["trace[1312309739] 'process raft request' (duration: 428.866248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:15:57.02637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:15:56.597089Z","time spent":"429.191826ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T09:16:14.321939Z","caller":"traceutil/trace.go:171","msg":"trace[1565301719] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"161.74487ms","start":"2026-04-16T09:16:14.160175Z","end":"2026-04-16T09:16:14.32192Z","steps":["trace[1565301719] 'process raft request' (duration: 161.623247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:15.007161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278187945994543,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T09:16:15.065865Z","caller":"traceutil/trace.go:171","msg":"trace[96501330] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1101; }","duration":"558.94302ms","start":"2026-04-16T09:16:14.506897Z","end":"2026-04-16T09:16:15.06584Z","steps":["trace[96501330] 'read index received' (duration: 558.770656ms)","trace[96501330] 'applied index is now lower than readState.Index' (duration: 171.344µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:16:15.065965Z","caller":"traceutil/trace.go:171","msg":"trace[255316172] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"672.07199ms","start":"2026-04-16T09:16:14.393864Z","end":"2026-04-16T09:16:15.065936Z","steps":["trace[255316172] 'process raft request' (duration: 671.831644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:15.066179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:14.393854Z","time spent":"672.240153ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:16:15.066019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.105584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:16:15.06627Z","caller":"traceutil/trace.go:171","msg":"trace[1795127997] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1055; }","duration":"559.406791ms","start":"2026-04-16T09:16:14.506846Z","end":"2026-04-16T09:16:15.066253Z","steps":["trace[1795127997] 'agreement among raft nodes before linearized reading' (duration: 559.111084ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:15.066337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:14.506826Z","time spent":"559.496153ms","remote":"127.0.0.1:47514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T09:16:15.566298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278187945994546,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T09:16:16.033444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.42702ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T09:16:16.033663Z","caller":"traceutil/trace.go:171","msg":"trace[369264965] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1102; }","duration":"967.716141ms","start":"2026-04-16T09:16:15.065932Z","end":"2026-04-16T09:16:16.033648Z","steps":["trace[369264965] 'read index received' (duration: 639.177319ms)","trace[369264965] 'applied index is now lower than readState.Index' (duration: 328.537952ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:16:16.033714Z","caller":"traceutil/trace.go:171","msg":"trace[1660206233] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"946.96458ms","start":"2026-04-16T09:16:15.086727Z","end":"2026-04-16T09:16:16.033691Z","steps":["trace[1660206233] 'process raft request' (duration: 946.804066ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:16:16.033745Z","caller":"traceutil/trace.go:171","msg":"trace[1835435712] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"900.570391ms","start":"2026-04-16T09:16:15.133164Z","end":"2026-04-16T09:16:16.033734Z","steps":["trace[1835435712] 'process raft request' (duration: 900.447918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:16.033822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:15.133155Z","time spent":"900.613702ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:16:16.03383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:15.086701Z","time spent":"947.060582ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:16:16.033897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.291271ms","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-04-16T09:16:16.033943Z","caller":"traceutil/trace.go:171","msg":"trace[1188264930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"267.368943ms","start":"2026-04-16T09:16:15.766561Z","end":"2026-04-16T09:16:16.03393Z","steps":["trace[1188264930] 'agreement among raft nodes before linearized reading' (duration: 267.23316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:16.033994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.256427499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:16:16.034104Z","caller":"traceutil/trace.go:171","msg":"trace[168390115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"1.256605224s","start":"2026-04-16T09:16:14.777469Z","end":"2026-04-16T09:16:16.034074Z","steps":["trace[168390115] 'agreement among raft nodes before linearized reading' (duration: 1.256400919s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:16.034153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:14.777438Z","time spent":"1.256706356s","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T09:16:40.268644Z","caller":"traceutil/trace.go:171","msg":"trace[944006755] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"109.565644ms","start":"2026-04-16T09:16:40.159041Z","end":"2026-04-16T09:16:40.268607Z","steps":["trace[944006755] 'process raft request' (duration: 109.37465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:41.587726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.111137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T09:16:41.587783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.166424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T09:16:41.58775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.429139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T09:16:41.587863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.334918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T09:16:41.587872Z","caller":"traceutil/trace.go:171","msg":"trace[594179015] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1137; }","duration":"191.341682ms","start":"2026-04-16T09:16:41.396495Z","end":"2026-04-16T09:16:41.587837Z","steps":["trace[594179015] 'count revisions from in-memory index tree' (duration: 190.954583ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:16:41.587911Z","caller":"traceutil/trace.go:171","msg":"trace[1395360056] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1137; }","duration":"811.362039ms","start":"2026-04-16T09:16:40.776515Z","end":"2026-04-16T09:16:41.587877Z","steps":["trace[1395360056] 'range keys from in-memory index tree' (duration: 810.98831ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:16:41.587927Z","caller":"traceutil/trace.go:171","msg":"trace[997079764] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1137; }","duration":"355.718715ms","start":"2026-04-16T09:16:41.232177Z","end":"2026-04-16T09:16:41.587896Z","steps":["trace[997079764] 'count revisions from in-memory index tree' (duration: 355.267655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:41.587979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:40.776495Z","time spent":"811.467291ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T09:16:41.588217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099515212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-16T09:16:41.588354Z","caller":"traceutil/trace.go:171","msg":"trace[2023438518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1137; }","duration":"1.099694557s","start":"2026-04-16T09:16:40.488634Z","end":"2026-04-16T09:16:41.588329Z","steps":["trace[2023438518] 'range keys from in-memory index tree' (duration: 1.099302818s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:41.588008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:41.232148Z","time spent":"355.844369ms","remote":"127.0.0.1:47442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T09:16:41.588604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.099727198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-16T09:16:41.588655Z","caller":"traceutil/trace.go:171","msg":"trace[971882380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1137; }","duration":"1.099846401s","start":"2026-04-16T09:16:40.488796Z","end":"2026-04-16T09:16:41.588642Z","steps":["trace[971882380] 'range keys from in-memory index tree' (duration: 1.099537223s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:41.588688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:40.48876Z","time spent":"1.099920393s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T09:16:41.588705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072219416s","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-04-16T09:16:41.587925Z","caller":"traceutil/trace.go:171","msg":"trace[2075872596] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1137; }","duration":"350.434361ms","start":"2026-04-16T09:16:41.237475Z","end":"2026-04-16T09:16:41.587909Z","steps":["trace[2075872596] 'range keys from in-memory index tree' (duration: 350.093002ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:16:41.588761Z","caller":"traceutil/trace.go:171","msg":"trace[945306187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1137; }","duration":"1.072311448s","start":"2026-04-16T09:16:40.516436Z","end":"2026-04-16T09:16:41.588748Z","steps":["trace[945306187] 'range keys from in-memory index tree' (duration: 1.072031022s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:41.588459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:40.488608Z","time spent":"1.09983042s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T09:16:41.588802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:40.516414Z","time spent":"1.0723772s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T09:16:41.588835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:41.237455Z","time spent":"351.333911ms","remote":"127.0.0.1:42696","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-04-16T09:16:43.241966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.099011ms","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-04-16T09:16:43.242159Z","caller":"traceutil/trace.go:171","msg":"trace[1376253765] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1143; }","duration":"222.313826ms","start":"2026-04-16T09:16:43.019819Z","end":"2026-04-16T09:16:43.242133Z","steps":["trace[1376253765] 'range keys from in-memory index tree' (duration: 222.069859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:43.243038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.873629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T09:16:43.243174Z","caller":"traceutil/trace.go:171","msg":"trace[359237381] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"650.365235ms","start":"2026-04-16T09:16:42.592768Z","end":"2026-04-16T09:16:43.243134Z","steps":["trace[359237381] 'read index received' (duration: 199.332803ms)","trace[359237381] 'applied index is now lower than readState.Index' (duration: 451.030612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:16:43.24335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.5811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:16:43.243391Z","caller":"traceutil/trace.go:171","msg":"trace[790314441] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1144; }","duration":"650.672682ms","start":"2026-04-16T09:16:42.592706Z","end":"2026-04-16T09:16:43.243378Z","steps":["trace[790314441] 'agreement among raft nodes before linearized reading' (duration: 650.565709ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:16:43.243376Z","caller":"traceutil/trace.go:171","msg":"trace[905280068] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"869.827173ms","start":"2026-04-16T09:16:42.37344Z","end":"2026-04-16T09:16:43.243267Z","steps":["trace[905280068] 'process raft request' (duration: 418.589676ms)","trace[905280068] 'compare' (duration: 449.581238ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:16:43.243439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:42.592678Z","time spent":"650.749904ms","remote":"127.0.0.1:35628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T09:16:43.243578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:42.373419Z","time spent":"870.045968ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:16:43.243569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.843008ms","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":"warn","ts":"2026-04-16T09:16:43.243621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.431104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:16:43.243686Z","caller":"traceutil/trace.go:171","msg":"trace[83730650] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1144; }","duration":"394.016383ms","start":"2026-04-16T09:16:42.849645Z","end":"2026-04-16T09:16:43.243662Z","steps":["trace[83730650] 'agreement among raft nodes before linearized reading' (duration: 393.813498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:43.243763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:42.84963Z","time spent":"394.120515ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-04-16T09:16:43.243764Z","caller":"traceutil/trace.go:171","msg":"trace[1909408871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1144; }","duration":"465.648959ms","start":"2026-04-16T09:16:42.778085Z","end":"2026-04-16T09:16:43.243734Z","steps":["trace[1909408871] 'agreement among raft nodes before linearized reading' (duration: 465.370323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:16:43.243882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:16:42.778055Z","time spent":"465.806182ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T09:16:44.523373Z","caller":"traceutil/trace.go:171","msg":"trace[707971376] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"149.071271ms","start":"2026-04-16T09:16:44.37427Z","end":"2026-04-16T09:16:44.523341Z","steps":["trace[707971376] 'process raft request' (duration: 148.846846ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:19:24.514162Z","caller":"traceutil/trace.go:171","msg":"trace[1768715042] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"147.089601ms","start":"2026-04-16T09:19:24.367018Z","end":"2026-04-16T09:19:24.514107Z","steps":["trace[1768715042] 'process raft request' (duration: 146.862175ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:21:03.722873Z","caller":"traceutil/trace.go:171","msg":"trace[1628319348] transaction","detail":"{read_only:false; response_revision:2002; number_of_response:1; }","duration":"191.073124ms","start":"2026-04-16T09:21:03.531756Z","end":"2026-04-16T09:21:03.722829Z","steps":["trace[1628319348] 'process raft request' (duration: 190.844138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:21:31.54157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.115194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T09:21:31.541708Z","caller":"traceutil/trace.go:171","msg":"trace[920730022] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"282.398448ms","start":"2026-04-16T09:21:31.259257Z","end":"2026-04-16T09:21:31.541655Z","steps":["trace[920730022] 'process raft request' (duration: 175.1083ms)","trace[920730022] 'compare' (duration: 106.885838ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:21:31.550899Z","caller":"traceutil/trace.go:171","msg":"trace[719799349] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"290.888534ms","start":"2026-04-16T09:21:31.259997Z","end":"2026-04-16T09:21:31.550886Z","steps":["trace[719799349] 'process raft request' (duration: 290.740379ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:21:31.551645Z","caller":"traceutil/trace.go:171","msg":"trace[1540137723] transaction","detail":"{read_only:false; response_revision:2093; number_of_response:1; }","duration":"289.539038ms","start":"2026-04-16T09:21:31.261413Z","end":"2026-04-16T09:21:31.550952Z","steps":["trace[1540137723] 'process raft request' (duration: 289.383975ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:21:43.866672Z","caller":"traceutil/trace.go:171","msg":"trace[1832634814] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"253.844038ms","start":"2026-04-16T09:21:43.612782Z","end":"2026-04-16T09:21:43.866626Z","steps":["trace[1832634814] 'process raft request' (duration: 253.193361ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:21:43.866855Z","caller":"traceutil/trace.go:171","msg":"trace[70615525] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"253.587413ms","start":"2026-04-16T09:21:43.613256Z","end":"2026-04-16T09:21:43.866844Z","steps":["trace[70615525] 'process raft request' (duration: 252.890834ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:21:46.530458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.342706ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T09:21:46.530694Z","caller":"traceutil/trace.go:171","msg":"trace[1821413889] linearizableReadLoop","detail":"{readStateIndex:2304; appliedIndex:2303; }","duration":"868.248206ms","start":"2026-04-16T09:21:45.66242Z","end":"2026-04-16T09:21:46.530668Z","steps":["trace[1821413889] 'read index received' (duration: 324.618893ms)","trace[1821413889] 'applied index is now lower than readState.Index' (duration: 543.626533ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:21:46.530722Z","caller":"traceutil/trace.go:171","msg":"trace[18192104] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"454.78722ms","start":"2026-04-16T09:21:46.075915Z","end":"2026-04-16T09:21:46.530702Z","steps":["trace[18192104] 'process raft request' (duration: 454.667607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:21:46.530907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:21:46.075887Z","time spent":"454.930184ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:21:46.530972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.660654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T09:21:46.531034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.223748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:21:46.531175Z","caller":"traceutil/trace.go:171","msg":"trace[1648449066] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2189; }","duration":"607.454083ms","start":"2026-04-16T09:21:45.923684Z","end":"2026-04-16T09:21:46.531138Z","steps":["trace[1648449066] 'agreement among raft nodes before linearized reading' (duration: 607.210746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:21:46.531468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.050247ms","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-04-16T09:21:46.531532Z","caller":"traceutil/trace.go:171","msg":"trace[594459800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2189; }","duration":"869.16785ms","start":"2026-04-16T09:21:45.662349Z","end":"2026-04-16T09:21:46.531517Z","steps":["trace[594459800] 'agreement among raft nodes before linearized reading' (duration: 868.417161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:21:46.531583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:21:45.662319Z","time spent":"869.254963ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T09:21:46.531094Z","caller":"traceutil/trace.go:171","msg":"trace[1165934439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2189; }","duration":"753.847489ms","start":"2026-04-16T09:21:45.777215Z","end":"2026-04-16T09:21:46.531063Z","steps":["trace[1165934439] 'agreement among raft nodes before linearized reading' (duration: 753.642624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:21:46.53166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:21:45.777189Z","time spent":"754.456844ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T09:21:46.531026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.317034ms","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-04-16T09:21:46.531616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:21:45.923651Z","time spent":"607.879984ms","remote":"127.0.0.1:36556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-16T09:21:46.53172Z","caller":"traceutil/trace.go:171","msg":"trace[906858304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2189; }","duration":"660.024902ms","start":"2026-04-16T09:21:45.871679Z","end":"2026-04-16T09:21:46.531704Z","steps":["trace[906858304] 'agreement among raft nodes before linearized reading' (duration: 659.224931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:21:46.531878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:21:45.871675Z","time spent":"660.181405ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T09:21:46.531172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.886597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T09:21:46.532025Z","caller":"traceutil/trace.go:171","msg":"trace[447909211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2189; }","duration":"660.79912ms","start":"2026-04-16T09:21:45.871206Z","end":"2026-04-16T09:21:46.532005Z","steps":["trace[447909211] 'agreement among raft nodes before linearized reading' (duration: 659.742044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:21:46.532062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:21:45.871184Z","time spent":"660.870472ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T09:21:50.270328Z","caller":"traceutil/trace.go:171","msg":"trace[1663865952] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2318; }","duration":"146.787245ms","start":"2026-04-16T09:21:50.123516Z","end":"2026-04-16T09:21:50.270303Z","steps":["trace[1663865952] 'read index received' (duration: 126.129604ms)","trace[1663865952] 'applied index is now lower than readState.Index' (duration: 20.656231ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:21:50.270393Z","caller":"traceutil/trace.go:171","msg":"trace[1212887093] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"241.321321ms","start":"2026-04-16T09:21:50.029053Z","end":"2026-04-16T09:21:50.270375Z","steps":["trace[1212887093] 'process raft request' (duration: 220.653ms)","trace[1212887093] 'compare' (duration: 20.381104ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:21:50.270458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.92879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:21:50.270486Z","caller":"traceutil/trace.go:171","msg":"trace[1980249137] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2202; }","duration":"147.017412ms","start":"2026-04-16T09:21:50.12346Z","end":"2026-04-16T09:21:50.270477Z","steps":["trace[1980249137] 'agreement among raft nodes before linearized reading' (duration: 146.922958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:00.218379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.725674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T09:22:00.218531Z","caller":"traceutil/trace.go:171","msg":"trace[1859713751] transaction","detail":"{read_only:false; response_revision:2235; number_of_response:1; }","duration":"185.863602ms","start":"2026-04-16T09:22:00.03264Z","end":"2026-04-16T09:22:00.218503Z","steps":["trace[1859713751] 'process raft request' (duration: 78.876981ms)","trace[1859713751] 'compare' (duration: 106.538179ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:22:00.691535Z","caller":"traceutil/trace.go:171","msg":"trace[230481568] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"103.607026ms","start":"2026-04-16T09:22:00.587842Z","end":"2026-04-16T09:22:00.691449Z","steps":["trace[230481568] 'process raft request' (duration: 103.246296ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:04.400737Z","caller":"traceutil/trace.go:171","msg":"trace[517324038] transaction","detail":"{read_only:false; response_revision:2275; number_of_response:1; }","duration":"542.971395ms","start":"2026-04-16T09:22:03.857744Z","end":"2026-04-16T09:22:04.400715Z","steps":["trace[517324038] 'process raft request' (duration: 542.512145ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:04.40086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:03.857727Z","time spent":"543.067017ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:22:04.889154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.950518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:04.889224Z","caller":"traceutil/trace.go:171","msg":"trace[1422931631] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:2275; }","duration":"475.058302ms","start":"2026-04-16T09:22:04.414151Z","end":"2026-04-16T09:22:04.889209Z","steps":["trace[1422931631] 'range keys from in-memory index tree' (duration: 474.898777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:04.889252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:04.414138Z","time spent":"475.108503ms","remote":"127.0.0.1:42574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"info","ts":"2026-04-16T09:22:04.90482Z","caller":"traceutil/trace.go:171","msg":"trace[1465268631] linearizableReadLoop","detail":"{readStateIndex:2395; appliedIndex:2394; }","duration":"127.657042ms","start":"2026-04-16T09:22:04.777152Z","end":"2026-04-16T09:22:04.904809Z","steps":["trace[1465268631] 'read index received' (duration: 127.528219ms)","trace[1465268631] 'applied index is now lower than readState.Index' (duration: 128.313µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:22:04.904984Z","caller":"traceutil/trace.go:171","msg":"trace[2023114075] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"177.264694ms","start":"2026-04-16T09:22:04.72771Z","end":"2026-04-16T09:22:04.904974Z","steps":["trace[2023114075] 'process raft request' (duration: 177.014798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:04.905088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.93527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:04.905109Z","caller":"traceutil/trace.go:171","msg":"trace[139819993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2276; }","duration":"127.996472ms","start":"2026-04-16T09:22:04.777107Z","end":"2026-04-16T09:22:04.905103Z","steps":["trace[139819993] 'agreement among raft nodes before linearized reading' (duration: 127.961991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:04.905378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.570979ms","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-04-16T09:22:04.905406Z","caller":"traceutil/trace.go:171","msg":"trace[79881733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2276; }","duration":"121.613179ms","start":"2026-04-16T09:22:04.783786Z","end":"2026-04-16T09:22:04.9054Z","steps":["trace[79881733] 'agreement among raft nodes before linearized reading' (duration: 121.511807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:04.905544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.595144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:04.90557Z","caller":"traceutil/trace.go:171","msg":"trace[1877331875] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2276; }","duration":"120.635844ms","start":"2026-04-16T09:22:04.784926Z","end":"2026-04-16T09:22:04.905562Z","steps":["trace[1877331875] 'agreement among raft nodes before linearized reading' (duration: 120.600473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:04.905819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.59462ms","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-04-16T09:22:04.905899Z","caller":"traceutil/trace.go:171","msg":"trace[897715076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2276; }","duration":"121.715903ms","start":"2026-04-16T09:22:04.784164Z","end":"2026-04-16T09:22:04.90588Z","steps":["trace[897715076] 'agreement among raft nodes before linearized reading' (duration: 121.459076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:04.905998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.145299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-16T09:22:04.906082Z","caller":"traceutil/trace.go:171","msg":"trace[990170021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2276; }","duration":"121.252041ms","start":"2026-04-16T09:22:04.78481Z","end":"2026-04-16T09:22:04.906062Z","steps":["trace[990170021] 'agreement among raft nodes before linearized reading' (duration: 120.992835ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:05.051962Z","caller":"traceutil/trace.go:171","msg":"trace[108764124] transaction","detail":"{read_only:false; response_revision:2279; number_of_response:1; }","duration":"141.141783ms","start":"2026-04-16T09:22:04.910767Z","end":"2026-04-16T09:22:05.051909Z","steps":["trace[108764124] 'process raft request' (duration: 141.04912ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:05.052028Z","caller":"traceutil/trace.go:171","msg":"trace[2016179643] transaction","detail":"{read_only:false; response_revision:2278; number_of_response:1; }","duration":"141.4564ms","start":"2026-04-16T09:22:04.910513Z","end":"2026-04-16T09:22:05.051969Z","steps":["trace[2016179643] 'process raft request' (duration: 141.098701ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:05.080169Z","caller":"traceutil/trace.go:171","msg":"trace[135070903] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"136.960497ms","start":"2026-04-16T09:22:04.943181Z","end":"2026-04-16T09:22:05.080142Z","steps":["trace[135070903] 'process raft request' (duration: 136.863685ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:05.245498Z","caller":"traceutil/trace.go:171","msg":"trace[1827369506] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"147.244846ms","start":"2026-04-16T09:22:05.098226Z","end":"2026-04-16T09:22:05.245471Z","steps":["trace[1827369506] 'process raft request' (duration: 68.990881ms)","trace[1827369506] 'compare' (duration: 78.148813ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:22:05.429787Z","caller":"traceutil/trace.go:171","msg":"trace[1484242724] linearizableReadLoop","detail":"{readStateIndex:2402; appliedIndex:2401; }","duration":"121.511057ms","start":"2026-04-16T09:22:05.308249Z","end":"2026-04-16T09:22:05.42976Z","steps":["trace[1484242724] 'read index received' (duration: 121.342943ms)","trace[1484242724] 'applied index is now lower than readState.Index' (duration: 166.914µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:22:05.429996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.702732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:05.429801Z","caller":"traceutil/trace.go:171","msg":"trace[2097189135] transaction","detail":"{read_only:false; response_revision:2283; number_of_response:1; }","duration":"176.434413ms","start":"2026-04-16T09:22:05.25334Z","end":"2026-04-16T09:22:05.429774Z","steps":["trace[2097189135] 'process raft request' (duration: 176.260759ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:05.430082Z","caller":"traceutil/trace.go:171","msg":"trace[718186] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:2283; }","duration":"121.847216ms","start":"2026-04-16T09:22:05.308211Z","end":"2026-04-16T09:22:05.430058Z","steps":["trace[718186] 'agreement among raft nodes before linearized reading' (duration: 121.64763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:05.614161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.900319ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T09:22:05.614459Z","caller":"traceutil/trace.go:171","msg":"trace[213995585] linearizableReadLoop","detail":"{readStateIndex:2404; appliedIndex:2402; }","duration":"170.610077ms","start":"2026-04-16T09:22:05.44381Z","end":"2026-04-16T09:22:05.61442Z","steps":["trace[213995585] 'read index received' (duration: 19.868972ms)","trace[213995585] 'applied index is now lower than readState.Index' (duration: 150.740275ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:22:05.614633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.813071ms","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-04-16T09:22:05.614652Z","caller":"traceutil/trace.go:171","msg":"trace[1585031191] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2284; }","duration":"170.881734ms","start":"2026-04-16T09:22:05.443762Z","end":"2026-04-16T09:22:05.614644Z","steps":["trace[1585031191] 'agreement among raft nodes before linearized reading' (duration: 170.735801ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:05.614679Z","caller":"traceutil/trace.go:171","msg":"trace[311206199] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"180.427494ms","start":"2026-04-16T09:22:05.434205Z","end":"2026-04-16T09:22:05.614633Z","steps":["trace[311206199] 'process raft request' (duration: 180.078826ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:06.110497Z","caller":"traceutil/trace.go:171","msg":"trace[1225672889] transaction","detail":"{read_only:false; response_revision:2286; number_of_response:1; }","duration":"308.076327ms","start":"2026-04-16T09:22:05.802381Z","end":"2026-04-16T09:22:06.110458Z","steps":["trace[1225672889] 'process raft request' (duration: 307.852102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:06.110772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:05.802359Z","time spent":"308.23777ms","remote":"127.0.0.1:42600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T09:22:06.99091Z","caller":"traceutil/trace.go:171","msg":"trace[2133179634] transaction","detail":"{read_only:false; response_revision:2287; number_of_response:1; }","duration":"873.613002ms","start":"2026-04-16T09:22:06.117278Z","end":"2026-04-16T09:22:06.990891Z","steps":["trace[2133179634] 'process raft request' (duration: 873.273654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:06.991025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:06.117258Z","time spent":"873.713845ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":767,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T09:22:07.203989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.354421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T09:22:07.204274Z","caller":"traceutil/trace.go:171","msg":"trace[102553221] linearizableReadLoop","detail":"{readStateIndex:2408; appliedIndex:2406; }","duration":"427.783198ms","start":"2026-04-16T09:22:06.776434Z","end":"2026-04-16T09:22:07.204217Z","steps":["trace[102553221] 'read index received' (duration: 214.281239ms)","trace[102553221] 'applied index is now lower than readState.Index' (duration: 213.500669ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:22:07.204285Z","caller":"traceutil/trace.go:171","msg":"trace[1468285690] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"1.081546471s","start":"2026-04-16T09:22:06.122699Z","end":"2026-04-16T09:22:07.204246Z","steps":["trace[1468285690] 'process raft request' (duration: 971.809271ms)","trace[1468285690] 'compare' (duration: 109.115144ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:22:07.204327Z","caller":"traceutil/trace.go:171","msg":"trace[1224848581] transaction","detail":"{read_only:false; response_revision:2289; number_of_response:1; }","duration":"291.649932ms","start":"2026-04-16T09:22:06.912647Z","end":"2026-04-16T09:22:07.204297Z","steps":["trace[1224848581] 'process raft request' (duration: 291.471158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:07.204454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:06.116508Z","time spent":"1.087854071s","remote":"127.0.0.1:42600","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:22:07.204478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.043916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:07.204552Z","caller":"traceutil/trace.go:171","msg":"trace[218488889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2289; }","duration":"428.258261ms","start":"2026-04-16T09:22:06.776275Z","end":"2026-04-16T09:22:07.204533Z","steps":["trace[218488889] 'agreement among raft nodes before linearized reading' (duration: 428.127687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:07.204585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:06.776251Z","time spent":"428.327723ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T09:22:07.204672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.035712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-16T09:22:07.204696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.425983ms","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-04-16T09:22:07.204735Z","caller":"traceutil/trace.go:171","msg":"trace[1453234945] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2289; }","duration":"147.167505ms","start":"2026-04-16T09:22:07.057551Z","end":"2026-04-16T09:22:07.204718Z","steps":["trace[1453234945] 'agreement among raft nodes before linearized reading' (duration: 146.95602ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:07.204772Z","caller":"traceutil/trace.go:171","msg":"trace[975895754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2289; }","duration":"258.568046ms","start":"2026-04-16T09:22:06.946164Z","end":"2026-04-16T09:22:07.204732Z","steps":["trace[975895754] 'agreement among raft nodes before linearized reading' (duration: 258.30464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:07.204865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.230286ms","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-04-16T09:22:07.204987Z","caller":"traceutil/trace.go:171","msg":"trace[1394761497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2289; }","duration":"147.423682ms","start":"2026-04-16T09:22:07.057537Z","end":"2026-04-16T09:22:07.20496Z","steps":["trace[1394761497] 'agreement among raft nodes before linearized reading' (duration: 146.998441ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:07.407718Z","caller":"traceutil/trace.go:171","msg":"trace[364801360] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"197.196547ms","start":"2026-04-16T09:22:07.210484Z","end":"2026-04-16T09:22:07.407681Z","steps":["trace[364801360] 'process raft request' (duration: 196.976322ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:07.450296Z","caller":"traceutil/trace.go:171","msg":"trace[1558752488] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"237.991487ms","start":"2026-04-16T09:22:07.212268Z","end":"2026-04-16T09:22:07.45026Z","steps":["trace[1558752488] 'process raft request' (duration: 237.935935ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:07.450382Z","caller":"traceutil/trace.go:171","msg":"trace[1681060373] transaction","detail":"{read_only:false; response_revision:2291; number_of_response:1; }","duration":"239.837745ms","start":"2026-04-16T09:22:07.210507Z","end":"2026-04-16T09:22:07.450345Z","steps":["trace[1681060373] 'process raft request' (duration: 239.490975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:07.984395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.741096ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T09:22:07.984546Z","caller":"traceutil/trace.go:171","msg":"trace[764545233] linearizableReadLoop","detail":"{readStateIndex:2414; appliedIndex:2413; }","duration":"207.843606ms","start":"2026-04-16T09:22:07.776665Z","end":"2026-04-16T09:22:07.984508Z","steps":["trace[764545233] 'read index received' (duration: 52.774302ms)","trace[764545233] 'applied index is now lower than readState.Index' (duration: 155.067574ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:22:07.984618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.95075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:07.984637Z","caller":"traceutil/trace.go:171","msg":"trace[968907528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2294; }","duration":"207.99966ms","start":"2026-04-16T09:22:07.776629Z","end":"2026-04-16T09:22:07.984628Z","steps":["trace[968907528] 'agreement among raft nodes before linearized reading' (duration: 207.951729ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:07.984601Z","caller":"traceutil/trace.go:171","msg":"trace[532673725] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"260.191449ms","start":"2026-04-16T09:22:07.724369Z","end":"2026-04-16T09:22:07.98456Z","steps":["trace[532673725] 'process raft request' (duration: 105.144364ms)","trace[532673725] 'compare' (duration: 154.581743ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:22:08.460386Z","caller":"traceutil/trace.go:171","msg":"trace[835284001] transaction","detail":"{read_only:false; response_revision:2295; number_of_response:1; }","duration":"344.721923ms","start":"2026-04-16T09:22:08.115638Z","end":"2026-04-16T09:22:08.46036Z","steps":["trace[835284001] 'process raft request' (duration: 305.463071ms)","trace[835284001] 'compare' (duration: 39.075947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:22:08.460955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:08.115609Z","time spent":"344.846875ms","remote":"127.0.0.1:42510","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T09:22:08.52971Z","caller":"traceutil/trace.go:171","msg":"trace[231968926] transaction","detail":"{read_only:false; response_revision:2296; number_of_response:1; }","duration":"187.844782ms","start":"2026-04-16T09:22:08.341848Z","end":"2026-04-16T09:22:08.529692Z","steps":["trace[231968926] 'process raft request' (duration: 187.619397ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:08.793628Z","caller":"traceutil/trace.go:171","msg":"trace[1098841505] transaction","detail":"{read_only:false; response_revision:2297; number_of_response:1; }","duration":"258.497306ms","start":"2026-04-16T09:22:08.535087Z","end":"2026-04-16T09:22:08.793585Z","steps":["trace[1098841505] 'process raft request' (duration: 213.91645ms)","trace[1098841505] 'compare' (duration: 44.35919ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:22:09.822791Z","caller":"traceutil/trace.go:171","msg":"trace[925109472] transaction","detail":"{read_only:false; response_revision:2301; number_of_response:1; }","duration":"334.589486ms","start":"2026-04-16T09:22:09.488176Z","end":"2026-04-16T09:22:09.822765Z","steps":["trace[925109472] 'process raft request' (duration: 334.431092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:09.822957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:09.488153Z","time spent":"334.71618ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T09:22:09.824877Z","caller":"traceutil/trace.go:171","msg":"trace[211688735] linearizableReadLoop","detail":"{readStateIndex:2422; appliedIndex:2420; }","duration":"312.55296ms","start":"2026-04-16T09:22:09.512302Z","end":"2026-04-16T09:22:09.824855Z","steps":["trace[211688735] 'read index received' (duration: 310.346635ms)","trace[211688735] 'applied index is now lower than readState.Index' (duration: 2.203524ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T09:22:09.82502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.698093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:09.825008Z","caller":"traceutil/trace.go:171","msg":"trace[1617886265] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"336.391341ms","start":"2026-04-16T09:22:09.488577Z","end":"2026-04-16T09:22:09.824969Z","steps":["trace[1617886265] 'process raft request' (duration: 336.165476ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:09.825078Z","caller":"traceutil/trace.go:171","msg":"trace[513640634] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:2302; }","duration":"312.789976ms","start":"2026-04-16T09:22:09.512266Z","end":"2026-04-16T09:22:09.825056Z","steps":["trace[513640634] 'agreement among raft nodes before linearized reading' (duration: 312.703243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:09.825119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:09.512251Z","time spent":"312.859608ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-16T09:22:09.825149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:09.488548Z","time spent":"336.528056ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T09:22:23.94683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.870378ms","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-04-16T09:22:23.946909Z","caller":"traceutil/trace.go:171","msg":"trace[182042071] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2408; }","duration":"128.005211ms","start":"2026-04-16T09:22:23.818886Z","end":"2026-04-16T09:22:23.946891Z","steps":["trace[182042071] 'range keys from in-memory index tree' (duration: 127.616362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:23.947309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.237153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:23.947442Z","caller":"traceutil/trace.go:171","msg":"trace[74140285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2408; }","duration":"173.466558ms","start":"2026-04-16T09:22:23.773951Z","end":"2026-04-16T09:22:23.947418Z","steps":["trace[74140285] 'range keys from in-memory index tree' (duration: 173.142292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:33.228897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.324485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T09:22:33.229057Z","caller":"traceutil/trace.go:171","msg":"trace[1056782413] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2456; }","duration":"747.5657ms","start":"2026-04-16T09:22:32.481465Z","end":"2026-04-16T09:22:33.229031Z","steps":["trace[1056782413] 'count revisions from in-memory index tree' (duration: 747.235582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:33.229059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.674863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-04-16T09:22:33.229121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:32.481442Z","time spent":"747.665002ms","remote":"127.0.0.1:36578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":150,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-04-16T09:22:33.229197Z","caller":"traceutil/trace.go:171","msg":"trace[316802910] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2456; }","duration":"396.831988ms","start":"2026-04-16T09:22:32.832305Z","end":"2026-04-16T09:22:33.229137Z","steps":["trace[316802910] 'range keys from in-memory index tree' (duration: 396.444487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:33.229253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:32.832282Z","time spent":"396.95997ms","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-16T09:22:33.229776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.418603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T09:22:33.22981Z","caller":"traceutil/trace.go:171","msg":"trace[1573011089] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2456; }","duration":"296.514096ms","start":"2026-04-16T09:22:32.933284Z","end":"2026-04-16T09:22:33.229798Z","steps":["trace[1573011089] 'count revisions from in-memory index tree' (duration: 296.213507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:33.229924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.197631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T09:22:33.230016Z","caller":"traceutil/trace.go:171","msg":"trace[2113148835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2456; }","duration":"453.392535ms","start":"2026-04-16T09:22:32.776599Z","end":"2026-04-16T09:22:33.229992Z","steps":["trace[2113148835] 'range keys from in-memory index tree' (duration: 452.996104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:33.230073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:32.776534Z","time spent":"453.525508ms","remote":"127.0.0.1:42450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T09:22:33.230251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.048976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-16T09:22:33.2303Z","caller":"traceutil/trace.go:171","msg":"trace[1796004515] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2456; }","duration":"249.124348ms","start":"2026-04-16T09:22:32.981162Z","end":"2026-04-16T09:22:33.230287Z","steps":["trace[1796004515] 'range keys from in-memory index tree' (duration: 248.853392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:33.230641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.032031371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T09:22:33.230631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.298378ms","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-04-16T09:22:33.230699Z","caller":"traceutil/trace.go:171","msg":"trace[2091421464] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2456; }","duration":"1.032148224s","start":"2026-04-16T09:22:32.198535Z","end":"2026-04-16T09:22:33.230683Z","steps":["trace[2091421464] 'range keys from in-memory index tree' (duration: 1.030917603s)"],"step_count":1} {"level":"info","ts":"2026-04-16T09:22:33.230724Z","caller":"traceutil/trace.go:171","msg":"trace[384442426] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2456; }","duration":"210.398481ms","start":"2026-04-16T09:22:33.020312Z","end":"2026-04-16T09:22:33.23071Z","steps":["trace[384442426] 'range keys from in-memory index tree' (duration: 210.283628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:33.230741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T09:22:32.1985Z","time spent":"1.032230115s","remote":"127.0.0.1:42696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T09:22:34.118978Z","caller":"traceutil/trace.go:171","msg":"trace[771455030] linearizableReadLoop","detail":"{readStateIndex:2587; appliedIndex:2586; }","duration":"120.298077ms","start":"2026-04-16T09:22:33.998651Z","end":"2026-04-16T09:22:34.118949Z","steps":["trace[771455030] 'read index received' (duration: 119.980898ms)","trace[771455030] 'applied index is now lower than readState.Index' (duration: 315.499µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T09:22:34.119048Z","caller":"traceutil/trace.go:171","msg":"trace[978224020] transaction","detail":"{read_only:false; response_revision:2462; number_of_response:1; }","duration":"146.928679ms","start":"2026-04-16T09:22:33.972075Z","end":"2026-04-16T09:22:34.119004Z","steps":["trace[978224020] 'process raft request' (duration: 146.670133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:34.119328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.622094ms","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-04-16T09:22:34.119709Z","caller":"traceutil/trace.go:171","msg":"trace[1140599071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2462; }","duration":"121.024474ms","start":"2026-04-16T09:22:33.998622Z","end":"2026-04-16T09:22:34.119646Z","steps":["trace[1140599071] 'agreement among raft nodes before linearized reading' (duration: 120.47862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:34.119824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.158356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T09:22:34.119874Z","caller":"traceutil/trace.go:171","msg":"trace[1725744887] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2462; }","duration":"115.242498ms","start":"2026-04-16T09:22:34.004613Z","end":"2026-04-16T09:22:34.119856Z","steps":["trace[1725744887] 'agreement among raft nodes before linearized reading' (duration: 115.139416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T09:22:34.119917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.899302ms","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-04-16T09:22:34.120026Z","caller":"traceutil/trace.go:171","msg":"trace[2030346225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2462; }","duration":"121.053797ms","start":"2026-04-16T09:22:33.998948Z","end":"2026-04-16T09:22:34.120001Z","steps":["trace[2030346225] 'agreement among raft nodes before linearized reading' (duration: 120.710348ms)"],"step_count":1}