{"level":"warn","ts":"2026-02-27T18:16:10.603619Z","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-27T18:16:10.603733Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.141:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.141:2380","--initial-cluster=instance=https://199.204.45.141:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.141:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.141: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-27T18:16:10.603828Z","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-27T18:16:10.603843Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-27T18:16:10.60386Z","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-27T18:16:10.60466Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"]} {"level":"info","ts":"2026-02-27T18:16:10.604739Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.141: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-27T18:16:10.611489Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.466703ms"} {"level":"info","ts":"2026-02-27T18:16:10.622676Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9ef3b4f68930dc2","cluster-id":"b19a5957e6e65747"} {"level":"info","ts":"2026-02-27T18:16:10.622814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T18:16:10.622842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 0"} {"level":"info","ts":"2026-02-27T18:16:10.622853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9ef3b4f68930dc2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T18:16:10.622868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became follower at term 1"} {"level":"info","ts":"2026-02-27T18:16:10.622995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"warn","ts":"2026-02-27T18:16:10.628596Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T18:16:10.630826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T18:16:10.632359Z","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-27T18:16:10.634591Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9ef3b4f68930dc2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T18:16:10.635007Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9ef3b4f68930dc2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T18:16:10.635065Z","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-27T18:16:10.635431Z","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-27T18:16:10.635464Z","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-27T18:16:10.636839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 switched to configuration voters=(715856078018121154)"} {"level":"info","ts":"2026-02-27T18:16:10.637053Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","added-peer-id":"9ef3b4f68930dc2","added-peer-peer-urls":["https://199.204.45.141:2380"]} {"level":"info","ts":"2026-02-27T18:16:10.642368Z","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-27T18:16:10.64268Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9ef3b4f68930dc2","initial-advertise-peer-urls":["https://199.204.45.141:2380"],"listen-peer-urls":["https://199.204.45.141:2380"],"advertise-client-urls":["https://199.204.45.141:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.141:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T18:16:10.642715Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T18:16:10.643971Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-27T18:16:10.644036Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.141:2380"} {"level":"info","ts":"2026-02-27T18:16:11.623889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T18:16:11.623972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T18:16:11.624024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgPreVoteResp from 9ef3b4f68930dc2 at term 1"} {"level":"info","ts":"2026-02-27T18:16:11.62405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became candidate at term 2"} {"level":"info","ts":"2026-02-27T18:16:11.624067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 received MsgVoteResp from 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-27T18:16:11.624089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9ef3b4f68930dc2 became leader at term 2"} {"level":"info","ts":"2026-02-27T18:16:11.624108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9ef3b4f68930dc2 elected leader 9ef3b4f68930dc2 at term 2"} {"level":"info","ts":"2026-02-27T18:16:11.625416Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:16:11.626182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T18:16:11.626181Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9ef3b4f68930dc2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.141:2379]}","request-path":"/0/members/9ef3b4f68930dc2/attributes","cluster-id":"b19a5957e6e65747","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T18:16:11.626423Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T18:16:11.635872Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b19a5957e6e65747","local-member-id":"9ef3b4f68930dc2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:16:11.636205Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:16:11.636212Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T18:16:11.636231Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T18:16:11.636237Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T18:16:11.637333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.141:2379"} {"level":"info","ts":"2026-02-27T18:16:11.638517Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-27T18:16:14.386371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.157842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-27T18:16:14.386523Z","caller":"traceutil/trace.go:171","msg":"trace[1753198005] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"226.114767ms","start":"2026-02-27T18:16:14.160388Z","end":"2026-02-27T18:16:14.386503Z","steps":["trace[1753198005] 'process raft request' (duration: 45.28191ms)","trace[1753198005] 'compare' (duration: 179.95564ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:14.994424Z","caller":"traceutil/trace.go:171","msg":"trace[1255832057] transaction","detail":"{read_only:false; response_revision:169; number_of_response:1; }","duration":"246.832502ms","start":"2026-02-27T18:16:14.747553Z","end":"2026-02-27T18:16:14.994385Z","steps":["trace[1255832057] 'process raft request' (duration: 154.084169ms)","trace[1255832057] 'compare' (duration: 92.629483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:20.018736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.368179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-27T18:16:20.018963Z","caller":"traceutil/trace.go:171","msg":"trace[1430789287] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:293; }","duration":"170.653451ms","start":"2026-02-27T18:16:19.848286Z","end":"2026-02-27T18:16:20.01894Z","steps":["trace[1430789287] 'range keys from in-memory index tree' (duration: 170.216138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:21.516327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.329199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:21.516444Z","caller":"traceutil/trace.go:171","msg":"trace[1992505485] linearizableReadLoop","detail":"{readStateIndex:307; appliedIndex:306; }","duration":"1.452783358s","start":"2026-02-27T18:16:20.063642Z","end":"2026-02-27T18:16:21.516425Z","steps":["trace[1992505485] 'read index received' (duration: 457.197756ms)","trace[1992505485] 'applied index is now lower than readState.Index' (duration: 995.584431ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:21.516534Z","caller":"traceutil/trace.go:171","msg":"trace[1613258161] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"1.468550458s","start":"2026-02-27T18:16:20.04797Z","end":"2026-02-27T18:16:21.516521Z","steps":["trace[1613258161] 'process raft request' (duration: 472.944967ms)","trace[1613258161] 'compare' (duration: 995.167708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:21.516588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:20.047945Z","time spent":"1.468617319s","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":184,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:16:21.516755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405948116s","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-27T18:16:21.516812Z","caller":"traceutil/trace.go:171","msg":"trace[1034934124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:295; }","duration":"1.406068167s","start":"2026-02-27T18:16:20.110727Z","end":"2026-02-27T18:16:21.516796Z","steps":["trace[1034934124] 'agreement among raft nodes before linearized reading' (duration: 1.405895946s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:21.516846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:20.110697Z","time spent":"1.406141138s","remote":"127.0.0.1:39184","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-27T18:16:21.516843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45317839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T18:16:21.516919Z","caller":"traceutil/trace.go:171","msg":"trace[1158880345] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:295; }","duration":"1.45329582s","start":"2026-02-27T18:16:20.063604Z","end":"2026-02-27T18:16:21.516899Z","steps":["trace[1158880345] 'agreement among raft nodes before linearized reading' (duration: 1.453122119s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:21.516943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.380802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T18:16:21.516956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:20.06359Z","time spent":"1.453358831s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T18:16:21.516984Z","caller":"traceutil/trace.go:171","msg":"trace[1827309376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:295; }","duration":"930.467133ms","start":"2026-02-27T18:16:20.586504Z","end":"2026-02-27T18:16:21.516972Z","steps":["trace[1827309376] 'agreement among raft nodes before linearized reading' (duration: 930.396892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:21.517019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:20.586479Z","time spent":"930.532683ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T18:16:22.036657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527080337899984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:16:22.537469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527080337899984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:16:22.989876Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.468225193s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T18:16:23.032056Z","caller":"traceutil/trace.go:171","msg":"trace[1175491710] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"1.511282037s","start":"2026-02-27T18:16:21.520734Z","end":"2026-02-27T18:16:23.032016Z","steps":["trace[1175491710] 'process raft request' (duration: 1.469480491s)","trace[1175491710] 'compare' (duration: 41.387113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:23.032479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:21.520714Z","time spent":"1.511422337s","remote":"127.0.0.1:39184","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-27T18:16:23.067973Z","caller":"traceutil/trace.go:171","msg":"trace[388339133] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:307; }","duration":"1.546714601s","start":"2026-02-27T18:16:21.521227Z","end":"2026-02-27T18:16:23.067941Z","steps":["trace[388339133] 'read index received' (duration: 1.469012688s)","trace[388339133] 'applied index is now lower than readState.Index' (duration: 77.700573ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:23.068481Z","caller":"traceutil/trace.go:171","msg":"trace[1696075744] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"1.547041362s","start":"2026-02-27T18:16:21.521423Z","end":"2026-02-27T18:16:23.068464Z","steps":["trace[1696075744] 'process raft request' (duration: 1.546352278s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.068576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377069666s","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-27T18:16:23.068611Z","caller":"traceutil/trace.go:171","msg":"trace[1656389767] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"1.546529469s","start":"2026-02-27T18:16:21.522036Z","end":"2026-02-27T18:16:23.068566Z","steps":["trace[1656389767] 'process raft request' (duration: 1.545863255s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.068748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:21.522028Z","time spent":"1.54664599s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:16:23.068674Z","caller":"traceutil/trace.go:171","msg":"trace[1712786690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:298; }","duration":"1.377213627s","start":"2026-02-27T18:16:21.691443Z","end":"2026-02-27T18:16:23.068657Z","steps":["trace[1712786690] 'agreement among raft nodes before linearized reading' (duration: 1.377051606s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.06874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547465375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:16:23.068786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:21.521404Z","time spent":"1.547121033s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:16:23.068831Z","caller":"traceutil/trace.go:171","msg":"trace[345507990] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:298; }","duration":"1.547609246s","start":"2026-02-27T18:16:21.521196Z","end":"2026-02-27T18:16:23.068805Z","steps":["trace[345507990] 'agreement among raft nodes before linearized reading' (duration: 1.547401495s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.068881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:21.521181Z","time spent":"1.547686196s","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" "} {"level":"warn","ts":"2026-02-27T18:16:23.068811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:21.691423Z","time spent":"1.377376518s","remote":"127.0.0.1:39184","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-27T18:16:23.263509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.615849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:23.263835Z","caller":"traceutil/trace.go:171","msg":"trace[1119146973] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"172.471013ms","start":"2026-02-27T18:16:23.09131Z","end":"2026-02-27T18:16:23.263781Z","steps":["trace[1119146973] 'process raft request' (duration: 55.498562ms)","trace[1119146973] 'compare' (duration: 116.491968ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:23.26393Z","caller":"traceutil/trace.go:171","msg":"trace[1712304573] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"171.605889ms","start":"2026-02-27T18:16:23.092258Z","end":"2026-02-27T18:16:23.263864Z","steps":["trace[1712304573] 'process raft request' (duration: 171.469118ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.431373Z","caller":"traceutil/trace.go:171","msg":"trace[2009475945] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"126.618403ms","start":"2026-02-27T18:16:23.304728Z","end":"2026-02-27T18:16:23.431346Z","steps":["trace[2009475945] 'process raft request' (duration: 126.481572ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.520971Z","caller":"traceutil/trace.go:171","msg":"trace[951999573] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:312; }","duration":"213.421933ms","start":"2026-02-27T18:16:23.307524Z","end":"2026-02-27T18:16:23.520946Z","steps":["trace[951999573] 'read index received' (duration: 123.682774ms)","trace[951999573] 'applied index is now lower than readState.Index' (duration: 89.738239ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:23.521247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.202614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-27T18:16:23.521295Z","caller":"traceutil/trace.go:171","msg":"trace[1068149432] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:304; }","duration":"166.306825ms","start":"2026-02-27T18:16:23.35498Z","end":"2026-02-27T18:16:23.521287Z","steps":["trace[1068149432] 'agreement among raft nodes before linearized reading' (duration: 166.217505ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.521033Z","caller":"traceutil/trace.go:171","msg":"trace[1064875104] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"209.061126ms","start":"2026-02-27T18:16:23.311933Z","end":"2026-02-27T18:16:23.520995Z","steps":["trace[1064875104] 'process raft request' (duration: 208.914025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.521443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.918947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-27T18:16:23.521578Z","caller":"traceutil/trace.go:171","msg":"trace[1841703767] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:304; }","duration":"214.053828ms","start":"2026-02-27T18:16:23.307511Z","end":"2026-02-27T18:16:23.521565Z","steps":["trace[1841703767] 'agreement among raft nodes before linearized reading' (duration: 213.910787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.52163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.989993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-27T18:16:23.521664Z","caller":"traceutil/trace.go:171","msg":"trace[1964457840] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:304; }","duration":"166.065404ms","start":"2026-02-27T18:16:23.355589Z","end":"2026-02-27T18:16:23.521654Z","steps":["trace[1964457840] 'agreement among raft nodes before linearized reading' (duration: 166.002563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.52159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.730657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-27T18:16:23.521698Z","caller":"traceutil/trace.go:171","msg":"trace[755871823] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:304; }","duration":"114.859909ms","start":"2026-02-27T18:16:23.406829Z","end":"2026-02-27T18:16:23.521689Z","steps":["trace[755871823] 'agreement among raft nodes before linearized reading' (duration: 114.743778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.521736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.406788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-27T18:16:23.521755Z","caller":"traceutil/trace.go:171","msg":"trace[2063948188] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:304; }","duration":"160.458858ms","start":"2026-02-27T18:16:23.361289Z","end":"2026-02-27T18:16:23.521748Z","steps":["trace[2063948188] 'agreement among raft nodes before linearized reading' (duration: 160.425438ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.520958Z","caller":"traceutil/trace.go:171","msg":"trace[451243737] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"213.918006ms","start":"2026-02-27T18:16:23.307006Z","end":"2026-02-27T18:16:23.520924Z","steps":["trace[451243737] 'process raft request' (duration: 213.695115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.521862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.993826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-27T18:16:23.521891Z","caller":"traceutil/trace.go:171","msg":"trace[588418906] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:304; }","duration":"165.040167ms","start":"2026-02-27T18:16:23.356841Z","end":"2026-02-27T18:16:23.521881Z","steps":["trace[588418906] 'agreement among raft nodes before linearized reading' (duration: 164.983447ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.772051Z","caller":"traceutil/trace.go:171","msg":"trace[1967562022] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"231.968681ms","start":"2026-02-27T18:16:23.54006Z","end":"2026-02-27T18:16:23.772029Z","steps":["trace[1967562022] 'process raft request' (duration: 231.929921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.7722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.043584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-02-27T18:16:23.772355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.085033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-27T18:16:23.772424Z","caller":"traceutil/trace.go:171","msg":"trace[64015126] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:308; }","duration":"245.249485ms","start":"2026-02-27T18:16:23.52716Z","end":"2026-02-27T18:16:23.772409Z","steps":["trace[64015126] 'agreement among raft nodes before linearized reading' (duration: 245.014544ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.772485Z","caller":"traceutil/trace.go:171","msg":"trace[775352893] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:308; }","duration":"245.263605ms","start":"2026-02-27T18:16:23.527115Z","end":"2026-02-27T18:16:23.772378Z","steps":["trace[775352893] 'agreement among raft nodes before linearized reading' (duration: 244.986253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.772491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.340616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-27T18:16:23.772063Z","caller":"traceutil/trace.go:171","msg":"trace[68397590] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:317; }","duration":"237.909049ms","start":"2026-02-27T18:16:23.534116Z","end":"2026-02-27T18:16:23.772025Z","steps":["trace[68397590] 'read index received' (duration: 139.396504ms)","trace[68397590] 'applied index is now lower than readState.Index' (duration: 98.510125ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:23.772052Z","caller":"traceutil/trace.go:171","msg":"trace[1375078715] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"243.960307ms","start":"2026-02-27T18:16:23.528038Z","end":"2026-02-27T18:16:23.771998Z","steps":["trace[1375078715] 'process raft request' (duration: 145.461842ms)","trace[1375078715] 'compare' (duration: 98.304773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:23.772635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.245954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-02-27T18:16:23.772615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.393235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-27T18:16:23.772429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.236575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-27T18:16:23.772688Z","caller":"traceutil/trace.go:171","msg":"trace[633153719] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:308; }","duration":"245.484846ms","start":"2026-02-27T18:16:23.527187Z","end":"2026-02-27T18:16:23.772672Z","steps":["trace[633153719] 'agreement among raft nodes before linearized reading' (duration: 245.345355ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.77256Z","caller":"traceutil/trace.go:171","msg":"trace[1921941545] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:308; }","duration":"245.416906ms","start":"2026-02-27T18:16:23.527134Z","end":"2026-02-27T18:16:23.772551Z","steps":["trace[1921941545] 'agreement among raft nodes before linearized reading' (duration: 245.316445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:23.772743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.410216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-27T18:16:23.772768Z","caller":"traceutil/trace.go:171","msg":"trace[1561928861] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:308; }","duration":"245.461986ms","start":"2026-02-27T18:16:23.5273Z","end":"2026-02-27T18:16:23.772762Z","steps":["trace[1561928861] 'agreement among raft nodes before linearized reading' (duration: 245.415616ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.772794Z","caller":"traceutil/trace.go:171","msg":"trace[1613599653] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:308; }","duration":"245.569647ms","start":"2026-02-27T18:16:23.527151Z","end":"2026-02-27T18:16:23.772721Z","steps":["trace[1613599653] 'agreement among raft nodes before linearized reading' (duration: 245.163495ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.77266Z","caller":"traceutil/trace.go:171","msg":"trace[1339151293] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:308; }","duration":"245.306465ms","start":"2026-02-27T18:16:23.527347Z","end":"2026-02-27T18:16:23.772653Z","steps":["trace[1339151293] 'agreement among raft nodes before linearized reading' (duration: 245.234484ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:23.773161Z","caller":"traceutil/trace.go:171","msg":"trace[220647142] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"235.499933ms","start":"2026-02-27T18:16:23.536657Z","end":"2026-02-27T18:16:23.772157Z","steps":["trace[220647142] 'process raft request' (duration: 235.289181ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.033903Z","caller":"traceutil/trace.go:171","msg":"trace[1007434762] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"253.615148ms","start":"2026-02-27T18:16:23.780254Z","end":"2026-02-27T18:16:24.033869Z","steps":["trace[1007434762] 'process raft request' (duration: 253.385705ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.033918Z","caller":"traceutil/trace.go:171","msg":"trace[852865621] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"252.610632ms","start":"2026-02-27T18:16:23.781283Z","end":"2026-02-27T18:16:24.033893Z","steps":["trace[852865621] 'process raft request' (duration: 252.43443ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.034182Z","caller":"traceutil/trace.go:171","msg":"trace[317185350] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"256.993629ms","start":"2026-02-27T18:16:23.777173Z","end":"2026-02-27T18:16:24.034167Z","steps":["trace[317185350] 'process raft request' (duration: 173.002727ms)","trace[317185350] 'compare' (duration: 83.291386ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:24.034383Z","caller":"traceutil/trace.go:171","msg":"trace[1935202950] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"251.150482ms","start":"2026-02-27T18:16:23.783206Z","end":"2026-02-27T18:16:24.034356Z","steps":["trace[1935202950] 'process raft request' (duration: 250.970101ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.034405Z","caller":"traceutil/trace.go:171","msg":"trace[1420739981] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"252.796413ms","start":"2026-02-27T18:16:23.781585Z","end":"2026-02-27T18:16:24.034381Z","steps":["trace[1420739981] 'process raft request' (duration: 252.184599ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.03447Z","caller":"traceutil/trace.go:171","msg":"trace[1175219170] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"251.853436ms","start":"2026-02-27T18:16:23.782606Z","end":"2026-02-27T18:16:24.034459Z","steps":["trace[1175219170] 'process raft request' (duration: 251.524174ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.034477Z","caller":"traceutil/trace.go:171","msg":"trace[131274443] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"252.58195ms","start":"2026-02-27T18:16:23.781879Z","end":"2026-02-27T18:16:24.034461Z","steps":["trace[131274443] 'process raft request' (duration: 251.991587ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.034408Z","caller":"traceutil/trace.go:171","msg":"trace[1029928266] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"202.765316ms","start":"2026-02-27T18:16:23.831627Z","end":"2026-02-27T18:16:24.034392Z","steps":["trace[1029928266] 'process raft request' (duration: 202.729946ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.034664Z","caller":"traceutil/trace.go:171","msg":"trace[151392548] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"250.86766ms","start":"2026-02-27T18:16:23.783783Z","end":"2026-02-27T18:16:24.034651Z","steps":["trace[151392548] 'process raft request' (duration: 250.479598ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.034807Z","caller":"traceutil/trace.go:171","msg":"trace[1751207593] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"250.787179ms","start":"2026-02-27T18:16:23.784009Z","end":"2026-02-27T18:16:24.034796Z","steps":["trace[1751207593] 'process raft request' (duration: 250.291856ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.034574Z","caller":"traceutil/trace.go:171","msg":"trace[2095288119] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"252.589871ms","start":"2026-02-27T18:16:23.781977Z","end":"2026-02-27T18:16:24.034567Z","steps":["trace[2095288119] 'process raft request' (duration: 251.995877ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.396646Z","caller":"traceutil/trace.go:171","msg":"trace[895649238] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"356.539041ms","start":"2026-02-27T18:16:24.040058Z","end":"2026-02-27T18:16:24.396597Z","steps":["trace[895649238] 'process raft request' (duration: 292.178815ms)","trace[895649238] 'compare' (duration: 64.023204ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:24.396705Z","caller":"traceutil/trace.go:171","msg":"trace[949378077] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"356.452861ms","start":"2026-02-27T18:16:24.040206Z","end":"2026-02-27T18:16:24.396659Z","steps":["trace[949378077] 'process raft request' (duration: 356.2923ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.396788Z","caller":"traceutil/trace.go:171","msg":"trace[410119484] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"355.281434ms","start":"2026-02-27T18:16:24.041465Z","end":"2026-02-27T18:16:24.396747Z","steps":["trace[410119484] 'process raft request' (duration: 355.104223ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.396824Z","caller":"traceutil/trace.go:171","msg":"trace[125302203] linearizableReadLoop","detail":"{readStateIndex:335; appliedIndex:333; }","duration":"356.36326ms","start":"2026-02-27T18:16:24.04042Z","end":"2026-02-27T18:16:24.396783Z","steps":["trace[125302203] 'read index received' (duration: 91.79216ms)","trace[125302203] 'applied index is now lower than readState.Index' (duration: 264.5693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:24.396899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.040194Z","time spent":"356.617203ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:16:24.396868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.040032Z","time spent":"356.721093ms","remote":"127.0.0.1:38992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:16:24.396947Z","caller":"traceutil/trace.go:171","msg":"trace[899081610] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"355.276644ms","start":"2026-02-27T18:16:24.041656Z","end":"2026-02-27T18:16:24.396932Z","steps":["trace[899081610] 'process raft request' (duration: 354.983973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:24.39697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.041458Z","time spent":"355.415875ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:24.397047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.041647Z","time spent":"355.337025ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:16:24.39717Z","caller":"traceutil/trace.go:171","msg":"trace[192312429] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"354.486238ms","start":"2026-02-27T18:16:24.042641Z","end":"2026-02-27T18:16:24.397127Z","steps":["trace[192312429] 'process raft request' (duration: 354.065316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:24.397334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.042633Z","time spent":"354.645819ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:24.397408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.973794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-27T18:16:24.397487Z","caller":"traceutil/trace.go:171","msg":"trace[698377273] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:326; }","duration":"357.068584ms","start":"2026-02-27T18:16:24.040402Z","end":"2026-02-27T18:16:24.397471Z","steps":["trace[698377273] 'agreement among raft nodes before linearized reading' (duration: 356.812613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:24.397799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.040396Z","time spent":"357.385046ms","remote":"127.0.0.1:39132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-27T18:16:24.689432Z","caller":"traceutil/trace.go:171","msg":"trace[1039394719] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"260.655496ms","start":"2026-02-27T18:16:24.428746Z","end":"2026-02-27T18:16:24.689402Z","steps":["trace[1039394719] 'process raft request' (duration: 202.317037ms)","trace[1039394719] 'compare' (duration: 58.106006ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:24.689599Z","caller":"traceutil/trace.go:171","msg":"trace[1582276389] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"260.790677ms","start":"2026-02-27T18:16:24.428787Z","end":"2026-02-27T18:16:24.689577Z","steps":["trace[1582276389] 'process raft request' (duration: 260.539975ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.689649Z","caller":"traceutil/trace.go:171","msg":"trace[1157653149] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"223.300041ms","start":"2026-02-27T18:16:24.466326Z","end":"2026-02-27T18:16:24.689626Z","steps":["trace[1157653149] 'process raft request' (duration: 223.263571ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.689721Z","caller":"traceutil/trace.go:171","msg":"trace[741316855] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"258.642544ms","start":"2026-02-27T18:16:24.43101Z","end":"2026-02-27T18:16:24.689652Z","steps":["trace[741316855] 'process raft request' (duration: 258.527963ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.689817Z","caller":"traceutil/trace.go:171","msg":"trace[1453692085] linearizableReadLoop","detail":"{readStateIndex:343; appliedIndex:338; }","duration":"258.473713ms","start":"2026-02-27T18:16:24.431334Z","end":"2026-02-27T18:16:24.689807Z","steps":["trace[1453692085] 'read index received' (duration: 28.631652ms)","trace[1453692085] 'applied index is now lower than readState.Index' (duration: 229.841251ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:24.689649Z","caller":"traceutil/trace.go:171","msg":"trace[1487037782] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"259.895351ms","start":"2026-02-27T18:16:24.429713Z","end":"2026-02-27T18:16:24.689609Z","steps":["trace[1487037782] 'process raft request' (duration: 259.66904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:24.689954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.436414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:16:24.689954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.611524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-02-27T18:16:24.689991Z","caller":"traceutil/trace.go:171","msg":"trace[104861263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:332; }","duration":"225.535015ms","start":"2026-02-27T18:16:24.464446Z","end":"2026-02-27T18:16:24.689981Z","steps":["trace[104861263] 'agreement among raft nodes before linearized reading' (duration: 225.466225ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:24.69Z","caller":"traceutil/trace.go:171","msg":"trace[176124682] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:332; }","duration":"258.717824ms","start":"2026-02-27T18:16:24.431271Z","end":"2026-02-27T18:16:24.689989Z","steps":["trace[176124682] 'agreement among raft nodes before linearized reading' (duration: 258.620654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:24.690184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.798549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-z6ls8\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-02-27T18:16:24.690228Z","caller":"traceutil/trace.go:171","msg":"trace[745141591] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-z6ls8; range_end:; response_count:1; response_revision:332; }","duration":"224.89847ms","start":"2026-02-27T18:16:24.465313Z","end":"2026-02-27T18:16:24.690212Z","steps":["trace[745141591] 'agreement among raft nodes before linearized reading' (duration: 224.83668ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:25.091593Z","caller":"traceutil/trace.go:171","msg":"trace[63834879] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"394.238708ms","start":"2026-02-27T18:16:24.697328Z","end":"2026-02-27T18:16:25.091566Z","steps":["trace[63834879] 'process raft request' (duration: 357.225436ms)","trace[63834879] 'compare' (duration: 36.830141ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:25.091681Z","caller":"traceutil/trace.go:171","msg":"trace[2128730743] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:345; }","duration":"256.391476ms","start":"2026-02-27T18:16:24.83527Z","end":"2026-02-27T18:16:25.091661Z","steps":["trace[2128730743] 'read index received' (duration: 219.202473ms)","trace[2128730743] 'applied index is now lower than readState.Index' (duration: 37.187993ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:25.091741Z","caller":"traceutil/trace.go:171","msg":"trace[1980281329] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"385.14936ms","start":"2026-02-27T18:16:24.706563Z","end":"2026-02-27T18:16:25.091712Z","steps":["trace[1980281329] 'process raft request' (duration: 385.037279ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:25.091791Z","caller":"traceutil/trace.go:171","msg":"trace[1759332477] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"383.56542ms","start":"2026-02-27T18:16:24.708217Z","end":"2026-02-27T18:16:25.091783Z","steps":["trace[1759332477] 'process raft request' (duration: 383.411369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:25.091848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.706552Z","time spent":"385.23672ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4451,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:25.091902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.708208Z","time spent":"383.61778ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:16:25.091937Z","caller":"traceutil/trace.go:171","msg":"trace[1884315578] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"393.836245ms","start":"2026-02-27T18:16:24.698081Z","end":"2026-02-27T18:16:25.091918Z","steps":["trace[1884315578] 'process raft request' (duration: 393.447343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:25.092026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.754529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:16:25.091992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.697313Z","time spent":"394.354348ms","remote":"127.0.0.1:38992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:16:25.092033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.698071Z","time spent":"393.922585ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:16:25.092059Z","caller":"traceutil/trace.go:171","msg":"trace[1367689447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:338; }","duration":"256.807699ms","start":"2026-02-27T18:16:24.835242Z","end":"2026-02-27T18:16:25.09205Z","steps":["trace[1367689447] 'agreement among raft nodes before linearized reading' (duration: 256.760348ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:25.092114Z","caller":"traceutil/trace.go:171","msg":"trace[357094725] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"388.944084ms","start":"2026-02-27T18:16:24.703157Z","end":"2026-02-27T18:16:25.092102Z","steps":["trace[357094725] 'process raft request' (duration: 388.408431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:25.092169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:24.703137Z","time spent":"389.009665ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:16:25.276436Z","caller":"traceutil/trace.go:171","msg":"trace[1287986039] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"174.016315ms","start":"2026-02-27T18:16:25.102402Z","end":"2026-02-27T18:16:25.276418Z","steps":["trace[1287986039] 'process raft request' (duration: 173.949384ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:25.276644Z","caller":"traceutil/trace.go:171","msg":"trace[160536445] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"176.596141ms","start":"2026-02-27T18:16:25.100038Z","end":"2026-02-27T18:16:25.276635Z","steps":["trace[160536445] 'process raft request' (duration: 176.212429ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:25.276669Z","caller":"traceutil/trace.go:171","msg":"trace[1304371641] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"177.439906ms","start":"2026-02-27T18:16:25.099192Z","end":"2026-02-27T18:16:25.276632Z","steps":["trace[1304371641] 'process raft request' (duration: 145.144404ms)","trace[1304371641] 'compare' (duration: 31.782169ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:25.276797Z","caller":"traceutil/trace.go:171","msg":"trace[1604751826] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"174.319066ms","start":"2026-02-27T18:16:25.102367Z","end":"2026-02-27T18:16:25.276686Z","steps":["trace[1604751826] 'process raft request' (duration: 173.952894ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:25.277099Z","caller":"traceutil/trace.go:171","msg":"trace[825566959] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"174.459907ms","start":"2026-02-27T18:16:25.10262Z","end":"2026-02-27T18:16:25.27708Z","steps":["trace[825566959] 'process raft request' (duration: 173.770673ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:25.277111Z","caller":"traceutil/trace.go:171","msg":"trace[1049357744] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:351; }","duration":"143.991016ms","start":"2026-02-27T18:16:25.133092Z","end":"2026-02-27T18:16:25.277083Z","steps":["trace[1049357744] 'read index received' (duration: 111.360451ms)","trace[1049357744] 'applied index is now lower than readState.Index' (duration: 32.629155ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:25.277177Z","caller":"traceutil/trace.go:171","msg":"trace[1059846700] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"173.551772ms","start":"2026-02-27T18:16:25.103557Z","end":"2026-02-27T18:16:25.277109Z","steps":["trace[1059846700] 'process raft request' (duration: 173.397461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:25.277227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.693688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-02-27T18:16:25.277288Z","caller":"traceutil/trace.go:171","msg":"trace[1332052508] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:345; }","duration":"174.813399ms","start":"2026-02-27T18:16:25.102464Z","end":"2026-02-27T18:16:25.277278Z","steps":["trace[1332052508] 'agreement among raft nodes before linearized reading' (duration: 174.712258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:25.509304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.919535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:25.509415Z","caller":"traceutil/trace.go:171","msg":"trace[1461625346] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"232.197351ms","start":"2026-02-27T18:16:25.2772Z","end":"2026-02-27T18:16:25.509397Z","steps":["trace[1461625346] 'read index received' (duration: 107.053704ms)","trace[1461625346] 'applied index is now lower than readState.Index' (duration: 125.142387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:25.509492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.689638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.141\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T18:16:25.509525Z","caller":"traceutil/trace.go:171","msg":"trace[1928958529] range","detail":"{range_begin:/registry/masterleases/199.204.45.141; range_end:; response_count:1; response_revision:346; }","duration":"344.756598ms","start":"2026-02-27T18:16:25.164758Z","end":"2026-02-27T18:16:25.509514Z","steps":["trace[1928958529] 'agreement among raft nodes before linearized reading' (duration: 344.688258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:25.509562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:25.164745Z","time spent":"344.809369ms","remote":"127.0.0.1:38934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.141\" "} {"level":"warn","ts":"2026-02-27T18:16:25.509704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.82501ms","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-27T18:16:25.50979Z","caller":"traceutil/trace.go:171","msg":"trace[1376269686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:346; }","duration":"241.951371ms","start":"2026-02-27T18:16:25.267817Z","end":"2026-02-27T18:16:25.509769Z","steps":["trace[1376269686] 'agreement among raft nodes before linearized reading' (duration: 241.81109ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:25.510011Z","caller":"traceutil/trace.go:171","msg":"trace[1200537301] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"367.638042ms","start":"2026-02-27T18:16:25.14234Z","end":"2026-02-27T18:16:25.509978Z","steps":["trace[1200537301] 'process raft request' (duration: 241.899632ms)","trace[1200537301] 'compare' (duration: 124.776014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:25.510637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:25.14232Z","time spent":"368.203276ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7096,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:16:25.518483Z","caller":"traceutil/trace.go:171","msg":"trace[1700412585] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"235.849134ms","start":"2026-02-27T18:16:25.282603Z","end":"2026-02-27T18:16:25.518452Z","steps":["trace[1700412585] 'process raft request' (duration: 235.670892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:26.470691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.546924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:26.471189Z","caller":"traceutil/trace.go:171","msg":"trace[868690586] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:361; }","duration":"419.842141ms","start":"2026-02-27T18:16:26.051287Z","end":"2026-02-27T18:16:26.47113Z","steps":["trace[868690586] 'read index received' (duration: 86.218439ms)","trace[868690586] 'applied index is now lower than readState.Index' (duration: 333.622262ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:26.471468Z","caller":"traceutil/trace.go:171","msg":"trace[1739703109] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"897.761337ms","start":"2026-02-27T18:16:25.573461Z","end":"2026-02-27T18:16:26.471222Z","steps":["trace[1739703109] 'process raft request' (duration: 897.530075ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:26.471501Z","caller":"traceutil/trace.go:171","msg":"trace[428773038] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"903.940656ms","start":"2026-02-27T18:16:25.567322Z","end":"2026-02-27T18:16:26.471263Z","steps":["trace[428773038] 'process raft request' (duration: 903.597834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:26.471674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:25.573449Z","time spent":"898.11881ms","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:26.47152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.239854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:16:26.471785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:25.567297Z","time spent":"904.330888ms","remote":"127.0.0.1:38934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:16:26.471791Z","caller":"traceutil/trace.go:171","msg":"trace[826478323] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:351; }","duration":"420.534566ms","start":"2026-02-27T18:16:26.051231Z","end":"2026-02-27T18:16:26.471765Z","steps":["trace[826478323] 'agreement among raft nodes before linearized reading' (duration: 420.003493ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:26.471782Z","caller":"traceutil/trace.go:171","msg":"trace[633938416] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"947.36764ms","start":"2026-02-27T18:16:25.523793Z","end":"2026-02-27T18:16:26.471161Z","steps":["trace[633938416] 'process raft request' (duration: 127.228961ms)","trace[633938416] 'compare' (duration: 819.334083ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:26.471862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:26.051201Z","time spent":"420.639837ms","remote":"127.0.0.1:38884","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-27T18:16:26.472019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:25.523777Z","time spent":"948.106484ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:27.070463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.919915ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:16:27.070677Z","caller":"traceutil/trace.go:171","msg":"trace[1413237905] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:351; }","duration":"483.172836ms","start":"2026-02-27T18:16:26.587479Z","end":"2026-02-27T18:16:27.070651Z","steps":["trace[1413237905] 'range keys from in-memory index tree' (duration: 482.796424ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:27.283352Z","caller":"traceutil/trace.go:171","msg":"trace[1502180648] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"202.396974ms","start":"2026-02-27T18:16:27.080924Z","end":"2026-02-27T18:16:27.283321Z","steps":["trace[1502180648] 'process raft request' (duration: 202.197012ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:27.469214Z","caller":"traceutil/trace.go:171","msg":"trace[894699462] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:364; }","duration":"331.495437ms","start":"2026-02-27T18:16:27.137693Z","end":"2026-02-27T18:16:27.469188Z","steps":["trace[894699462] 'read index received' (duration: 145.419887ms)","trace[894699462] 'applied index is now lower than readState.Index' (duration: 186.07449ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:27.469282Z","caller":"traceutil/trace.go:171","msg":"trace[311149184] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"303.399273ms","start":"2026-02-27T18:16:27.165863Z","end":"2026-02-27T18:16:27.469262Z","steps":["trace[311149184] 'process raft request' (duration: 303.261241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:27.469427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.724859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T18:16:27.469488Z","caller":"traceutil/trace.go:171","msg":"trace[1053812183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:354; }","duration":"331.830949ms","start":"2026-02-27T18:16:27.137641Z","end":"2026-02-27T18:16:27.469472Z","steps":["trace[1053812183] 'agreement among raft nodes before linearized reading' (duration: 331.726649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:27.46954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:27.137622Z","time spent":"331.90631ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T18:16:27.469539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.309527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-02-27T18:16:27.469591Z","caller":"traceutil/trace.go:171","msg":"trace[563250302] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:354; }","duration":"182.363117ms","start":"2026-02-27T18:16:27.287218Z","end":"2026-02-27T18:16:27.469581Z","steps":["trace[563250302] 'agreement among raft nodes before linearized reading' (duration: 182.264927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:27.469651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.5698ms","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-27T18:16:27.469405Z","caller":"traceutil/trace.go:171","msg":"trace[1597076107] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"379.89824ms","start":"2026-02-27T18:16:27.08941Z","end":"2026-02-27T18:16:27.469308Z","steps":["trace[1597076107] 'process raft request' (duration: 319.124656ms)","trace[1597076107] 'compare' (duration: 60.427951ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:27.46976Z","caller":"traceutil/trace.go:171","msg":"trace[579960858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:354; }","duration":"187.807532ms","start":"2026-02-27T18:16:27.281932Z","end":"2026-02-27T18:16:27.46974Z","steps":["trace[579960858] 'agreement among raft nodes before linearized reading' (duration: 187.500499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:27.469785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.095137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-27T18:16:27.469434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:27.165829Z","time spent":"303.493983ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:27.469992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:27.089378Z","time spent":"380.437545ms","remote":"127.0.0.1:38992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:16:27.470048Z","caller":"traceutil/trace.go:171","msg":"trace[1705301809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:354; }","duration":"187.352939ms","start":"2026-02-27T18:16:27.282584Z","end":"2026-02-27T18:16:27.469937Z","steps":["trace[1705301809] 'agreement among raft nodes before linearized reading' (duration: 187.031037ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:27.719952Z","caller":"traceutil/trace.go:171","msg":"trace[1808912704] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"244.83335ms","start":"2026-02-27T18:16:27.475095Z","end":"2026-02-27T18:16:27.719929Z","steps":["trace[1808912704] 'process raft request' (duration: 200.530412ms)","trace[1808912704] 'compare' (duration: 43.795544ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:27.719967Z","caller":"traceutil/trace.go:171","msg":"trace[1375151570] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"244.417406ms","start":"2026-02-27T18:16:27.475525Z","end":"2026-02-27T18:16:27.719942Z","steps":["trace[1375151570] 'process raft request' (duration: 244.288425ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:27.72009Z","caller":"traceutil/trace.go:171","msg":"trace[1101062314] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:368; }","duration":"148.087289ms","start":"2026-02-27T18:16:27.571983Z","end":"2026-02-27T18:16:27.720071Z","steps":["trace[1101062314] 'read index received' (duration: 103.526779ms)","trace[1101062314] 'applied index is now lower than readState.Index' (duration: 44.55904ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:27.720158Z","caller":"traceutil/trace.go:171","msg":"trace[363306487] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"242.437539ms","start":"2026-02-27T18:16:27.477681Z","end":"2026-02-27T18:16:27.720119Z","steps":["trace[363306487] 'process raft request' (duration: 242.194157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:27.72023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.2251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T18:16:27.72031Z","caller":"traceutil/trace.go:171","msg":"trace[1546868696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:358; }","duration":"148.364773ms","start":"2026-02-27T18:16:27.571925Z","end":"2026-02-27T18:16:27.72029Z","steps":["trace[1546868696] 'agreement among raft nodes before linearized reading' (duration: 148.203261ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:27.975168Z","caller":"traceutil/trace.go:171","msg":"trace[85471648] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"190.391632ms","start":"2026-02-27T18:16:27.784743Z","end":"2026-02-27T18:16:27.975135Z","steps":["trace[85471648] 'process raft request' (duration: 173.344252ms)","trace[85471648] 'compare' (duration: 16.332084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:28.261908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.913903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:28.262029Z","caller":"traceutil/trace.go:171","msg":"trace[917625776] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"263.436922ms","start":"2026-02-27T18:16:27.998562Z","end":"2026-02-27T18:16:28.261999Z","steps":["trace[917625776] 'process raft request' (duration: 85.353684ms)","trace[917625776] 'compare' (duration: 177.518072ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:28.262187Z","caller":"traceutil/trace.go:171","msg":"trace[138058525] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"207.883485ms","start":"2026-02-27T18:16:28.054275Z","end":"2026-02-27T18:16:28.262158Z","steps":["trace[138058525] 'read index received' (duration: 29.655022ms)","trace[138058525] 'applied index is now lower than readState.Index' (duration: 178.224982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:28.262376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.08207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:16:28.262418Z","caller":"traceutil/trace.go:171","msg":"trace[1891402608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:362; }","duration":"208.191183ms","start":"2026-02-27T18:16:28.054216Z","end":"2026-02-27T18:16:28.262407Z","steps":["trace[1891402608] 'agreement among raft nodes before linearized reading' (duration: 208.065919ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:29.057215Z","caller":"traceutil/trace.go:171","msg":"trace[1588803894] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"108.522776ms","start":"2026-02-27T18:16:28.948663Z","end":"2026-02-27T18:16:29.057186Z","steps":["trace[1588803894] 'process raft request' (duration: 108.30649ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:32.06482Z","caller":"traceutil/trace.go:171","msg":"trace[486954413] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"239.978982ms","start":"2026-02-27T18:16:31.824817Z","end":"2026-02-27T18:16:32.064796Z","steps":["trace[486954413] 'process raft request' (duration: 236.19066ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:38.873396Z","caller":"traceutil/trace.go:171","msg":"trace[35002938] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"140.213347ms","start":"2026-02-27T18:16:38.733156Z","end":"2026-02-27T18:16:38.87337Z","steps":["trace[35002938] 'process raft request' (duration: 100.386964ms)","trace[35002938] 'compare' (duration: 39.567736ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:38.873436Z","caller":"traceutil/trace.go:171","msg":"trace[182869806] transaction","detail":"{read_only:false; number_of_response:1; response_revision:406; }","duration":"137.492877ms","start":"2026-02-27T18:16:38.73592Z","end":"2026-02-27T18:16:38.873413Z","steps":["trace[182869806] 'process raft request' (duration: 137.444315ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:38.87347Z","caller":"traceutil/trace.go:171","msg":"trace[1519048287] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"138.893894ms","start":"2026-02-27T18:16:38.734557Z","end":"2026-02-27T18:16:38.873451Z","steps":["trace[1519048287] 'process raft request' (duration: 138.716749ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:39.032412Z","caller":"traceutil/trace.go:171","msg":"trace[514030428] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"152.363109ms","start":"2026-02-27T18:16:38.880021Z","end":"2026-02-27T18:16:39.032384Z","steps":["trace[514030428] 'process raft request' (duration: 121.11202ms)","trace[514030428] 'compare' (duration: 31.147046ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:39.033289Z","caller":"traceutil/trace.go:171","msg":"trace[1421354594] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"153.033386ms","start":"2026-02-27T18:16:38.880234Z","end":"2026-02-27T18:16:39.033268Z","steps":["trace[1421354594] 'process raft request' (duration: 152.979044ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:39.033344Z","caller":"traceutil/trace.go:171","msg":"trace[1741746305] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"153.235711ms","start":"2026-02-27T18:16:38.880087Z","end":"2026-02-27T18:16:39.033323Z","steps":["trace[1741746305] 'process raft request' (duration: 153.033506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:45.474695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.431257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:45.474797Z","caller":"traceutil/trace.go:171","msg":"trace[1004299728] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"248.968231ms","start":"2026-02-27T18:16:45.225815Z","end":"2026-02-27T18:16:45.474783Z","steps":["trace[1004299728] 'process raft request' (duration: 28.388961ms)","trace[1004299728] 'compare' (duration: 220.289613ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:49.95437Z","caller":"traceutil/trace.go:171","msg":"trace[1774691749] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"139.82959ms","start":"2026-02-27T18:16:49.814516Z","end":"2026-02-27T18:16:49.954346Z","steps":["trace[1774691749] 'process raft request' (duration: 139.770159ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:49.954428Z","caller":"traceutil/trace.go:171","msg":"trace[1378278256] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"140.387523ms","start":"2026-02-27T18:16:49.814Z","end":"2026-02-27T18:16:49.954387Z","steps":["trace[1378278256] 'process raft request' (duration: 53.83964ms)","trace[1378278256] 'compare' (duration: 86.055502ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:50.156435Z","caller":"traceutil/trace.go:171","msg":"trace[693028427] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:467; }","duration":"102.94513ms","start":"2026-02-27T18:16:50.053467Z","end":"2026-02-27T18:16:50.156412Z","steps":["trace[693028427] 'read index received' (duration: 56.298483ms)","trace[693028427] 'applied index is now lower than readState.Index' (duration: 46.645637ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:50.156549Z","caller":"traceutil/trace.go:171","msg":"trace[825496988] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"126.132671ms","start":"2026-02-27T18:16:50.030405Z","end":"2026-02-27T18:16:50.156537Z","steps":["trace[825496988] 'process raft request' (duration: 125.975377ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:50.156609Z","caller":"traceutil/trace.go:171","msg":"trace[1402391434] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"126.213683ms","start":"2026-02-27T18:16:50.030356Z","end":"2026-02-27T18:16:50.15657Z","steps":["trace[1402391434] 'process raft request' (duration: 79.400702ms)","trace[1402391434] 'compare' (duration: 46.501433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:50.15687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.39426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:16:50.156914Z","caller":"traceutil/trace.go:171","msg":"trace[621262321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:450; }","duration":"103.452782ms","start":"2026-02-27T18:16:50.053449Z","end":"2026-02-27T18:16:50.156902Z","steps":["trace[621262321] 'agreement among raft nodes before linearized reading' (duration: 103.349329ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:50.391703Z","caller":"traceutil/trace.go:171","msg":"trace[1375669035] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:473; }","duration":"112.372912ms","start":"2026-02-27T18:16:50.279308Z","end":"2026-02-27T18:16:50.391681Z","steps":["trace[1375669035] 'read index received' (duration: 25.550464ms)","trace[1375669035] 'applied index is now lower than readState.Index' (duration: 86.820988ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:50.391735Z","caller":"traceutil/trace.go:171","msg":"trace[1746503209] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"119.334288ms","start":"2026-02-27T18:16:50.272364Z","end":"2026-02-27T18:16:50.391698Z","steps":["trace[1746503209] 'process raft request' (duration: 103.669597ms)","trace[1746503209] 'compare' (duration: 15.469916ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:50.3918Z","caller":"traceutil/trace.go:171","msg":"trace[762372183] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"112.563256ms","start":"2026-02-27T18:16:50.279213Z","end":"2026-02-27T18:16:50.391776Z","steps":["trace[762372183] 'process raft request' (duration: 112.406813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:50.391866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.534506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T18:16:50.39192Z","caller":"traceutil/trace.go:171","msg":"trace[1117196503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:456; }","duration":"112.648419ms","start":"2026-02-27T18:16:50.279258Z","end":"2026-02-27T18:16:50.391906Z","steps":["trace[1117196503] 'agreement among raft nodes before linearized reading' (duration: 112.516455ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:50.512219Z","caller":"traceutil/trace.go:171","msg":"trace[2096382144] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"110.756235ms","start":"2026-02-27T18:16:50.401417Z","end":"2026-02-27T18:16:50.512173Z","steps":["trace[2096382144] 'process raft request' (duration: 98.501791ms)","trace[2096382144] 'compare' (duration: 12.027529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:50.902877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.590572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:50.903156Z","caller":"traceutil/trace.go:171","msg":"trace[234607048] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:479; }","duration":"381.281777ms","start":"2026-02-27T18:16:50.521794Z","end":"2026-02-27T18:16:50.903075Z","steps":["trace[234607048] 'read index received' (duration: 143.386498ms)","trace[234607048] 'applied index is now lower than readState.Index' (duration: 237.893399ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:50.903172Z","caller":"traceutil/trace.go:171","msg":"trace[871116773] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"423.919774ms","start":"2026-02-27T18:16:50.479206Z","end":"2026-02-27T18:16:50.903126Z","steps":["trace[871116773] 'process raft request' (duration: 185.927833ms)","trace[871116773] 'compare' (duration: 237.381968ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:50.903362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:50.47918Z","time spent":"424.084577ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:16:50.903497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.643705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:16:50.903604Z","caller":"traceutil/trace.go:171","msg":"trace[1163192274] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:460; }","duration":"381.825379ms","start":"2026-02-27T18:16:50.521737Z","end":"2026-02-27T18:16:50.903563Z","steps":["trace[1163192274] 'agreement among raft nodes before linearized reading' (duration: 381.497341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:50.903691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:50.521715Z","time spent":"381.936612ms","remote":"127.0.0.1:39074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-02-27T18:16:52.222478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030542008s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:52.222601Z","caller":"traceutil/trace.go:171","msg":"trace[507064002] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"1.663561793s","start":"2026-02-27T18:16:50.559017Z","end":"2026-02-27T18:16:52.222579Z","steps":["trace[507064002] 'process raft request' (duration: 632.626016ms)","trace[507064002] 'compare' (duration: 1.030278063s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:52.222662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:50.558978Z","time spent":"1.663654165s","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:53.002313Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.810722621s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T18:16:53.056439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000477883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-27T18:16:53.056567Z","caller":"traceutil/trace.go:171","msg":"trace[889150416] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000673617s","start":"2026-02-27T18:16:51.055855Z","end":"2026-02-27T18:16:53.056528Z","steps":["trace[889150416] 'agreement among raft nodes before linearized reading' (duration: 2.000519164s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.056666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:51.055825Z","time spent":"2.0008216s","remote":"127.0.0.1:38884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/27 18:16:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-27T18:16:53.11204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.401423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:16:53.1126Z","caller":"traceutil/trace.go:171","msg":"trace[1244985844] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"2.20349066s","start":"2026-02-27T18:16:50.909075Z","end":"2026-02-27T18:16:53.112566Z","steps":["trace[1244985844] 'process raft request' (duration: 2.093439842s)","trace[1244985844] 'compare' (duration: 108.752649ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:53.1127Z","caller":"traceutil/trace.go:171","msg":"trace[885140799] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"2.203306326s","start":"2026-02-27T18:16:50.909332Z","end":"2026-02-27T18:16:53.112638Z","steps":["trace[885140799] 'process raft request' (duration: 2.202850427s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.112741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:50.909048Z","time spent":"2.203634243s","remote":"127.0.0.1:38992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:16:53.112844Z","caller":"traceutil/trace.go:171","msg":"trace[1617265035] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"2.200652426s","start":"2026-02-27T18:16:50.912173Z","end":"2026-02-27T18:16:53.112826Z","steps":["trace[1617265035] 'process raft request' (duration: 2.200430592s)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.11278Z","caller":"traceutil/trace.go:171","msg":"trace[1488211595] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"2.203094331s","start":"2026-02-27T18:16:50.909673Z","end":"2026-02-27T18:16:53.112767Z","steps":["trace[1488211595] 'process raft request' (duration: 2.202863336s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.112977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:50.909663Z","time spent":"2.203284875s","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T18:16:53.112722Z","caller":"traceutil/trace.go:171","msg":"trace[399172380] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:480; }","duration":"2.056782659s","start":"2026-02-27T18:16:51.055918Z","end":"2026-02-27T18:16:53.112701Z","steps":["trace[399172380] 'read index received' (duration: 135.657403ms)","trace[399172380] 'applied index is now lower than readState.Index' (duration: 1.921124046s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:53.11309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:50.912166Z","time spent":"2.200764999s","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:53.112826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:50.909323Z","time spent":"2.20346914s","remote":"127.0.0.1:39120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T18:16:53.113235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0198943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:16:53.113316Z","caller":"traceutil/trace.go:171","msg":"trace[456780344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"1.020008873s","start":"2026-02-27T18:16:52.093291Z","end":"2026-02-27T18:16:53.1133Z","steps":["trace[456780344] 'agreement among raft nodes before linearized reading' (duration: 1.019887791s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.113374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:52.093273Z","time spent":"1.020089435s","remote":"127.0.0.1:38884","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-27T18:16:53.113446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.273496ms","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-27T18:16:53.113512Z","caller":"traceutil/trace.go:171","msg":"trace[1323263136] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"708.386689ms","start":"2026-02-27T18:16:52.405109Z","end":"2026-02-27T18:16:53.113495Z","steps":["trace[1323263136] 'agreement among raft nodes before linearized reading' (duration: 708.296137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.113569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:52.405079Z","time spent":"708.478281ms","remote":"127.0.0.1:39184","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-27T18:16:53.113637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.555639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-27T18:16:53.113669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.212547ms","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-27T18:16:53.113681Z","caller":"traceutil/trace.go:171","msg":"trace[466473617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"897.660341ms","start":"2026-02-27T18:16:52.216008Z","end":"2026-02-27T18:16:53.113668Z","steps":["trace[466473617] 'agreement among raft nodes before linearized reading' (duration: 897.574029ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.113704Z","caller":"traceutil/trace.go:171","msg":"trace[1149786155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"805.267609ms","start":"2026-02-27T18:16:52.308424Z","end":"2026-02-27T18:16:53.113692Z","steps":["trace[1149786155] 'agreement among raft nodes before linearized reading' (duration: 805.203467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.113722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:52.215975Z","time spent":"897.738433ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T18:16:53.113731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:52.308413Z","time spent":"805.309689ms","remote":"127.0.0.1:39184","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-27T18:16:53.113755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.144323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"warn","ts":"2026-02-27T18:16:53.113371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.246089ms","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-27T18:16:53.113804Z","caller":"traceutil/trace.go:171","msg":"trace[1564627125] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:465; }","duration":"813.245635ms","start":"2026-02-27T18:16:52.300544Z","end":"2026-02-27T18:16:53.11379Z","steps":["trace[1564627125] 'agreement among raft nodes before linearized reading' (duration: 812.905458ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.113811Z","caller":"traceutil/trace.go:171","msg":"trace[129713107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:465; }","duration":"715.75329ms","start":"2026-02-27T18:16:52.398049Z","end":"2026-02-27T18:16:53.113802Z","steps":["trace[129713107] 'agreement among raft nodes before linearized reading' (duration: 715.251879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.113846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:52.397983Z","time spent":"715.854642ms","remote":"127.0.0.1:39184","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-27T18:16:53.113844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:52.300517Z","time spent":"813.316946ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329979,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-27T18:16:53.279387Z","caller":"traceutil/trace.go:171","msg":"trace[1884547900] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"157.605702ms","start":"2026-02-27T18:16:53.121761Z","end":"2026-02-27T18:16:53.279366Z","steps":["trace[1884547900] 'process raft request' (duration: 157.460199ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.279425Z","caller":"traceutil/trace.go:171","msg":"trace[560647821] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"157.678233ms","start":"2026-02-27T18:16:53.121662Z","end":"2026-02-27T18:16:53.27934Z","steps":["trace[560647821] 'process raft request' (duration: 116.568139ms)","trace[560647821] 'compare' (duration: 40.794987ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:53.279496Z","caller":"traceutil/trace.go:171","msg":"trace[1986030638] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"157.570451ms","start":"2026-02-27T18:16:53.121856Z","end":"2026-02-27T18:16:53.279426Z","steps":["trace[1986030638] 'process raft request' (duration: 157.417758ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.279575Z","caller":"traceutil/trace.go:171","msg":"trace[141934573] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"157.49255ms","start":"2026-02-27T18:16:53.122025Z","end":"2026-02-27T18:16:53.279517Z","steps":["trace[141934573] 'process raft request' (duration: 157.282946ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.279702Z","caller":"traceutil/trace.go:171","msg":"trace[3821702] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"156.004307ms","start":"2026-02-27T18:16:53.123688Z","end":"2026-02-27T18:16:53.279692Z","steps":["trace[3821702] 'process raft request' (duration: 155.714901ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.279812Z","caller":"traceutil/trace.go:171","msg":"trace[413166822] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"157.614402ms","start":"2026-02-27T18:16:53.122191Z","end":"2026-02-27T18:16:53.279805Z","steps":["trace[413166822] 'process raft request' (duration: 157.173003ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.279835Z","caller":"traceutil/trace.go:171","msg":"trace[1797085635] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:485; }","duration":"157.612902ms","start":"2026-02-27T18:16:53.122169Z","end":"2026-02-27T18:16:53.279781Z","steps":["trace[1797085635] 'read index received' (duration: 70.820191ms)","trace[1797085635] 'applied index is now lower than readState.Index' (duration: 86.791451ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:53.280101Z","caller":"traceutil/trace.go:171","msg":"trace[1642431725] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"146.807518ms","start":"2026-02-27T18:16:53.133275Z","end":"2026-02-27T18:16:53.280082Z","steps":["trace[1642431725] 'process raft request' (duration: 146.399038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.280154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.970301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-02-27T18:16:53.280181Z","caller":"traceutil/trace.go:171","msg":"trace[741363998] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:476; }","duration":"158.033132ms","start":"2026-02-27T18:16:53.12214Z","end":"2026-02-27T18:16:53.280173Z","steps":["trace[741363998] 'agreement among raft nodes before linearized reading' (duration: 157.970421ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.280192Z","caller":"traceutil/trace.go:171","msg":"trace[344664263] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"155.385215ms","start":"2026-02-27T18:16:53.124788Z","end":"2026-02-27T18:16:53.280173Z","steps":["trace[344664263] 'process raft request' (duration: 154.74363ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.280206Z","caller":"traceutil/trace.go:171","msg":"trace[1821275598] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"155.325273ms","start":"2026-02-27T18:16:53.124863Z","end":"2026-02-27T18:16:53.280188Z","steps":["trace[1821275598] 'process raft request' (duration: 154.717319ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.280241Z","caller":"traceutil/trace.go:171","msg":"trace[2031118050] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"155.765993ms","start":"2026-02-27T18:16:53.124459Z","end":"2026-02-27T18:16:53.280225Z","steps":["trace[2031118050] 'process raft request' (duration: 154.995126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.288216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.249674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:16:53.288277Z","caller":"traceutil/trace.go:171","msg":"trace[259524277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"162.331765ms","start":"2026-02-27T18:16:53.125928Z","end":"2026-02-27T18:16:53.28826Z","steps":["trace[259524277] 'agreement among raft nodes before linearized reading' (duration: 162.147562ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.437134Z","caller":"traceutil/trace.go:171","msg":"trace[1306290752] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"136.649676ms","start":"2026-02-27T18:16:53.300468Z","end":"2026-02-27T18:16:53.437117Z","steps":["trace[1306290752] 'read index received' (duration: 23.642134ms)","trace[1306290752] 'applied index is now lower than readState.Index' (duration: 113.006622ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:53.437362Z","caller":"traceutil/trace.go:171","msg":"trace[977868578] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"115.049916ms","start":"2026-02-27T18:16:53.322306Z","end":"2026-02-27T18:16:53.437356Z","steps":["trace[977868578] 'process raft request' (duration: 114.765819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:53.437455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.125153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-02-27T18:16:53.437493Z","caller":"traceutil/trace.go:171","msg":"trace[1870550400] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:480; }","duration":"135.186855ms","start":"2026-02-27T18:16:53.302297Z","end":"2026-02-27T18:16:53.437484Z","steps":["trace[1870550400] 'agreement among raft nodes before linearized reading' (duration: 135.100783ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.437152Z","caller":"traceutil/trace.go:171","msg":"trace[21634298] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"138.17746ms","start":"2026-02-27T18:16:53.298956Z","end":"2026-02-27T18:16:53.437133Z","steps":["trace[21634298] 'process raft request' (duration: 100.221643ms)","trace[21634298] 'compare' (duration: 37.785643ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:53.437648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.176578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-02-27T18:16:53.43767Z","caller":"traceutil/trace.go:171","msg":"trace[1465507461] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:480; }","duration":"137.225269ms","start":"2026-02-27T18:16:53.300436Z","end":"2026-02-27T18:16:53.437661Z","steps":["trace[1465507461] 'agreement among raft nodes before linearized reading' (duration: 137.186528ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:53.602714Z","caller":"traceutil/trace.go:171","msg":"trace[525597002] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"151.017999ms","start":"2026-02-27T18:16:53.451671Z","end":"2026-02-27T18:16:53.602689Z","steps":["trace[525597002] 'process raft request' (duration: 136.203586ms)","trace[525597002] 'compare' (duration: 14.584168ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:53.797057Z","caller":"traceutil/trace.go:171","msg":"trace[1875381969] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"192.881071ms","start":"2026-02-27T18:16:53.604141Z","end":"2026-02-27T18:16:53.797022Z","steps":["trace[1875381969] 'read index received' (duration: 154.769511ms)","trace[1875381969] 'applied index is now lower than readState.Index' (duration: 38.11024ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:16:53.797168Z","caller":"traceutil/trace.go:171","msg":"trace[1809623769] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"267.881825ms","start":"2026-02-27T18:16:53.529246Z","end":"2026-02-27T18:16:53.797128Z","steps":["trace[1809623769] 'process raft request' (duration: 229.721413ms)","trace[1809623769] 'compare' (duration: 37.858905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:16:53.797271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.085716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-z2vtt\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-02-27T18:16:53.797309Z","caller":"traceutil/trace.go:171","msg":"trace[2083127647] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-z2vtt; range_end:; response_count:1; response_revision:483; }","duration":"193.201818ms","start":"2026-02-27T18:16:53.604097Z","end":"2026-02-27T18:16:53.797299Z","steps":["trace[2083127647] 'agreement among raft nodes before linearized reading' (duration: 193.045885ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:55.261616Z","caller":"traceutil/trace.go:171","msg":"trace[463339675] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"150.214607ms","start":"2026-02-27T18:16:55.111377Z","end":"2026-02-27T18:16:55.261591Z","steps":["trace[463339675] 'process raft request' (duration: 149.963842ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:57.537613Z","caller":"traceutil/trace.go:171","msg":"trace[2143514036] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"141.046253ms","start":"2026-02-27T18:16:57.396531Z","end":"2026-02-27T18:16:57.537577Z","steps":["trace[2143514036] 'process raft request' (duration: 140.774317ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:57.680054Z","caller":"traceutil/trace.go:171","msg":"trace[816695823] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"282.21803ms","start":"2026-02-27T18:16:57.397808Z","end":"2026-02-27T18:16:57.680026Z","steps":["trace[816695823] 'process raft request' (duration: 282.172279ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:57.680114Z","caller":"traceutil/trace.go:171","msg":"trace[537963546] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"282.65736ms","start":"2026-02-27T18:16:57.397428Z","end":"2026-02-27T18:16:57.680085Z","steps":["trace[537963546] 'process raft request' (duration: 282.340683ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:57.68021Z","caller":"traceutil/trace.go:171","msg":"trace[762951199] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"282.703021ms","start":"2026-02-27T18:16:57.397431Z","end":"2026-02-27T18:16:57.680134Z","steps":["trace[762951199] 'process raft request' (duration: 282.504587ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:59.145863Z","caller":"traceutil/trace.go:171","msg":"trace[1236149081] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"840.077404ms","start":"2026-02-27T18:16:58.305761Z","end":"2026-02-27T18:16:59.145838Z","steps":["trace[1236149081] 'process raft request' (duration: 839.87566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:59.146016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:58.305736Z","time spent":"840.204037ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:16:59.979014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.589148ms","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-27T18:16:59.97909Z","caller":"traceutil/trace.go:171","msg":"trace[2132097765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"436.709451ms","start":"2026-02-27T18:16:59.542363Z","end":"2026-02-27T18:16:59.979072Z","steps":["trace[2132097765] 'range keys from in-memory index tree' (duration: 436.506666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:59.97913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:59.542343Z","time spent":"436.777643ms","remote":"127.0.0.1:39184","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-27T18:16:59.979157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.133212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-27T18:16:59.979157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.169303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T18:16:59.979237Z","caller":"traceutil/trace.go:171","msg":"trace[303411772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"294.271845ms","start":"2026-02-27T18:16:59.684944Z","end":"2026-02-27T18:16:59.979216Z","steps":["trace[303411772] 'range keys from in-memory index tree' (duration: 294.011479ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:16:59.979237Z","caller":"traceutil/trace.go:171","msg":"trace[332415169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:497; }","duration":"294.290515ms","start":"2026-02-27T18:16:59.684933Z","end":"2026-02-27T18:16:59.979224Z","steps":["trace[332415169] 'range keys from in-memory index tree' (duration: 294.03344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:16:59.979233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.841888ms","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-27T18:16:59.979308Z","caller":"traceutil/trace.go:171","msg":"trace[577014797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"295.916779ms","start":"2026-02-27T18:16:59.683378Z","end":"2026-02-27T18:16:59.979294Z","steps":["trace[577014797] 'range keys from in-memory index tree' (duration: 295.778216ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:00.161207Z","caller":"traceutil/trace.go:171","msg":"trace[517256497] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"177.967525ms","start":"2026-02-27T18:16:59.983218Z","end":"2026-02-27T18:17:00.161185Z","steps":["trace[517256497] 'process raft request' (duration: 177.834102ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:00.288849Z","caller":"traceutil/trace.go:171","msg":"trace[268661396] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:519; }","duration":"305.472749ms","start":"2026-02-27T18:16:59.983352Z","end":"2026-02-27T18:17:00.288825Z","steps":["trace[268661396] 'read index received' (duration: 177.819272ms)","trace[268661396] 'applied index is now lower than readState.Index' (duration: 127.651997ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:17:00.28891Z","caller":"traceutil/trace.go:171","msg":"trace[2040139610] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"305.625072ms","start":"2026-02-27T18:16:59.983255Z","end":"2026-02-27T18:17:00.28888Z","steps":["trace[2040139610] 'process raft request' (duration: 305.345427ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:00.288913Z","caller":"traceutil/trace.go:171","msg":"trace[1254267040] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"305.638593ms","start":"2026-02-27T18:16:59.983257Z","end":"2026-02-27T18:17:00.288895Z","steps":["trace[1254267040] 'process raft request' (duration: 305.49234ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:00.288973Z","caller":"traceutil/trace.go:171","msg":"trace[1572057861] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"305.451188ms","start":"2026-02-27T18:16:59.983512Z","end":"2026-02-27T18:17:00.288963Z","steps":["trace[1572057861] 'process raft request' (duration: 305.278725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:00.28907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:59.983238Z","time spent":"305.737444ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:17:00.289081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:59.983238Z","time spent":"305.728904ms","remote":"127.0.0.1:39184","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-27T18:17:00.289121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:59.983505Z","time spent":"305.5053ms","remote":"127.0.0.1:39184","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-27T18:17:00.289299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.94402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T18:17:00.28933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.709097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:17:00.289346Z","caller":"traceutil/trace.go:171","msg":"trace[294633006] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:0; response_revision:501; }","duration":"305.98603ms","start":"2026-02-27T18:16:59.983347Z","end":"2026-02-27T18:17:00.289333Z","steps":["trace[294633006] 'agreement among raft nodes before linearized reading' (duration: 305.902089ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:00.289401Z","caller":"traceutil/trace.go:171","msg":"trace[1836294240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"237.816829ms","start":"2026-02-27T18:17:00.051567Z","end":"2026-02-27T18:17:00.289384Z","steps":["trace[1836294240] 'agreement among raft nodes before linearized reading' (duration: 237.679307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:00.289414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:16:59.983293Z","time spent":"306.106863ms","remote":"127.0.0.1:39022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/configmaps/local-path-storage/local-path-config\" "} {"level":"info","ts":"2026-02-27T18:17:00.751552Z","caller":"traceutil/trace.go:171","msg":"trace[1386207758] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"118.337168ms","start":"2026-02-27T18:17:00.633187Z","end":"2026-02-27T18:17:00.751524Z","steps":["trace[1386207758] 'process raft request' (duration: 95.411461ms)","trace[1386207758] 'compare' (duration: 22.798585ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:17:00.897255Z","caller":"traceutil/trace.go:171","msg":"trace[72900704] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"137.59192ms","start":"2026-02-27T18:17:00.759642Z","end":"2026-02-27T18:17:00.897234Z","steps":["trace[72900704] 'process raft request' (duration: 137.516619ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:00.897281Z","caller":"traceutil/trace.go:171","msg":"trace[2081091661] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"139.437158ms","start":"2026-02-27T18:17:00.75782Z","end":"2026-02-27T18:17:00.897258Z","steps":["trace[2081091661] 'process raft request' (duration: 139.194023ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:01.096032Z","caller":"traceutil/trace.go:171","msg":"trace[1037174870] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"191.732784ms","start":"2026-02-27T18:17:00.904267Z","end":"2026-02-27T18:17:01.096Z","steps":["trace[1037174870] 'process raft request' (duration: 173.587518ms)","trace[1037174870] 'compare' (duration: 18.012242ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:17:01.21073Z","caller":"traceutil/trace.go:171","msg":"trace[1910161260] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:536; }","duration":"248.759421ms","start":"2026-02-27T18:17:00.961936Z","end":"2026-02-27T18:17:01.210695Z","steps":["trace[1910161260] 'read index received' (duration: 116.022716ms)","trace[1910161260] 'applied index is now lower than readState.Index' (duration: 132.735805ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:17:01.210807Z","caller":"traceutil/trace.go:171","msg":"trace[1710354581] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"293.855529ms","start":"2026-02-27T18:17:00.916927Z","end":"2026-02-27T18:17:01.210783Z","steps":["trace[1710354581] 'process raft request' (duration: 289.348978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:01.210955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.999696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-02-27T18:17:01.21098Z","caller":"traceutil/trace.go:171","msg":"trace[2091636676] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:516; }","duration":"249.072148ms","start":"2026-02-27T18:17:00.9619Z","end":"2026-02-27T18:17:01.210972Z","steps":["trace[2091636676] 'agreement among raft nodes before linearized reading' (duration: 248.946586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:01.21107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.938724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:17:01.211185Z","caller":"traceutil/trace.go:171","msg":"trace[546837700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"159.118598ms","start":"2026-02-27T18:17:01.052032Z","end":"2026-02-27T18:17:01.21115Z","steps":["trace[546837700] 'agreement among raft nodes before linearized reading' (duration: 158.892073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:01.211996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.069039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"info","ts":"2026-02-27T18:17:01.212063Z","caller":"traceutil/trace.go:171","msg":"trace[873927803] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:516; }","duration":"109.901884ms","start":"2026-02-27T18:17:01.102142Z","end":"2026-02-27T18:17:01.212044Z","steps":["trace[873927803] 'agreement among raft nodes before linearized reading' (duration: 109.040257ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:01.361764Z","caller":"traceutil/trace.go:171","msg":"trace[1812916311] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"145.674056ms","start":"2026-02-27T18:17:01.216065Z","end":"2026-02-27T18:17:01.361739Z","steps":["trace[1812916311] 'process raft request' (duration: 116.644151ms)","trace[1812916311] 'compare' (duration: 28.825941ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:17:01.361833Z","caller":"traceutil/trace.go:171","msg":"trace[1569084361] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"145.449842ms","start":"2026-02-27T18:17:01.216337Z","end":"2026-02-27T18:17:01.361787Z","steps":["trace[1569084361] 'process raft request' (duration: 145.328789ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:01.361869Z","caller":"traceutil/trace.go:171","msg":"trace[876954046] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"143.9094ms","start":"2026-02-27T18:17:01.217931Z","end":"2026-02-27T18:17:01.36184Z","steps":["trace[876954046] 'process raft request' (duration: 143.774667ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:01.36203Z","caller":"traceutil/trace.go:171","msg":"trace[1408687363] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"142.623294ms","start":"2026-02-27T18:17:01.219397Z","end":"2026-02-27T18:17:01.36202Z","steps":["trace[1408687363] 'process raft request' (duration: 142.362318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:01.607347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.737895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:17:01.607487Z","caller":"traceutil/trace.go:171","msg":"trace[1592818606] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"238.733012ms","start":"2026-02-27T18:17:01.368733Z","end":"2026-02-27T18:17:01.607466Z","steps":["trace[1592818606] 'read index received' (duration: 112.754392ms)","trace[1592818606] 'applied index is now lower than readState.Index' (duration: 125.97718ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:17:01.607656Z","caller":"traceutil/trace.go:171","msg":"trace[1474640420] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"321.116912ms","start":"2026-02-27T18:17:01.286489Z","end":"2026-02-27T18:17:01.607605Z","steps":["trace[1474640420] 'process raft request' (duration: 194.999399ms)","trace[1474640420] 'compare' (duration: 125.570882ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:17:01.607785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.059668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3696"} {"level":"info","ts":"2026-02-27T18:17:01.607829Z","caller":"traceutil/trace.go:171","msg":"trace[387164154] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:522; }","duration":"239.14801ms","start":"2026-02-27T18:17:01.368669Z","end":"2026-02-27T18:17:01.607817Z","steps":["trace[387164154] 'agreement among raft nodes before linearized reading' (duration: 238.850714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:01.607907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:01.286466Z","time spent":"321.301895ms","remote":"127.0.0.1:39362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3249,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:17:08.236903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.486789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l2xbt\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-27T18:17:08.237Z","caller":"traceutil/trace.go:171","msg":"trace[2104838478] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l2xbt; range_end:; response_count:1; response_revision:540; }","duration":"289.644792ms","start":"2026-02-27T18:17:07.947333Z","end":"2026-02-27T18:17:08.236978Z","steps":["trace[2104838478] 'range keys from in-memory index tree' (duration: 289.343877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:08.23713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.793251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:17:08.237196Z","caller":"traceutil/trace.go:171","msg":"trace[1242569200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"184.863322ms","start":"2026-02-27T18:17:08.052314Z","end":"2026-02-27T18:17:08.237177Z","steps":["trace[1242569200] 'range keys from in-memory index tree' (duration: 184.72904ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:09.048886Z","caller":"traceutil/trace.go:171","msg":"trace[1267980382] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"671.246973ms","start":"2026-02-27T18:17:08.377614Z","end":"2026-02-27T18:17:09.048861Z","steps":["trace[1267980382] 'process raft request' (duration: 671.196292ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:09.048916Z","caller":"traceutil/trace.go:171","msg":"trace[1600372917] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"672.572638ms","start":"2026-02-27T18:17:08.376312Z","end":"2026-02-27T18:17:09.048885Z","steps":["trace[1600372917] 'process raft request' (duration: 653.843798ms)","trace[1600372917] 'compare' (duration: 18.520656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:17:09.049065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:08.377602Z","time spent":"671.376336ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:17:09.049132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:08.376282Z","time spent":"672.775413ms","remote":"127.0.0.1:39184","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-27T18:17:10.55482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":991527080337900480,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T18:17:10.687179Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.216802836s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T18:17:10.687504Z","caller":"traceutil/trace.go:171","msg":"trace[812777616] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"633.448214ms","start":"2026-02-27T18:17:10.054035Z","end":"2026-02-27T18:17:10.687484Z","steps":["trace[812777616] 'read index received' (duration: 633.2229ms)","trace[812777616] 'applied index is now lower than readState.Index' (duration: 224.264µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:17:10.687615Z","caller":"traceutil/trace.go:171","msg":"trace[237233614] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.217304585s","start":"2026-02-27T18:17:09.470298Z","end":"2026-02-27T18:17:10.687603Z","steps":["trace[237233614] 'process raft request' (duration: 1.217069681s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:10.687733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:09.470279Z","time spent":"1.217368617s","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T18:17:10.687873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.853122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T18:17:10.687909Z","caller":"traceutil/trace.go:171","msg":"trace[43187198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"633.929653ms","start":"2026-02-27T18:17:10.05397Z","end":"2026-02-27T18:17:10.6879Z","steps":["trace[43187198] 'agreement among raft nodes before linearized reading' (duration: 633.876412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:10.68794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:10.053933Z","time spent":"633.997895ms","remote":"127.0.0.1:38884","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-27T18:17:10.688204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.019182ms","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-27T18:17:10.688201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.444136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-27T18:17:10.688241Z","caller":"traceutil/trace.go:171","msg":"trace[1953088005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"283.091052ms","start":"2026-02-27T18:17:10.40514Z","end":"2026-02-27T18:17:10.688231Z","steps":["trace[1953088005] 'agreement among raft nodes before linearized reading' (duration: 282.95063ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T18:17:10.688262Z","caller":"traceutil/trace.go:171","msg":"trace[1587054391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"410.578819ms","start":"2026-02-27T18:17:10.277667Z","end":"2026-02-27T18:17:10.688246Z","steps":["trace[1587054391] 'agreement among raft nodes before linearized reading' (duration: 410.418846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:10.68831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:10.277644Z","time spent":"410.65864ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T18:17:11.390813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.834907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T18:17:11.391337Z","caller":"traceutil/trace.go:171","msg":"trace[1648810400] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"684.766353ms","start":"2026-02-27T18:17:10.706482Z","end":"2026-02-27T18:17:11.391248Z","steps":["trace[1648810400] 'process raft request' (duration: 479.393185ms)","trace[1648810400] 'compare' (duration: 204.517521ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T18:17:11.391341Z","caller":"traceutil/trace.go:171","msg":"trace[608327591] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"684.62069ms","start":"2026-02-27T18:17:10.706692Z","end":"2026-02-27T18:17:11.391313Z","steps":["trace[608327591] 'process raft request' (duration: 684.202242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:11.391398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:10.706464Z","time spent":"684.907115ms","remote":"127.0.0.1:39184","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-27T18:17:11.391512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:10.706635Z","time spent":"684.777573ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T18:17:11.399859Z","caller":"traceutil/trace.go:171","msg":"trace[1681326671] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:569; }","duration":"344.979695ms","start":"2026-02-27T18:17:11.054867Z","end":"2026-02-27T18:17:11.399847Z","steps":["trace[1681326671] 'read index received' (duration: 131.012969ms)","trace[1681326671] 'applied index is now lower than readState.Index' (duration: 213.965636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T18:17:11.400068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.206929ms","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-27T18:17:11.400122Z","caller":"traceutil/trace.go:171","msg":"trace[1539991979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:548; }","duration":"345.306251ms","start":"2026-02-27T18:17:11.054808Z","end":"2026-02-27T18:17:11.400115Z","steps":["trace[1539991979] 'agreement among raft nodes before linearized reading' (duration: 345.115928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:11.400144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:11.054781Z","time spent":"345.358121ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T18:17:11.400194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.874233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T18:17:11.400267Z","caller":"traceutil/trace.go:171","msg":"trace[1708870650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"345.002595ms","start":"2026-02-27T18:17:11.055247Z","end":"2026-02-27T18:17:11.40025Z","steps":["trace[1708870650] 'agreement among raft nodes before linearized reading' (duration: 344.760181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T18:17:11.400307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T18:17:11.055222Z","time spent":"345.076126ms","remote":"127.0.0.1:39184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T18:17:13.373217Z","caller":"traceutil/trace.go:171","msg":"trace[1213464458] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"128.710246ms","start":"2026-02-27T18:17:13.244487Z","end":"2026-02-27T18:17:13.373197Z","steps":["trace[1213464458] 'process raft request' (duration: 128.587464ms)"],"step_count":1}