{"level":"warn","ts":"2026-02-25T22:13:28.893437Z","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-25T22:13:28.893526Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-25T22:13:28.893602Z","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-25T22:13:28.893618Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-25T22:13:28.893637Z","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-25T22:13:28.894353Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-02-25T22:13:28.894449Z","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.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-25T22:13:28.90211Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.428938ms"} {"level":"info","ts":"2026-02-25T22:13:28.916643Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-02-25T22:13:28.916802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T22:13:28.916873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-02-25T22:13:28.916906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T22:13:28.916934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-02-25T22:13:28.917077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-02-25T22:13:28.921639Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T22:13:28.922792Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T22:13:28.923864Z","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-25T22:13:28.926199Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T22:13:28.928627Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T22:13:28.929236Z","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-25T22:13:28.9294Z","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-25T22:13:28.929676Z","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-25T22:13:28.930455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-02-25T22:13:28.932135Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-02-25T22:13:28.932612Z","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-25T22:13:28.932723Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-25T22:13:28.933256Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-02-25T22:13:28.933441Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T22:13:28.933533Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T22:13:29.317729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T22:13:29.31778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T22:13:29.3178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-02-25T22:13:29.317815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-02-25T22:13:29.317822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-25T22:13:29.317832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-02-25T22:13:29.317844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-02-25T22:13:29.318625Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:13:29.319292Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T22:13:29.319359Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T22:13:29.319412Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T22:13:29.319698Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T22:13:29.319725Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T22:13:29.319721Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:13:29.319897Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:13:29.319947Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T22:13:29.322786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T22:13:29.34213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-02-25T22:13:55.359541Z","caller":"traceutil/trace.go:171","msg":"trace[1564230028] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"376.861432ms","start":"2026-02-25T22:13:54.982656Z","end":"2026-02-25T22:13:55.359518Z","steps":["trace[1564230028] 'read index received' (duration: 376.669378ms)","trace[1564230028] 'applied index is now lower than readState.Index' (duration: 191.124µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:13:55.359743Z","caller":"traceutil/trace.go:171","msg":"trace[1505352665] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"633.494121ms","start":"2026-02-25T22:13:54.726201Z","end":"2026-02-25T22:13:55.359695Z","steps":["trace[1505352665] 'process raft request' (duration: 633.190273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:13:55.359858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.609508ms","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-25T22:13:55.359873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.189991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:13:55.359948Z","caller":"traceutil/trace.go:171","msg":"trace[1570306300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"358.766791ms","start":"2026-02-25T22:13:55.001164Z","end":"2026-02-25T22:13:55.35993Z","steps":["trace[1570306300] 'agreement among raft nodes before linearized reading' (duration: 358.573847ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:13:55.359978Z","caller":"traceutil/trace.go:171","msg":"trace[932155654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:466; }","duration":"377.355535ms","start":"2026-02-25T22:13:54.982601Z","end":"2026-02-25T22:13:55.359956Z","steps":["trace[932155654] 'agreement among raft nodes before linearized reading' (duration: 377.129959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:13:55.359991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:13:55.001141Z","time spent":"358.839163ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T22:13:55.360034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:13:54.982581Z","time spent":"377.439796ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:13:55.360505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:13:54.726173Z","time spent":"633.682395ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:13:55.468358Z","caller":"traceutil/trace.go:171","msg":"trace[1157758765] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"103.756874ms","start":"2026-02-25T22:13:55.364578Z","end":"2026-02-25T22:13:55.468335Z","steps":["trace[1157758765] 'process raft request' (duration: 37.547275ms)","trace[1157758765] 'compare' (duration: 66.091676ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:13:56.241858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.457443ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:13:56.241955Z","caller":"traceutil/trace.go:171","msg":"trace[1493260993] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"267.296822ms","start":"2026-02-25T22:13:55.974644Z","end":"2026-02-25T22:13:56.24194Z","steps":["trace[1493260993] 'read index received' (duration: 32.361µs)","trace[1493260993] 'applied index is now lower than readState.Index' (duration: 267.263511ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:13:56.242036Z","caller":"traceutil/trace.go:171","msg":"trace[1215558362] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"682.689314ms","start":"2026-02-25T22:13:55.559334Z","end":"2026-02-25T22:13:56.242024Z","steps":["trace[1215558362] 'process raft request' (duration: 76.870972ms)","trace[1215558362] 'compare' (duration: 605.30276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:13:56.242095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:13:55.559313Z","time spent":"682.752716ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:13:56.242256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.608649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12665"} {"level":"info","ts":"2026-02-25T22:13:56.242363Z","caller":"traceutil/trace.go:171","msg":"trace[564944396] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:469; }","duration":"267.747533ms","start":"2026-02-25T22:13:55.974603Z","end":"2026-02-25T22:13:56.242351Z","steps":["trace[564944396] 'agreement among raft nodes before linearized reading' (duration: 267.599989ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:13:59.618475Z","caller":"traceutil/trace.go:171","msg":"trace[923227194] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"231.648692ms","start":"2026-02-25T22:13:59.386802Z","end":"2026-02-25T22:13:59.618451Z","steps":["trace[923227194] 'read index received' (duration: 231.513119ms)","trace[923227194] 'applied index is now lower than readState.Index' (duration: 134.483µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:13:59.618614Z","caller":"traceutil/trace.go:171","msg":"trace[525186273] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"234.382617ms","start":"2026-02-25T22:13:59.384198Z","end":"2026-02-25T22:13:59.618581Z","steps":["trace[525186273] 'process raft request' (duration: 234.115591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:13:59.618681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.846957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:13:59.618722Z","caller":"traceutil/trace.go:171","msg":"trace[1461157678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"231.960839ms","start":"2026-02-25T22:13:59.38675Z","end":"2026-02-25T22:13:59.618711Z","steps":["trace[1461157678] 'agreement among raft nodes before linearized reading' (duration: 231.842727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:13:59.618882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.527862ms","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-25T22:13:59.618882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.31174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4419"} {"level":"info","ts":"2026-02-25T22:13:59.618914Z","caller":"traceutil/trace.go:171","msg":"trace[1325273461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"138.613084ms","start":"2026-02-25T22:13:59.480294Z","end":"2026-02-25T22:13:59.618907Z","steps":["trace[1325273461] 'agreement among raft nodes before linearized reading' (duration: 138.555863ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:13:59.618938Z","caller":"traceutil/trace.go:171","msg":"trace[1404377521] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"183.426132ms","start":"2026-02-25T22:13:59.435493Z","end":"2026-02-25T22:13:59.618919Z","steps":["trace[1404377521] 'agreement among raft nodes before linearized reading' (duration: 183.31329ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:06.383623Z","caller":"traceutil/trace.go:171","msg":"trace[2044831160] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"337.159357ms","start":"2026-02-25T22:14:06.046441Z","end":"2026-02-25T22:14:06.3836Z","steps":["trace[2044831160] 'read index received' (duration: 336.932262ms)","trace[2044831160] 'applied index is now lower than readState.Index' (duration: 226.095µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:06.383681Z","caller":"traceutil/trace.go:171","msg":"trace[747118997] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"671.211519ms","start":"2026-02-25T22:14:05.712435Z","end":"2026-02-25T22:14:06.383646Z","steps":["trace[747118997] 'process raft request' (duration: 671.030915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:06.383824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.787488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:14:06.383873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:05.712416Z","time spent":"671.371703ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:06.383889Z","caller":"traceutil/trace.go:171","msg":"trace[1632406212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"208.90042ms","start":"2026-02-25T22:14:06.174972Z","end":"2026-02-25T22:14:06.383872Z","steps":["trace[1632406212] 'agreement among raft nodes before linearized reading' (duration: 208.799257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:06.384399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.950406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-66rnn\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-02-25T22:14:06.38445Z","caller":"traceutil/trace.go:171","msg":"trace[1265647834] range","detail":"{range_begin:/registry/pods/kube-system/cilium-66rnn; range_end:; response_count:1; response_revision:529; }","duration":"338.037368ms","start":"2026-02-25T22:14:06.0464Z","end":"2026-02-25T22:14:06.384438Z","steps":["trace[1265647834] 'agreement among raft nodes before linearized reading' (duration: 337.890935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:06.384528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:06.046381Z","time spent":"338.132491ms","remote":"127.0.0.1:34158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-66rnn\" "} {"level":"info","ts":"2026-02-25T22:14:06.83831Z","caller":"traceutil/trace.go:171","msg":"trace[446862325] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"450.09231ms","start":"2026-02-25T22:14:06.388187Z","end":"2026-02-25T22:14:06.838279Z","steps":["trace[446862325] 'process raft request' (duration: 367.242285ms)","trace[446862325] 'compare' (duration: 82.512837ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:06.838446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:06.38817Z","time spent":"450.204543ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:07.562776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.496056ms","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-25T22:14:07.562894Z","caller":"traceutil/trace.go:171","msg":"trace[494227836] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"388.445311ms","start":"2026-02-25T22:14:07.174427Z","end":"2026-02-25T22:14:07.562872Z","steps":["trace[494227836] 'read index received' (duration: 325.315035ms)","trace[494227836] 'applied index is now lower than readState.Index' (duration: 63.129066ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:07.562908Z","caller":"traceutil/trace.go:171","msg":"trace[661949733] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.164229574s","start":"2026-02-25T22:14:06.398653Z","end":"2026-02-25T22:14:07.562883Z","steps":["trace[661949733] 'process raft request' (duration: 1.101130349s)","trace[661949733] 'compare' (duration: 62.867729ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:07.562921Z","caller":"traceutil/trace.go:171","msg":"trace[594852727] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:530; }","duration":"186.67559ms","start":"2026-02-25T22:14:07.376223Z","end":"2026-02-25T22:14:07.562899Z","steps":["trace[594852727] 'range keys from in-memory index tree' (duration: 186.474486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:07.563023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:06.398617Z","time spent":"1.164347457s","remote":"127.0.0.1:34158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:07.563314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.642635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:07.563383Z","caller":"traceutil/trace.go:171","msg":"trace[1112390211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"388.989963ms","start":"2026-02-25T22:14:07.174376Z","end":"2026-02-25T22:14:07.563366Z","steps":["trace[1112390211] 'agreement among raft nodes before linearized reading' (duration: 388.575913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:07.563436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:07.174356Z","time spent":"389.068265ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:14:09.020839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.120326ms","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-25T22:14:09.020918Z","caller":"traceutil/trace.go:171","msg":"trace[1274666734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"178.25046ms","start":"2026-02-25T22:14:08.84265Z","end":"2026-02-25T22:14:09.0209Z","steps":["trace[1274666734] 'range keys from in-memory index tree' (duration: 177.913391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:09.020929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237649794s","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-25T22:14:09.021008Z","caller":"traceutil/trace.go:171","msg":"trace[783297661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"1.237779377s","start":"2026-02-25T22:14:07.783212Z","end":"2026-02-25T22:14:09.020991Z","steps":["trace[783297661] 'range keys from in-memory index tree' (duration: 1.237468069s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:09.021074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:07.783194Z","time spent":"1.23786988s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T22:14:09.021052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.749211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:09.021186Z","caller":"traceutil/trace.go:171","msg":"trace[1528154112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"845.913575ms","start":"2026-02-25T22:14:08.175216Z","end":"2026-02-25T22:14:09.021129Z","steps":["trace[1528154112] 'range keys from in-memory index tree' (duration: 845.67535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:09.021214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.275226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T22:14:09.021237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:08.175195Z","time spent":"846.029128ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T22:14:09.021256Z","caller":"traceutil/trace.go:171","msg":"trace[1997878334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"633.354448ms","start":"2026-02-25T22:14:08.387891Z","end":"2026-02-25T22:14:09.021245Z","steps":["trace[1997878334] 'range keys from in-memory index tree' (duration: 633.119843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:09.021288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:08.38787Z","time spent":"633.40836ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T22:14:09.131792Z","caller":"traceutil/trace.go:171","msg":"trace[1586156031] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"100.816524ms","start":"2026-02-25T22:14:09.030951Z","end":"2026-02-25T22:14:09.131768Z","steps":["trace[1586156031] 'process raft request' (duration: 100.684301ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:09.1331Z","caller":"traceutil/trace.go:171","msg":"trace[1285736476] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"101.348116ms","start":"2026-02-25T22:14:09.031733Z","end":"2026-02-25T22:14:09.133081Z","steps":["trace[1285736476] 'process raft request' (duration: 101.191422ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:09.133253Z","caller":"traceutil/trace.go:171","msg":"trace[2065751565] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"101.277725ms","start":"2026-02-25T22:14:09.031912Z","end":"2026-02-25T22:14:09.13319Z","steps":["trace[2065751565] 'process raft request' (duration: 101.134001ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:10.105707Z","caller":"traceutil/trace.go:171","msg":"trace[1937333875] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"356.687913ms","start":"2026-02-25T22:14:09.748995Z","end":"2026-02-25T22:14:10.105683Z","steps":["trace[1937333875] 'process raft request' (duration: 356.538959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:10.105926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:09.748973Z","time spent":"356.860007ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:12.678554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.846347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T22:14:12.678616Z","caller":"traceutil/trace.go:171","msg":"trace[417623638] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:564; }","duration":"421.97044ms","start":"2026-02-25T22:14:12.256632Z","end":"2026-02-25T22:14:12.678603Z","steps":["trace[417623638] 'range keys from in-memory index tree' (duration: 421.713123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:12.678644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:12.2566Z","time spent":"422.037231ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"warn","ts":"2026-02-25T22:14:12.678783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.621945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:12.678808Z","caller":"traceutil/trace.go:171","msg":"trace[1137884427] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:564; }","duration":"409.644555ms","start":"2026-02-25T22:14:12.269156Z","end":"2026-02-25T22:14:12.6788Z","steps":["trace[1137884427] 'range keys from in-memory index tree' (duration: 409.585524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:12.678824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:12.26915Z","time spent":"409.670526ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-25T22:14:12.684699Z","caller":"traceutil/trace.go:171","msg":"trace[221487899] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"213.342395ms","start":"2026-02-25T22:14:12.471338Z","end":"2026-02-25T22:14:12.68468Z","steps":["trace[221487899] 'process raft request' (duration: 213.068859ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:14.205536Z","caller":"traceutil/trace.go:171","msg":"trace[2064266650] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"110.668278ms","start":"2026-02-25T22:14:14.094842Z","end":"2026-02-25T22:14:14.20551Z","steps":["trace[2064266650] 'process raft request' (duration: 87.69004ms)","trace[2064266650] 'compare' (duration: 22.833354ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:14.227163Z","caller":"traceutil/trace.go:171","msg":"trace[520917643] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"118.027143ms","start":"2026-02-25T22:14:14.109109Z","end":"2026-02-25T22:14:14.227136Z","steps":["trace[520917643] 'process raft request' (duration: 117.48798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:15.474158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.894031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-25T22:14:15.474235Z","caller":"traceutil/trace.go:171","msg":"trace[989654278] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"204.020544ms","start":"2026-02-25T22:14:15.270197Z","end":"2026-02-25T22:14:15.474217Z","steps":["trace[989654278] 'range keys from in-memory index tree' (duration: 203.721407ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:15.612577Z","caller":"traceutil/trace.go:171","msg":"trace[790085036] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"116.641931ms","start":"2026-02-25T22:14:15.495909Z","end":"2026-02-25T22:14:15.612551Z","steps":["trace[790085036] 'process raft request' (duration: 116.392465ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:16.296594Z","caller":"traceutil/trace.go:171","msg":"trace[941025558] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"126.540147ms","start":"2026-02-25T22:14:16.170034Z","end":"2026-02-25T22:14:16.296574Z","steps":["trace[941025558] 'process raft request' (duration: 108.658741ms)","trace[941025558] 'compare' (duration: 17.559958ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:16.312378Z","caller":"traceutil/trace.go:171","msg":"trace[1197966437] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"103.63198ms","start":"2026-02-25T22:14:16.208727Z","end":"2026-02-25T22:14:16.312359Z","steps":["trace[1197966437] 'read index received' (duration: 70.05261ms)","trace[1197966437] 'applied index is now lower than readState.Index' (duration: 33.57838ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:16.312431Z","caller":"traceutil/trace.go:171","msg":"trace[1327409820] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"124.17723ms","start":"2026-02-25T22:14:16.188234Z","end":"2026-02-25T22:14:16.312412Z","steps":["trace[1327409820] 'process raft request' (duration: 123.860883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:16.313249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.49736ms","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-25T22:14:16.313316Z","caller":"traceutil/trace.go:171","msg":"trace[1073376586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:618; }","duration":"104.598373ms","start":"2026-02-25T22:14:16.208702Z","end":"2026-02-25T22:14:16.3133Z","steps":["trace[1073376586] 'agreement among raft nodes before linearized reading' (duration: 104.44862ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:16.539207Z","caller":"traceutil/trace.go:171","msg":"trace[1310932081] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"103.656221ms","start":"2026-02-25T22:14:16.435526Z","end":"2026-02-25T22:14:16.539182Z","steps":["trace[1310932081] 'process raft request' (duration: 103.513898ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:17.338967Z","caller":"traceutil/trace.go:171","msg":"trace[1894805470] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"160.749385ms","start":"2026-02-25T22:14:17.17819Z","end":"2026-02-25T22:14:17.33894Z","steps":["trace[1894805470] 'process raft request' (duration: 76.68386ms)","trace[1894805470] 'compare' (duration: 83.930802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:17.621996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.352364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:14:17.622425Z","caller":"traceutil/trace.go:171","msg":"trace[116880090] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:645; }","duration":"236.224786ms","start":"2026-02-25T22:14:17.386181Z","end":"2026-02-25T22:14:17.622406Z","steps":["trace[116880090] 'read index received' (duration: 78.36467ms)","trace[116880090] 'applied index is now lower than readState.Index' (duration: 157.858926ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:17.622503Z","caller":"traceutil/trace.go:171","msg":"trace[1605066442] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"443.793808ms","start":"2026-02-25T22:14:17.178699Z","end":"2026-02-25T22:14:17.622493Z","steps":["trace[1605066442] 'process raft request' (duration: 285.834909ms)","trace[1605066442] 'compare' (duration: 157.193141ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:17.622568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:17.17869Z","time spent":"443.840159ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:17.622655Z","caller":"traceutil/trace.go:171","msg":"trace[394383931] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"440.378727ms","start":"2026-02-25T22:14:17.182237Z","end":"2026-02-25T22:14:17.622616Z","steps":["trace[394383931] 'process raft request' (duration: 439.966537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:17.622868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:17.182231Z","time spent":"440.54205ms","remote":"127.0.0.1:34158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:17.622901Z","caller":"traceutil/trace.go:171","msg":"trace[1574242660] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"443.759147ms","start":"2026-02-25T22:14:17.179115Z","end":"2026-02-25T22:14:17.622874Z","steps":["trace[1574242660] 'process raft request' (duration: 443.028429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:17.622901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.680496ms","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:5"} {"level":"info","ts":"2026-02-25T22:14:17.62299Z","caller":"traceutil/trace.go:171","msg":"trace[1418627356] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:627; }","duration":"236.81977ms","start":"2026-02-25T22:14:17.38615Z","end":"2026-02-25T22:14:17.62297Z","steps":["trace[1418627356] 'agreement among raft nodes before linearized reading' (duration: 236.587045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:17.623029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:17.179109Z","time spent":"443.849879ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:17.679142Z","caller":"traceutil/trace.go:171","msg":"trace[542488898] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"273.544316ms","start":"2026-02-25T22:14:17.405581Z","end":"2026-02-25T22:14:17.679126Z","steps":["trace[542488898] 'process raft request' (duration: 273.493215ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:17.679221Z","caller":"traceutil/trace.go:171","msg":"trace[569492058] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"276.797673ms","start":"2026-02-25T22:14:17.402387Z","end":"2026-02-25T22:14:17.679185Z","steps":["trace[569492058] 'process raft request' (duration: 276.596099ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:17.91134Z","caller":"traceutil/trace.go:171","msg":"trace[470238937] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"288.850581ms","start":"2026-02-25T22:14:17.622464Z","end":"2026-02-25T22:14:17.911314Z","steps":["trace[470238937] 'read index received' (duration: 56.919178ms)","trace[470238937] 'applied index is now lower than readState.Index' (duration: 231.929633ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:17.91148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.772979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:14:17.911665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.324032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:17.911773Z","caller":"traceutil/trace.go:171","msg":"trace[57031279] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:629; }","duration":"525.458606ms","start":"2026-02-25T22:14:17.386287Z","end":"2026-02-25T22:14:17.911745Z","steps":["trace[57031279] 'agreement among raft nodes before linearized reading' (duration: 525.274322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:17.911828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:17.386282Z","time spent":"525.533648ms","remote":"127.0.0.1:44498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 "} {"level":"info","ts":"2026-02-25T22:14:17.911727Z","caller":"traceutil/trace.go:171","msg":"trace[167015976] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:629; }","duration":"525.000075ms","start":"2026-02-25T22:14:17.386654Z","end":"2026-02-25T22:14:17.911654Z","steps":["trace[167015976] 'agreement among raft nodes before linearized reading' (duration: 524.730779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:17.912096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:17.386648Z","time spent":"525.410445ms","remote":"127.0.0.1:44498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 "} {"level":"info","ts":"2026-02-25T22:14:17.954757Z","caller":"traceutil/trace.go:171","msg":"trace[803157682] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"265.974935ms","start":"2026-02-25T22:14:17.68876Z","end":"2026-02-25T22:14:17.954735Z","steps":["trace[803157682] 'process raft request' (duration: 265.919014ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:17.954822Z","caller":"traceutil/trace.go:171","msg":"trace[977424372] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"271.276171ms","start":"2026-02-25T22:14:17.683513Z","end":"2026-02-25T22:14:17.954789Z","steps":["trace[977424372] 'process raft request' (duration: 271.046686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:27.817605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.615976ms","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-25T22:14:27.817699Z","caller":"traceutil/trace.go:171","msg":"trace[745893259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"122.748368ms","start":"2026-02-25T22:14:27.694929Z","end":"2026-02-25T22:14:27.817677Z","steps":["trace[745893259] 'range keys from in-memory index tree' (duration: 122.463362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:27.817842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.338062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:14:27.817923Z","caller":"traceutil/trace.go:171","msg":"trace[1918931188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"123.449245ms","start":"2026-02-25T22:14:27.694453Z","end":"2026-02-25T22:14:27.817902Z","steps":["trace[1918931188] 'range keys from in-memory index tree' (duration: 123.168429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:28.492162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.979441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:14:28.492574Z","caller":"traceutil/trace.go:171","msg":"trace[1499696320] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"669.927473ms","start":"2026-02-25T22:14:27.822621Z","end":"2026-02-25T22:14:28.492549Z","steps":["trace[1499696320] 'process raft request' (duration: 189.46858ms)","trace[1499696320] 'compare' (duration: 479.780087ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:28.492678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:27.822598Z","time spent":"670.032355ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:29.343577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.296189ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T22:14:29.343685Z","caller":"traceutil/trace.go:171","msg":"trace[1766287971] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:743; }","duration":"917.11009ms","start":"2026-02-25T22:14:28.426549Z","end":"2026-02-25T22:14:29.343659Z","steps":["trace[1766287971] 'read index received' (duration: 63.625718ms)","trace[1766287971] 'applied index is now lower than readState.Index' (duration: 853.483072ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:29.344107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.55618ms","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-25T22:14:29.3441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.969519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:14:29.344097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.819379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T22:14:29.344142Z","caller":"traceutil/trace.go:171","msg":"trace[733702259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"917.636132ms","start":"2026-02-25T22:14:28.426496Z","end":"2026-02-25T22:14:29.344132Z","steps":["trace[733702259] 'agreement among raft nodes before linearized reading' (duration: 917.428677ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:29.344172Z","caller":"traceutil/trace.go:171","msg":"trace[966906263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"171.084971ms","start":"2026-02-25T22:14:29.173068Z","end":"2026-02-25T22:14:29.344153Z","steps":["trace[966906263] 'agreement among raft nodes before linearized reading' (duration: 170.955368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:29.344183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:28.426477Z","time spent":"917.696684ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T22:14:29.344185Z","caller":"traceutil/trace.go:171","msg":"trace[354879076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"910.915752ms","start":"2026-02-25T22:14:28.433249Z","end":"2026-02-25T22:14:29.344165Z","steps":["trace[354879076] 'agreement among raft nodes before linearized reading' (duration: 910.672796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:29.344226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:28.433155Z","time spent":"911.061415ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T22:14:29.888829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301220,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T22:14:30.189175Z","caller":"traceutil/trace.go:171","msg":"trace[1409245973] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"841.213229ms","start":"2026-02-25T22:14:29.347944Z","end":"2026-02-25T22:14:30.189158Z","steps":["trace[1409245973] 'process raft request' (duration: 841.115047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:30.189277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:29.34792Z","time spent":"841.305142ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:31.003296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.710571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:14:31.003391Z","caller":"traceutil/trace.go:171","msg":"trace[1445924195] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"1.614579638s","start":"2026-02-25T22:14:29.388795Z","end":"2026-02-25T22:14:31.003375Z","steps":["trace[1445924195] 'read index received' (duration: 800.705133ms)","trace[1445924195] 'applied index is now lower than readState.Index' (duration: 813.872855ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:31.003474Z","caller":"traceutil/trace.go:171","msg":"trace[1612966153] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"1.654674284s","start":"2026-02-25T22:14:29.348789Z","end":"2026-02-25T22:14:31.003463Z","steps":["trace[1612966153] 'process raft request' (duration: 910.717507ms)","trace[1612966153] 'compare' (duration: 743.359793ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:31.003542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:29.348772Z","time spent":"1.654727655s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:31.003566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090856704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:14:31.003596Z","caller":"traceutil/trace.go:171","msg":"trace[1965363279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"1.090923115s","start":"2026-02-25T22:14:29.912663Z","end":"2026-02-25T22:14:31.003586Z","steps":["trace[1965363279] 'agreement among raft nodes before linearized reading' (duration: 1.090823873s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:31.003616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:29.912643Z","time spent":"1.090968566s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:14:31.003695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614909706s","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-25T22:14:31.00371Z","caller":"traceutil/trace.go:171","msg":"trace[929002909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:722; }","duration":"1.614957027s","start":"2026-02-25T22:14:29.388748Z","end":"2026-02-25T22:14:31.003705Z","steps":["trace[929002909] 'agreement among raft nodes before linearized reading' (duration: 1.614911986s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:31.003729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:29.38873Z","time spent":"1.614995278s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T22:14:31.003806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.031494ms","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-25T22:14:31.003824Z","caller":"traceutil/trace.go:171","msg":"trace[1968729472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:722; }","duration":"507.087496ms","start":"2026-02-25T22:14:30.49673Z","end":"2026-02-25T22:14:31.003818Z","steps":["trace[1968729472] 'agreement among raft nodes before linearized reading' (duration: 507.022864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:31.003841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:30.496711Z","time spent":"507.125517ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T22:14:31.00389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.19148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:31.003928Z","caller":"traceutil/trace.go:171","msg":"trace[384606165] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:722; }","duration":"733.254652ms","start":"2026-02-25T22:14:30.270659Z","end":"2026-02-25T22:14:31.003914Z","steps":["trace[384606165] 'agreement among raft nodes before linearized reading' (duration: 733.199601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:31.003959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:30.270646Z","time spent":"733.305743ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-25T22:14:31.381504Z","caller":"traceutil/trace.go:171","msg":"trace[21664881] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:748; }","duration":"209.220941ms","start":"2026-02-25T22:14:31.17226Z","end":"2026-02-25T22:14:31.381481Z","steps":["trace[21664881] 'read index received' (duration: 209.013336ms)","trace[21664881] 'applied index is now lower than readState.Index' (duration: 206.655µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:31.38156Z","caller":"traceutil/trace.go:171","msg":"trace[391764514] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"372.424415ms","start":"2026-02-25T22:14:31.009105Z","end":"2026-02-25T22:14:31.381529Z","steps":["trace[391764514] 'process raft request' (duration: 372.332663ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:31.381541Z","caller":"traceutil/trace.go:171","msg":"trace[398658569] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"373.02162ms","start":"2026-02-25T22:14:31.008483Z","end":"2026-02-25T22:14:31.381505Z","steps":["trace[398658569] 'process raft request' (duration: 372.811975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:31.381622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.350715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:31.381656Z","caller":"traceutil/trace.go:171","msg":"trace[1683831583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"209.440147ms","start":"2026-02-25T22:14:31.172205Z","end":"2026-02-25T22:14:31.381645Z","steps":["trace[1683831583] 'agreement among raft nodes before linearized reading' (duration: 209.366465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:31.381724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:31.009084Z","time spent":"372.549709ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:31.381756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:31.008456Z","time spent":"373.204364ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:32.675414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:14:33.176292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:14:33.325131Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.350356715s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T22:14:33.325441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:31.974658Z","time spent":"1.350777595s","remote":"127.0.0.1:34038","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T22:14:33.948922Z","caller":"traceutil/trace.go:171","msg":"trace[136313523] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:750; }","duration":"1.77430928s","start":"2026-02-25T22:14:32.174589Z","end":"2026-02-25T22:14:33.948898Z","steps":["trace[136313523] 'read index received' (duration: 1.150809774s)","trace[136313523] 'applied index is now lower than readState.Index' (duration: 623.498416ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:33.94906Z","caller":"traceutil/trace.go:171","msg":"trace[1160976518] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"1.73828392s","start":"2026-02-25T22:14:32.210766Z","end":"2026-02-25T22:14:33.94905Z","steps":["trace[1160976518] 'process raft request' (duration: 1.737986273s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.949172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:32.210746Z","time spent":"1.738344291s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:33.949218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.774581146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:33.949313Z","caller":"traceutil/trace.go:171","msg":"trace[1990376281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"1.7747364s","start":"2026-02-25T22:14:32.174558Z","end":"2026-02-25T22:14:33.949294Z","steps":["trace[1990376281] 'agreement among raft nodes before linearized reading' (duration: 1.774526075s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.949359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:32.174543Z","time spent":"1.774807101s","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T22:14:33.980433Z","caller":"traceutil/trace.go:171","msg":"trace[1376166923] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"653.781528ms","start":"2026-02-25T22:14:33.326635Z","end":"2026-02-25T22:14:33.980417Z","steps":["trace[1376166923] 'process raft request' (duration: 653.668295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.980541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:33.326611Z","time spent":"653.88103ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:14:33.980786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.427635ms","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-25T22:14:33.980809Z","caller":"traceutil/trace.go:171","msg":"trace[1789649940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:727; }","duration":"595.495767ms","start":"2026-02-25T22:14:33.385306Z","end":"2026-02-25T22:14:33.980802Z","steps":["trace[1789649940] 'agreement among raft nodes before linearized reading' (duration: 595.384654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.980829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:33.385289Z","time spent":"595.535888ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T22:14:33.980995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.007619ms","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-25T22:14:33.981043Z","caller":"traceutil/trace.go:171","msg":"trace[1616431695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"596.100311ms","start":"2026-02-25T22:14:33.384936Z","end":"2026-02-25T22:14:33.981037Z","steps":["trace[1616431695] 'agreement among raft nodes before linearized reading' (duration: 595.993449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.981062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:33.384916Z","time spent":"596.142113ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T22:14:33.981495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.609137ms","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-25T22:14:33.981522Z","caller":"traceutil/trace.go:171","msg":"trace[310462835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"973.681409ms","start":"2026-02-25T22:14:33.007835Z","end":"2026-02-25T22:14:33.981516Z","steps":["trace[310462835] 'agreement among raft nodes before linearized reading' (duration: 973.612497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.98154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:33.007814Z","time spent":"973.72173ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T22:14:33.981654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.537509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:14:33.981677Z","caller":"traceutil/trace.go:171","msg":"trace[2020074862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"891.59633ms","start":"2026-02-25T22:14:33.090075Z","end":"2026-02-25T22:14:33.981671Z","steps":["trace[2020074862] 'agreement among raft nodes before linearized reading' (duration: 891.535589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.981694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:33.090055Z","time spent":"891.634102ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:14:33.981676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.789282867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T22:14:33.981746Z","caller":"traceutil/trace.go:171","msg":"trace[15030768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"1.78941816s","start":"2026-02-25T22:14:32.19231Z","end":"2026-02-25T22:14:33.981728Z","steps":["trace[15030768] 'agreement among raft nodes before linearized reading' (duration: 1.789089722s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.98179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:32.192297Z","time spent":"1.789483582s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T22:14:33.981813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.724244415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T22:14:33.981882Z","caller":"traceutil/trace.go:171","msg":"trace[150034063] range","detail":"{range_begin:/registry/masterleases/162.253.55.74; range_end:; response_count:1; response_revision:727; }","duration":"1.724347008s","start":"2026-02-25T22:14:32.257518Z","end":"2026-02-25T22:14:33.981865Z","steps":["trace[150034063] 'agreement among raft nodes before linearized reading' (duration: 1.724145893s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:33.981923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:32.25749Z","time spent":"1.72442345s","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.74\" "} {"level":"info","ts":"2026-02-25T22:14:34.123713Z","caller":"traceutil/trace.go:171","msg":"trace[289304656] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"136.533777ms","start":"2026-02-25T22:14:33.987152Z","end":"2026-02-25T22:14:34.123686Z","steps":["trace[289304656] 'process raft request' (duration: 136.277241ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:34.123747Z","caller":"traceutil/trace.go:171","msg":"trace[1990019524] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"136.553707ms","start":"2026-02-25T22:14:33.987172Z","end":"2026-02-25T22:14:34.123726Z","steps":["trace[1990019524] 'process raft request' (duration: 136.446925ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:34.123994Z","caller":"traceutil/trace.go:171","msg":"trace[243548079] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"136.755633ms","start":"2026-02-25T22:14:33.987233Z","end":"2026-02-25T22:14:34.123989Z","steps":["trace[243548079] 'process raft request' (duration: 136.437915ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:34.124114Z","caller":"traceutil/trace.go:171","msg":"trace[1561696457] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"136.766962ms","start":"2026-02-25T22:14:33.987336Z","end":"2026-02-25T22:14:34.124102Z","steps":["trace[1561696457] 'process raft request' (duration: 136.586778ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:34.124221Z","caller":"traceutil/trace.go:171","msg":"trace[2032671484] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"134.780426ms","start":"2026-02-25T22:14:33.989428Z","end":"2026-02-25T22:14:34.124208Z","steps":["trace[2032671484] 'process raft request' (duration: 134.635623ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:34.124207Z","caller":"traceutil/trace.go:171","msg":"trace[53832535] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:753; }","duration":"134.276373ms","start":"2026-02-25T22:14:33.989905Z","end":"2026-02-25T22:14:34.124182Z","steps":["trace[53832535] 'read index received' (duration: 49.497821ms)","trace[53832535] 'applied index is now lower than readState.Index' (duration: 84.777352ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:34.124368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.432927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:34.124395Z","caller":"traceutil/trace.go:171","msg":"trace[846248256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"134.507259ms","start":"2026-02-25T22:14:33.98988Z","end":"2026-02-25T22:14:34.124387Z","steps":["trace[846248256] 'agreement among raft nodes before linearized reading' (duration: 134.397797ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:34.475472Z","caller":"traceutil/trace.go:171","msg":"trace[2134383463] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"285.164663ms","start":"2026-02-25T22:14:34.19028Z","end":"2026-02-25T22:14:34.475444Z","steps":["trace[2134383463] 'read index received' (duration: 238.07146ms)","trace[2134383463] 'applied index is now lower than readState.Index' (duration: 47.091503ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:34.475504Z","caller":"traceutil/trace.go:171","msg":"trace[7950648] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"402.674627ms","start":"2026-02-25T22:14:34.072792Z","end":"2026-02-25T22:14:34.475466Z","steps":["trace[7950648] 'process raft request' (duration: 355.657995ms)","trace[7950648] 'compare' (duration: 46.817937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:34.475653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.361167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:14:34.475707Z","caller":"traceutil/trace.go:171","msg":"trace[698145372] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:733; }","duration":"285.48046ms","start":"2026-02-25T22:14:34.190215Z","end":"2026-02-25T22:14:34.475695Z","steps":["trace[698145372] 'agreement among raft nodes before linearized reading' (duration: 285.327827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:34.47574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:34.072763Z","time spent":"402.8213ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:35.576088Z","caller":"traceutil/trace.go:171","msg":"trace[1380843407] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"396.977731ms","start":"2026-02-25T22:14:35.17909Z","end":"2026-02-25T22:14:35.576068Z","steps":["trace[1380843407] 'read index received' (duration: 396.891749ms)","trace[1380843407] 'applied index is now lower than readState.Index' (duration: 85.062µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:35.576137Z","caller":"traceutil/trace.go:171","msg":"trace[1168436912] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"759.964121ms","start":"2026-02-25T22:14:34.816147Z","end":"2026-02-25T22:14:35.576111Z","steps":["trace[1168436912] 'process raft request' (duration: 759.808677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:35.576181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.074493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:35.576201Z","caller":"traceutil/trace.go:171","msg":"trace[850732121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"397.179236ms","start":"2026-02-25T22:14:35.179016Z","end":"2026-02-25T22:14:35.576195Z","steps":["trace[850732121] 'agreement among raft nodes before linearized reading' (duration: 397.125794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:35.576223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:35.178996Z","time spent":"397.219726ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:14:35.576259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:34.816114Z","time spent":"760.078443ms","remote":"127.0.0.1:34142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5169,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:35.857515Z","caller":"traceutil/trace.go:171","msg":"trace[2096422252] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"280.529053ms","start":"2026-02-25T22:14:35.576964Z","end":"2026-02-25T22:14:35.857493Z","steps":["trace[2096422252] 'read index received' (duration: 280.352918ms)","trace[2096422252] 'applied index is now lower than readState.Index' (duration: 175.265µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:35.85769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.690146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:35.857723Z","caller":"traceutil/trace.go:171","msg":"trace[799219750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:735; }","duration":"280.779629ms","start":"2026-02-25T22:14:35.576934Z","end":"2026-02-25T22:14:35.857714Z","steps":["trace[799219750] 'agreement among raft nodes before linearized reading' (duration: 280.679596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:35.857758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.513668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:14:35.857842Z","caller":"traceutil/trace.go:171","msg":"trace[1404067148] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:735; }","duration":"261.675732ms","start":"2026-02-25T22:14:35.596148Z","end":"2026-02-25T22:14:35.857824Z","steps":["trace[1404067148] 'agreement among raft nodes before linearized reading' (duration: 261.499088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:35.858357Z","caller":"traceutil/trace.go:171","msg":"trace[1235293724] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"584.409512ms","start":"2026-02-25T22:14:35.273933Z","end":"2026-02-25T22:14:35.858343Z","steps":["trace[1235293724] 'process raft request' (duration: 583.436319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:35.858455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:35.273898Z","time spent":"584.497654ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:38.284877Z","caller":"traceutil/trace.go:171","msg":"trace[1313846481] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"104.950034ms","start":"2026-02-25T22:14:38.179907Z","end":"2026-02-25T22:14:38.284857Z","steps":["trace[1313846481] 'process raft request' (duration: 104.799021ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:38.285113Z","caller":"traceutil/trace.go:171","msg":"trace[675807359] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"104.965944ms","start":"2026-02-25T22:14:38.180138Z","end":"2026-02-25T22:14:38.285104Z","steps":["trace[675807359] 'process raft request' (duration: 104.685047ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:38.285388Z","caller":"traceutil/trace.go:171","msg":"trace[901713085] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"104.223396ms","start":"2026-02-25T22:14:38.181145Z","end":"2026-02-25T22:14:38.285368Z","steps":["trace[901713085] 'process raft request' (duration: 103.925269ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:54.502573Z","caller":"traceutil/trace.go:171","msg":"trace[132452583] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"126.216561ms","start":"2026-02-25T22:14:54.376333Z","end":"2026-02-25T22:14:54.50255Z","steps":["trace[132452583] 'process raft request' (duration: 126.1734ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:54.502613Z","caller":"traceutil/trace.go:171","msg":"trace[1568973389] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"127.486081ms","start":"2026-02-25T22:14:54.375096Z","end":"2026-02-25T22:14:54.502582Z","steps":["trace[1568973389] 'process raft request' (duration: 127.269296ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:54.535644Z","caller":"traceutil/trace.go:171","msg":"trace[892037326] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"149.709831ms","start":"2026-02-25T22:14:54.385916Z","end":"2026-02-25T22:14:54.535626Z","steps":["trace[892037326] 'process raft request' (duration: 149.381984ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:56.684701Z","caller":"traceutil/trace.go:171","msg":"trace[1566821636] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"331.719313ms","start":"2026-02-25T22:14:56.352958Z","end":"2026-02-25T22:14:56.684677Z","steps":["trace[1566821636] 'read index received' (duration: 331.485888ms)","trace[1566821636] 'applied index is now lower than readState.Index' (duration: 232.335µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:14:56.684787Z","caller":"traceutil/trace.go:171","msg":"trace[587011706] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"399.488481ms","start":"2026-02-25T22:14:56.285256Z","end":"2026-02-25T22:14:56.684744Z","steps":["trace[587011706] 'process raft request' (duration: 399.291186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:56.684941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.96858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:14:56.684975Z","caller":"traceutil/trace.go:171","msg":"trace[34099527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"332.056631ms","start":"2026-02-25T22:14:56.352908Z","end":"2026-02-25T22:14:56.684965Z","steps":["trace[34099527] 'agreement among raft nodes before linearized reading' (duration: 331.913078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:56.68498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.951368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T22:14:56.68501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:56.352887Z","time spent":"332.113653ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T22:14:56.685053Z","caller":"traceutil/trace.go:171","msg":"trace[607188459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:796; }","duration":"311.044641ms","start":"2026-02-25T22:14:56.373968Z","end":"2026-02-25T22:14:56.685013Z","steps":["trace[607188459] 'agreement among raft nodes before linearized reading' (duration: 310.911957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:56.68509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:56.373947Z","time spent":"311.133123ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T22:14:56.685088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.266372ms","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-25T22:14:56.685185Z","caller":"traceutil/trace.go:171","msg":"trace[1737902625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:796; }","duration":"178.411436ms","start":"2026-02-25T22:14:56.506747Z","end":"2026-02-25T22:14:56.685158Z","steps":["trace[1737902625] 'agreement among raft nodes before linearized reading' (duration: 178.16089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:56.685218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.488368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T22:14:56.685186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:56.285232Z","time spent":"399.692445ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:56.685256Z","caller":"traceutil/trace.go:171","msg":"trace[1544176029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:796; }","duration":"178.55752ms","start":"2026-02-25T22:14:56.506681Z","end":"2026-02-25T22:14:56.685239Z","steps":["trace[1544176029] 'agreement among raft nodes before linearized reading' (duration: 178.478178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:56.685301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.543766ms","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-25T22:14:56.685355Z","caller":"traceutil/trace.go:171","msg":"trace[1170733212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"299.599907ms","start":"2026-02-25T22:14:56.38574Z","end":"2026-02-25T22:14:56.68534Z","steps":["trace[1170733212] 'agreement among raft nodes before linearized reading' (duration: 299.452284ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:57.003097Z","caller":"traceutil/trace.go:171","msg":"trace[1581246288] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"313.783625ms","start":"2026-02-25T22:14:56.689282Z","end":"2026-02-25T22:14:57.003065Z","steps":["trace[1581246288] 'process raft request' (duration: 229.123235ms)","trace[1581246288] 'compare' (duration: 84.519336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:14:57.003308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:56.689266Z","time spent":"313.971079ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:57.081174Z","caller":"traceutil/trace.go:171","msg":"trace[1857569679] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"391.805726ms","start":"2026-02-25T22:14:56.689346Z","end":"2026-02-25T22:14:57.081152Z","steps":["trace[1857569679] 'process raft request' (duration: 391.630922ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:57.081517Z","caller":"traceutil/trace.go:171","msg":"trace[21894599] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"392.132794ms","start":"2026-02-25T22:14:56.689371Z","end":"2026-02-25T22:14:57.081504Z","steps":["trace[21894599] 'process raft request' (duration: 391.733595ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:14:57.081592Z","caller":"traceutil/trace.go:171","msg":"trace[955316153] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"391.591491ms","start":"2026-02-25T22:14:56.689975Z","end":"2026-02-25T22:14:57.081567Z","steps":["trace[955316153] 'process raft request' (duration: 391.531409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:57.081625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:56.689355Z","time spent":"392.236686ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:57.081672Z","caller":"traceutil/trace.go:171","msg":"trace[112727220] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"391.98542ms","start":"2026-02-25T22:14:56.689669Z","end":"2026-02-25T22:14:57.081654Z","steps":["trace[112727220] 'process raft request' (duration: 391.738074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:57.08197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:56.689659Z","time spent":"392.265367ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:57.081749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:56.689965Z","time spent":"391.703123ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:14:57.0825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:56.689327Z","time spent":"392.030272ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:14:59.119525Z","caller":"traceutil/trace.go:171","msg":"trace[519875053] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"107.904983ms","start":"2026-02-25T22:14:59.011023Z","end":"2026-02-25T22:14:59.118928Z","steps":["trace[519875053] 'process raft request' (duration: 107.731019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:14:59.676111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:15:00.177063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:15:00.418518Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.194292071s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T22:15:00.929146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.359887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:15:00.929236Z","caller":"traceutil/trace.go:171","msg":"trace[453214313] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"1.806496667s","start":"2026-02-25T22:14:59.12272Z","end":"2026-02-25T22:15:00.929217Z","steps":["trace[453214313] 'process raft request' (duration: 1.295986068s)","trace[453214313] 'compare' (duration: 509.744941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:00.929312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:59.122704Z","time spent":"1.806561048s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:01.175215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000091757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T22:15:01.175293Z","caller":"traceutil/trace.go:171","msg":"trace[1988060951] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000216739s","start":"2026-02-25T22:14:59.175056Z","end":"2026-02-25T22:15:01.175273Z","steps":["trace[1988060951] 'agreement among raft nodes before linearized reading' (duration: 2.000115346s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:01.175348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:59.175038Z","time spent":"2.000300961s","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 22:15:01 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T22:15:01.42632Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.007577248s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T22:15:01.426687Z","caller":"traceutil/trace.go:171","msg":"trace[527398323] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"2.303612587s","start":"2026-02-25T22:14:59.123048Z","end":"2026-02-25T22:15:01.426661Z","steps":["trace[527398323] 'process raft request' (duration: 2.303431192s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:01.426833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:59.123036Z","time spent":"2.303700748s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:01.42703Z","caller":"traceutil/trace.go:171","msg":"trace[150074050] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:835; }","duration":"2.251916714s","start":"2026-02-25T22:14:59.175091Z","end":"2026-02-25T22:15:01.427007Z","steps":["trace[150074050] 'read index received' (duration: 1.243612878s)","trace[150074050] 'applied index is now lower than readState.Index' (duration: 1.008302756s)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:01.427107Z","caller":"traceutil/trace.go:171","msg":"trace[1512438124] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"2.303097344s","start":"2026-02-25T22:14:59.123967Z","end":"2026-02-25T22:15:01.427064Z","steps":["trace[1512438124] 'process raft request' (duration: 2.302820668s)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:01.427158Z","caller":"traceutil/trace.go:171","msg":"trace[1738198509] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"2.304025907s","start":"2026-02-25T22:14:59.123099Z","end":"2026-02-25T22:15:01.427125Z","steps":["trace[1738198509] 'process raft request' (duration: 2.303516925s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:01.427281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:59.123953Z","time spent":"2.303243927s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:01.427309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:59.123075Z","time spent":"2.304160759s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:01.427629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509713187s","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-25T22:15:01.427679Z","caller":"traceutil/trace.go:171","msg":"trace[790635375] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:806; }","duration":"1.50980083s","start":"2026-02-25T22:14:59.917867Z","end":"2026-02-25T22:15:01.427668Z","steps":["trace[790635375] 'agreement among raft nodes before linearized reading' (duration: 1.509717128s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:01.427715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:14:59.917849Z","time spent":"1.509853551s","remote":"127.0.0.1:34462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:15:01.427796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.33538ms","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-25T22:15:01.427832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021159682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T22:15:01.427859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.639957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:15:01.427861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282247311s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:15:01.42788Z","caller":"traceutil/trace.go:171","msg":"trace[707238308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"304.473633ms","start":"2026-02-25T22:15:01.123387Z","end":"2026-02-25T22:15:01.427861Z","steps":["trace[707238308] 'agreement among raft nodes before linearized reading' (duration: 304.203787ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:01.427898Z","caller":"traceutil/trace.go:171","msg":"trace[2021886669] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:806; }","duration":"1.021270895s","start":"2026-02-25T22:15:00.406613Z","end":"2026-02-25T22:15:01.427884Z","steps":["trace[2021886669] 'agreement among raft nodes before linearized reading' (duration: 1.021148192s)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:01.427911Z","caller":"traceutil/trace.go:171","msg":"trace[1031629536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"252.717058ms","start":"2026-02-25T22:15:01.175179Z","end":"2026-02-25T22:15:01.427896Z","steps":["trace[1031629536] 'agreement among raft nodes before linearized reading' (duration: 252.634096ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:01.427921Z","caller":"traceutil/trace.go:171","msg":"trace[307616078] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:806; }","duration":"1.282320302s","start":"2026-02-25T22:15:00.145584Z","end":"2026-02-25T22:15:01.427904Z","steps":["trace[307616078] 'agreement among raft nodes before linearized reading' (duration: 1.28221059s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:01.427954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:01.123369Z","time spent":"304.553006ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T22:15:01.42797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:00.406591Z","time spent":"1.021368957s","remote":"127.0.0.1:34126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:15:01.427997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:00.145529Z","time spent":"1.282456446s","remote":"127.0.0.1:34476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:15:01.760348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.950726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:15:01.760443Z","caller":"traceutil/trace.go:171","msg":"trace[700903181] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:840; }","duration":"324.467742ms","start":"2026-02-25T22:15:01.43596Z","end":"2026-02-25T22:15:01.760428Z","steps":["trace[700903181] 'read index received' (duration: 16.408882ms)","trace[700903181] 'applied index is now lower than readState.Index' (duration: 308.05741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:01.760542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.578764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:01.760569Z","caller":"traceutil/trace.go:171","msg":"trace[2118571788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"324.637136ms","start":"2026-02-25T22:15:01.435921Z","end":"2026-02-25T22:15:01.760558Z","steps":["trace[2118571788] 'agreement among raft nodes before linearized reading' (duration: 324.548384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:01.760606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:01.435907Z","time spent":"324.689727ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T22:15:01.760545Z","caller":"traceutil/trace.go:171","msg":"trace[394782801] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"327.311709ms","start":"2026-02-25T22:15:01.433199Z","end":"2026-02-25T22:15:01.760511Z","steps":["trace[394782801] 'process raft request' (duration: 198.126387ms)","trace[394782801] 'compare' (duration: 128.730311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:01.760739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:01.433178Z","time spent":"327.485593ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:07.167101Z","caller":"traceutil/trace.go:171","msg":"trace[298475767] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"101.549103ms","start":"2026-02-25T22:15:07.065531Z","end":"2026-02-25T22:15:07.16708Z","steps":["trace[298475767] 'process raft request' (duration: 101.45334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:07.563751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.397883ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-25T22:15:07.564095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.880165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:15:07.564151Z","caller":"traceutil/trace.go:171","msg":"trace[516292319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"115.985487ms","start":"2026-02-25T22:15:07.448149Z","end":"2026-02-25T22:15:07.564135Z","steps":["trace[516292319] 'agreement among raft nodes before linearized reading' (duration: 115.801583ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:07.563889Z","caller":"traceutil/trace.go:171","msg":"trace[1553981651] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:856; }","duration":"115.66856ms","start":"2026-02-25T22:15:07.4482Z","end":"2026-02-25T22:15:07.563869Z","steps":["trace[1553981651] 'read index received' (duration: 42.061µs)","trace[1553981651] 'applied index is now lower than readState.Index' (duration: 115.624689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:07.564738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.579383ms","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-25T22:15:07.56479Z","caller":"traceutil/trace.go:171","msg":"trace[113410818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:822; }","duration":"114.650015ms","start":"2026-02-25T22:15:07.45013Z","end":"2026-02-25T22:15:07.56478Z","steps":["trace[113410818] 'agreement among raft nodes before linearized reading' (duration: 114.517692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:07.564935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.990333ms","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-25T22:15:07.565304Z","caller":"traceutil/trace.go:171","msg":"trace[2001808251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"115.527376ms","start":"2026-02-25T22:15:07.449753Z","end":"2026-02-25T22:15:07.56528Z","steps":["trace[2001808251] 'agreement among raft nodes before linearized reading' (duration: 114.887751ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:12.756359Z","caller":"traceutil/trace.go:171","msg":"trace[274205327] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"106.527111ms","start":"2026-02-25T22:15:12.649805Z","end":"2026-02-25T22:15:12.756332Z","steps":["trace[274205327] 'process raft request' (duration: 106.400728ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:13.048772Z","caller":"traceutil/trace.go:171","msg":"trace[345104801] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"105.012266ms","start":"2026-02-25T22:15:12.943739Z","end":"2026-02-25T22:15:13.048751Z","steps":["trace[345104801] 'process raft request' (duration: 104.976105ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:13.048919Z","caller":"traceutil/trace.go:171","msg":"trace[1907829779] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"105.607789ms","start":"2026-02-25T22:15:12.943303Z","end":"2026-02-25T22:15:13.048911Z","steps":["trace[1907829779] 'process raft request' (duration: 105.289472ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:13.27556Z","caller":"traceutil/trace.go:171","msg":"trace[1634922814] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"181.991872ms","start":"2026-02-25T22:15:13.093545Z","end":"2026-02-25T22:15:13.275537Z","steps":["trace[1634922814] 'process raft request' (duration: 181.848748ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:13.292707Z","caller":"traceutil/trace.go:171","msg":"trace[1415105044] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:882; }","duration":"171.962682ms","start":"2026-02-25T22:15:13.120697Z","end":"2026-02-25T22:15:13.29266Z","steps":["trace[1415105044] 'read index received' (duration: 154.793683ms)","trace[1415105044] 'applied index is now lower than readState.Index' (duration: 17.167709ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:13.292743Z","caller":"traceutil/trace.go:171","msg":"trace[1081674878] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"198.822704ms","start":"2026-02-25T22:15:13.093896Z","end":"2026-02-25T22:15:13.292719Z","steps":["trace[1081674878] 'process raft request' (duration: 198.6504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:13.29289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.173817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:13.292959Z","caller":"traceutil/trace.go:171","msg":"trace[1486839845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"172.27116ms","start":"2026-02-25T22:15:13.120669Z","end":"2026-02-25T22:15:13.29294Z","steps":["trace[1486839845] 'agreement among raft nodes before linearized reading' (duration: 172.131437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:13.293652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.997817ms","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-25T22:15:13.293719Z","caller":"traceutil/trace.go:171","msg":"trace[2080756252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"160.103869ms","start":"2026-02-25T22:15:13.133598Z","end":"2026-02-25T22:15:13.293702Z","steps":["trace[2080756252] 'agreement among raft nodes before linearized reading' (duration: 159.243259ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:13.448474Z","caller":"traceutil/trace.go:171","msg":"trace[222352634] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"150.648524ms","start":"2026-02-25T22:15:13.297797Z","end":"2026-02-25T22:15:13.448446Z","steps":["trace[222352634] 'process raft request' (duration: 53.345303ms)","trace[222352634] 'compare' (duration: 97.117527ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:13.448532Z","caller":"traceutil/trace.go:171","msg":"trace[1657336577] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"150.619794ms","start":"2026-02-25T22:15:13.297902Z","end":"2026-02-25T22:15:13.448522Z","steps":["trace[1657336577] 'process raft request' (duration: 150.47173ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:13.448483Z","caller":"traceutil/trace.go:171","msg":"trace[650652208] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"150.207394ms","start":"2026-02-25T22:15:13.29826Z","end":"2026-02-25T22:15:13.448467Z","steps":["trace[650652208] 'process raft request' (duration: 150.146782ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:13.696237Z","caller":"traceutil/trace.go:171","msg":"trace[1093522044] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"192.034181ms","start":"2026-02-25T22:15:13.504157Z","end":"2026-02-25T22:15:13.696191Z","steps":["trace[1093522044] 'process raft request' (duration: 191.846867ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:15.607078Z","caller":"traceutil/trace.go:171","msg":"trace[1754909299] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"150.47803ms","start":"2026-02-25T22:15:15.456575Z","end":"2026-02-25T22:15:15.607053Z","steps":["trace[1754909299] 'process raft request' (duration: 150.344597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:16.737328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.541952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:15:16.737346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.791983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:16.737395Z","caller":"traceutil/trace.go:171","msg":"trace[1116566881] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:899; }","duration":"215.656965ms","start":"2026-02-25T22:15:16.521717Z","end":"2026-02-25T22:15:16.737374Z","steps":["trace[1116566881] 'count revisions from in-memory index tree' (duration: 215.45551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:16.73741Z","caller":"traceutil/trace.go:171","msg":"trace[1084676501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"561.930446ms","start":"2026-02-25T22:15:16.175463Z","end":"2026-02-25T22:15:16.737393Z","steps":["trace[1084676501] 'range keys from in-memory index tree' (duration: 561.68049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:16.737443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:16.175432Z","time spent":"562.003018ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:15:16.737484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.579806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:16.737551Z","caller":"traceutil/trace.go:171","msg":"trace[1977442998] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:899; }","duration":"291.73467ms","start":"2026-02-25T22:15:16.445799Z","end":"2026-02-25T22:15:16.737534Z","steps":["trace[1977442998] 'count revisions from in-memory index tree' (duration: 291.433933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:16.73775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.031575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:16.737842Z","caller":"traceutil/trace.go:171","msg":"trace[2096078665] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:899; }","duration":"390.176188ms","start":"2026-02-25T22:15:16.347642Z","end":"2026-02-25T22:15:16.737818Z","steps":["trace[2096078665] 'count revisions from in-memory index tree' (duration: 389.626865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:16.737901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:16.34762Z","time spent":"390.262691ms","remote":"127.0.0.1:34184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:15:19.727614Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.99106505s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T22:15:19.729059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.992521435s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T22:15:19.729668Z","caller":"traceutil/trace.go:171","msg":"trace[580326787] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:936; }","duration":"2.119091495s","start":"2026-02-25T22:15:17.610553Z","end":"2026-02-25T22:15:19.729644Z","steps":["trace[580326787] 'read index received' (duration: 125.919684ms)","trace[580326787] 'applied index is now lower than readState.Index' (duration: 1.993170251s)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:19.729698Z","caller":"traceutil/trace.go:171","msg":"trace[469072338] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"2.251604216s","start":"2026-02-25T22:15:17.478062Z","end":"2026-02-25T22:15:19.729666Z","steps":["trace[469072338] 'process raft request' (duration: 2.251085504s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:19.72982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:17.478035Z","time spent":"2.251713058s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:20.314297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.386933345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T22:15:20.314334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.139796158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:20.314383Z","caller":"traceutil/trace.go:171","msg":"trace[1413424685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"2.387061787s","start":"2026-02-25T22:15:17.927297Z","end":"2026-02-25T22:15:20.314359Z","steps":["trace[1413424685] 'agreement among raft nodes before linearized reading' (duration: 1.802540922s)","trace[1413424685] 'range keys from in-memory index tree' (duration: 584.30512ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:20.314408Z","caller":"traceutil/trace.go:171","msg":"trace[2069917934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"2.139938792s","start":"2026-02-25T22:15:18.174449Z","end":"2026-02-25T22:15:20.314388Z","steps":["trace[2069917934] 'agreement among raft nodes before linearized reading' (duration: 1.555363106s)","trace[2069917934] 'range keys from in-memory index tree' (duration: 584.456233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:20.314432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:17.927284Z","time spent":"2.38713653s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T22:15:20.314454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:18.174432Z","time spent":"2.140011803s","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:15:20.314466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.449061267s","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-25T22:15:20.314544Z","caller":"traceutil/trace.go:171","msg":"trace[1842936214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"2.449177129s","start":"2026-02-25T22:15:17.865345Z","end":"2026-02-25T22:15:20.314523Z","steps":["trace[1842936214] 'agreement among raft nodes before linearized reading' (duration: 1.86448099s)","trace[1842936214] 'range keys from in-memory index tree' (duration: 584.500474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:20.31456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.891864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:15:20.314562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.554056452s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T22:15:20.314576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:17.865333Z","time spent":"2.449236021s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T22:15:20.31459Z","caller":"traceutil/trace.go:171","msg":"trace[2015757847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"138.953025ms","start":"2026-02-25T22:15:20.175627Z","end":"2026-02-25T22:15:20.31458Z","steps":["trace[2015757847] 'range keys from in-memory index tree' (duration: 138.819902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:20.31455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.435891813s","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-25T22:15:20.3146Z","caller":"traceutil/trace.go:171","msg":"trace[1886895681] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:900; }","duration":"2.554128424s","start":"2026-02-25T22:15:17.760459Z","end":"2026-02-25T22:15:20.314587Z","steps":["trace[1886895681] 'agreement among raft nodes before linearized reading' (duration: 1.969335742s)","trace[1886895681] 'count revisions from in-memory index tree' (duration: 584.744941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:20.314634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:17.76044Z","time spent":"2.554185684s","remote":"127.0.0.1:34158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:15:20.314632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.436011955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:15:20.314643Z","caller":"traceutil/trace.go:171","msg":"trace[1026212959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"2.436029585s","start":"2026-02-25T22:15:17.878593Z","end":"2026-02-25T22:15:20.314622Z","steps":["trace[1026212959] 'agreement among raft nodes before linearized reading' (duration: 1.851224244s)","trace[1026212959] 'range keys from in-memory index tree' (duration: 584.576616ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:20.314681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:17.878554Z","time spent":"2.436120058s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T22:15:20.314678Z","caller":"traceutil/trace.go:171","msg":"trace[1572509642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"2.436094177s","start":"2026-02-25T22:15:17.878568Z","end":"2026-02-25T22:15:20.314662Z","steps":["trace[1572509642] 'agreement among raft nodes before linearized reading' (duration: 1.851251815s)","trace[1572509642] 'range keys from in-memory index tree' (duration: 584.705209ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:20.31471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:17.878546Z","time spent":"2.436156039s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:15:20.314963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.704396918s","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-25T22:15:20.315039Z","caller":"traceutil/trace.go:171","msg":"trace[852756423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"2.704498001s","start":"2026-02-25T22:15:17.610524Z","end":"2026-02-25T22:15:20.315022Z","steps":["trace[852756423] 'agreement among raft nodes before linearized reading' (duration: 2.119239399s)","trace[852756423] 'range keys from in-memory index tree' (duration: 584.581266ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:20.315086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:17.61051Z","time spent":"2.704556642s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T22:15:20.819353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301673,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T22:15:21.042395Z","caller":"traceutil/trace.go:171","msg":"trace[528384908] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"723.728945ms","start":"2026-02-25T22:15:20.318644Z","end":"2026-02-25T22:15:21.042373Z","steps":["trace[528384908] 'process raft request' (duration: 723.584602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:21.042562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:20.318629Z","time spent":"723.839328ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:21.140053Z","caller":"traceutil/trace.go:171","msg":"trace[1790883949] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"821.291503ms","start":"2026-02-25T22:15:20.318737Z","end":"2026-02-25T22:15:21.140028Z","steps":["trace[1790883949] 'process raft request' (duration: 821.049017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:21.140247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:20.318724Z","time spent":"821.436766ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:21.140355Z","caller":"traceutil/trace.go:171","msg":"trace[1127169272] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:939; }","duration":"821.014136ms","start":"2026-02-25T22:15:20.319316Z","end":"2026-02-25T22:15:21.14033Z","steps":["trace[1127169272] 'read index received' (duration: 723.682464ms)","trace[1127169272] 'applied index is now lower than readState.Index' (duration: 97.330762ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:21.14037Z","caller":"traceutil/trace.go:171","msg":"trace[919723366] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"821.462046ms","start":"2026-02-25T22:15:20.318873Z","end":"2026-02-25T22:15:21.140335Z","steps":["trace[919723366] 'process raft request' (duration: 821.072647ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:21.140479Z","caller":"traceutil/trace.go:171","msg":"trace[1652533279] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"820.637767ms","start":"2026-02-25T22:15:20.319814Z","end":"2026-02-25T22:15:21.140451Z","steps":["trace[1652533279] 'process raft request' (duration: 820.460953ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:21.140467Z","caller":"traceutil/trace.go:171","msg":"trace[1224909207] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"821.211111ms","start":"2026-02-25T22:15:20.319185Z","end":"2026-02-25T22:15:21.140396Z","steps":["trace[1224909207] 'process raft request' (duration: 821.006886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:21.14061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.268102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18979d1737323745\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-25T22:15:21.140655Z","caller":"traceutil/trace.go:171","msg":"trace[2088347751] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18979d1737323745; range_end:; response_count:1; response_revision:905; }","duration":"821.339714ms","start":"2026-02-25T22:15:20.319302Z","end":"2026-02-25T22:15:21.140642Z","steps":["trace[2088347751] 'agreement among raft nodes before linearized reading' (duration: 821.16394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:21.140656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.484992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:15:21.140661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:20.319808Z","time spent":"820.7474ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:21.140694Z","caller":"traceutil/trace.go:171","msg":"trace[1741188001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"817.538353ms","start":"2026-02-25T22:15:20.323145Z","end":"2026-02-25T22:15:21.140684Z","steps":["trace[1741188001] 'agreement among raft nodes before linearized reading' (duration: 817.461321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:21.140699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:20.319296Z","time spent":"821.392275ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18979d1737323745\" "} {"level":"warn","ts":"2026-02-25T22:15:21.140693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.740955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:15:21.140733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:20.323136Z","time spent":"817.590695ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T22:15:21.140743Z","caller":"traceutil/trace.go:171","msg":"trace[1477920970] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:905; }","duration":"588.791986ms","start":"2026-02-25T22:15:20.551939Z","end":"2026-02-25T22:15:21.140731Z","steps":["trace[1477920970] 'agreement among raft nodes before linearized reading' (duration: 588.720224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:21.140775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:20.551877Z","time spent":"588.890298ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:15:21.14055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:20.318854Z","time spent":"821.5982ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:21.141504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:20.319168Z","time spent":"821.440416ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:37.813153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:15:38.204312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.394988ms","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-25T22:15:38.204435Z","caller":"traceutil/trace.go:171","msg":"trace[1615286783] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:947; }","duration":"828.526211ms","start":"2026-02-25T22:15:37.375887Z","end":"2026-02-25T22:15:38.204414Z","steps":["trace[1615286783] 'range keys from in-memory index tree' (duration: 828.380028ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:38.20481Z","caller":"traceutil/trace.go:171","msg":"trace[712117988] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"921.246222ms","start":"2026-02-25T22:15:37.283534Z","end":"2026-02-25T22:15:38.20478Z","steps":["trace[712117988] 'process raft request' (duration: 916.5374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:38.204955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:37.283508Z","time spent":"921.372204ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:38.294997Z","caller":"traceutil/trace.go:171","msg":"trace[810257758] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:988; }","duration":"982.270827ms","start":"2026-02-25T22:15:37.312698Z","end":"2026-02-25T22:15:38.294969Z","steps":["trace[810257758] 'read index received' (duration: 887.384995ms)","trace[810257758] 'applied index is now lower than readState.Index' (duration: 94.883942ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:38.295212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.503483ms","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-25T22:15:38.29527Z","caller":"traceutil/trace.go:171","msg":"trace[986388047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"982.564264ms","start":"2026-02-25T22:15:37.312691Z","end":"2026-02-25T22:15:38.295256Z","steps":["trace[986388047] 'agreement among raft nodes before linearized reading' (duration: 982.426931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:38.295301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:37.312632Z","time spent":"982.661456ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T22:15:38.295315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.625268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:15:38.295378Z","caller":"traceutil/trace.go:171","msg":"trace[1100715887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"980.726579ms","start":"2026-02-25T22:15:37.314635Z","end":"2026-02-25T22:15:38.295361Z","steps":["trace[1100715887] 'agreement among raft nodes before linearized reading' (duration: 980.507404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:38.295434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:37.314625Z","time spent":"980.795742ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:15:38.295813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.752967ms","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-25T22:15:38.295865Z","caller":"traceutil/trace.go:171","msg":"trace[1535100056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"940.806118ms","start":"2026-02-25T22:15:37.355047Z","end":"2026-02-25T22:15:38.295853Z","steps":["trace[1535100056] 'agreement among raft nodes before linearized reading' (duration: 940.656375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:38.295903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:37.354988Z","time spent":"940.90463ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T22:15:39.409083Z","caller":"traceutil/trace.go:171","msg":"trace[1613786936] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"121.560969ms","start":"2026-02-25T22:15:39.287502Z","end":"2026-02-25T22:15:39.409063Z","steps":["trace[1613786936] 'process raft request' (duration: 121.395545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:42.567369Z","caller":"traceutil/trace.go:171","msg":"trace[721487174] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"140.917588ms","start":"2026-02-25T22:15:42.426431Z","end":"2026-02-25T22:15:42.567349Z","steps":["trace[721487174] 'process raft request' (duration: 140.784815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:42.819926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.305693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:15:42.82039Z","caller":"traceutil/trace.go:171","msg":"trace[200268242] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"245.37366ms","start":"2026-02-25T22:15:42.574985Z","end":"2026-02-25T22:15:42.820359Z","steps":["trace[200268242] 'process raft request' (duration: 86.554574ms)","trace[200268242] 'compare' (duration: 158.186561ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:42.820698Z","caller":"traceutil/trace.go:171","msg":"trace[1635602140] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"244.173551ms","start":"2026-02-25T22:15:42.57623Z","end":"2026-02-25T22:15:42.820404Z","steps":["trace[1635602140] 'process raft request' (duration: 244.034507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:44.766162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:15:45.244094Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.01553358s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T22:15:45.24436Z","caller":"traceutil/trace.go:171","msg":"trace[693472645] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"978.542198ms","start":"2026-02-25T22:15:44.265794Z","end":"2026-02-25T22:15:45.244337Z","steps":["trace[693472645] 'read index received' (duration: 978.392054ms)","trace[693472645] 'applied index is now lower than readState.Index' (duration: 149.544µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:45.244558Z","caller":"traceutil/trace.go:171","msg":"trace[500264255] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"1.016111284s","start":"2026-02-25T22:15:44.228439Z","end":"2026-02-25T22:15:45.244551Z","steps":["trace[500264255] 'process raft request' (duration: 1.015789306s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:45.244649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:44.228414Z","time spent":"1.016171265s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:45.24481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.023059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T22:15:45.244838Z","caller":"traceutil/trace.go:171","msg":"trace[615739054] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:985; }","duration":"979.097121ms","start":"2026-02-25T22:15:44.265734Z","end":"2026-02-25T22:15:45.244831Z","steps":["trace[615739054] 'agreement among raft nodes before linearized reading' (duration: 979.0322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:45.244858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:44.265712Z","time spent":"979.141432ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:15:45.245007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.606515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:15:45.245056Z","caller":"traceutil/trace.go:171","msg":"trace[527168411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:985; }","duration":"883.691457ms","start":"2026-02-25T22:15:44.361355Z","end":"2026-02-25T22:15:45.245047Z","steps":["trace[527168411] 'agreement among raft nodes before linearized reading' (duration: 883.595764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:45.245076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:44.361334Z","time spent":"883.737327ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:15:45.245302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.621541ms","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-25T22:15:45.245329Z","caller":"traceutil/trace.go:171","msg":"trace[411239257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:985; }","duration":"882.725333ms","start":"2026-02-25T22:15:44.362599Z","end":"2026-02-25T22:15:45.245324Z","steps":["trace[411239257] 'agreement among raft nodes before linearized reading' (duration: 882.664752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:45.245347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:44.362576Z","time spent":"882.766795ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T22:15:45.245452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.680766ms","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-25T22:15:45.245475Z","caller":"traceutil/trace.go:171","msg":"trace[938422630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:985; }","duration":"883.762768ms","start":"2026-02-25T22:15:44.361707Z","end":"2026-02-25T22:15:45.24547Z","steps":["trace[938422630] 'agreement among raft nodes before linearized reading' (duration: 883.711236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:45.245491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:44.361685Z","time spent":"883.801649ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T22:15:45.997829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301881,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:15:46.06106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.745175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:15:46.061136Z","caller":"traceutil/trace.go:171","msg":"trace[383475734] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"808.722399ms","start":"2026-02-25T22:15:45.252402Z","end":"2026-02-25T22:15:46.061125Z","steps":["trace[383475734] 'process raft request' (duration: 99.84276ms)","trace[383475734] 'compare' (duration: 708.548621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:46.061184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:45.252375Z","time spent":"808.792841ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:46.153902Z","caller":"traceutil/trace.go:171","msg":"trace[1555414882] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"901.019969ms","start":"2026-02-25T22:15:45.252851Z","end":"2026-02-25T22:15:46.153871Z","steps":["trace[1555414882] 'process raft request' (duration: 900.855365ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:46.153955Z","caller":"traceutil/trace.go:171","msg":"trace[1295822779] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"900.879256ms","start":"2026-02-25T22:15:45.252966Z","end":"2026-02-25T22:15:46.153845Z","steps":["trace[1295822779] 'process raft request' (duration: 900.838005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:46.154036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:45.252824Z","time spent":"901.139992ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:46.15408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:45.252957Z","time spent":"901.05671ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:46.384389Z","caller":"traceutil/trace.go:171","msg":"trace[109643048] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"887.22974ms","start":"2026-02-25T22:15:45.497133Z","end":"2026-02-25T22:15:46.384363Z","steps":["trace[109643048] 'read index received' (duration: 656.790197ms)","trace[109643048] 'applied index is now lower than readState.Index' (duration: 230.438273ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:46.38461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.451196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T22:15:46.384646Z","caller":"traceutil/trace.go:171","msg":"trace[1344533431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"887.63832ms","start":"2026-02-25T22:15:45.496995Z","end":"2026-02-25T22:15:46.384633Z","steps":["trace[1344533431] 'agreement among raft nodes before linearized reading' (duration: 887.453656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:46.384682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:45.496977Z","time spent":"887.695662ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T22:15:46.384771Z","caller":"traceutil/trace.go:171","msg":"trace[209965452] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"825.159471ms","start":"2026-02-25T22:15:45.559466Z","end":"2026-02-25T22:15:46.384625Z","steps":["trace[209965452] 'process raft request' (duration: 751.850333ms)","trace[209965452] 'compare' (duration: 72.944679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:46.384954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:45.559449Z","time spent":"825.402856ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:47.807816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.335613ms","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-25T22:15:47.807962Z","caller":"traceutil/trace.go:171","msg":"trace[79937845] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:990; }","duration":"431.502657ms","start":"2026-02-25T22:15:47.37644Z","end":"2026-02-25T22:15:47.807943Z","steps":["trace[79937845] 'range keys from in-memory index tree' (duration: 431.316892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:47.808609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227908433s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:15:47.808714Z","caller":"traceutil/trace.go:171","msg":"trace[1071990299] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"647.880515ms","start":"2026-02-25T22:15:47.160814Z","end":"2026-02-25T22:15:47.808695Z","steps":["trace[1071990299] 'read index received' (duration: 49.211µs)","trace[1071990299] 'applied index is now lower than readState.Index' (duration: 647.829863ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:47.808788Z","caller":"traceutil/trace.go:171","msg":"trace[500569576] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.405789752s","start":"2026-02-25T22:15:46.402987Z","end":"2026-02-25T22:15:47.808777Z","steps":["trace[500569576] 'process raft request' (duration: 177.637084ms)","trace[500569576] 'compare' (duration: 1.227270777s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:47.808843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:46.402972Z","time spent":"1.405845214s","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:15:47.808941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.123001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:47.808988Z","caller":"traceutil/trace.go:171","msg":"trace[1249989622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"648.203092ms","start":"2026-02-25T22:15:47.160774Z","end":"2026-02-25T22:15:47.808977Z","steps":["trace[1249989622] 'agreement among raft nodes before linearized reading' (duration: 648.041618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:47.809625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:47.160735Z","time spent":"648.875988ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:15:47.996086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.672704ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T22:15:47.996306Z","caller":"traceutil/trace.go:171","msg":"trace[701234759] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"187.547321ms","start":"2026-02-25T22:15:47.808745Z","end":"2026-02-25T22:15:47.996292Z","steps":["trace[701234759] 'read index received' (duration: 51.566029ms)","trace[701234759] 'applied index is now lower than readState.Index' (duration: 135.980112ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:47.996363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.728085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:47.996379Z","caller":"traceutil/trace.go:171","msg":"trace[720915058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:991; }","duration":"820.798307ms","start":"2026-02-25T22:15:47.175575Z","end":"2026-02-25T22:15:47.996373Z","steps":["trace[720915058] 'agreement among raft nodes before linearized reading' (duration: 820.751485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:47.996402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:47.175549Z","time spent":"820.846998ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:15:47.996902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.5024ms","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-25T22:15:47.996972Z","caller":"traceutil/trace.go:171","msg":"trace[1855928191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:991; }","duration":"747.616182ms","start":"2026-02-25T22:15:47.249338Z","end":"2026-02-25T22:15:47.996954Z","steps":["trace[1855928191] 'agreement among raft nodes before linearized reading' (duration: 747.416368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:47.997006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:47.249319Z","time spent":"747.679014ms","remote":"127.0.0.1:34228","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-25T22:15:48.308367Z","caller":"traceutil/trace.go:171","msg":"trace[88406230] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"306.730993ms","start":"2026-02-25T22:15:48.001613Z","end":"2026-02-25T22:15:48.308344Z","steps":["trace[88406230] 'process raft request' (duration: 306.280792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:48.308632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:48.001586Z","time spent":"306.968588ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:48.308683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.29478ms","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-25T22:15:48.308741Z","caller":"traceutil/trace.go:171","msg":"trace[1521053688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"243.410013ms","start":"2026-02-25T22:15:48.065318Z","end":"2026-02-25T22:15:48.308728Z","steps":["trace[1521053688] 'agreement among raft nodes before linearized reading' (duration: 243.209088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:48.308476Z","caller":"traceutil/trace.go:171","msg":"trace[407602922] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1037; }","duration":"243.079065ms","start":"2026-02-25T22:15:48.065365Z","end":"2026-02-25T22:15:48.308444Z","steps":["trace[407602922] 'read index received' (duration: 243.067785ms)","trace[407602922] 'applied index is now lower than readState.Index' (duration: 8.5µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:48.309173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.427406ms","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-25T22:15:48.309254Z","caller":"traceutil/trace.go:171","msg":"trace[1410864509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"150.550799ms","start":"2026-02-25T22:15:48.15869Z","end":"2026-02-25T22:15:48.309241Z","steps":["trace[1410864509] 'agreement among raft nodes before linearized reading' (duration: 150.374145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:48.309247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.349473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:48.309404Z","caller":"traceutil/trace.go:171","msg":"trace[1728095389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:992; }","duration":"134.535287ms","start":"2026-02-25T22:15:48.174841Z","end":"2026-02-25T22:15:48.309376Z","steps":["trace[1728095389] 'agreement among raft nodes before linearized reading' (duration: 134.334043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:48.309649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.440666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T22:15:48.309723Z","caller":"traceutil/trace.go:171","msg":"trace[1244204340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"150.59225ms","start":"2026-02-25T22:15:48.159107Z","end":"2026-02-25T22:15:48.309699Z","steps":["trace[1244204340] 'agreement among raft nodes before linearized reading' (duration: 150.260062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:48.626431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.714594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:15:48.626722Z","caller":"traceutil/trace.go:171","msg":"trace[1606849455] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1037; }","duration":"210.272602ms","start":"2026-02-25T22:15:48.416419Z","end":"2026-02-25T22:15:48.626691Z","steps":["trace[1606849455] 'read index received' (duration: 145.345494ms)","trace[1606849455] 'applied index is now lower than readState.Index' (duration: 64.925758ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:48.626735Z","caller":"traceutil/trace.go:171","msg":"trace[253471469] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"313.245618ms","start":"2026-02-25T22:15:48.313419Z","end":"2026-02-25T22:15:48.626665Z","steps":["trace[253471469] 'process raft request' (duration: 94.166295ms)","trace[253471469] 'compare' (duration: 218.483508ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:48.626883Z","caller":"traceutil/trace.go:171","msg":"trace[575297171] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"313.286979ms","start":"2026-02-25T22:15:48.31359Z","end":"2026-02-25T22:15:48.626877Z","steps":["trace[575297171] 'process raft request' (duration: 312.992362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:48.626833Z","caller":"traceutil/trace.go:171","msg":"trace[837129140] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"312.309986ms","start":"2026-02-25T22:15:48.31449Z","end":"2026-02-25T22:15:48.6268Z","steps":["trace[837129140] 'process raft request' (duration: 312.163182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:48.626988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:48.313582Z","time spent":"313.325699ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:48.626988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.13624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T22:15:48.627033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:48.313405Z","time spent":"313.490793ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:48.62712Z","caller":"traceutil/trace.go:171","msg":"trace[1803353812] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:995; }","duration":"132.275844ms","start":"2026-02-25T22:15:48.494775Z","end":"2026-02-25T22:15:48.627051Z","steps":["trace[1803353812] 'agreement among raft nodes before linearized reading' (duration: 132.11913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:48.62713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:48.314481Z","time spent":"312.541531ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:48.627627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.551229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T22:15:48.627738Z","caller":"traceutil/trace.go:171","msg":"trace[1343868033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:995; }","duration":"211.353619ms","start":"2026-02-25T22:15:48.416329Z","end":"2026-02-25T22:15:48.627683Z","steps":["trace[1343868033] 'agreement among raft nodes before linearized reading' (duration: 210.476228ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:50.18052Z","caller":"traceutil/trace.go:171","msg":"trace[1610180423] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"493.171607ms","start":"2026-02-25T22:15:49.687324Z","end":"2026-02-25T22:15:50.180496Z","steps":["trace[1610180423] 'read index received' (duration: 492.914831ms)","trace[1610180423] 'applied index is now lower than readState.Index' (duration: 255.396µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:50.180704Z","caller":"traceutil/trace.go:171","msg":"trace[1205209319] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"577.267321ms","start":"2026-02-25T22:15:49.603299Z","end":"2026-02-25T22:15:50.180566Z","steps":["trace[1205209319] 'process raft request' (duration: 577.014105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:50.180746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.401602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:50.180847Z","caller":"traceutil/trace.go:171","msg":"trace[757106080] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:997; }","duration":"493.558916ms","start":"2026-02-25T22:15:49.687274Z","end":"2026-02-25T22:15:50.180833Z","steps":["trace[757106080] 'agreement among raft nodes before linearized reading' (duration: 493.343861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:50.180888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:49.687252Z","time spent":"493.625147ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T22:15:50.180989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:49.603265Z","time spent":"577.538797ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:15:50.814202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301906,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:15:51.247493Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058370911s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T22:15:51.248098Z","caller":"traceutil/trace.go:171","msg":"trace[603813523] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"1.059083938s","start":"2026-02-25T22:15:50.188989Z","end":"2026-02-25T22:15:51.248073Z","steps":["trace[603813523] 'process raft request' (duration: 1.058925734s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.248244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:50.188966Z","time spent":"1.059212711s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:15:51.584608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.14782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:15:51.585041Z","caller":"traceutil/trace.go:171","msg":"trace[11050722] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"1.232155064s","start":"2026-02-25T22:15:50.352834Z","end":"2026-02-25T22:15:51.58499Z","steps":["trace[11050722] 'process raft request' (duration: 969.541254ms)","trace[11050722] 'compare' (duration: 261.76116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:51.585126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:50.352808Z","time spent":"1.232279586s","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:51.585614Z","caller":"traceutil/trace.go:171","msg":"trace[152985426] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1042; }","duration":"1.272164128s","start":"2026-02-25T22:15:50.313436Z","end":"2026-02-25T22:15:51.5856Z","steps":["trace[152985426] 'read index received' (duration: 934.429087ms)","trace[152985426] 'applied index is now lower than readState.Index' (duration: 337.733831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:51.585862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272425863s","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-25T22:15:51.585936Z","caller":"traceutil/trace.go:171","msg":"trace[1285454294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1000; }","duration":"1.272568467s","start":"2026-02-25T22:15:50.31335Z","end":"2026-02-25T22:15:51.585918Z","steps":["trace[1285454294] 'agreement among raft nodes before linearized reading' (duration: 1.272342632s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.585984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:50.313317Z","time spent":"1.27265552s","remote":"127.0.0.1:34228","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-25T22:15:51.586003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.542546ms","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-25T22:15:51.586045Z","caller":"traceutil/trace.go:171","msg":"trace[1108579433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"954.619297ms","start":"2026-02-25T22:15:50.631412Z","end":"2026-02-25T22:15:51.586032Z","steps":["trace[1108579433] 'agreement among raft nodes before linearized reading' (duration: 954.460123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.586082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:50.631392Z","time spent":"954.683279ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T22:15:51.586187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.791373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:51.586222Z","caller":"traceutil/trace.go:171","msg":"trace[1067812620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"410.871334ms","start":"2026-02-25T22:15:51.175342Z","end":"2026-02-25T22:15:51.586213Z","steps":["trace[1067812620] 'agreement among raft nodes before linearized reading' (duration: 410.821073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.586247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:51.17529Z","time spent":"410.952287ms","remote":"127.0.0.1:33928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:15:51.586312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.78936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T22:15:51.586355Z","caller":"traceutil/trace.go:171","msg":"trace[48973699] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"912.886222ms","start":"2026-02-25T22:15:50.673457Z","end":"2026-02-25T22:15:51.586343Z","steps":["trace[48973699] 'agreement among raft nodes before linearized reading' (duration: 912.76978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.586387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:50.673441Z","time spent":"912.936153ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T22:15:51.586399Z","caller":"traceutil/trace.go:171","msg":"trace[834066482] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"332.64941ms","start":"2026-02-25T22:15:51.253739Z","end":"2026-02-25T22:15:51.586389Z","steps":["trace[834066482] 'process raft request' (duration: 331.785459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.586468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:51.253699Z","time spent":"332.730191ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T22:15:51.586568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.626808ms","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-25T22:15:51.586608Z","caller":"traceutil/trace.go:171","msg":"trace[1347432657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"954.680068ms","start":"2026-02-25T22:15:50.631917Z","end":"2026-02-25T22:15:51.586597Z","steps":["trace[1347432657] 'agreement among raft nodes before linearized reading' (duration: 954.592466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.586637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:50.63191Z","time spent":"954.71858ms","remote":"127.0.0.1:34228","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-25T22:15:51.586672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.511464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-25T22:15:51.585551Z","caller":"traceutil/trace.go:171","msg":"trace[1508499931] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"462.434414ms","start":"2026-02-25T22:15:51.123071Z","end":"2026-02-25T22:15:51.585506Z","steps":["trace[1508499931] 'process raft request' (duration: 461.641176ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:51.586709Z","caller":"traceutil/trace.go:171","msg":"trace[1587849033] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1001; }","duration":"334.579796ms","start":"2026-02-25T22:15:51.25212Z","end":"2026-02-25T22:15:51.5867Z","steps":["trace[1587849033] 'agreement among raft nodes before linearized reading' (duration: 334.479214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.586738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:51.252101Z","time spent":"334.630407ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T22:15:51.586803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.307404ms","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-25T22:15:51.586838Z","caller":"traceutil/trace.go:171","msg":"trace[366267436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"955.384626ms","start":"2026-02-25T22:15:50.631445Z","end":"2026-02-25T22:15:51.58683Z","steps":["trace[366267436] 'agreement among raft nodes before linearized reading' (duration: 955.292624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:51.586867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:50.631423Z","time spent":"955.437177ms","remote":"127.0.0.1:34228","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-25T22:15:51.586908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:51.123046Z","time spent":"463.713454ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:15:51.84793Z","caller":"traceutil/trace.go:171","msg":"trace[982306745] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"256.061744ms","start":"2026-02-25T22:15:51.591844Z","end":"2026-02-25T22:15:51.847905Z","steps":["trace[982306745] 'process raft request' (duration: 255.747956ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:51.848022Z","caller":"traceutil/trace.go:171","msg":"trace[390873075] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"256.069774ms","start":"2026-02-25T22:15:51.591891Z","end":"2026-02-25T22:15:51.84796Z","steps":["trace[390873075] 'process raft request' (duration: 255.852919ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:51.848056Z","caller":"traceutil/trace.go:171","msg":"trace[1245913692] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"255.416119ms","start":"2026-02-25T22:15:51.592588Z","end":"2026-02-25T22:15:51.848004Z","steps":["trace[1245913692] 'process raft request' (duration: 255.272456ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:51.847981Z","caller":"traceutil/trace.go:171","msg":"trace[648326332] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"245.468752ms","start":"2026-02-25T22:15:51.602495Z","end":"2026-02-25T22:15:51.847964Z","steps":["trace[648326332] 'process raft request' (duration: 245.429681ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:51.848019Z","caller":"traceutil/trace.go:171","msg":"trace[1014455102] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"256.070124ms","start":"2026-02-25T22:15:51.591934Z","end":"2026-02-25T22:15:51.848004Z","steps":["trace[1014455102] 'process raft request' (duration: 255.88648ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:51.848028Z","caller":"traceutil/trace.go:171","msg":"trace[405245126] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"253.569315ms","start":"2026-02-25T22:15:51.59445Z","end":"2026-02-25T22:15:51.848019Z","steps":["trace[405245126] 'process raft request' (duration: 253.442732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:53.674906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360525313301922,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T22:15:53.88649Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.437525229s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T22:15:53.886838Z","caller":"traceutil/trace.go:171","msg":"trace[1489688844] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"711.942361ms","start":"2026-02-25T22:15:53.174872Z","end":"2026-02-25T22:15:53.886814Z","steps":["trace[1489688844] 'read index received' (duration: 711.754937ms)","trace[1489688844] 'applied index is now lower than readState.Index' (duration: 186.704µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:53.886943Z","caller":"traceutil/trace.go:171","msg":"trace[585075201] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.447046705s","start":"2026-02-25T22:15:52.439886Z","end":"2026-02-25T22:15:53.886933Z","steps":["trace[585075201] 'process raft request' (duration: 1.446794189s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:53.887342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:52.439858Z","time spent":"1.447119157s","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:53.887457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.497314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:15:53.887634Z","caller":"traceutil/trace.go:171","msg":"trace[1051872784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"712.792521ms","start":"2026-02-25T22:15:53.174821Z","end":"2026-02-25T22:15:53.887614Z","steps":["trace[1051872784] 'agreement among raft nodes before linearized reading' (duration: 712.453013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:53.887709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:53.174801Z","time spent":"712.892934ms","remote":"127.0.0.1:33926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T22:15:54.238604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.284052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T22:15:54.238863Z","caller":"traceutil/trace.go:171","msg":"trace[1096693287] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1055; }","duration":"344.844601ms","start":"2026-02-25T22:15:53.894002Z","end":"2026-02-25T22:15:54.238846Z","steps":["trace[1096693287] 'read index received' (duration: 137.268532ms)","trace[1096693287] 'applied index is now lower than readState.Index' (duration: 207.575059ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:15:54.238981Z","caller":"traceutil/trace.go:171","msg":"trace[79377421] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"345.21609ms","start":"2026-02-25T22:15:53.893755Z","end":"2026-02-25T22:15:54.238971Z","steps":["trace[79377421] 'process raft request' (duration: 344.990834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:54.239049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:53.893732Z","time spent":"345.283592ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:54.239269Z","caller":"traceutil/trace.go:171","msg":"trace[184616171] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"345.294812ms","start":"2026-02-25T22:15:53.893948Z","end":"2026-02-25T22:15:54.239242Z","steps":["trace[184616171] 'process raft request' (duration: 344.845642ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:54.239303Z","caller":"traceutil/trace.go:171","msg":"trace[1784540504] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"345.306652ms","start":"2026-02-25T22:15:53.893977Z","end":"2026-02-25T22:15:54.239283Z","steps":["trace[1784540504] 'process raft request' (duration: 344.841891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:54.239493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:53.893942Z","time spent":"345.425575ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:54.239425Z","caller":"traceutil/trace.go:171","msg":"trace[2012521670] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"346.122302ms","start":"2026-02-25T22:15:53.893259Z","end":"2026-02-25T22:15:54.239382Z","steps":["trace[2012521670] 'process raft request' (duration: 137.986629ms)","trace[2012521670] 'compare' (duration: 206.972435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:54.239595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:53.893856Z","time spent":"345.657981ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:54.239663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:53.893224Z","time spent":"346.390158ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:54.23976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.445125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T22:15:54.239835Z","caller":"traceutil/trace.go:171","msg":"trace[721291174] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1014; }","duration":"345.805694ms","start":"2026-02-25T22:15:53.89398Z","end":"2026-02-25T22:15:54.239786Z","steps":["trace[721291174] 'agreement among raft nodes before linearized reading' (duration: 345.21048ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:54.239818Z","caller":"traceutil/trace.go:171","msg":"trace[1022048634] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"345.297742ms","start":"2026-02-25T22:15:53.893714Z","end":"2026-02-25T22:15:54.239011Z","steps":["trace[1022048634] 'process raft request' (duration: 344.985264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:54.239892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:53.893975Z","time spent":"345.888436ms","remote":"127.0.0.1:33978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-25T22:15:54.239922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:53.893688Z","time spent":"346.194514ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:57.220523Z","caller":"traceutil/trace.go:171","msg":"trace[1626456237] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"971.13025ms","start":"2026-02-25T22:15:56.249334Z","end":"2026-02-25T22:15:57.220464Z","steps":["trace[1626456237] 'process raft request' (duration: 970.969296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:57.220676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:56.249316Z","time spent":"971.283244ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:57.22356Z","caller":"traceutil/trace.go:171","msg":"trace[1274474877] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1061; }","duration":"370.215816ms","start":"2026-02-25T22:15:56.853324Z","end":"2026-02-25T22:15:57.223539Z","steps":["trace[1274474877] 'read index received' (duration: 367.621114ms)","trace[1274474877] 'applied index is now lower than readState.Index' (duration: 2.593322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:15:57.22379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.473492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-25T22:15:57.223856Z","caller":"traceutil/trace.go:171","msg":"trace[1686265570] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1020; }","duration":"370.587205ms","start":"2026-02-25T22:15:56.853252Z","end":"2026-02-25T22:15:57.223839Z","steps":["trace[1686265570] 'agreement among raft nodes before linearized reading' (duration: 370.384731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:57.2239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:56.853224Z","time spent":"370.665077ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-25T22:15:57.22418Z","caller":"traceutil/trace.go:171","msg":"trace[210302103] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"974.385948ms","start":"2026-02-25T22:15:56.249778Z","end":"2026-02-25T22:15:57.224164Z","steps":["trace[210302103] 'process raft request' (duration: 973.61623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:57.22426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:56.24977Z","time spent":"974.439029ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:57.224418Z","caller":"traceutil/trace.go:171","msg":"trace[1026627298] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"974.652905ms","start":"2026-02-25T22:15:56.249743Z","end":"2026-02-25T22:15:57.224396Z","steps":["trace[1026627298] 'process raft request' (duration: 973.594869ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:57.224418Z","caller":"traceutil/trace.go:171","msg":"trace[776333308] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"973.986339ms","start":"2026-02-25T22:15:56.250411Z","end":"2026-02-25T22:15:57.224397Z","steps":["trace[776333308] 'process raft request' (duration: 973.035386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:57.224525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:56.24973Z","time spent":"974.737916ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T22:15:57.224551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:56.250402Z","time spent":"974.078061ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:57.224677Z","caller":"traceutil/trace.go:171","msg":"trace[729348634] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"586.626764ms","start":"2026-02-25T22:15:56.638036Z","end":"2026-02-25T22:15:57.224662Z","steps":["trace[729348634] 'process raft request' (duration: 585.455686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:57.224764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:56.638012Z","time spent":"586.706286ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:57.224987Z","caller":"traceutil/trace.go:171","msg":"trace[1701043708] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"975.276079ms","start":"2026-02-25T22:15:56.249691Z","end":"2026-02-25T22:15:57.224967Z","steps":["trace[1701043708] 'process raft request' (duration: 973.511127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:15:57.225121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:15:56.24967Z","time spent":"975.402222ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T22:15:59.434882Z","caller":"traceutil/trace.go:171","msg":"trace[405258798] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"121.563708ms","start":"2026-02-25T22:15:59.313251Z","end":"2026-02-25T22:15:59.434815Z","steps":["trace[405258798] 'process raft request' (duration: 121.381723ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:59.436177Z","caller":"traceutil/trace.go:171","msg":"trace[263004354] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"122.48573ms","start":"2026-02-25T22:15:59.31365Z","end":"2026-02-25T22:15:59.436135Z","steps":["trace[263004354] 'process raft request' (duration: 122.217484ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:59.436357Z","caller":"traceutil/trace.go:171","msg":"trace[1972227635] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"122.528281ms","start":"2026-02-25T22:15:59.313817Z","end":"2026-02-25T22:15:59.436345Z","steps":["trace[1972227635] 'process raft request' (duration: 122.264765ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:15:59.436514Z","caller":"traceutil/trace.go:171","msg":"trace[1619056126] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"121.63056ms","start":"2026-02-25T22:15:59.314862Z","end":"2026-02-25T22:15:59.436493Z","steps":["trace[1619056126] 'process raft request' (duration: 121.402174ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:16:02.928141Z","caller":"traceutil/trace.go:171","msg":"trace[1590808439] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"430.81102ms","start":"2026-02-25T22:16:02.497309Z","end":"2026-02-25T22:16:02.92812Z","steps":["trace[1590808439] 'read index received' (duration: 430.643066ms)","trace[1590808439] 'applied index is now lower than readState.Index' (duration: 167.084µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:16:02.928267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.938304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:16:02.928298Z","caller":"traceutil/trace.go:171","msg":"trace[1246670459] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1059; }","duration":"431.011095ms","start":"2026-02-25T22:16:02.497277Z","end":"2026-02-25T22:16:02.928288Z","steps":["trace[1246670459] 'agreement among raft nodes before linearized reading' (duration: 430.924833ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:16:02.92827Z","caller":"traceutil/trace.go:171","msg":"trace[1438532337] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"439.371794ms","start":"2026-02-25T22:16:02.488841Z","end":"2026-02-25T22:16:02.928213Z","steps":["trace[1438532337] 'process raft request' (duration: 439.130008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:16:02.928332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:16:02.497264Z","time spent":"431.057597ms","remote":"127.0.0.1:34228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-25T22:16:02.92839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:16:02.488823Z","time spent":"439.516078ms","remote":"127.0.0.1:34038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":795,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T22:16:17.707126Z","caller":"traceutil/trace.go:171","msg":"trace[976643818] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"119.342725ms","start":"2026-02-25T22:16:17.587742Z","end":"2026-02-25T22:16:17.707085Z","steps":["trace[976643818] 'process raft request' (duration: 46.876647ms)","trace[976643818] 'compare' (duration: 72.248683ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:16:17.707674Z","caller":"traceutil/trace.go:171","msg":"trace[275548716] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"118.68203ms","start":"2026-02-25T22:16:17.588974Z","end":"2026-02-25T22:16:17.707656Z","steps":["trace[275548716] 'process raft request' (duration: 118.041404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:16:49.875717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.782267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T22:16:49.875819Z","caller":"traceutil/trace.go:171","msg":"trace[1881534438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1257; }","duration":"106.92061ms","start":"2026-02-25T22:16:49.768878Z","end":"2026-02-25T22:16:49.875798Z","steps":["trace[1881534438] 'range keys from in-memory index tree' (duration: 106.632463ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:17:02.233473Z","caller":"traceutil/trace.go:171","msg":"trace[598145466] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"201.455946ms","start":"2026-02-25T22:17:02.031985Z","end":"2026-02-25T22:17:02.233441Z","steps":["trace[598145466] 'process raft request' (duration: 109.167693ms)","trace[598145466] 'compare' (duration: 92.094348ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T22:17:08.369285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.156162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:17:08.369395Z","caller":"traceutil/trace.go:171","msg":"trace[1307433088] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1334; }","duration":"154.333186ms","start":"2026-02-25T22:17:08.215038Z","end":"2026-02-25T22:17:08.369371Z","steps":["trace[1307433088] 'count revisions from in-memory index tree' (duration: 154.0661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:17:08.369416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.243586ms","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-25T22:17:08.36948Z","caller":"traceutil/trace.go:171","msg":"trace[1322536183] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1334; }","duration":"112.336219ms","start":"2026-02-25T22:17:08.257132Z","end":"2026-02-25T22:17:08.369468Z","steps":["trace[1322536183] 'range keys from in-memory index tree' (duration: 112.069312ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:17:28.352005Z","caller":"traceutil/trace.go:171","msg":"trace[603877810] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"107.539455ms","start":"2026-02-25T22:17:28.244319Z","end":"2026-02-25T22:17:28.351859Z","steps":["trace[603877810] 'process raft request' (duration: 16.775329ms)","trace[603877810] 'compare' (duration: 90.635753ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:17:44.489819Z","caller":"traceutil/trace.go:171","msg":"trace[40407789] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"100.479381ms","start":"2026-02-25T22:17:44.389316Z","end":"2026-02-25T22:17:44.489795Z","steps":["trace[40407789] 'process raft request' (duration: 100.156003ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:18:53.341505Z","caller":"traceutil/trace.go:171","msg":"trace[1549655448] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"184.031557ms","start":"2026-02-25T22:18:53.157444Z","end":"2026-02-25T22:18:53.341475Z","steps":["trace[1549655448] 'process raft request' (duration: 143.453287ms)","trace[1549655448] 'compare' (duration: 40.429747ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:18:53.343603Z","caller":"traceutil/trace.go:171","msg":"trace[1978521918] linearizableReadLoop","detail":"{readStateIndex:1851; appliedIndex:1849; }","duration":"180.38434ms","start":"2026-02-25T22:18:53.163191Z","end":"2026-02-25T22:18:53.343576Z","steps":["trace[1978521918] 'read index received' (duration: 137.582697ms)","trace[1978521918] 'applied index is now lower than readState.Index' (duration: 42.800423ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T22:18:53.343721Z","caller":"traceutil/trace.go:171","msg":"trace[366755832] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"186.130207ms","start":"2026-02-25T22:18:53.157538Z","end":"2026-02-25T22:18:53.343668Z","steps":["trace[366755832] 'process raft request' (duration: 185.923142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:18:53.343812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.597556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:18:53.343841Z","caller":"traceutil/trace.go:171","msg":"trace[196287899] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1768; }","duration":"180.657387ms","start":"2026-02-25T22:18:53.163176Z","end":"2026-02-25T22:18:53.343833Z","steps":["trace[196287899] 'agreement among raft nodes before linearized reading' (duration: 180.540195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:18:53.343899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.791323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:18:53.343985Z","caller":"traceutil/trace.go:171","msg":"trace[752099118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1768; }","duration":"170.956907ms","start":"2026-02-25T22:18:53.173005Z","end":"2026-02-25T22:18:53.343962Z","steps":["trace[752099118] 'agreement among raft nodes before linearized reading' (duration: 170.787503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:19:10.962031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.99333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T22:19:10.962171Z","caller":"traceutil/trace.go:171","msg":"trace[1508928987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"158.187235ms","start":"2026-02-25T22:19:10.803959Z","end":"2026-02-25T22:19:10.962146Z","steps":["trace[1508928987] 'range keys from in-memory index tree' (duration: 157.788515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:19:10.962235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.25951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T22:19:10.962332Z","caller":"traceutil/trace.go:171","msg":"trace[1988079159] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1860; }","duration":"480.418584ms","start":"2026-02-25T22:19:10.481892Z","end":"2026-02-25T22:19:10.962311Z","steps":["trace[1988079159] 'count revisions from in-memory index tree' (duration: 479.920032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T22:19:10.96238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T22:19:10.481868Z","time spent":"480.500836ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T22:19:16.202793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.875495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:500 ","response":"range_response_count:1 size:7034"} {"level":"info","ts":"2026-02-25T22:19:16.202921Z","caller":"traceutil/trace.go:171","msg":"trace[969484400] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:1889; }","duration":"111.069879ms","start":"2026-02-25T22:19:16.091825Z","end":"2026-02-25T22:19:16.202895Z","steps":["trace[969484400] 'range keys from in-memory index tree' (duration: 110.553887ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T22:19:27.477713Z","caller":"traceutil/trace.go:171","msg":"trace[609523535] transaction","detail":"{read_only:false; response_revision:1939; number_of_response:1; }","duration":"127.420736ms","start":"2026-02-25T22:19:27.350261Z","end":"2026-02-25T22:19:27.477682Z","steps":["trace[609523535] 'process raft request' (duration: 68.268766ms)","trace[609523535] 'compare' (duration: 58.790652ms)"],"step_count":2}