{"level":"warn","ts":"2026-02-27T20:25:11.4517Z","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-27T20:25:11.451786Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-27T20:25:11.451856Z","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-27T20:25:11.451869Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-27T20:25:11.451896Z","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-27T20:25:11.452547Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-27T20:25:11.452627Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-27T20:25:11.458146Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.324925ms"} {"level":"info","ts":"2026-02-27T20:25:11.465255Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-27T20:25:11.465385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T20:25:11.465488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-27T20:25:11.466134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T20:25:11.466158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-27T20:25:11.466293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-27T20:25:11.473417Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T20:25:11.474598Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T20:25:11.476196Z","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-27T20:25:11.477957Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T20:25:11.478204Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T20:25:11.4782Z","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-27T20:25:11.478987Z","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-27T20:25:11.479037Z","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-27T20:25:11.479885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-27T20:25:11.480048Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-27T20:25:11.48285Z","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-27T20:25:11.483007Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-27T20:25:11.483082Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-27T20:25:11.48319Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T20:25:11.483251Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T20:25:12.367288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T20:25:12.367344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T20:25:12.367379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-27T20:25:12.367397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-27T20:25:12.367408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-27T20:25:12.36743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-27T20:25:12.367444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-27T20:25:12.368315Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:25:12.368312Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:25:12.368363Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T20:25:12.368382Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:25:12.368816Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T20:25:12.368851Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T20:25:12.369054Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:25:12.369205Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:25:12.369259Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:25:12.372217Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T20:25:12.372367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-27T20:25:22.11869Z","caller":"traceutil/trace.go:171","msg":"trace[1383308897] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"154.789349ms","start":"2026-02-27T20:25:21.963875Z","end":"2026-02-27T20:25:22.118665Z","steps":["trace[1383308897] 'read index received' (duration: 153.915871ms)","trace[1383308897] 'applied index is now lower than readState.Index' (duration: 872.258µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:22.118947Z","caller":"traceutil/trace.go:171","msg":"trace[722597479] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"199.527532ms","start":"2026-02-27T20:25:21.919407Z","end":"2026-02-27T20:25:22.118935Z","steps":["trace[722597479] 'process raft request' (duration: 198.452349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:22.119185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.292841ms","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-27T20:25:22.119249Z","caller":"traceutil/trace.go:171","msg":"trace[569028723] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:352; }","duration":"155.413893ms","start":"2026-02-27T20:25:21.963826Z","end":"2026-02-27T20:25:22.11924Z","steps":["trace[569028723] 'agreement among raft nodes before linearized reading' (duration: 155.28014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:22.119318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.483357ms","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-27T20:25:22.119365Z","caller":"traceutil/trace.go:171","msg":"trace[169954180] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:352; }","duration":"130.532578ms","start":"2026-02-27T20:25:21.988827Z","end":"2026-02-27T20:25:22.11936Z","steps":["trace[169954180] 'agreement among raft nodes before linearized reading' (duration: 130.477107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:22.119711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.155844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-27T20:25:22.119739Z","caller":"traceutil/trace.go:171","msg":"trace[232371986] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:352; }","duration":"106.212055ms","start":"2026-02-27T20:25:22.013521Z","end":"2026-02-27T20:25:22.119733Z","steps":["trace[232371986] 'agreement among raft nodes before linearized reading' (duration: 106.170144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:27.759609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.444431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-02-27T20:25:27.759785Z","caller":"traceutil/trace.go:171","msg":"trace[1992351650] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:412; }","duration":"409.698268ms","start":"2026-02-27T20:25:27.350067Z","end":"2026-02-27T20:25:27.759765Z","steps":["trace[1992351650] 'range keys from in-memory index tree' (duration: 409.287977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:27.75983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:27.35003Z","time spent":"409.79098ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4396,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-27T20:25:27.759686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.565865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4791"} {"level":"info","ts":"2026-02-27T20:25:27.76017Z","caller":"traceutil/trace.go:171","msg":"trace[91212938] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:412; }","duration":"570.089518ms","start":"2026-02-27T20:25:27.189994Z","end":"2026-02-27T20:25:27.760084Z","steps":["trace[91212938] 'range keys from in-memory index tree' (duration: 569.433401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:27.760416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:27.18997Z","time spent":"570.316454ms","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4813,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-02-27T20:25:27.781926Z","caller":"traceutil/trace.go:171","msg":"trace[1139690311] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"285.65799ms","start":"2026-02-27T20:25:27.496245Z","end":"2026-02-27T20:25:27.781903Z","steps":["trace[1139690311] 'process raft request' (duration: 285.531917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:28.651175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433545642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:25:29.006073Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.22391052s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:25:29.0068Z","caller":"traceutil/trace.go:171","msg":"trace[1582290528] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"856.076307ms","start":"2026-02-27T20:25:28.150676Z","end":"2026-02-27T20:25:29.006752Z","steps":["trace[1582290528] 'read index received' (duration: 855.787959ms)","trace[1582290528] 'applied index is now lower than readState.Index' (duration: 286.508µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:29.006919Z","caller":"traceutil/trace.go:171","msg":"trace[408240695] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.236830035s","start":"2026-02-27T20:25:27.770041Z","end":"2026-02-27T20:25:29.006871Z","steps":["trace[408240695] 'process raft request' (duration: 1.236451425s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:29.00708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.331231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T20:25:29.00715Z","caller":"traceutil/trace.go:171","msg":"trace[245567980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"805.449374ms","start":"2026-02-27T20:25:28.201668Z","end":"2026-02-27T20:25:29.007117Z","steps":["trace[245567980] 'agreement among raft nodes before linearized reading' (duration: 805.30622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:29.00713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.447865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-02-27T20:25:29.0072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:28.201654Z","time spent":"805.535846ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-27T20:25:29.007237Z","caller":"traceutil/trace.go:171","msg":"trace[38878186] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:414; }","duration":"270.545188ms","start":"2026-02-27T20:25:28.736666Z","end":"2026-02-27T20:25:29.007211Z","steps":["trace[38878186] 'agreement among raft nodes before linearized reading' (duration: 270.394204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:29.00713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.424046ms","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-27T20:25:29.007356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.449133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:25:29.007364Z","caller":"traceutil/trace.go:171","msg":"trace[1931957919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"856.665253ms","start":"2026-02-27T20:25:28.150662Z","end":"2026-02-27T20:25:29.007328Z","steps":["trace[1931957919] 'agreement among raft nodes before linearized reading' (duration: 856.301993ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:29.007418Z","caller":"traceutil/trace.go:171","msg":"trace[2125859519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"788.586436ms","start":"2026-02-27T20:25:28.218817Z","end":"2026-02-27T20:25:29.007403Z","steps":["trace[2125859519] 'agreement among raft nodes before linearized reading' (duration: 788.277349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:29.007456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:28.150605Z","time spent":"856.820576ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:25:29.007482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:28.218802Z","time spent":"788.666698ms","remote":"127.0.0.1:52086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:25:29.008056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:27.769995Z","time spent":"1.237009789s","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:25:29.212751Z","caller":"traceutil/trace.go:171","msg":"trace[810428890] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"200.960723ms","start":"2026-02-27T20:25:29.011752Z","end":"2026-02-27T20:25:29.212712Z","steps":["trace[810428890] 'process raft request' (duration: 188.756556ms)","trace[810428890] 'compare' (duration: 12.004662ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:29.212804Z","caller":"traceutil/trace.go:171","msg":"trace[975825544] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:432; }","duration":"179.6424ms","start":"2026-02-27T20:25:29.033138Z","end":"2026-02-27T20:25:29.21278Z","steps":["trace[975825544] 'read index received' (duration: 167.482004ms)","trace[975825544] 'applied index is now lower than readState.Index' (duration: 12.159336ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:29.212803Z","caller":"traceutil/trace.go:171","msg":"trace[2028119643] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"199.395203ms","start":"2026-02-27T20:25:29.013385Z","end":"2026-02-27T20:25:29.21278Z","steps":["trace[2028119643] 'process raft request' (duration: 199.310981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:29.212966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.814625ms","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-27T20:25:29.212942Z","caller":"traceutil/trace.go:171","msg":"trace[242706021] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"188.902831ms","start":"2026-02-27T20:25:29.023985Z","end":"2026-02-27T20:25:29.212887Z","steps":["trace[242706021] 'process raft request' (duration: 188.746777ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:29.21301Z","caller":"traceutil/trace.go:171","msg":"trace[479697408] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"179.929717ms","start":"2026-02-27T20:25:29.033069Z","end":"2026-02-27T20:25:29.212998Z","steps":["trace[479697408] 'agreement among raft nodes before linearized reading' (duration: 179.796404ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:29.213065Z","caller":"traceutil/trace.go:171","msg":"trace[2106306525] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"199.69547ms","start":"2026-02-27T20:25:29.013347Z","end":"2026-02-27T20:25:29.213042Z","steps":["trace[2106306525] 'process raft request' (duration: 199.29644ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:37.461781Z","caller":"traceutil/trace.go:171","msg":"trace[230316453] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"157.199098ms","start":"2026-02-27T20:25:37.304553Z","end":"2026-02-27T20:25:37.461752Z","steps":["trace[230316453] 'process raft request' (duration: 147.445255ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:37.461818Z","caller":"traceutil/trace.go:171","msg":"trace[802214157] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"157.144326ms","start":"2026-02-27T20:25:37.304637Z","end":"2026-02-27T20:25:37.461781Z","steps":["trace[802214157] 'process raft request' (duration: 157.003643ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:37.461786Z","caller":"traceutil/trace.go:171","msg":"trace[1000230666] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"156.867739ms","start":"2026-02-27T20:25:37.304891Z","end":"2026-02-27T20:25:37.461759Z","steps":["trace[1000230666] 'process raft request' (duration: 156.817958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:49.843542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433545871,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T20:25:50.067579Z","caller":"traceutil/trace.go:171","msg":"trace[412106839] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"725.054129ms","start":"2026-02-27T20:25:49.342494Z","end":"2026-02-27T20:25:50.067548Z","steps":["trace[412106839] 'read index received' (duration: 724.67126ms)","trace[412106839] 'applied index is now lower than readState.Index' (duration: 381.219µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:50.067695Z","caller":"traceutil/trace.go:171","msg":"trace[1764793361] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"758.858545ms","start":"2026-02-27T20:25:49.308805Z","end":"2026-02-27T20:25:50.067663Z","steps":["trace[1764793361] 'process raft request' (duration: 758.450555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:50.067873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:49.308782Z","time spent":"759.00367ms","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:25:50.067856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.342476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T20:25:50.06794Z","caller":"traceutil/trace.go:171","msg":"trace[1194181611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"725.47989ms","start":"2026-02-27T20:25:49.342438Z","end":"2026-02-27T20:25:50.067918Z","steps":["trace[1194181611] 'agreement among raft nodes before linearized reading' (duration: 725.252324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:50.067858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.096696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-27T20:25:50.067957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.41632ms","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-27T20:25:50.067984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:49.342417Z","time spent":"725.559052ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T20:25:50.068006Z","caller":"traceutil/trace.go:171","msg":"trace[391668674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"551.294881ms","start":"2026-02-27T20:25:49.516691Z","end":"2026-02-27T20:25:50.067986Z","steps":["trace[391668674] 'agreement among raft nodes before linearized reading' (duration: 551.023634ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:50.06801Z","caller":"traceutil/trace.go:171","msg":"trace[1571679663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"547.490912ms","start":"2026-02-27T20:25:49.520506Z","end":"2026-02-27T20:25:50.067997Z","steps":["trace[1571679663] 'agreement among raft nodes before linearized reading' (duration: 547.331938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:50.068052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:49.520494Z","time spent":"547.547664ms","remote":"127.0.0.1:52354","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-27T20:25:50.068054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:49.516671Z","time spent":"551.370602ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:25:50.068082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.635912ms","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-27T20:25:50.068132Z","caller":"traceutil/trace.go:171","msg":"trace[1337879787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"548.768076ms","start":"2026-02-27T20:25:49.51935Z","end":"2026-02-27T20:25:50.068119Z","steps":["trace[1337879787] 'agreement among raft nodes before linearized reading' (duration: 548.564661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:50.068164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:49.519331Z","time spent":"548.824907ms","remote":"127.0.0.1:52354","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-27T20:25:50.717438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433545874,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:25:51.117964Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.016186401s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:25:51.605339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.918331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:25:51.605639Z","caller":"traceutil/trace.go:171","msg":"trace[1083350899] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.532947056s","start":"2026-02-27T20:25:50.072664Z","end":"2026-02-27T20:25:51.605611Z","steps":["trace[1083350899] 'process raft request' (duration: 1.045513522s)","trace[1083350899] 'compare' (duration: 486.682815ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:25:51.605666Z","caller":"traceutil/trace.go:171","msg":"trace[1714975265] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.531834837s","start":"2026-02-27T20:25:50.073803Z","end":"2026-02-27T20:25:51.605638Z","steps":["trace[1714975265] 'process raft request' (duration: 1.531599941s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:25:51.605688Z","caller":"traceutil/trace.go:171","msg":"trace[1143665235] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.531723135s","start":"2026-02-27T20:25:50.073944Z","end":"2026-02-27T20:25:51.605667Z","steps":["trace[1143665235] 'process raft request' (duration: 1.531518169s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:51.60577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:50.072636Z","time spent":"1.533068219s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:25:51.60581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:50.073929Z","time spent":"1.531826517s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:25:51.605925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:50.073789Z","time spent":"1.53193867s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:25:51.704353Z","caller":"traceutil/trace.go:171","msg":"trace[1826541432] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:553; }","duration":"1.48723932s","start":"2026-02-27T20:25:50.217079Z","end":"2026-02-27T20:25:51.704319Z","steps":["trace[1826541432] 'read index received' (duration: 901.096965ms)","trace[1826541432] 'applied index is now lower than readState.Index' (duration: 586.140465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:25:51.704513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487409225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:25:51.704546Z","caller":"traceutil/trace.go:171","msg":"trace[1401132012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"1.487499297s","start":"2026-02-27T20:25:50.217037Z","end":"2026-02-27T20:25:51.704536Z","steps":["trace[1401132012] 'agreement among raft nodes before linearized reading' (duration: 1.487405834s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:25:51.70458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:25:50.217017Z","time spent":"1.487556049s","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:26:04.889291Z","caller":"traceutil/trace.go:171","msg":"trace[153873417] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"108.141276ms","start":"2026-02-27T20:26:04.78113Z","end":"2026-02-27T20:26:04.889271Z","steps":["trace[153873417] 'process raft request' (duration: 108.026833ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:10.892221Z","caller":"traceutil/trace.go:171","msg":"trace[138009176] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"685.848662ms","start":"2026-02-27T20:26:10.206353Z","end":"2026-02-27T20:26:10.892202Z","steps":["trace[138009176] 'process raft request' (duration: 685.721898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:10.892349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:10.206342Z","time spent":"685.949024ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:26:11.556551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.933447ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-27T20:26:11.556648Z","caller":"traceutil/trace.go:171","msg":"trace[298291336] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:741; }","duration":"907.020499ms","start":"2026-02-27T20:26:10.649612Z","end":"2026-02-27T20:26:11.556632Z","steps":["trace[298291336] 'read index received' (duration: 242.532762ms)","trace[298291336] 'applied index is now lower than readState.Index' (duration: 664.486577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:26:11.55688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.932937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-27T20:26:11.556911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.301196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-64xgw\" ","response":"range_response_count:1 size:18709"} {"level":"info","ts":"2026-02-27T20:26:11.556921Z","caller":"traceutil/trace.go:171","msg":"trace[495415672] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:712; }","duration":"397.016339ms","start":"2026-02-27T20:26:11.159892Z","end":"2026-02-27T20:26:11.556909Z","steps":["trace[495415672] 'agreement among raft nodes before linearized reading' (duration: 396.860875ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:11.556942Z","caller":"traceutil/trace.go:171","msg":"trace[1768195990] range","detail":"{range_begin:/registry/pods/kube-system/cilium-64xgw; range_end:; response_count:1; response_revision:712; }","duration":"907.368688ms","start":"2026-02-27T20:26:10.649564Z","end":"2026-02-27T20:26:11.556933Z","steps":["trace[1768195990] 'agreement among raft nodes before linearized reading' (duration: 907.248215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:11.556957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:11.159872Z","time spent":"397.07744ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:26:11.556969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:10.649546Z","time spent":"907.417169ms","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18731,"request content":"key:\"/registry/pods/kube-system/cilium-64xgw\" "} {"level":"warn","ts":"2026-02-27T20:26:11.557076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.432059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:26:11.557149Z","caller":"traceutil/trace.go:171","msg":"trace[1570738848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"338.545082ms","start":"2026-02-27T20:26:11.218586Z","end":"2026-02-27T20:26:11.557131Z","steps":["trace[1570738848] 'agreement among raft nodes before linearized reading' (duration: 338.422869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:11.55719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:11.218569Z","time spent":"338.613743ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:26:11.688966Z","caller":"traceutil/trace.go:171","msg":"trace[678292833] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"126.900961ms","start":"2026-02-27T20:26:11.56204Z","end":"2026-02-27T20:26:11.688941Z","steps":["trace[678292833] 'process raft request' (duration: 126.741217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:12.191816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433546228,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T20:26:12.240612Z","caller":"traceutil/trace.go:171","msg":"trace[277380547] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"548.957331ms","start":"2026-02-27T20:26:11.69163Z","end":"2026-02-27T20:26:12.240588Z","steps":["trace[277380547] 'read index received' (duration: 548.740105ms)","trace[277380547] 'applied index is now lower than readState.Index' (duration: 216.386µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:26:12.240948Z","caller":"traceutil/trace.go:171","msg":"trace[1611140354] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"670.034652ms","start":"2026-02-27T20:26:11.5709Z","end":"2026-02-27T20:26:12.240935Z","steps":["trace[1611140354] 'process raft request' (duration: 669.486498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:12.241073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:11.570883Z","time spent":"670.109973ms","remote":"127.0.0.1:52256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18506,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:26:12.241261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.641688ms","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-27T20:26:12.241294Z","caller":"traceutil/trace.go:171","msg":"trace[1326303873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"549.715179ms","start":"2026-02-27T20:26:11.69157Z","end":"2026-02-27T20:26:12.241285Z","steps":["trace[1326303873] 'agreement among raft nodes before linearized reading' (duration: 549.607017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:12.241321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:11.691548Z","time spent":"549.767131ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:26:12.241518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.632292ms","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-27T20:26:12.24156Z","caller":"traceutil/trace.go:171","msg":"trace[1389646544] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"548.694524ms","start":"2026-02-27T20:26:11.692847Z","end":"2026-02-27T20:26:12.241542Z","steps":["trace[1389646544] 'agreement among raft nodes before linearized reading' (duration: 548.611982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:12.241586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:11.692831Z","time spent":"548.748515ms","remote":"127.0.0.1:52354","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-27T20:26:12.241761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.403294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T20:26:12.241786Z","caller":"traceutil/trace.go:171","msg":"trace[696319291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"546.450045ms","start":"2026-02-27T20:26:11.695329Z","end":"2026-02-27T20:26:12.241779Z","steps":["trace[696319291] 'agreement among raft nodes before linearized reading' (duration: 546.380503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:12.241811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:11.695316Z","time spent":"546.489467ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:26:13.396303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433546232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:26:13.896877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433546232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:26:14.271508Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.96756105s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:26:14.286825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.811793ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:26:14.286948Z","caller":"traceutil/trace.go:171","msg":"trace[1656825519] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:719; }","duration":"384.949406ms","start":"2026-02-27T20:26:13.90198Z","end":"2026-02-27T20:26:14.28693Z","steps":["trace[1656825519] 'range keys from in-memory index tree' (duration: 384.797403ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:14.28714Z","caller":"traceutil/trace.go:171","msg":"trace[1037200433] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"1.985063424s","start":"2026-02-27T20:26:12.302063Z","end":"2026-02-27T20:26:14.287126Z","steps":["trace[1037200433] 'process raft request' (duration: 1.969618413s)","trace[1037200433] 'compare' (duration: 14.727423ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:26:14.287228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:12.302033Z","time spent":"1.985136497s","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4793,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:26:14.287381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391542408s","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-27T20:26:14.287467Z","caller":"traceutil/trace.go:171","msg":"trace[1997618193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"1.391688511s","start":"2026-02-27T20:26:12.89575Z","end":"2026-02-27T20:26:14.287439Z","steps":["trace[1997618193] 'agreement among raft nodes before linearized reading' (duration: 1.391467546s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:14.287517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:12.895729Z","time spent":"1.391775784s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:26:14.289414Z","caller":"traceutil/trace.go:171","msg":"trace[1024285487] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"1.391327362s","start":"2026-02-27T20:26:12.8958Z","end":"2026-02-27T20:26:14.287127Z","steps":["trace[1024285487] 'read index received' (duration: 1.37581733s)","trace[1024285487] 'applied index is now lower than readState.Index' (duration: 15.508542ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:26:14.289601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071934927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:26:14.289631Z","caller":"traceutil/trace.go:171","msg":"trace[1641278300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"1.072014739s","start":"2026-02-27T20:26:13.217603Z","end":"2026-02-27T20:26:14.289617Z","steps":["trace[1641278300] 'agreement among raft nodes before linearized reading' (duration: 1.071938457s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:14.289658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:13.21758Z","time spent":"1.072072101s","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:26:14.289884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.482017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T20:26:14.289966Z","caller":"traceutil/trace.go:171","msg":"trace[1453056335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"595.5951ms","start":"2026-02-27T20:26:13.694346Z","end":"2026-02-27T20:26:14.289941Z","steps":["trace[1453056335] 'agreement among raft nodes before linearized reading' (duration: 595.398805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:14.290061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:13.694327Z","time spent":"595.719564ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:26:35.3252Z","caller":"traceutil/trace.go:171","msg":"trace[1882903502] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:824; }","duration":"108.413841ms","start":"2026-02-27T20:26:35.216757Z","end":"2026-02-27T20:26:35.325171Z","steps":["trace[1882903502] 'read index received' (duration: 108.403091ms)","trace[1882903502] 'applied index is now lower than readState.Index' (duration: 8.65µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:26:35.325342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.578555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:26:35.32542Z","caller":"traceutil/trace.go:171","msg":"trace[308072762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"108.710068ms","start":"2026-02-27T20:26:35.216688Z","end":"2026-02-27T20:26:35.325398Z","steps":["trace[308072762] 'agreement among raft nodes before linearized reading' (duration: 108.577965ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:35.41521Z","caller":"traceutil/trace.go:171","msg":"trace[410198792] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"157.28586ms","start":"2026-02-27T20:26:35.257897Z","end":"2026-02-27T20:26:35.415183Z","steps":["trace[410198792] 'process raft request' (duration: 126.833884ms)","trace[410198792] 'compare' (duration: 30.248632ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:26:36.639483Z","caller":"traceutil/trace.go:171","msg":"trace[1116149178] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"132.690782ms","start":"2026-02-27T20:26:36.506765Z","end":"2026-02-27T20:26:36.639456Z","steps":["trace[1116149178] 'read index received' (duration: 70.412431ms)","trace[1116149178] 'applied index is now lower than readState.Index' (duration: 62.277181ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:26:36.639515Z","caller":"traceutil/trace.go:171","msg":"trace[1107867404] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"173.386152ms","start":"2026-02-27T20:26:36.466093Z","end":"2026-02-27T20:26:36.63948Z","steps":["trace[1107867404] 'process raft request' (duration: 111.12473ms)","trace[1107867404] 'compare' (duration: 62.131109ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:26:36.639721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.943709ms","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-27T20:26:36.639762Z","caller":"traceutil/trace.go:171","msg":"trace[710763874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"133.04082ms","start":"2026-02-27T20:26:36.506711Z","end":"2026-02-27T20:26:36.639751Z","steps":["trace[710763874] 'agreement among raft nodes before linearized reading' (duration: 132.895527ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:37.142488Z","caller":"traceutil/trace.go:171","msg":"trace[1844279860] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:828; }","duration":"483.933743ms","start":"2026-02-27T20:26:36.65853Z","end":"2026-02-27T20:26:37.142464Z","steps":["trace[1844279860] 'read index received' (duration: 7.301549ms)","trace[1844279860] 'applied index is now lower than readState.Index' (duration: 476.631244ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:26:37.142623Z","caller":"traceutil/trace.go:171","msg":"trace[794466780] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"498.663596ms","start":"2026-02-27T20:26:36.64392Z","end":"2026-02-27T20:26:37.142584Z","steps":["trace[794466780] 'process raft request' (duration: 498.409231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:37.14272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.133747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:26:37.142787Z","caller":"traceutil/trace.go:171","msg":"trace[665895767] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:795; }","duration":"484.25988ms","start":"2026-02-27T20:26:36.658516Z","end":"2026-02-27T20:26:37.142776Z","steps":["trace[665895767] 'agreement among raft nodes before linearized reading' (duration: 484.035265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:37.142851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:36.643907Z","time spent":"498.8173ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:26:37.142863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:36.65848Z","time spent":"484.369114ms","remote":"127.0.0.1:52162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":12,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:26:37.342998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.302691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:26:37.343096Z","caller":"traceutil/trace.go:171","msg":"trace[104727661] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"125.422284ms","start":"2026-02-27T20:26:37.217655Z","end":"2026-02-27T20:26:37.343077Z","steps":["trace[104727661] 'range keys from in-memory index tree' (duration: 125.218029ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:49.276434Z","caller":"traceutil/trace.go:171","msg":"trace[787863829] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:917; }","duration":"394.734754ms","start":"2026-02-27T20:26:48.881679Z","end":"2026-02-27T20:26:49.276413Z","steps":["trace[787863829] 'read index received' (duration: 16.550702ms)","trace[787863829] 'applied index is now lower than readState.Index' (duration: 378.183002ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:26:49.27662Z","caller":"traceutil/trace.go:171","msg":"trace[1965140501] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"407.208216ms","start":"2026-02-27T20:26:48.869403Z","end":"2026-02-27T20:26:49.276612Z","steps":["trace[1965140501] 'process raft request' (duration: 329.868654ms)","trace[1965140501] 'compare' (duration: 76.900113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:26:49.276697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:48.869388Z","time spent":"407.248137ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:26:49.276853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.860995ms","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-27T20:26:49.276905Z","caller":"traceutil/trace.go:171","msg":"trace[1944339041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"393.927136ms","start":"2026-02-27T20:26:48.882965Z","end":"2026-02-27T20:26:49.276892Z","steps":["trace[1944339041] 'agreement among raft nodes before linearized reading' (duration: 393.782593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:49.276907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.195394ms","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-27T20:26:49.276936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:48.882958Z","time spent":"393.971527ms","remote":"127.0.0.1:52354","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":"info","ts":"2026-02-27T20:26:49.276978Z","caller":"traceutil/trace.go:171","msg":"trace[983140962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:879; }","duration":"395.329437ms","start":"2026-02-27T20:26:48.881629Z","end":"2026-02-27T20:26:49.276959Z","steps":["trace[983140962] 'agreement among raft nodes before linearized reading' (duration: 395.122572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:49.277019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:48.881617Z","time spent":"395.393408ms","remote":"127.0.0.1:52354","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-27T20:26:51.402681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433546645,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T20:26:51.496865Z","caller":"traceutil/trace.go:171","msg":"trace[303335014] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"980.427903ms","start":"2026-02-27T20:26:50.516418Z","end":"2026-02-27T20:26:51.496846Z","steps":["trace[303335014] 'process raft request' (duration: 980.302591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:51.496982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:50.516393Z","time spent":"980.531686ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:26:52.938891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.593247ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-27T20:26:52.939021Z","caller":"traceutil/trace.go:171","msg":"trace[1117212398] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:922; }","duration":"2.036808898s","start":"2026-02-27T20:26:50.902192Z","end":"2026-02-27T20:26:52.939001Z","steps":["trace[1117212398] 'read index received' (duration: 594.561223ms)","trace[1117212398] 'applied index is now lower than readState.Index' (duration: 1.442246145s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:26:52.93927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.037056544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T20:26:52.939318Z","caller":"traceutil/trace.go:171","msg":"trace[1782071232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"2.037116835s","start":"2026-02-27T20:26:50.902188Z","end":"2026-02-27T20:26:52.939305Z","steps":["trace[1782071232] 'agreement among raft nodes before linearized reading' (duration: 2.036927561s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:52.939355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:50.902139Z","time spent":"2.037208407s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T20:26:52.939802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72183185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:26:52.939829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644681343s","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-27T20:26:52.939856Z","caller":"traceutil/trace.go:171","msg":"trace[265340332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"1.721918681s","start":"2026-02-27T20:26:51.217924Z","end":"2026-02-27T20:26:52.939843Z","steps":["trace[265340332] 'agreement among raft nodes before linearized reading' (duration: 1.721824789s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:26:52.939865Z","caller":"traceutil/trace.go:171","msg":"trace[562676696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"1.644738654s","start":"2026-02-27T20:26:51.295116Z","end":"2026-02-27T20:26:52.939855Z","steps":["trace[562676696] 'agreement among raft nodes before linearized reading' (duration: 1.644640602s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:52.93988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:51.217911Z","time spent":"1.721965582s","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:26:52.939893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:51.295106Z","time spent":"1.644781435s","remote":"127.0.0.1:52354","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-27T20:26:52.940034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644834346s","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-27T20:26:52.940435Z","caller":"traceutil/trace.go:171","msg":"trace[1797644449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"1.645250575s","start":"2026-02-27T20:26:51.295156Z","end":"2026-02-27T20:26:52.940407Z","steps":["trace[1797644449] 'agreement among raft nodes before linearized reading' (duration: 1.644655052s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:52.940226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654877795s","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-27T20:26:52.940589Z","caller":"traceutil/trace.go:171","msg":"trace[1912662587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:883; }","duration":"1.655271994s","start":"2026-02-27T20:26:51.285297Z","end":"2026-02-27T20:26:52.940569Z","steps":["trace[1912662587] 'agreement among raft nodes before linearized reading' (duration: 1.654511887s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:26:52.940712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:51.285287Z","time spent":"1.655406166s","remote":"127.0.0.1:52354","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-27T20:26:52.940526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:26:51.295151Z","time spent":"1.645332687s","remote":"127.0.0.1:52354","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":"info","ts":"2026-02-27T20:27:05.176534Z","caller":"traceutil/trace.go:171","msg":"trace[354476144] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"145.787818ms","start":"2026-02-27T20:27:05.030725Z","end":"2026-02-27T20:27:05.176512Z","steps":["trace[354476144] 'process raft request' (duration: 145.619865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:05.5171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.767738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:27:05.517167Z","caller":"traceutil/trace.go:171","msg":"trace[829146602] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"312.999895ms","start":"2026-02-27T20:27:05.204157Z","end":"2026-02-27T20:27:05.517156Z","steps":["trace[829146602] 'read index received' (duration: 55.066774ms)","trace[829146602] 'applied index is now lower than readState.Index' (duration: 257.932001ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:05.517243Z","caller":"traceutil/trace.go:171","msg":"trace[538616266] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"337.337957ms","start":"2026-02-27T20:27:05.179873Z","end":"2026-02-27T20:27:05.517211Z","steps":["trace[538616266] 'process raft request' (duration: 79.405786ms)","trace[538616266] 'compare' (duration: 257.687906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:05.517329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.131926ms","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-27T20:27:05.517349Z","caller":"traceutil/trace.go:171","msg":"trace[273883819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"301.171517ms","start":"2026-02-27T20:27:05.216172Z","end":"2026-02-27T20:27:05.517344Z","steps":["trace[273883819] 'agreement among raft nodes before linearized reading' (duration: 301.130776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:05.517369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.21616Z","time spent":"301.203578ms","remote":"127.0.0.1:52354","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-27T20:27:05.517386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.179854Z","time spent":"337.45989ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:05.517288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.131528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-27T20:27:05.517453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.172838ms","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-27T20:27:05.517512Z","caller":"traceutil/trace.go:171","msg":"trace[282475556] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:918; }","duration":"313.380244ms","start":"2026-02-27T20:27:05.20412Z","end":"2026-02-27T20:27:05.517501Z","steps":["trace[282475556] 'agreement among raft nodes before linearized reading' (duration: 313.090098ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:05.517526Z","caller":"traceutil/trace.go:171","msg":"trace[930393386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:918; }","duration":"227.271891ms","start":"2026-02-27T20:27:05.290235Z","end":"2026-02-27T20:27:05.517507Z","steps":["trace[930393386] 'agreement among raft nodes before linearized reading' (duration: 227.086027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:05.517549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.204106Z","time spent":"313.434345ms","remote":"127.0.0.1:52098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"info","ts":"2026-02-27T20:27:05.868902Z","caller":"traceutil/trace.go:171","msg":"trace[1787792354] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:961; }","duration":"272.976301ms","start":"2026-02-27T20:27:05.595903Z","end":"2026-02-27T20:27:05.86888Z","steps":["trace[1787792354] 'read index received' (duration: 192.071682ms)","trace[1787792354] 'applied index is now lower than readState.Index' (duration: 80.903439ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:05.869071Z","caller":"traceutil/trace.go:171","msg":"trace[1235747113] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"348.164045ms","start":"2026-02-27T20:27:05.520881Z","end":"2026-02-27T20:27:05.869045Z","steps":["trace[1235747113] 'process raft request' (duration: 347.861658ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:05.869081Z","caller":"traceutil/trace.go:171","msg":"trace[97875640] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"347.466579ms","start":"2026-02-27T20:27:05.521597Z","end":"2026-02-27T20:27:05.869064Z","steps":["trace[97875640] 'process raft request' (duration: 347.247434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:05.869079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.519156Z","time spent":"349.915303ms","remote":"127.0.0.1:52098","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-27T20:27:05.869246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.520858Z","time spent":"348.287807ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:05.869295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:05.521583Z","time spent":"347.569832ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:05.86911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.185876ms","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-27T20:27:05.869422Z","caller":"traceutil/trace.go:171","msg":"trace[1745477771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"273.518502ms","start":"2026-02-27T20:27:05.59589Z","end":"2026-02-27T20:27:05.869408Z","steps":["trace[1745477771] 'agreement among raft nodes before linearized reading' (duration: 273.085603ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:09.45846Z","caller":"traceutil/trace.go:171","msg":"trace[1489742631] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"111.121591ms","start":"2026-02-27T20:27:09.347311Z","end":"2026-02-27T20:27:09.458432Z","steps":["trace[1489742631] 'process raft request' (duration: 110.979548ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:09.732836Z","caller":"traceutil/trace.go:171","msg":"trace[1644449417] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"129.244807ms","start":"2026-02-27T20:27:09.603572Z","end":"2026-02-27T20:27:09.732817Z","steps":["trace[1644449417] 'process raft request' (duration: 129.116934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:09.96614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.698468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:27:09.96635Z","caller":"traceutil/trace.go:171","msg":"trace[1390967617] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"268.112803ms","start":"2026-02-27T20:27:09.698211Z","end":"2026-02-27T20:27:09.966324Z","steps":["trace[1390967617] 'process raft request' (duration: 78.153599ms)","trace[1390967617] 'compare' (duration: 189.484954ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:09.966689Z","caller":"traceutil/trace.go:171","msg":"trace[982879325] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"223.272014ms","start":"2026-02-27T20:27:09.743402Z","end":"2026-02-27T20:27:09.966674Z","steps":["trace[982879325] 'process raft request' (duration: 223.026987ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:09.966696Z","caller":"traceutil/trace.go:171","msg":"trace[1963580925] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"227.909246ms","start":"2026-02-27T20:27:09.738764Z","end":"2026-02-27T20:27:09.966673Z","steps":["trace[1963580925] 'process raft request' (duration: 227.481175ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:09.966936Z","caller":"traceutil/trace.go:171","msg":"trace[1796149123] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"224.297985ms","start":"2026-02-27T20:27:09.742631Z","end":"2026-02-27T20:27:09.966929Z","steps":["trace[1796149123] 'process raft request' (duration: 223.704991ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:10.279747Z","caller":"traceutil/trace.go:171","msg":"trace[1977245136] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"306.916123ms","start":"2026-02-27T20:27:09.972798Z","end":"2026-02-27T20:27:10.279714Z","steps":["trace[1977245136] 'process raft request' (duration: 306.76459ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:10.279818Z","caller":"traceutil/trace.go:171","msg":"trace[1149818826] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"308.424125ms","start":"2026-02-27T20:27:09.971356Z","end":"2026-02-27T20:27:10.27978Z","steps":["trace[1149818826] 'process raft request' (duration: 261.955489ms)","trace[1149818826] 'compare' (duration: 46.116799ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:10.279932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:09.972786Z","time spent":"307.057796ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:10.279908Z","caller":"traceutil/trace.go:171","msg":"trace[1711319912] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"307.020355ms","start":"2026-02-27T20:27:09.972843Z","end":"2026-02-27T20:27:10.279863Z","steps":["trace[1711319912] 'process raft request' (duration: 306.748939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:10.280021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:09.971332Z","time spent":"308.571929ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:10.280319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:09.972839Z","time spent":"307.372684ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:11.100454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.648647ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-27T20:27:11.835203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433546821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:27:12.202757Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001162117s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:27:12.203125Z","caller":"traceutil/trace.go:171","msg":"trace[396755831] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"867.963504ms","start":"2026-02-27T20:27:11.33513Z","end":"2026-02-27T20:27:12.203094Z","steps":["trace[396755831] 'read index received' (duration: 867.80139ms)","trace[396755831] 'applied index is now lower than readState.Index' (duration: 160.914µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:12.203416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.187008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:27:12.20366Z","caller":"traceutil/trace.go:171","msg":"trace[272876141] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:955; }","duration":"868.552896ms","start":"2026-02-27T20:27:11.335083Z","end":"2026-02-27T20:27:12.203636Z","steps":["trace[272876141] 'agreement among raft nodes before linearized reading' (duration: 868.131397ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:12.204177Z","caller":"traceutil/trace.go:171","msg":"trace[661599817] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.002685s","start":"2026-02-27T20:27:11.201458Z","end":"2026-02-27T20:27:12.204143Z","steps":["trace[661599817] 'process raft request' (duration: 1.001481014s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:12.204279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:11.201444Z","time spent":"1.002778353s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:12.204188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:11.335064Z","time spent":"868.793332ms","remote":"127.0.0.1:42158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-27T20:27:12.357547Z","caller":"traceutil/trace.go:171","msg":"trace[1184674757] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"154.313905ms","start":"2026-02-27T20:27:12.203208Z","end":"2026-02-27T20:27:12.357521Z","steps":["trace[1184674757] 'read index received' (duration: 64.95221ms)","trace[1184674757] 'applied index is now lower than readState.Index' (duration: 89.359975ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:12.357672Z","caller":"traceutil/trace.go:171","msg":"trace[365402257] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"506.818505ms","start":"2026-02-27T20:27:11.85084Z","end":"2026-02-27T20:27:12.357659Z","steps":["trace[365402257] 'process raft request' (duration: 417.372689ms)","trace[365402257] 'compare' (duration: 89.096908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:12.357756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.803382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:12.357825Z","caller":"traceutil/trace.go:171","msg":"trace[818531274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"141.891654ms","start":"2026-02-27T20:27:12.215906Z","end":"2026-02-27T20:27:12.357798Z","steps":["trace[818531274] 'agreement among raft nodes before linearized reading' (duration: 141.781141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:12.357769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:11.850824Z","time spent":"506.874956ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:27:12.357951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.767766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T20:27:12.357988Z","caller":"traceutil/trace.go:171","msg":"trace[1854884015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:956; }","duration":"817.806057ms","start":"2026-02-27T20:27:11.54017Z","end":"2026-02-27T20:27:12.357976Z","steps":["trace[1854884015] 'agreement among raft nodes before linearized reading' (duration: 817.666144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:12.358041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:11.540153Z","time spent":"817.856047ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:27:12.89612Z","caller":"traceutil/trace.go:171","msg":"trace[397375612] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"121.745932ms","start":"2026-02-27T20:27:12.774335Z","end":"2026-02-27T20:27:12.896081Z","steps":["trace[397375612] 'process raft request' (duration: 121.572998ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:13.035419Z","caller":"traceutil/trace.go:171","msg":"trace[1474794362] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"129.621835ms","start":"2026-02-27T20:27:12.905771Z","end":"2026-02-27T20:27:13.035393Z","steps":["trace[1474794362] 'process raft request' (duration: 68.507689ms)","trace[1474794362] 'compare' (duration: 60.797109ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:13.717035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433546838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:27:14.218203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433546838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:27:14.309339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.116235ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:14.309467Z","caller":"traceutil/trace.go:171","msg":"trace[746335283] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:964; }","duration":"407.253448ms","start":"2026-02-27T20:27:13.902196Z","end":"2026-02-27T20:27:14.30945Z","steps":["trace[746335283] 'range keys from in-memory index tree' (duration: 407.101375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:14.718839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133038433546838,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T20:27:14.781635Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.742257456s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T20:27:14.782115Z","caller":"traceutil/trace.go:171","msg":"trace[92347040] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"1.742803879s","start":"2026-02-27T20:27:13.039288Z","end":"2026-02-27T20:27:14.782092Z","steps":["trace[92347040] 'process raft request' (duration: 1.742634144s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:14.782449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:13.039267Z","time spent":"1.742905131s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:15.217515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000544785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-27T20:27:15.217654Z","caller":"traceutil/trace.go:171","msg":"trace[1750419132] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00073164s","start":"2026-02-27T20:27:13.216894Z","end":"2026-02-27T20:27:15.217626Z","steps":["trace[1750419132] 'agreement among raft nodes before linearized reading' (duration: 2.000576256s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.217919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:13.216875Z","time spent":"2.000835551s","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:27:15.302091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.333458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:27:15.302196Z","caller":"traceutil/trace.go:171","msg":"trace[1065669721] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1009; }","duration":"2.085245008s","start":"2026-02-27T20:27:13.216935Z","end":"2026-02-27T20:27:15.30218Z","steps":["trace[1065669721] 'read index received' (duration: 1.564992878s)","trace[1065669721] 'applied index is now lower than readState.Index' (duration: 520.25083ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:15.302312Z","caller":"traceutil/trace.go:171","msg":"trace[1415382536] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"2.262246759s","start":"2026-02-27T20:27:13.040055Z","end":"2026-02-27T20:27:15.302302Z","steps":["trace[1415382536] 'process raft request' (duration: 2.148538183s)","trace[1415382536] 'compare' (duration: 112.869738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:15.302364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:13.040042Z","time spent":"2.26229748s","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:27:15.302743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.044418464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:15.30279Z","caller":"traceutil/trace.go:171","msg":"trace[1229199351] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:966; }","duration":"2.044499316s","start":"2026-02-27T20:27:13.25828Z","end":"2026-02-27T20:27:15.302779Z","steps":["trace[1229199351] 'agreement among raft nodes before linearized reading' (duration: 2.044395433s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.302808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.41895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T20:27:15.302882Z","caller":"traceutil/trace.go:171","msg":"trace[1502214260] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"882.516323ms","start":"2026-02-27T20:27:14.420326Z","end":"2026-02-27T20:27:15.302842Z","steps":["trace[1502214260] 'agreement among raft nodes before linearized reading' (duration: 882.35926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.302906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.537114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-27T20:27:15.302924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:14.420293Z","time spent":"882.622504ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:27:15.302932Z","caller":"traceutil/trace.go:171","msg":"trace[2009104781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"883.601956ms","start":"2026-02-27T20:27:14.419323Z","end":"2026-02-27T20:27:15.302925Z","steps":["trace[2009104781] 'agreement among raft nodes before linearized reading' (duration: 883.517244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.302958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:14.419303Z","time spent":"883.650097ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:27:15.303043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.00132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:27:15.303078Z","caller":"traceutil/trace.go:171","msg":"trace[1524659294] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:966; }","duration":"171.063353ms","start":"2026-02-27T20:27:15.132006Z","end":"2026-02-27T20:27:15.30307Z","steps":["trace[1524659294] 'agreement among raft nodes before linearized reading' (duration: 171.011221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.30314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.826962ms","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-27T20:27:15.303175Z","caller":"traceutil/trace.go:171","msg":"trace[1392095565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"883.881573ms","start":"2026-02-27T20:27:14.419286Z","end":"2026-02-27T20:27:15.303167Z","steps":["trace[1392095565] 'agreement among raft nodes before linearized reading' (duration: 883.796021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.303207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:14.419274Z","time spent":"883.926964ms","remote":"127.0.0.1:52354","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-27T20:27:15.303228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.293364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:15.303267Z","caller":"traceutil/trace.go:171","msg":"trace[1224405987] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:966; }","duration":"837.351205ms","start":"2026-02-27T20:27:14.465904Z","end":"2026-02-27T20:27:15.303255Z","steps":["trace[1224405987] 'agreement among raft nodes before linearized reading' (duration: 837.301884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.303296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:14.465888Z","time spent":"837.400375ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:27:15.303356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.910948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T20:27:15.30339Z","caller":"traceutil/trace.go:171","msg":"trace[553901325] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"928.985929ms","start":"2026-02-27T20:27:14.374396Z","end":"2026-02-27T20:27:15.303382Z","steps":["trace[553901325] 'agreement among raft nodes before linearized reading' (duration: 928.907178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.302823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:13.258237Z","time spent":"2.044578227s","remote":"127.0.0.1:52380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:27:15.303416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:14.374377Z","time spent":"929.03363ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T20:27:15.303531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016480832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:15.303563Z","caller":"traceutil/trace.go:171","msg":"trace[1458219533] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:966; }","duration":"1.016544854s","start":"2026-02-27T20:27:14.287011Z","end":"2026-02-27T20:27:15.303556Z","steps":["trace[1458219533] 'agreement among raft nodes before linearized reading' (duration: 1.016498483s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.303591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:14.286994Z","time spent":"1.016590265s","remote":"127.0.0.1:52392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:27:15.303779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095546102s","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-27T20:27:15.303828Z","caller":"traceutil/trace.go:171","msg":"trace[813018990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"1.095628414s","start":"2026-02-27T20:27:14.208187Z","end":"2026-02-27T20:27:15.303815Z","steps":["trace[813018990] 'agreement among raft nodes before linearized reading' (duration: 1.095516291s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.303807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.997127151s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T20:27:15.303861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:14.208164Z","time spent":"1.095690745s","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:27:15.303888Z","caller":"traceutil/trace.go:171","msg":"trace[247459168] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:966; }","duration":"1.997220423s","start":"2026-02-27T20:27:13.306647Z","end":"2026-02-27T20:27:15.303867Z","steps":["trace[247459168] 'agreement among raft nodes before linearized reading' (duration: 1.99709784s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:15.303927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:13.306606Z","time spent":"1.997311375s","remote":"127.0.0.1:52526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-27T20:27:16.018895Z","caller":"traceutil/trace.go:171","msg":"trace[1387821812] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"710.372317ms","start":"2026-02-27T20:27:15.308501Z","end":"2026-02-27T20:27:16.018873Z","steps":["trace[1387821812] 'process raft request' (duration: 710.304296ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:16.018917Z","caller":"traceutil/trace.go:171","msg":"trace[537121995] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"710.45462ms","start":"2026-02-27T20:27:15.308429Z","end":"2026-02-27T20:27:16.018883Z","steps":["trace[537121995] 'process raft request' (duration: 709.660642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:16.019071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:15.308495Z","time spent":"710.46476ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:16.01919Z","caller":"traceutil/trace.go:171","msg":"trace[859933851] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"710.574482ms","start":"2026-02-27T20:27:15.308594Z","end":"2026-02-27T20:27:16.019168Z","steps":["trace[859933851] 'process raft request' (duration: 710.248315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:16.019187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:15.308409Z","time spent":"710.587422ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:16.01923Z","caller":"traceutil/trace.go:171","msg":"trace[270129095] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"709.940228ms","start":"2026-02-27T20:27:15.309278Z","end":"2026-02-27T20:27:16.019218Z","steps":["trace[270129095] 'process raft request' (duration: 709.812775ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:16.019194Z","caller":"traceutil/trace.go:171","msg":"trace[1281341661] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1011; }","duration":"707.221758ms","start":"2026-02-27T20:27:15.311952Z","end":"2026-02-27T20:27:16.019174Z","steps":["trace[1281341661] 'read index received' (duration: 58.399207ms)","trace[1281341661] 'applied index is now lower than readState.Index' (duration: 648.821671ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:16.019286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:15.308586Z","time spent":"710.649114ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:16.019375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.421983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:27:16.019353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:15.309267Z","time spent":"709.989249ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:27:16.019406Z","caller":"traceutil/trace.go:171","msg":"trace[207141816] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"708.858085ms","start":"2026-02-27T20:27:15.310536Z","end":"2026-02-27T20:27:16.019394Z","steps":["trace[207141816] 'process raft request' (duration: 708.602659ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:16.019416Z","caller":"traceutil/trace.go:171","msg":"trace[870986608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"707.517155ms","start":"2026-02-27T20:27:15.311886Z","end":"2026-02-27T20:27:16.019403Z","steps":["trace[870986608] 'agreement among raft nodes before linearized reading' (duration: 707.439403ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:16.019426Z","caller":"traceutil/trace.go:171","msg":"trace[1450501707] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"710.459218ms","start":"2026-02-27T20:27:15.308884Z","end":"2026-02-27T20:27:16.019343Z","steps":["trace[1450501707] 'process raft request' (duration: 710.130971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:16.019452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:15.311862Z","time spent":"707.583396ms","remote":"127.0.0.1:52080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:27:16.01948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:15.310528Z","time spent":"708.910246ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:16.019512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:15.308873Z","time spent":"710.603931ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:27:16.07675Z","caller":"traceutil/trace.go:171","msg":"trace[2100846681] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"703.902676ms","start":"2026-02-27T20:27:15.372825Z","end":"2026-02-27T20:27:16.076728Z","steps":["trace[2100846681] 'process raft request' (duration: 703.787993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:16.076893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:15.372791Z","time spent":"704.024269ms","remote":"127.0.0.1:52098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:16.759847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.912814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-27T20:27:16.759938Z","caller":"traceutil/trace.go:171","msg":"trace[380884372] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:973; }","duration":"681.063476ms","start":"2026-02-27T20:27:16.078853Z","end":"2026-02-27T20:27:16.759916Z","steps":["trace[380884372] 'range keys from in-memory index tree' (duration: 680.79317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:16.759988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:16.078837Z","time spent":"681.138459ms","remote":"127.0.0.1:52230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-27T20:27:16.855543Z","caller":"traceutil/trace.go:171","msg":"trace[1030781759] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"453.456048ms","start":"2026-02-27T20:27:16.402063Z","end":"2026-02-27T20:27:16.855519Z","steps":["trace[1030781759] 'process raft request' (duration: 453.268264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:16.856044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:16.402039Z","time spent":"453.56921ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:17.274918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.292553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:27:17.275063Z","caller":"traceutil/trace.go:171","msg":"trace[619488862] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:974; }","duration":"111.477377ms","start":"2026-02-27T20:27:17.163567Z","end":"2026-02-27T20:27:17.275045Z","steps":["trace[619488862] 'count revisions from in-memory index tree' (duration: 111.225082ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:20.133598Z","caller":"traceutil/trace.go:171","msg":"trace[877899956] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"132.253013ms","start":"2026-02-27T20:27:20.0013Z","end":"2026-02-27T20:27:20.133553Z","steps":["trace[877899956] 'process raft request' (duration: 131.932506ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:20.346414Z","caller":"traceutil/trace.go:171","msg":"trace[247307438] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"208.248556ms","start":"2026-02-27T20:27:20.138105Z","end":"2026-02-27T20:27:20.346354Z","steps":["trace[247307438] 'process raft request' (duration: 192.491891ms)","trace[247307438] 'compare' (duration: 15.635752ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:20.39188Z","caller":"traceutil/trace.go:171","msg":"trace[818343188] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"251.685844ms","start":"2026-02-27T20:27:20.138324Z","end":"2026-02-27T20:27:20.390009Z","steps":["trace[818343188] 'process raft request' (duration: 251.50426ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:20.392131Z","caller":"traceutil/trace.go:171","msg":"trace[1458879260] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"253.231158ms","start":"2026-02-27T20:27:20.138863Z","end":"2026-02-27T20:27:20.392094Z","steps":["trace[1458879260] 'process raft request' (duration: 251.104021ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:20.392941Z","caller":"traceutil/trace.go:171","msg":"trace[1271984668] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1034; }","duration":"173.383602ms","start":"2026-02-27T20:27:20.219481Z","end":"2026-02-27T20:27:20.392865Z","steps":["trace[1271984668] 'read index received' (duration: 111.11417ms)","trace[1271984668] 'applied index is now lower than readState.Index' (duration: 62.259001ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:20.393607Z","caller":"traceutil/trace.go:171","msg":"trace[1754605376] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"254.559848ms","start":"2026-02-27T20:27:20.139001Z","end":"2026-02-27T20:27:20.393561Z","steps":["trace[1754605376] 'process raft request' (duration: 253.665717ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:20.393844Z","caller":"traceutil/trace.go:171","msg":"trace[832822423] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"254.729342ms","start":"2026-02-27T20:27:20.139079Z","end":"2026-02-27T20:27:20.393809Z","steps":["trace[832822423] 'process raft request' (duration: 253.707859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:20.394556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.039518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:20.394628Z","caller":"traceutil/trace.go:171","msg":"trace[2128422914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"175.170402ms","start":"2026-02-27T20:27:20.219438Z","end":"2026-02-27T20:27:20.394608Z","steps":["trace[2128422914] 'agreement among raft nodes before linearized reading' (duration: 173.570957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:22.076249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.221215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:27:22.076352Z","caller":"traceutil/trace.go:171","msg":"trace[1990578160] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"1.15909589s","start":"2026-02-27T20:27:20.91724Z","end":"2026-02-27T20:27:22.076336Z","steps":["trace[1990578160] 'read index received' (duration: 223.782453ms)","trace[1990578160] 'applied index is now lower than readState.Index' (duration: 935.311937ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:22.076473Z","caller":"traceutil/trace.go:171","msg":"trace[285094065] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.574795014s","start":"2026-02-27T20:27:20.501612Z","end":"2026-02-27T20:27:22.076407Z","steps":["trace[285094065] 'process raft request' (duration: 639.327563ms)","trace[285094065] 'compare' (duration: 934.871628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:22.076584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159342396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T20:27:22.07668Z","caller":"traceutil/trace.go:171","msg":"trace[999107122] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:994; }","duration":"1.159470508s","start":"2026-02-27T20:27:20.917196Z","end":"2026-02-27T20:27:22.076666Z","steps":["trace[999107122] 'agreement among raft nodes before linearized reading' (duration: 1.159339846s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:22.076701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:20.501576Z","time spent":"1.574997278s","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5957,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:22.076717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:20.917173Z","time spent":"1.1595324s","remote":"127.0.0.1:42120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-27T20:27:22.17147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.027205156s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:27:22.372204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.515616ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-27T20:27:22.372307Z","caller":"traceutil/trace.go:171","msg":"trace[1441031801] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"295.908222ms","start":"2026-02-27T20:27:22.076378Z","end":"2026-02-27T20:27:22.372286Z","steps":["trace[1441031801] 'read index received' (duration: 95.221683ms)","trace[1441031801] 'applied index is now lower than readState.Index' (duration: 200.684979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:27:22.372404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.644264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:27:22.37241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.435208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:22.372434Z","caller":"traceutil/trace.go:171","msg":"trace[760282676] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:994; }","duration":"935.687795ms","start":"2026-02-27T20:27:21.436738Z","end":"2026-02-27T20:27:22.372426Z","steps":["trace[760282676] 'agreement among raft nodes before linearized reading' (duration: 935.617794ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:22.372448Z","caller":"traceutil/trace.go:171","msg":"trace[992582897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"154.511669ms","start":"2026-02-27T20:27:22.217927Z","end":"2026-02-27T20:27:22.372439Z","steps":["trace[992582897] 'agreement among raft nodes before linearized reading' (duration: 154.453688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:22.372458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:21.436675Z","time spent":"935.777267ms","remote":"127.0.0.1:52416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-27T20:27:22.741172Z","caller":"traceutil/trace.go:171","msg":"trace[485195120] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"342.44637ms","start":"2026-02-27T20:27:22.398702Z","end":"2026-02-27T20:27:22.741149Z","steps":["trace[485195120] 'read index received' (duration: 342.225376ms)","trace[485195120] 'applied index is now lower than readState.Index' (duration: 220.034µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:22.741259Z","caller":"traceutil/trace.go:171","msg":"trace[2046794552] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"364.633145ms","start":"2026-02-27T20:27:22.376582Z","end":"2026-02-27T20:27:22.741215Z","steps":["trace[2046794552] 'process raft request' (duration: 364.4348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:22.741434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:22.376568Z","time spent":"364.770648ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:27:22.741494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.918278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-27T20:27:22.741477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.745248ms","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-27T20:27:22.741516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.811586ms","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-27T20:27:22.741542Z","caller":"traceutil/trace.go:171","msg":"trace[522323081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"342.01529ms","start":"2026-02-27T20:27:22.399512Z","end":"2026-02-27T20:27:22.741527Z","steps":["trace[522323081] 'agreement among raft nodes before linearized reading' (duration: 341.849767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:22.741518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.817445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T20:27:22.741571Z","caller":"traceutil/trace.go:171","msg":"trace[2034613777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"342.87838ms","start":"2026-02-27T20:27:22.39867Z","end":"2026-02-27T20:27:22.741548Z","steps":["trace[2034613777] 'agreement among raft nodes before linearized reading' (duration: 342.603134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:22.741589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:22.399492Z","time spent":"342.088832ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T20:27:22.741589Z","caller":"traceutil/trace.go:171","msg":"trace[1945040803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"341.926618ms","start":"2026-02-27T20:27:22.399643Z","end":"2026-02-27T20:27:22.74157Z","steps":["trace[1945040803] 'agreement among raft nodes before linearized reading' (duration: 341.728554ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:22.74161Z","caller":"traceutil/trace.go:171","msg":"trace[641145826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"340.922837ms","start":"2026-02-27T20:27:22.400659Z","end":"2026-02-27T20:27:22.741581Z","steps":["trace[641145826] 'agreement among raft nodes before linearized reading' (duration: 340.700132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:22.74162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:22.398656Z","time spent":"342.954971ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:27:22.74163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:22.399616Z","time spent":"342.005841ms","remote":"127.0.0.1:52354","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-27T20:27:22.741647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:27:22.400651Z","time spent":"340.988148ms","remote":"127.0.0.1:52354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T20:27:22.879412Z","caller":"traceutil/trace.go:171","msg":"trace[1399317498] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"133.606982ms","start":"2026-02-27T20:27:22.745778Z","end":"2026-02-27T20:27:22.879385Z","steps":["trace[1399317498] 'process raft request' (duration: 125.144167ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:22.886311Z","caller":"traceutil/trace.go:171","msg":"trace[328045546] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"140.214377ms","start":"2026-02-27T20:27:22.746069Z","end":"2026-02-27T20:27:22.886284Z","steps":["trace[328045546] 'process raft request' (duration: 140.026583ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:22.886392Z","caller":"traceutil/trace.go:171","msg":"trace[1700588984] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"139.323517ms","start":"2026-02-27T20:27:22.747045Z","end":"2026-02-27T20:27:22.886369Z","steps":["trace[1700588984] 'process raft request' (duration: 139.154063ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:22.886464Z","caller":"traceutil/trace.go:171","msg":"trace[1800047708] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"139.279547ms","start":"2026-02-27T20:27:22.747161Z","end":"2026-02-27T20:27:22.886441Z","steps":["trace[1800047708] 'process raft request' (duration: 139.070642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:24.056267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.475688ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:27:24.056415Z","caller":"traceutil/trace.go:171","msg":"trace[1551504419] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1006; }","duration":"154.641802ms","start":"2026-02-27T20:27:23.901751Z","end":"2026-02-27T20:27:24.056392Z","steps":["trace[1551504419] 'range keys from in-memory index tree' (duration: 154.434348ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:26.894863Z","caller":"traceutil/trace.go:171","msg":"trace[1777292117] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"127.482009ms","start":"2026-02-27T20:27:26.76736Z","end":"2026-02-27T20:27:26.894842Z","steps":["trace[1777292117] 'read index received' (duration: 127.171772ms)","trace[1777292117] 'applied index is now lower than readState.Index' (duration: 308.997µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:26.894976Z","caller":"traceutil/trace.go:171","msg":"trace[383189618] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"210.725691ms","start":"2026-02-27T20:27:26.68422Z","end":"2026-02-27T20:27:26.894945Z","steps":["trace[383189618] 'process raft request' (duration: 210.506977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:26.895165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.789038ms","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-27T20:27:26.895205Z","caller":"traceutil/trace.go:171","msg":"trace[1050221521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1028; }","duration":"127.876819ms","start":"2026-02-27T20:27:26.767318Z","end":"2026-02-27T20:27:26.895195Z","steps":["trace[1050221521] 'agreement among raft nodes before linearized reading' (duration: 127.726004ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:27.00551Z","caller":"traceutil/trace.go:171","msg":"trace[1713823049] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"104.593907ms","start":"2026-02-27T20:27:26.900899Z","end":"2026-02-27T20:27:27.005493Z","steps":["trace[1713823049] 'process raft request' (duration: 86.867799ms)","trace[1713823049] 'compare' (duration: 17.591715ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:27.005636Z","caller":"traceutil/trace.go:171","msg":"trace[1254321130] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"104.72496ms","start":"2026-02-27T20:27:26.900904Z","end":"2026-02-27T20:27:27.005629Z","steps":["trace[1254321130] 'process raft request' (duration: 104.550496ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:27.047546Z","caller":"traceutil/trace.go:171","msg":"trace[1229897319] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"146.510014ms","start":"2026-02-27T20:27:26.901012Z","end":"2026-02-27T20:27:27.047522Z","steps":["trace[1229897319] 'process raft request' (duration: 146.34053ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:27.048063Z","caller":"traceutil/trace.go:171","msg":"trace[918928616] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1076; }","duration":"143.825015ms","start":"2026-02-27T20:27:26.904178Z","end":"2026-02-27T20:27:27.048002Z","steps":["trace[918928616] 'read index received' (duration: 83.6747ms)","trace[918928616] 'applied index is now lower than readState.Index' (duration: 60.148925ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:27:27.048244Z","caller":"traceutil/trace.go:171","msg":"trace[237943641] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"142.048087ms","start":"2026-02-27T20:27:26.906181Z","end":"2026-02-27T20:27:27.048229Z","steps":["trace[237943641] 'process raft request' (duration: 141.578827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:27.048515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.341175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T20:27:27.048586Z","caller":"traceutil/trace.go:171","msg":"trace[1584544136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1033; }","duration":"143.436597ms","start":"2026-02-27T20:27:26.905133Z","end":"2026-02-27T20:27:27.048569Z","steps":["trace[1584544136] 'agreement among raft nodes before linearized reading' (duration: 143.265553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:27:27.04852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.329826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-27T20:27:27.048658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.430638ms","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-27T20:27:27.04879Z","caller":"traceutil/trace.go:171","msg":"trace[740670353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1033; }","duration":"144.55046ms","start":"2026-02-27T20:27:26.904189Z","end":"2026-02-27T20:27:27.04874Z","steps":["trace[740670353] 'agreement among raft nodes before linearized reading' (duration: 144.0602ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:27.048704Z","caller":"traceutil/trace.go:171","msg":"trace[796002353] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1033; }","duration":"144.49689ms","start":"2026-02-27T20:27:26.904173Z","end":"2026-02-27T20:27:27.04867Z","steps":["trace[796002353] 'agreement among raft nodes before linearized reading' (duration: 144.181183ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:27.048149Z","caller":"traceutil/trace.go:171","msg":"trace[696905779] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"146.529414ms","start":"2026-02-27T20:27:26.901599Z","end":"2026-02-27T20:27:27.048129Z","steps":["trace[696905779] 'process raft request' (duration: 145.845369ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:27:45.249639Z","caller":"traceutil/trace.go:171","msg":"trace[1430443550] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"104.159177ms","start":"2026-02-27T20:27:45.145448Z","end":"2026-02-27T20:27:45.249607Z","steps":["trace[1430443550] 'process raft request' (duration: 29.802549ms)","trace[1430443550] 'compare' (duration: 74.162693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:28:31.610692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.596292ms","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-27T20:28:31.610782Z","caller":"traceutil/trace.go:171","msg":"trace[1878206080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1297; }","duration":"130.733115ms","start":"2026-02-27T20:28:31.48003Z","end":"2026-02-27T20:28:31.610763Z","steps":["trace[1878206080] 'range keys from in-memory index tree' (duration: 130.412268ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:46.030551Z","caller":"traceutil/trace.go:171","msg":"trace[124761832] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"180.567052ms","start":"2026-02-27T20:28:45.849956Z","end":"2026-02-27T20:28:46.030523Z","steps":["trace[124761832] 'read index received' (duration: 180.423889ms)","trace[124761832] 'applied index is now lower than readState.Index' (duration: 142.053µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:28:46.030621Z","caller":"traceutil/trace.go:171","msg":"trace[1705932794] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"182.778531ms","start":"2026-02-27T20:28:45.847801Z","end":"2026-02-27T20:28:46.03058Z","steps":["trace[1705932794] 'process raft request' (duration: 182.556696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:46.03099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.999841ms","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-27T20:28:46.031107Z","caller":"traceutil/trace.go:171","msg":"trace[587759035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"181.140536ms","start":"2026-02-27T20:28:45.849929Z","end":"2026-02-27T20:28:46.031069Z","steps":["trace[587759035] 'agreement among raft nodes before linearized reading' (duration: 180.736156ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:46.03384Z","caller":"traceutil/trace.go:171","msg":"trace[2122840079] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"183.028427ms","start":"2026-02-27T20:28:45.850787Z","end":"2026-02-27T20:28:46.033815Z","steps":["trace[2122840079] 'process raft request' (duration: 182.798682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:28:46.033865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.671279ms","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-27T20:28:46.033923Z","caller":"traceutil/trace.go:171","msg":"trace[489714063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1357; }","duration":"182.754852ms","start":"2026-02-27T20:28:45.851153Z","end":"2026-02-27T20:28:46.033908Z","steps":["trace[489714063] 'agreement among raft nodes before linearized reading' (duration: 182.558106ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:50.730099Z","caller":"traceutil/trace.go:171","msg":"trace[966571341] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"107.21847ms","start":"2026-02-27T20:28:50.622857Z","end":"2026-02-27T20:28:50.730076Z","steps":["trace[966571341] 'process raft request' (duration: 107.028836ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:51.878087Z","caller":"traceutil/trace.go:171","msg":"trace[752702115] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"159.477967ms","start":"2026-02-27T20:28:51.718588Z","end":"2026-02-27T20:28:51.878066Z","steps":["trace[752702115] 'process raft request' (duration: 159.359434ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:28:58.95092Z","caller":"traceutil/trace.go:171","msg":"trace[1141761046] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"114.033141ms","start":"2026-02-27T20:28:58.836673Z","end":"2026-02-27T20:28:58.950706Z","steps":["trace[1141761046] 'process raft request' (duration: 113.888078ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:29:28.444958Z","caller":"traceutil/trace.go:171","msg":"trace[1317886042] linearizableReadLoop","detail":"{readStateIndex:1600; appliedIndex:1598; }","duration":"109.997432ms","start":"2026-02-27T20:29:28.334939Z","end":"2026-02-27T20:29:28.444937Z","steps":["trace[1317886042] 'read index received' (duration: 16.572687ms)","trace[1317886042] 'applied index is now lower than readState.Index' (duration: 93.423955ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:29:28.44508Z","caller":"traceutil/trace.go:171","msg":"trace[1497388073] transaction","detail":"{read_only:false; response_revision:1528; number_of_response:1; }","duration":"133.513393ms","start":"2026-02-27T20:29:28.311553Z","end":"2026-02-27T20:29:28.445067Z","steps":["trace[1497388073] 'process raft request' (duration: 86.443702ms)","trace[1497388073] 'compare' (duration: 46.821905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:29:28.445441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.482463ms","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-27T20:29:28.44554Z","caller":"traceutil/trace.go:171","msg":"trace[213587813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1528; }","duration":"110.601255ms","start":"2026-02-27T20:29:28.334915Z","end":"2026-02-27T20:29:28.445516Z","steps":["trace[213587813] 'agreement among raft nodes before linearized reading' (duration: 110.454061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:30:47.665393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.787814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-27T20:30:47.665535Z","caller":"traceutil/trace.go:171","msg":"trace[178531665] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:1890; }","duration":"109.995369ms","start":"2026-02-27T20:30:47.555498Z","end":"2026-02-27T20:30:47.665493Z","steps":["trace[178531665] 'range keys from in-memory index tree' (duration: 109.563289ms)"],"step_count":1}