{"level":"warn","ts":"2026-02-23T20:11:22.64469Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-23T20:11:22.644819Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-23T20:11:22.644914Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-23T20:11:22.644933Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-23T20:11:22.644958Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-23T20:11:22.645861Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-02-23T20:11:22.646Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-23T20:11:22.651953Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.655853ms"} {"level":"info","ts":"2026-02-23T20:11:22.671567Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-02-23T20:11:22.671688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-23T20:11:22.671731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-02-23T20:11:22.671758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-23T20:11:22.671786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-02-23T20:11:22.671853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-02-23T20:11:22.691308Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-23T20:11:22.693473Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-23T20:11:22.695405Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-23T20:11:22.698323Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-23T20:11:22.701192Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-23T20:11:22.701501Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-23T20:11:22.701552Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-23T20:11:22.701563Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-23T20:11:22.705508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-02-23T20:11:22.705668Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-23T20:11:22.706497Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-23T20:11:22.706735Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-23T20:11:22.706771Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-23T20:11:22.706848Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-23T20:11:22.706863Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-23T20:11:23.472321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-23T20:11:23.472394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-23T20:11:23.47242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-02-23T20:11:23.472437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-02-23T20:11:23.472452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-23T20:11:23.472463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-02-23T20:11:23.472477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-23T20:11:23.473715Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T20:11:23.4737Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T20:11:23.474627Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T20:11:23.474767Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T20:11:23.474804Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T20:11:23.474828Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T20:11:23.476158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-02-23T20:11:23.473679Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-02-23T20:11:23.476784Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-23T20:11:23.476804Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-23T20:11:23.478556Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-23T20:11:25.063693Z","caller":"traceutil/trace.go:171","msg":"trace[214875802] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"136.466395ms","start":"2026-02-23T20:11:24.927193Z","end":"2026-02-23T20:11:25.063659Z","steps":["trace[214875802] 'process raft request' (duration: 133.414518ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.063774Z","caller":"traceutil/trace.go:171","msg":"trace[1625282835] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"136.523796ms","start":"2026-02-23T20:11:24.927216Z","end":"2026-02-23T20:11:25.06374Z","steps":["trace[1625282835] 'process raft request' (duration: 136.367182ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.064Z","caller":"traceutil/trace.go:171","msg":"trace[2082423778] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"136.030633ms","start":"2026-02-23T20:11:24.92795Z","end":"2026-02-23T20:11:25.06398Z","steps":["trace[2082423778] 'process raft request' (duration: 135.787387ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.06425Z","caller":"traceutil/trace.go:171","msg":"trace[1798350699] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"135.946801ms","start":"2026-02-23T20:11:24.9282Z","end":"2026-02-23T20:11:25.064147Z","steps":["trace[1798350699] 'process raft request' (duration: 135.580742ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.064353Z","caller":"traceutil/trace.go:171","msg":"trace[2095377417] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"126.997955ms","start":"2026-02-23T20:11:24.937329Z","end":"2026-02-23T20:11:25.064327Z","steps":["trace[2095377417] 'process raft request' (duration: 126.679437ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.064711Z","caller":"traceutil/trace.go:171","msg":"trace[2089843537] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"119.221228ms","start":"2026-02-23T20:11:24.945476Z","end":"2026-02-23T20:11:25.064698Z","steps":["trace[2089843537] 'process raft request' (duration: 118.692504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:25.064798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.678155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-23T20:11:25.06496Z","caller":"traceutil/trace.go:171","msg":"trace[1835318918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"137.879821ms","start":"2026-02-23T20:11:24.927065Z","end":"2026-02-23T20:11:25.064945Z","steps":["trace[1835318918] 'agreement among raft nodes before linearized reading' (duration: 137.652955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:25.065005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.060466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-23T20:11:25.065155Z","caller":"traceutil/trace.go:171","msg":"trace[762174723] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"138.194449ms","start":"2026-02-23T20:11:24.92687Z","end":"2026-02-23T20:11:25.065064Z","steps":["trace[762174723] 'agreement among raft nodes before linearized reading' (duration: 137.940963ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.063845Z","caller":"traceutil/trace.go:171","msg":"trace[1974662202] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"136.495925ms","start":"2026-02-23T20:11:24.927292Z","end":"2026-02-23T20:11:25.063788Z","steps":["trace[1974662202] 'process raft request' (duration: 136.366482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:25.462675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.303279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-23T20:11:25.463037Z","caller":"traceutil/trace.go:171","msg":"trace[216969150] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"219.713473ms","start":"2026-02-23T20:11:25.243254Z","end":"2026-02-23T20:11:25.462967Z","steps":["trace[216969150] 'process raft request' (duration: 117.291059ms)","trace[216969150] 'compare' (duration: 99.980682ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:11:25.463186Z","caller":"traceutil/trace.go:171","msg":"trace[1654242716] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"163.990701ms","start":"2026-02-23T20:11:25.299165Z","end":"2026-02-23T20:11:25.463156Z","steps":["trace[1654242716] 'process raft request' (duration: 163.695474ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.644894Z","caller":"traceutil/trace.go:171","msg":"trace[981505462] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"122.485121ms","start":"2026-02-23T20:11:25.522376Z","end":"2026-02-23T20:11:25.644861Z","steps":["trace[981505462] 'process raft request' (duration: 122.375238ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.645008Z","caller":"traceutil/trace.go:171","msg":"trace[769154664] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"124.84494ms","start":"2026-02-23T20:11:25.520135Z","end":"2026-02-23T20:11:25.64498Z","steps":["trace[769154664] 'process raft request' (duration: 44.228569ms)","trace[769154664] 'compare' (duration: 80.083886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:11:25.97304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.557881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-23T20:11:25.973339Z","caller":"traceutil/trace.go:171","msg":"trace[173778910] linearizableReadLoop","detail":"{readStateIndex:68; appliedIndex:66; }","duration":"150.533001ms","start":"2026-02-23T20:11:25.822784Z","end":"2026-02-23T20:11:25.973317Z","steps":["trace[173778910] 'read index received' (duration: 2.205856ms)","trace[173778910] 'applied index is now lower than readState.Index' (duration: 148.325775ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:11:25.973556Z","caller":"traceutil/trace.go:171","msg":"trace[1376935065] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"274.487959ms","start":"2026-02-23T20:11:25.699013Z","end":"2026-02-23T20:11:25.973501Z","steps":["trace[1376935065] 'process raft request' (duration: 123.192429ms)","trace[1376935065] 'compare' (duration: 150.105829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:11:25.973725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.938581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-23T20:11:25.973768Z","caller":"traceutil/trace.go:171","msg":"trace[316852596] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:64; }","duration":"151.023403ms","start":"2026-02-23T20:11:25.822735Z","end":"2026-02-23T20:11:25.973758Z","steps":["trace[316852596] 'agreement among raft nodes before linearized reading' (duration: 150.931011ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:25.973629Z","caller":"traceutil/trace.go:171","msg":"trace[382290558] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"252.588564ms","start":"2026-02-23T20:11:25.721028Z","end":"2026-02-23T20:11:25.973616Z","steps":["trace[382290558] 'process raft request' (duration: 252.145533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:25.973988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.127376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-23T20:11:25.97409Z","caller":"traceutil/trace.go:171","msg":"trace[1524170103] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:64; }","duration":"151.274119ms","start":"2026-02-23T20:11:25.822806Z","end":"2026-02-23T20:11:25.974081Z","steps":["trace[1524170103] 'agreement among raft nodes before linearized reading' (duration: 151.158306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:25.97445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.906074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-23T20:11:25.974563Z","caller":"traceutil/trace.go:171","msg":"trace[325252762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:64; }","duration":"103.07295ms","start":"2026-02-23T20:11:25.871468Z","end":"2026-02-23T20:11:25.974541Z","steps":["trace[325252762] 'agreement among raft nodes before linearized reading' (duration: 102.875855ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:26.410758Z","caller":"traceutil/trace.go:171","msg":"trace[1600333789] linearizableReadLoop","detail":"{readStateIndex:74; appliedIndex:71; }","duration":"353.356955ms","start":"2026-02-23T20:11:26.05737Z","end":"2026-02-23T20:11:26.410727Z","steps":["trace[1600333789] 'read index received' (duration: 345.555627ms)","trace[1600333789] 'applied index is now lower than readState.Index' (duration: 7.800058ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:11:26.410966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.55918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-23T20:11:26.411014Z","caller":"traceutil/trace.go:171","msg":"trace[1132912668] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:70; }","duration":"353.666083ms","start":"2026-02-23T20:11:26.057335Z","end":"2026-02-23T20:11:26.411001Z","steps":["trace[1132912668] 'agreement among raft nodes before linearized reading' (duration: 353.55588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:26.411065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:11:26.057317Z","time spent":"353.738204ms","remote":"127.0.0.1:51410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"info","ts":"2026-02-23T20:11:26.411207Z","caller":"traceutil/trace.go:171","msg":"trace[1333050202] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"355.760266ms","start":"2026-02-23T20:11:26.055382Z","end":"2026-02-23T20:11:26.411142Z","steps":["trace[1333050202] 'process raft request' (duration: 347.532448ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:26.411265Z","caller":"traceutil/trace.go:171","msg":"trace[1438618684] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"355.332885ms","start":"2026-02-23T20:11:26.055546Z","end":"2026-02-23T20:11:26.410879Z","steps":["trace[1438618684] 'process raft request' (duration: 355.053778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:26.411472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:11:26.055338Z","time spent":"355.990182ms","remote":"127.0.0.1:51428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T20:11:26.411463Z","caller":"traceutil/trace.go:171","msg":"trace[2091426175] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"355.035807ms","start":"2026-02-23T20:11:26.056349Z","end":"2026-02-23T20:11:26.411385Z","steps":["trace[2091426175] 'process raft request' (duration: 354.303549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:26.411741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:11:26.055526Z","time spent":"355.988041ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":581,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T20:11:26.411854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:11:26.056336Z","time spent":"355.252113ms","remote":"127.0.0.1:51500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1120,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:11:50.016783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.730156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-23T20:11:50.016904Z","caller":"traceutil/trace.go:171","msg":"trace[983360103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:429; }","duration":"528.90756ms","start":"2026-02-23T20:11:49.487972Z","end":"2026-02-23T20:11:50.016879Z","steps":["trace[983360103] 'range keys from in-memory index tree' (duration: 528.576492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:11:50.016946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:11:49.487948Z","time spent":"528.989422ms","remote":"127.0.0.1:51322","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-02-23T20:11:50.017437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.064712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:11:50.017554Z","caller":"traceutil/trace.go:171","msg":"trace[350655974] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"149.164846ms","start":"2026-02-23T20:11:49.868371Z","end":"2026-02-23T20:11:50.017536Z","steps":["trace[350655974] 'read index received' (duration: 110.583µs)","trace[350655974] 'applied index is now lower than readState.Index' (duration: 149.053163ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:11:50.017622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.259838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-23T20:11:50.017647Z","caller":"traceutil/trace.go:171","msg":"trace[1732859299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:430; }","duration":"149.364071ms","start":"2026-02-23T20:11:49.868274Z","end":"2026-02-23T20:11:50.017638Z","steps":["trace[1732859299] 'agreement among raft nodes before linearized reading' (duration: 149.309089ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:11:50.017874Z","caller":"traceutil/trace.go:171","msg":"trace[1967464188] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"420.635459ms","start":"2026-02-23T20:11:49.597205Z","end":"2026-02-23T20:11:50.017841Z","steps":["trace[1967464188] 'process raft request' (duration: 138.066475ms)","trace[1967464188] 'compare' (duration: 281.570989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:11:50.018001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:11:49.597186Z","time spent":"420.747172ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:11:50.223Z","caller":"traceutil/trace.go:171","msg":"trace[1562643014] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"200.520705ms","start":"2026-02-23T20:11:50.022416Z","end":"2026-02-23T20:11:50.222937Z","steps":["trace[1562643014] 'process raft request' (duration: 143.081762ms)","trace[1562643014] 'compare' (duration: 57.205317ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:11:55.806972Z","caller":"traceutil/trace.go:171","msg":"trace[303741077] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"153.235018ms","start":"2026-02-23T20:11:55.653711Z","end":"2026-02-23T20:11:55.806946Z","steps":["trace[303741077] 'process raft request' (duration: 153.077114ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:04.451784Z","caller":"traceutil/trace.go:171","msg":"trace[669257421] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"250.351287ms","start":"2026-02-23T20:12:04.201398Z","end":"2026-02-23T20:12:04.451749Z","steps":["trace[669257421] 'read index received' (duration: 249.968297ms)","trace[669257421] 'applied index is now lower than readState.Index' (duration: 381.4µs)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:12:04.452441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.017815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T20:12:04.452526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.52585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-23T20:12:04.452534Z","caller":"traceutil/trace.go:171","msg":"trace[1903563850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"251.21232ms","start":"2026-02-23T20:12:04.2013Z","end":"2026-02-23T20:12:04.452512Z","steps":["trace[1903563850] 'agreement among raft nodes before linearized reading' (duration: 251.013325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:04.452448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.663987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-23T20:12:04.453056Z","caller":"traceutil/trace.go:171","msg":"trace[1824375920] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"189.307233ms","start":"2026-02-23T20:12:04.263715Z","end":"2026-02-23T20:12:04.453023Z","steps":["trace[1824375920] 'agreement among raft nodes before linearized reading' (duration: 188.640126ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:04.452662Z","caller":"traceutil/trace.go:171","msg":"trace[1582914892] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"426.766085ms","start":"2026-02-23T20:12:04.02582Z","end":"2026-02-23T20:12:04.452586Z","steps":["trace[1582914892] 'process raft request' (duration: 425.696477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:04.453355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:04.02579Z","time spent":"427.433151ms","remote":"127.0.0.1:51230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:12:04.452884Z","caller":"traceutil/trace.go:171","msg":"trace[277362221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"163.90195ms","start":"2026-02-23T20:12:04.288965Z","end":"2026-02-23T20:12:04.452867Z","steps":["trace[277362221] 'agreement among raft nodes before linearized reading' (duration: 163.52658ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:04.749092Z","caller":"traceutil/trace.go:171","msg":"trace[703384929] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"194.408692ms","start":"2026-02-23T20:12:04.554656Z","end":"2026-02-23T20:12:04.749065Z","steps":["trace[703384929] 'read index received' (duration: 120.222394ms)","trace[703384929] 'applied index is now lower than readState.Index' (duration: 74.185078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:12:04.749226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.555196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-23T20:12:04.749302Z","caller":"traceutil/trace.go:171","msg":"trace[1837329550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:552; }","duration":"194.670588ms","start":"2026-02-23T20:12:04.554606Z","end":"2026-02-23T20:12:04.749277Z","steps":["trace[1837329550] 'agreement among raft nodes before linearized reading' (duration: 194.565036ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:04.749138Z","caller":"traceutil/trace.go:171","msg":"trace[1638107256] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"290.265198ms","start":"2026-02-23T20:12:04.458843Z","end":"2026-02-23T20:12:04.749108Z","steps":["trace[1638107256] 'process raft request' (duration: 216.091ms)","trace[1638107256] 'compare' (duration: 73.954852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:12:05.127963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.227671ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:12:05.128065Z","caller":"traceutil/trace.go:171","msg":"trace[1550703659] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"373.976458ms","start":"2026-02-23T20:12:04.754071Z","end":"2026-02-23T20:12:05.128048Z","steps":["trace[1550703659] 'process raft request' (duration: 229.583582ms)","trace[1550703659] 'compare' (duration: 144.093278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:12:05.12816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:04.754047Z","time spent":"374.084251ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:12:06.208328Z","caller":"traceutil/trace.go:171","msg":"trace[71710561] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"759.227ms","start":"2026-02-23T20:12:05.449078Z","end":"2026-02-23T20:12:06.208305Z","steps":["trace[71710561] 'process raft request' (duration: 759.081587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:06.208494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:05.449067Z","time spent":"759.336384ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:12:06.819701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.122081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:12:06.820131Z","caller":"traceutil/trace.go:171","msg":"trace[1595886568] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:571; }","duration":"619.554073ms","start":"2026-02-23T20:12:06.200521Z","end":"2026-02-23T20:12:06.820075Z","steps":["trace[1595886568] 'read index received' (duration: 7.623302ms)","trace[1595886568] 'applied index is now lower than readState.Index' (duration: 611.929291ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:12:06.820168Z","caller":"traceutil/trace.go:171","msg":"trace[311567305] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.370931276s","start":"2026-02-23T20:12:05.449199Z","end":"2026-02-23T20:12:06.82013Z","steps":["trace[311567305] 'process raft request' (duration: 883.252021ms)","trace[311567305] 'compare' (duration: 486.764011ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:12:06.820218Z","caller":"traceutil/trace.go:171","msg":"trace[1785581562] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"629.360372ms","start":"2026-02-23T20:12:06.190826Z","end":"2026-02-23T20:12:06.820186Z","steps":["trace[1785581562] 'process raft request' (duration: 629.116886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:06.820284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.760518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:12:06.820324Z","caller":"traceutil/trace.go:171","msg":"trace[695924166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"619.850141ms","start":"2026-02-23T20:12:06.200461Z","end":"2026-02-23T20:12:06.820311Z","steps":["trace[695924166] 'agreement among raft nodes before linearized reading' (duration: 619.755789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:06.820336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:06.190799Z","time spent":"629.458965ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:12:06.820335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:05.449193Z","time spent":"1.371052819s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:12:06.820361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:06.200445Z","time spent":"619.908872ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-23T20:12:06.820419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.9749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-23T20:12:06.820493Z","caller":"traceutil/trace.go:171","msg":"trace[2034298799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"335.114513ms","start":"2026-02-23T20:12:06.485357Z","end":"2026-02-23T20:12:06.820472Z","steps":["trace[2034298799] 'agreement among raft nodes before linearized reading' (duration: 334.939179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:06.820546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:06.485325Z","time spent":"335.212816ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-23T20:12:16.643005Z","caller":"traceutil/trace.go:171","msg":"trace[538515886] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"138.795223ms","start":"2026-02-23T20:12:16.504186Z","end":"2026-02-23T20:12:16.642981Z","steps":["trace[538515886] 'process raft request' (duration: 138.727902ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:16.643509Z","caller":"traceutil/trace.go:171","msg":"trace[1043421408] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"143.83006ms","start":"2026-02-23T20:12:16.499669Z","end":"2026-02-23T20:12:16.643499Z","steps":["trace[1043421408] 'process raft request' (duration: 142.984899ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:16.643654Z","caller":"traceutil/trace.go:171","msg":"trace[224136148] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"143.469392ms","start":"2026-02-23T20:12:16.500177Z","end":"2026-02-23T20:12:16.643646Z","steps":["trace[224136148] 'process raft request' (duration: 142.611391ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:16.6439Z","caller":"traceutil/trace.go:171","msg":"trace[747490236] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"142.028015ms","start":"2026-02-23T20:12:16.501863Z","end":"2026-02-23T20:12:16.643891Z","steps":["trace[747490236] 'process raft request' (duration: 141.002709ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:16.841543Z","caller":"traceutil/trace.go:171","msg":"trace[1498874133] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:640; }","duration":"308.321415ms","start":"2026-02-23T20:12:16.5332Z","end":"2026-02-23T20:12:16.841522Z","steps":["trace[1498874133] 'read index received' (duration: 109.501691ms)","trace[1498874133] 'applied index is now lower than readState.Index' (duration: 198.818434ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:12:16.841656Z","caller":"traceutil/trace.go:171","msg":"trace[513091408] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"308.484079ms","start":"2026-02-23T20:12:16.533162Z","end":"2026-02-23T20:12:16.841646Z","steps":["trace[513091408] 'process raft request' (duration: 304.57201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:16.841741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:16.533141Z","time spent":"308.538851ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T20:12:16.842071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.845137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-j2gtg\" ","response":"range_response_count:1 size:3849"} {"level":"info","ts":"2026-02-23T20:12:16.842137Z","caller":"traceutil/trace.go:171","msg":"trace[1403258545] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-j2gtg; range_end:; response_count:1; response_revision:624; }","duration":"308.95588ms","start":"2026-02-23T20:12:16.533164Z","end":"2026-02-23T20:12:16.84212Z","steps":["trace[1403258545] 'agreement among raft nodes before linearized reading' (duration: 308.827147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:16.842161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:16.533151Z","time spent":"309.004712ms","remote":"127.0.0.1:51230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3872,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-j2gtg\" "} {"level":"info","ts":"2026-02-23T20:12:22.585364Z","caller":"traceutil/trace.go:171","msg":"trace[941281408] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"301.297757ms","start":"2026-02-23T20:12:22.284036Z","end":"2026-02-23T20:12:22.585334Z","steps":["trace[941281408] 'process raft request' (duration: 301.103582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:22.585575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:22.284005Z","time spent":"301.458612ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:12:23.424575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.426531ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-23T20:12:23.424707Z","caller":"traceutil/trace.go:171","msg":"trace[688146654] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"524.680876ms","start":"2026-02-23T20:12:22.900005Z","end":"2026-02-23T20:12:23.424686Z","steps":["trace[688146654] 'read index received' (duration: 56.0679ms)","trace[688146654] 'applied index is now lower than readState.Index' (duration: 468.611196ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:12:23.424847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.82649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-23T20:12:23.424881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.408199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-23T20:12:23.424917Z","caller":"traceutil/trace.go:171","msg":"trace[417527391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:693; }","duration":"520.48548ms","start":"2026-02-23T20:12:22.904418Z","end":"2026-02-23T20:12:23.424903Z","steps":["trace[417527391] 'agreement among raft nodes before linearized reading' (duration: 520.379128ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:23.424915Z","caller":"traceutil/trace.go:171","msg":"trace[1603296378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"524.921462ms","start":"2026-02-23T20:12:22.899974Z","end":"2026-02-23T20:12:23.424895Z","steps":["trace[1603296378] 'agreement among raft nodes before linearized reading' (duration: 524.794819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:23.424952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:22.899949Z","time spent":"524.996864ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-23T20:12:23.424957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:22.904392Z","time spent":"520.555733ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-23T20:12:23.424946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.454666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T20:12:23.424985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.15975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:12:23.425058Z","caller":"traceutil/trace.go:171","msg":"trace[988677622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"224.61136ms","start":"2026-02-23T20:12:23.200416Z","end":"2026-02-23T20:12:23.425027Z","steps":["trace[988677622] 'agreement among raft nodes before linearized reading' (duration: 224.432365ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:23.425079Z","caller":"traceutil/trace.go:171","msg":"trace[572871416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"469.273462ms","start":"2026-02-23T20:12:22.955781Z","end":"2026-02-23T20:12:23.425054Z","steps":["trace[572871416] 'agreement among raft nodes before linearized reading' (duration: 469.120418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:23.425107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.671648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-23T20:12:23.425131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:22.955774Z","time spent":"469.347644ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-23T20:12:23.42516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.829323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-23T20:12:23.425172Z","caller":"traceutil/trace.go:171","msg":"trace[908255514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"511.759061ms","start":"2026-02-23T20:12:22.913395Z","end":"2026-02-23T20:12:23.425154Z","steps":["trace[908255514] 'agreement among raft nodes before linearized reading' (duration: 511.539315ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:23.425184Z","caller":"traceutil/trace.go:171","msg":"trace[926002764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:693; }","duration":"177.870293ms","start":"2026-02-23T20:12:23.247307Z","end":"2026-02-23T20:12:23.425177Z","steps":["trace[926002764] 'agreement among raft nodes before linearized reading' (duration: 177.777441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:23.425215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:22.913381Z","time spent":"511.824432ms","remote":"127.0.0.1:51322","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-02-23T20:12:27.178598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644642039576039,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-23T20:12:27.46345Z","caller":"traceutil/trace.go:171","msg":"trace[585719480] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"844.837849ms","start":"2026-02-23T20:12:26.61859Z","end":"2026-02-23T20:12:27.463428Z","steps":["trace[585719480] 'process raft request' (duration: 844.693645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:27.463583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:26.618562Z","time spent":"844.945951ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:12:27.493166Z","caller":"traceutil/trace.go:171","msg":"trace[2133669358] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:725; }","duration":"815.413308ms","start":"2026-02-23T20:12:26.677725Z","end":"2026-02-23T20:12:27.493138Z","steps":["trace[2133669358] 'read index received' (duration: 785.640359ms)","trace[2133669358] 'applied index is now lower than readState.Index' (duration: 29.771769ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:12:27.493278Z","caller":"traceutil/trace.go:171","msg":"trace[1003353161] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"376.990521ms","start":"2026-02-23T20:12:27.116248Z","end":"2026-02-23T20:12:27.493238Z","steps":["trace[1003353161] 'process raft request' (duration: 376.806017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:27.493455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.684685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-23T20:12:27.493477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:27.116215Z","time spent":"377.150555ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:12:27.493477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.574619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:12:27.493539Z","caller":"traceutil/trace.go:171","msg":"trace[132759214] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:705; }","duration":"719.682711ms","start":"2026-02-23T20:12:26.773835Z","end":"2026-02-23T20:12:27.493518Z","steps":["trace[132759214] 'agreement among raft nodes before linearized reading' (duration: 719.588158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:27.493576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:26.773807Z","time spent":"719.759303ms","remote":"127.0.0.1:51372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-23T20:12:27.493515Z","caller":"traceutil/trace.go:171","msg":"trace[1713705103] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:705; }","duration":"815.788428ms","start":"2026-02-23T20:12:26.677698Z","end":"2026-02-23T20:12:27.493486Z","steps":["trace[1713705103] 'agreement among raft nodes before linearized reading' (duration: 815.668524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:27.493671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:26.677685Z","time spent":"815.973543ms","remote":"127.0.0.1:51320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-23T20:12:27.493926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.012866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-23T20:12:27.49398Z","caller":"traceutil/trace.go:171","msg":"trace[1730383465] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:705; }","duration":"667.109738ms","start":"2026-02-23T20:12:26.82686Z","end":"2026-02-23T20:12:27.49397Z","steps":["trace[1730383465] 'agreement among raft nodes before linearized reading' (duration: 667.035176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:27.494009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:26.826835Z","time spent":"667.16598ms","remote":"127.0.0.1:51552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-23T20:12:27.493958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.939258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:12:27.494082Z","caller":"traceutil/trace.go:171","msg":"trace[74114055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"293.135212ms","start":"2026-02-23T20:12:27.200919Z","end":"2026-02-23T20:12:27.494054Z","steps":["trace[74114055] 'agreement among raft nodes before linearized reading' (duration: 292.913477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:27.49409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.529536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.110\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-23T20:12:27.494136Z","caller":"traceutil/trace.go:171","msg":"trace[656243554] range","detail":"{range_begin:/registry/masterleases/162.253.55.110; range_end:; response_count:1; response_revision:705; }","duration":"214.628758ms","start":"2026-02-23T20:12:27.279494Z","end":"2026-02-23T20:12:27.494123Z","steps":["trace[656243554] 'agreement among raft nodes before linearized reading' (duration: 214.454414ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:12:27.494298Z","caller":"traceutil/trace.go:171","msg":"trace[760231143] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"524.82443ms","start":"2026-02-23T20:12:26.968273Z","end":"2026-02-23T20:12:27.493098Z","steps":["trace[760231143] 'process raft request' (duration: 524.544883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:12:27.494386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:12:26.968241Z","time spent":"526.089682ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-23T20:12:57.959396Z","caller":"traceutil/trace.go:171","msg":"trace[1557324350] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"132.186155ms","start":"2026-02-23T20:12:57.827161Z","end":"2026-02-23T20:12:57.959348Z","steps":["trace[1557324350] 'process raft request' (duration: 45.122855ms)","trace[1557324350] 'compare' (duration: 86.786163ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:13:01.936255Z","caller":"traceutil/trace.go:171","msg":"trace[1899375959] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"181.830982ms","start":"2026-02-23T20:13:01.754381Z","end":"2026-02-23T20:13:01.936212Z","steps":["trace[1899375959] 'process raft request' (duration: 160.148558ms)","trace[1899375959] 'compare' (duration: 21.326295ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:13:04.452305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644642039576439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T20:13:04.9531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":708644642039576439,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T20:13:05.097661Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.394089543s","expected-duration":"1s"} {"level":"info","ts":"2026-02-23T20:13:05.098113Z","caller":"traceutil/trace.go:171","msg":"trace[1377588268] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"1.146422803s","start":"2026-02-23T20:13:03.951665Z","end":"2026-02-23T20:13:05.098087Z","steps":["trace[1377588268] 'read index received' (duration: 1.146208048s)","trace[1377588268] 'applied index is now lower than readState.Index' (duration: 214.005µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:13:05.098476Z","caller":"traceutil/trace.go:171","msg":"trace[1959986246] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"1.395003916s","start":"2026-02-23T20:13:03.703465Z","end":"2026-02-23T20:13:05.098469Z","steps":["trace[1959986246] 'process raft request' (duration: 1.394476183s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:05.09858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:03.703445Z","time spent":"1.395056538s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:13:05.098825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147167212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-23T20:13:05.098852Z","caller":"traceutil/trace.go:171","msg":"trace[452732932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:864; }","duration":"1.147233314s","start":"2026-02-23T20:13:03.951611Z","end":"2026-02-23T20:13:05.098845Z","steps":["trace[452732932] 'agreement among raft nodes before linearized reading' (duration: 1.147130141s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:05.098871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:03.951581Z","time spent":"1.147283885s","remote":"127.0.0.1:51322","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-02-23T20:13:05.099007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146590308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-23T20:13:05.099031Z","caller":"traceutil/trace.go:171","msg":"trace[228177820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"1.146644939s","start":"2026-02-23T20:13:03.952378Z","end":"2026-02-23T20:13:05.099023Z","steps":["trace[228177820] 'agreement among raft nodes before linearized reading' (duration: 1.146582488s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:05.099045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:03.952355Z","time spent":"1.14668758s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-23T20:13:05.099283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1196087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-23T20:13:05.099265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.352001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:13:05.099314Z","caller":"traceutil/trace.go:171","msg":"trace[127362358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:864; }","duration":"1.119673551s","start":"2026-02-23T20:13:03.979633Z","end":"2026-02-23T20:13:05.099307Z","steps":["trace[127362358] 'agreement among raft nodes before linearized reading' (duration: 1.11959877s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:05.099345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:03.979611Z","time spent":"1.119731542s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-23T20:13:05.099368Z","caller":"traceutil/trace.go:171","msg":"trace[266942736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"897.493344ms","start":"2026-02-23T20:13:04.201845Z","end":"2026-02-23T20:13:05.099338Z","steps":["trace[266942736] 'agreement among raft nodes before linearized reading' (duration: 897.32115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:05.099424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:04.201823Z","time spent":"897.592426ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-23T20:13:05.099669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10690985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-23T20:13:05.099863Z","caller":"traceutil/trace.go:171","msg":"trace[2025514345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:864; }","duration":"1.107143566s","start":"2026-02-23T20:13:03.992667Z","end":"2026-02-23T20:13:05.09981Z","steps":["trace[2025514345] 'agreement among raft nodes before linearized reading' (duration: 1.106313134s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:05.099991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:03.992652Z","time spent":"1.10732283s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-23T20:13:05.593351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.897693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:13:05.593506Z","caller":"traceutil/trace.go:171","msg":"trace[1504316692] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"489.638275ms","start":"2026-02-23T20:13:05.103826Z","end":"2026-02-23T20:13:05.593464Z","steps":["trace[1504316692] 'process raft request' (duration: 253.511496ms)","trace[1504316692] 'compare' (duration: 235.735729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:13:05.593584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:05.103814Z","time spent":"489.737688ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:13:05.977492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.724642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:13:05.97772Z","caller":"traceutil/trace.go:171","msg":"trace[2035187809] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"871.990122ms","start":"2026-02-23T20:13:05.105708Z","end":"2026-02-23T20:13:05.977698Z","steps":["trace[2035187809] 'process raft request' (duration: 871.93848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:05.977864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:05.105699Z","time spent":"872.082974ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:13:05.97789Z","caller":"traceutil/trace.go:171","msg":"trace[2070349102] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"873.857039ms","start":"2026-02-23T20:13:05.10394Z","end":"2026-02-23T20:13:05.977797Z","steps":["trace[2070349102] 'process raft request' (duration: 619.737287ms)","trace[2070349102] 'compare' (duration: 253.460976ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:13:05.977928Z","caller":"traceutil/trace.go:171","msg":"trace[538569900] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"872.960865ms","start":"2026-02-23T20:13:05.104928Z","end":"2026-02-23T20:13:05.977889Z","steps":["trace[538569900] 'process raft request' (duration: 872.665968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:05.978119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:05.103907Z","time spent":"874.094694ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:13:05.978164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:05.104907Z","time spent":"873.150631ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:13:08.753347Z","caller":"traceutil/trace.go:171","msg":"trace[1341473153] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"256.942212ms","start":"2026-02-23T20:13:08.49637Z","end":"2026-02-23T20:13:08.753312Z","steps":["trace[1341473153] 'process raft request' (duration: 256.576893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:22.000448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.612222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-23T20:13:22.000555Z","caller":"traceutil/trace.go:171","msg":"trace[811802709] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"316.783448ms","start":"2026-02-23T20:13:21.683748Z","end":"2026-02-23T20:13:22.000531Z","steps":["trace[811802709] 'range keys from in-memory index tree' (duration: 316.410838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:22.000609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:21.68372Z","time spent":"316.878259ms","remote":"127.0.0.1:51322","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-02-23T20:13:22.000789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.854255ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:13:22.000985Z","caller":"traceutil/trace.go:171","msg":"trace[66149068] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:918; }","duration":"157.07247ms","start":"2026-02-23T20:13:21.843885Z","end":"2026-02-23T20:13:22.000958Z","steps":["trace[66149068] 'range keys from in-memory index tree' (duration: 156.830264ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:22.316918Z","caller":"traceutil/trace.go:171","msg":"trace[1342171364] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"310.265624ms","start":"2026-02-23T20:13:22.006636Z","end":"2026-02-23T20:13:22.316902Z","steps":["trace[1342171364] 'process raft request' (duration: 309.944216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:22.317095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:22.006604Z","time spent":"310.389467ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:13:22.317182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.055802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-23T20:13:22.317375Z","caller":"traceutil/trace.go:171","msg":"trace[546060782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"225.230066ms","start":"2026-02-23T20:13:22.092032Z","end":"2026-02-23T20:13:22.317262Z","steps":["trace[546060782] 'agreement among raft nodes before linearized reading' (duration: 224.920948ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:22.31684Z","caller":"traceutil/trace.go:171","msg":"trace[1325163092] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"222.146718ms","start":"2026-02-23T20:13:22.094664Z","end":"2026-02-23T20:13:22.31681Z","steps":["trace[1325163092] 'read index received' (duration: 221.948263ms)","trace[1325163092] 'applied index is now lower than readState.Index' (duration: 196.885µs)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:13:22.364018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.136367ms","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-02-23T20:13:22.364039Z","caller":"traceutil/trace.go:171","msg":"trace[881244492] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"161.803449ms","start":"2026-02-23T20:13:22.202192Z","end":"2026-02-23T20:13:22.363996Z","steps":["trace[881244492] 'process raft request' (duration: 161.516062ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:22.364118Z","caller":"traceutil/trace.go:171","msg":"trace[842434295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"263.248152ms","start":"2026-02-23T20:13:22.100823Z","end":"2026-02-23T20:13:22.364071Z","steps":["trace[842434295] 'agreement among raft nodes before linearized reading' (duration: 263.014035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:22.364285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.952753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-23T20:13:22.364367Z","caller":"traceutil/trace.go:171","msg":"trace[45808651] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:920; }","duration":"253.076796ms","start":"2026-02-23T20:13:22.111271Z","end":"2026-02-23T20:13:22.364348Z","steps":["trace[45808651] 'agreement among raft nodes before linearized reading' (duration: 252.721867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:22.364555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.174565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:13:22.364608Z","caller":"traceutil/trace.go:171","msg":"trace[967112078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"163.250956ms","start":"2026-02-23T20:13:22.201343Z","end":"2026-02-23T20:13:22.364594Z","steps":["trace[967112078] 'agreement among raft nodes before linearized reading' (duration: 162.581399ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:22.535332Z","caller":"traceutil/trace.go:171","msg":"trace[645721095] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"168.934219ms","start":"2026-02-23T20:13:22.366371Z","end":"2026-02-23T20:13:22.535305Z","steps":["trace[645721095] 'read index received' (duration: 168.726484ms)","trace[645721095] 'applied index is now lower than readState.Index' (duration: 206.715µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:13:22.535427Z","caller":"traceutil/trace.go:171","msg":"trace[1286858033] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"214.425923ms","start":"2026-02-23T20:13:22.320947Z","end":"2026-02-23T20:13:22.535373Z","steps":["trace[1286858033] 'process raft request' (duration: 214.208338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:22.535469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.082873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:13:22.535525Z","caller":"traceutil/trace.go:171","msg":"trace[1318943137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"169.193356ms","start":"2026-02-23T20:13:22.366318Z","end":"2026-02-23T20:13:22.535512Z","steps":["trace[1318943137] 'agreement among raft nodes before linearized reading' (duration: 169.080633ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:22.550499Z","caller":"traceutil/trace.go:171","msg":"trace[1599383468] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"181.71805ms","start":"2026-02-23T20:13:22.368759Z","end":"2026-02-23T20:13:22.550477Z","steps":["trace[1599383468] 'process raft request' (duration: 181.533935ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:22.551014Z","caller":"traceutil/trace.go:171","msg":"trace[1759322929] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"182.153581ms","start":"2026-02-23T20:13:22.368835Z","end":"2026-02-23T20:13:22.550988Z","steps":["trace[1759322929] 'process raft request' (duration: 181.580936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:22.686798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.084133ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-23T20:13:23.512426Z","caller":"traceutil/trace.go:171","msg":"trace[521551084] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"318.231813ms","start":"2026-02-23T20:13:23.194166Z","end":"2026-02-23T20:13:23.512397Z","steps":["trace[521551084] 'read index received' (duration: 222.630729ms)","trace[521551084] 'applied index is now lower than readState.Index' (duration: 95.599024ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:13:23.512562Z","caller":"traceutil/trace.go:171","msg":"trace[871116417] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"385.468414ms","start":"2026-02-23T20:13:23.127057Z","end":"2026-02-23T20:13:23.512526Z","steps":["trace[871116417] 'process raft request' (duration: 289.774018ms)","trace[871116417] 'compare' (duration: 95.124792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:13:23.512683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:23.12704Z","time spent":"385.580037ms","remote":"127.0.0.1:51134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-23T20:13:23.512943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.759607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-23T20:13:23.512979Z","caller":"traceutil/trace.go:171","msg":"trace[1934905287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"318.847039ms","start":"2026-02-23T20:13:23.194124Z","end":"2026-02-23T20:13:23.512971Z","steps":["trace[1934905287] 'agreement among raft nodes before linearized reading' (duration: 318.360346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:23.513008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:23.194108Z","time spent":"318.892361ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-23T20:13:24.096666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.932181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:13:24.096794Z","caller":"traceutil/trace.go:171","msg":"trace[2129539687] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:964; }","duration":"525.218569ms","start":"2026-02-23T20:13:23.571555Z","end":"2026-02-23T20:13:24.096774Z","steps":["trace[2129539687] 'read index received' (duration: 275.911349ms)","trace[2129539687] 'applied index is now lower than readState.Index' (duration: 249.30552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:13:24.09693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.380813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:13:24.096883Z","caller":"traceutil/trace.go:171","msg":"trace[1497940182] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"579.588787ms","start":"2026-02-23T20:13:23.51722Z","end":"2026-02-23T20:13:24.096808Z","steps":["trace[1497940182] 'process raft request' (duration: 330.389819ms)","trace[1497940182] 'compare' (duration: 248.588923ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:13:24.096961Z","caller":"traceutil/trace.go:171","msg":"trace[1118058696] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:927; }","duration":"525.493826ms","start":"2026-02-23T20:13:23.571457Z","end":"2026-02-23T20:13:24.096951Z","steps":["trace[1118058696] 'agreement among raft nodes before linearized reading' (duration: 525.418974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:24.097012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:23.571432Z","time spent":"525.570569ms","remote":"127.0.0.1:51304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-23T20:13:24.097048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.408341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T20:13:24.097147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:23.517198Z","time spent":"579.811033ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:13:24.097164Z","caller":"traceutil/trace.go:171","msg":"trace[99689715] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:927; }","duration":"332.586915ms","start":"2026-02-23T20:13:23.76454Z","end":"2026-02-23T20:13:24.097127Z","steps":["trace[99689715] 'agreement among raft nodes before linearized reading' (duration: 332.3977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:24.097222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:23.764515Z","time spent":"332.696108ms","remote":"127.0.0.1:51148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-23T20:13:24.6048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.519903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:13:24.60492Z","caller":"traceutil/trace.go:171","msg":"trace[353745036] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"403.027976ms","start":"2026-02-23T20:13:24.20187Z","end":"2026-02-23T20:13:24.604898Z","steps":["trace[353745036] 'read index received' (duration: 98.525618ms)","trace[353745036] 'applied index is now lower than readState.Index' (duration: 304.500258ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:13:24.60502Z","caller":"traceutil/trace.go:171","msg":"trace[135156963] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"500.087997ms","start":"2026-02-23T20:13:24.104919Z","end":"2026-02-23T20:13:24.605007Z","steps":["trace[135156963] 'process raft request' (duration: 195.570519ms)","trace[135156963] 'compare' (duration: 303.38452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:13:24.605117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:24.104887Z","time spent":"500.16739ms","remote":"127.0.0.1:51228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4868,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:13:24.605391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.529199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:13:24.605445Z","caller":"traceutil/trace.go:171","msg":"trace[1901683668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"403.630432ms","start":"2026-02-23T20:13:24.201797Z","end":"2026-02-23T20:13:24.605427Z","steps":["trace[1901683668] 'agreement among raft nodes before linearized reading' (duration: 403.55259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:24.605479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:24.201758Z","time spent":"403.713395ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-23T20:13:24.606108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.054829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-23T20:13:24.606159Z","caller":"traceutil/trace.go:171","msg":"trace[729509996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"285.147592ms","start":"2026-02-23T20:13:24.320997Z","end":"2026-02-23T20:13:24.606144Z","steps":["trace[729509996] 'agreement among raft nodes before linearized reading' (duration: 284.194698ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:26.243156Z","caller":"traceutil/trace.go:171","msg":"trace[519096769] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"138.069072ms","start":"2026-02-23T20:13:26.10505Z","end":"2026-02-23T20:13:26.243119Z","steps":["trace[519096769] 'process raft request' (duration: 137.230071ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:27.526895Z","caller":"traceutil/trace.go:171","msg":"trace[2037418545] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"101.810458ms","start":"2026-02-23T20:13:27.425061Z","end":"2026-02-23T20:13:27.526872Z","steps":["trace[2037418545] 'process raft request' (duration: 101.648354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:35.912042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160764092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-23T20:13:35.912081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16882949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-23T20:13:35.912158Z","caller":"traceutil/trace.go:171","msg":"trace[2054342217] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:986; }","duration":"1.160945437s","start":"2026-02-23T20:13:34.751191Z","end":"2026-02-23T20:13:35.912136Z","steps":["trace[2054342217] 'range keys from in-memory index tree' (duration: 1.160587368s)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:35.912199Z","caller":"traceutil/trace.go:171","msg":"trace[408289833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"1.169003785s","start":"2026-02-23T20:13:34.743169Z","end":"2026-02-23T20:13:35.912173Z","steps":["trace[408289833] 'range keys from in-memory index tree' (duration: 1.168599685s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:35.91221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:34.751172Z","time spent":"1.161024829s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-23T20:13:35.912209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.357565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-02-23T20:13:35.912249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:34.743145Z","time spent":"1.169094497s","remote":"127.0.0.1:51322","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-02-23T20:13:35.912299Z","caller":"traceutil/trace.go:171","msg":"trace[1807053907] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:986; }","duration":"484.500848ms","start":"2026-02-23T20:13:35.427775Z","end":"2026-02-23T20:13:35.912275Z","steps":["trace[1807053907] 'range keys from in-memory index tree' (duration: 484.147919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:35.91234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:35.427745Z","time spent":"484.58688ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-23T20:13:35.912425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.992769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T20:13:35.912485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181929541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-23T20:13:35.912598Z","caller":"traceutil/trace.go:171","msg":"trace[2127515189] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:986; }","duration":"1.182082954s","start":"2026-02-23T20:13:34.730499Z","end":"2026-02-23T20:13:35.912581Z","steps":["trace[2127515189] 'range keys from in-memory index tree' (duration: 1.181754657s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:35.91263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:34.73041Z","time spent":"1.182212137s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-23T20:13:35.912615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.096979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T20:13:35.912631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180168178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-23T20:13:35.912667Z","caller":"traceutil/trace.go:171","msg":"trace[1267764587] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:986; }","duration":"542.176414ms","start":"2026-02-23T20:13:35.370335Z","end":"2026-02-23T20:13:35.912511Z","steps":["trace[1267764587] 'count revisions from in-memory index tree' (duration: 541.885107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:35.912738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:35.370311Z","time spent":"542.41353ms","remote":"127.0.0.1:40418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-23T20:13:35.912736Z","caller":"traceutil/trace.go:171","msg":"trace[335673469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"711.246422ms","start":"2026-02-23T20:13:35.201449Z","end":"2026-02-23T20:13:35.912695Z","steps":["trace[335673469] 'range keys from in-memory index tree' (duration: 711.003387ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:35.912736Z","caller":"traceutil/trace.go:171","msg":"trace[553632245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"1.180322162s","start":"2026-02-23T20:13:34.732389Z","end":"2026-02-23T20:13:35.912711Z","steps":["trace[553632245] 'range keys from in-memory index tree' (duration: 1.179976513s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:35.912796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:35.20142Z","time spent":"711.361795ms","remote":"127.0.0.1:51076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-23T20:13:35.912815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:34.732364Z","time spent":"1.180441804s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-23T20:13:36.583953Z","caller":"traceutil/trace.go:171","msg":"trace[428623038] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"665.07991ms","start":"2026-02-23T20:13:35.918845Z","end":"2026-02-23T20:13:36.583925Z","steps":["trace[428623038] 'process raft request' (duration: 664.879144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:36.584168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:35.918805Z","time spent":"665.227993ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:13:36.726192Z","caller":"traceutil/trace.go:171","msg":"trace[700889963] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"806.922909ms","start":"2026-02-23T20:13:35.91917Z","end":"2026-02-23T20:13:36.726093Z","steps":["trace[700889963] 'process raft request' (duration: 806.686324ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:13:36.72631Z","caller":"traceutil/trace.go:171","msg":"trace[1123680008] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"806.463379ms","start":"2026-02-23T20:13:35.919812Z","end":"2026-02-23T20:13:36.726275Z","steps":["trace[1123680008] 'process raft request' (duration: 806.231723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:36.72637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:35.919152Z","time spent":"807.121205ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:13:36.726125Z","caller":"traceutil/trace.go:171","msg":"trace[293909874] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1026; }","duration":"524.609602ms","start":"2026-02-23T20:13:36.201493Z","end":"2026-02-23T20:13:36.726103Z","steps":["trace[293909874] 'read index received' (duration: 382.279149ms)","trace[293909874] 'applied index is now lower than readState.Index' (duration: 142.329313ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:13:36.726464Z","caller":"traceutil/trace.go:171","msg":"trace[516440005] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"806.54924ms","start":"2026-02-23T20:13:35.919905Z","end":"2026-02-23T20:13:36.726454Z","steps":["trace[516440005] 'process raft request' (duration: 806.165791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:36.726484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:35.919792Z","time spent":"806.593561ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:13:36.726569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:35.919898Z","time spent":"806.587742ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:13:36.726639Z","caller":"traceutil/trace.go:171","msg":"trace[657830770] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"807.056832ms","start":"2026-02-23T20:13:35.919571Z","end":"2026-02-23T20:13:36.726628Z","steps":["trace[657830770] 'process raft request' (duration: 806.430947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:36.726692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:35.919555Z","time spent":"807.105524ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:13:36.726903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.420443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:13:36.726934Z","caller":"traceutil/trace.go:171","msg":"trace[115531989] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"525.501614ms","start":"2026-02-23T20:13:36.201421Z","end":"2026-02-23T20:13:36.726923Z","steps":["trace[115531989] 'agreement among raft nodes before linearized reading' (duration: 525.271458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:36.726989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:36.201386Z","time spent":"525.596657ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-23T20:13:36.727105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.894145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-23T20:13:36.727149Z","caller":"traceutil/trace.go:171","msg":"trace[1340670841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"395.954377ms","start":"2026-02-23T20:13:36.331184Z","end":"2026-02-23T20:13:36.727138Z","steps":["trace[1340670841] 'agreement among raft nodes before linearized reading' (duration: 395.779782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:13:36.727191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:13:36.331103Z","time spent":"396.08037ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-23T20:13:42.072343Z","caller":"traceutil/trace.go:171","msg":"trace[767095565] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"117.047492ms","start":"2026-02-23T20:13:41.955267Z","end":"2026-02-23T20:13:42.072314Z","steps":["trace[767095565] 'process raft request' (duration: 116.885438ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:14:53.362884Z","caller":"traceutil/trace.go:171","msg":"trace[1233110331] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"144.716145ms","start":"2026-02-23T20:14:53.218139Z","end":"2026-02-23T20:14:53.362855Z","steps":["trace[1233110331] 'process raft request' (duration: 144.408279ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:15:17.906152Z","caller":"traceutil/trace.go:171","msg":"trace[1653263249] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"103.453509ms","start":"2026-02-23T20:15:17.802662Z","end":"2026-02-23T20:15:17.906116Z","steps":["trace[1653263249] 'process raft request' (duration: 103.258264ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:15:19.661797Z","caller":"traceutil/trace.go:171","msg":"trace[1874410501] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"143.415675ms","start":"2026-02-23T20:15:19.518352Z","end":"2026-02-23T20:15:19.661768Z","steps":["trace[1874410501] 'process raft request' (duration: 79.187527ms)","trace[1874410501] 'compare' (duration: 64.074035ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:15:47.745275Z","caller":"traceutil/trace.go:171","msg":"trace[1333898291] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"102.568233ms","start":"2026-02-23T20:15:47.642676Z","end":"2026-02-23T20:15:47.745244Z","steps":["trace[1333898291] 'process raft request' (duration: 102.217965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:15:48.094755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.789712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:15:48.094872Z","caller":"traceutil/trace.go:171","msg":"trace[794455586] linearizableReadLoop","detail":"{readStateIndex:1612; appliedIndex:1611; }","duration":"222.126476ms","start":"2026-02-23T20:15:47.872728Z","end":"2026-02-23T20:15:48.094854Z","steps":["trace[794455586] 'read index received' (duration: 43.809749ms)","trace[794455586] 'applied index is now lower than readState.Index' (duration: 178.315527ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:15:48.094907Z","caller":"traceutil/trace.go:171","msg":"trace[1066032137] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"234.12615ms","start":"2026-02-23T20:15:47.86075Z","end":"2026-02-23T20:15:48.094876Z","steps":["trace[1066032137] 'process raft request' (duration: 64.767403ms)","trace[1066032137] 'compare' (duration: 168.621448ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:15:48.09498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.579537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:15:48.095022Z","caller":"traceutil/trace.go:171","msg":"trace[1794586763] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1545; }","duration":"118.6762ms","start":"2026-02-23T20:15:47.976334Z","end":"2026-02-23T20:15:48.09501Z","steps":["trace[1794586763] 'agreement among raft nodes before linearized reading' (duration: 118.612219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:15:48.0953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.566706ms","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-02-23T20:15:48.095337Z","caller":"traceutil/trace.go:171","msg":"trace[1109043616] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1545; }","duration":"222.667079ms","start":"2026-02-23T20:15:47.872662Z","end":"2026-02-23T20:15:48.095329Z","steps":["trace[1109043616] 'agreement among raft nodes before linearized reading' (duration: 222.27191ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:16:04.407715Z","caller":"traceutil/trace.go:171","msg":"trace[310185165] transaction","detail":"{read_only:false; response_revision:1612; number_of_response:1; }","duration":"123.423526ms","start":"2026-02-23T20:16:04.284262Z","end":"2026-02-23T20:16:04.407685Z","steps":["trace[310185165] 'process raft request' (duration: 123.280953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:16:10.183392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.890963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-23T20:16:10.183511Z","caller":"traceutil/trace.go:171","msg":"trace[1593038775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1632; }","duration":"112.072368ms","start":"2026-02-23T20:16:10.071412Z","end":"2026-02-23T20:16:10.183485Z","steps":["trace[1593038775] 'range keys from in-memory index tree' (duration: 111.612635ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:16:10.673771Z","caller":"traceutil/trace.go:171","msg":"trace[1645983170] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"154.257455ms","start":"2026-02-23T20:16:10.51948Z","end":"2026-02-23T20:16:10.673737Z","steps":["trace[1645983170] 'process raft request' (duration: 118.880145ms)","trace[1645983170] 'compare' (duration: 35.152524ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:16:17.064376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.400169ms","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-02-23T20:16:17.064454Z","caller":"traceutil/trace.go:171","msg":"trace[1581088837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1659; }","duration":"367.538012ms","start":"2026-02-23T20:16:16.6969Z","end":"2026-02-23T20:16:17.064438Z","steps":["trace[1581088837] 'range keys from in-memory index tree' (duration: 367.184054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:16:17.06449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:16:16.696881Z","time spent":"367.602493ms","remote":"127.0.0.1:51322","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-02-23T20:16:21.961786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.814323ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:16:21.961872Z","caller":"traceutil/trace.go:171","msg":"trace[1194217921] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1678; }","duration":"118.918735ms","start":"2026-02-23T20:16:21.842941Z","end":"2026-02-23T20:16:21.96186Z","steps":["trace[1194217921] 'range keys from in-memory index tree' (duration: 118.801982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:16:42.880483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.352698ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:16:42.881387Z","caller":"traceutil/trace.go:171","msg":"trace[2003212403] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"467.857489ms","start":"2026-02-23T20:16:42.413488Z","end":"2026-02-23T20:16:42.881345Z","steps":["trace[2003212403] 'process raft request' (duration: 26.481026ms)","trace[2003212403] 'compare' (duration: 440.221934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:16:42.881601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:16:42.413479Z","time spent":"468.011203ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T20:16:42.881946Z","caller":"traceutil/trace.go:171","msg":"trace[1493976652] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"285.906512ms","start":"2026-02-23T20:16:42.596025Z","end":"2026-02-23T20:16:42.881932Z","steps":["trace[1493976652] 'read index received' (duration: 34.431µs)","trace[1493976652] 'applied index is now lower than readState.Index' (duration: 285.869981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:16:42.884078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.895858ms","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-02-23T20:16:42.884231Z","caller":"traceutil/trace.go:171","msg":"trace[713671673] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1786; }","duration":"195.070332ms","start":"2026-02-23T20:16:42.689141Z","end":"2026-02-23T20:16:42.884211Z","steps":["trace[713671673] 'agreement among raft nodes before linearized reading' (duration: 194.751044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:16:42.885867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.804598ms","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:527"} {"level":"info","ts":"2026-02-23T20:16:42.8861Z","caller":"traceutil/trace.go:171","msg":"trace[483077596] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1786; }","duration":"290.067954ms","start":"2026-02-23T20:16:42.595998Z","end":"2026-02-23T20:16:42.886066Z","steps":["trace[483077596] 'agreement among raft nodes before linearized reading' (duration: 286.057415ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:16:49.208807Z","caller":"traceutil/trace.go:171","msg":"trace[1322741913] linearizableReadLoop","detail":"{readStateIndex:1897; appliedIndex:1896; }","duration":"147.960754ms","start":"2026-02-23T20:16:49.060829Z","end":"2026-02-23T20:16:49.20879Z","steps":["trace[1322741913] 'read index received' (duration: 147.813431ms)","trace[1322741913] 'applied index is now lower than readState.Index' (duration: 146.333µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:16:49.208913Z","caller":"traceutil/trace.go:171","msg":"trace[301573420] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"149.957084ms","start":"2026-02-23T20:16:49.05893Z","end":"2026-02-23T20:16:49.208887Z","steps":["trace[301573420] 'process raft request' (duration: 149.733548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:16:49.2091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.237011ms","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-02-23T20:16:49.209179Z","caller":"traceutil/trace.go:171","msg":"trace[699257135] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1817; }","duration":"148.382165ms","start":"2026-02-23T20:16:49.060779Z","end":"2026-02-23T20:16:49.209161Z","steps":["trace[699257135] 'agreement among raft nodes before linearized reading' (duration: 148.124239ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:16:51.342Z","caller":"traceutil/trace.go:171","msg":"trace[416507333] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"125.499699ms","start":"2026-02-23T20:16:51.216462Z","end":"2026-02-23T20:16:51.341962Z","steps":["trace[416507333] 'process raft request' (duration: 125.072048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:16:56.03264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.000996ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:16:56.032754Z","caller":"traceutil/trace.go:171","msg":"trace[1640260725] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1927; }","duration":"580.009767ms","start":"2026-02-23T20:16:55.452726Z","end":"2026-02-23T20:16:56.032735Z","steps":["trace[1640260725] 'read index received' (duration: 450.710144ms)","trace[1640260725] 'applied index is now lower than readState.Index' (duration: 129.297793ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T20:16:56.032793Z","caller":"traceutil/trace.go:171","msg":"trace[1275584867] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"587.638636ms","start":"2026-02-23T20:16:55.445122Z","end":"2026-02-23T20:16:56.03276Z","steps":["trace[1275584867] 'process raft request' (duration: 458.425076ms)","trace[1275584867] 'compare' (duration: 128.308478ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:16:56.032946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:16:55.445103Z","time spent":"587.76434ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:16:56.032944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.215442ms","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-02-23T20:16:56.032965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.961664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-23T20:16:56.032998Z","caller":"traceutil/trace.go:171","msg":"trace[1795863440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1847; }","duration":"127.025256ms","start":"2026-02-23T20:16:55.905962Z","end":"2026-02-23T20:16:56.032987Z","steps":["trace[1795863440] 'agreement among raft nodes before linearized reading' (duration: 126.875692ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:16:56.033001Z","caller":"traceutil/trace.go:171","msg":"trace[177008009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1847; }","duration":"580.304424ms","start":"2026-02-23T20:16:55.45268Z","end":"2026-02-23T20:16:56.032984Z","steps":["trace[177008009] 'agreement among raft nodes before linearized reading' (duration: 580.117309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T20:16:56.033038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:16:55.452662Z","time spent":"580.368646ms","remote":"127.0.0.1:51322","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-02-23T20:16:56.462426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.286831ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T20:16:56.462648Z","caller":"traceutil/trace.go:171","msg":"trace[697624079] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1928; }","duration":"264.366859ms","start":"2026-02-23T20:16:56.198264Z","end":"2026-02-23T20:16:56.462631Z","steps":["trace[697624079] 'read index received' (duration: 260.655946ms)","trace[697624079] 'applied index is now lower than readState.Index' (duration: 3.709893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:16:56.462762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.508543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T20:16:56.462779Z","caller":"traceutil/trace.go:171","msg":"trace[1409107017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1849; }","duration":"264.577154ms","start":"2026-02-23T20:16:56.198195Z","end":"2026-02-23T20:16:56.462772Z","steps":["trace[1409107017] 'agreement among raft nodes before linearized reading' (duration: 264.520103ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:16:56.462756Z","caller":"traceutil/trace.go:171","msg":"trace[874969318] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"425.180972ms","start":"2026-02-23T20:16:56.03754Z","end":"2026-02-23T20:16:56.462721Z","steps":["trace[874969318] 'process raft request' (duration: 424.999698ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:16:56.462812Z","caller":"traceutil/trace.go:171","msg":"trace[1219837108] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"425.428989ms","start":"2026-02-23T20:16:56.037353Z","end":"2026-02-23T20:16:56.462782Z","steps":["trace[1219837108] 'process raft request' (duration: 157.677116ms)","trace[1219837108] 'compare' (duration: 267.084896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T20:16:56.462988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:16:56.037512Z","time spent":"425.322166ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:16:56.462998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T20:16:56.037329Z","time spent":"425.587002ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T20:16:56.462989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.226394ms","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-02-23T20:16:56.463078Z","caller":"traceutil/trace.go:171","msg":"trace[762561731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1849; }","duration":"115.380088ms","start":"2026-02-23T20:16:56.347677Z","end":"2026-02-23T20:16:56.463057Z","steps":["trace[762561731] 'agreement among raft nodes before linearized reading' (duration: 115.080951ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T20:16:56.659003Z","caller":"traceutil/trace.go:171","msg":"trace[2091529177] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"131.170249ms","start":"2026-02-23T20:16:56.527768Z","end":"2026-02-23T20:16:56.658938Z","steps":["trace[2091529177] 'process raft request' (duration: 44.490552ms)","trace[2091529177] 'compare' (duration: 86.555724ms)"],"step_count":2}