{"level":"warn","ts":"2026-02-22T17:47:58.022499Z","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-22T17:47:58.022647Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-22T17:47:58.022807Z","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-22T17:47:58.022834Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-22T17:47:58.022881Z","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-22T17:47:58.024446Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-02-22T17:47:58.024617Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-22T17:47:58.028716Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.598085ms"} {"level":"info","ts":"2026-02-22T17:47:58.033429Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-02-22T17:47:58.0335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-02-22T17:47:58.033521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-02-22T17:47:58.033538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-22T17:47:58.033548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-02-22T17:47:58.033583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-02-22T17:47:58.05517Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-22T17:47:58.05651Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-22T17:47:58.057855Z","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-22T17:47:58.060372Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-22T17:47:58.061189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-02-22T17:47:58.06128Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-22T17:47:58.061319Z","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-22T17:47:58.061359Z","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-22T17:47:58.061366Z","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-22T17:47:58.060862Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-22T17:47:58.064719Z","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-22T17:47:58.064886Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-22T17:47:58.064949Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-22T17:47:58.064981Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-22T17:47:58.064923Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-22T17:47:58.933825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-02-22T17:47:58.933911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-02-22T17:47:58.933932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-02-22T17:47:58.933954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-02-22T17:47:58.933965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-22T17:47:58.933976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-02-22T17:47:58.933989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-22T17:47:58.93525Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-02-22T17:47:58.935417Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:47:58.935453Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:47:58.935604Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:47:58.936788Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:47:58.93692Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:47:58.936958Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:47:58.93739Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-22T17:47:58.938293Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-22T17:47:58.938338Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-22T17:47:58.938492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-02-22T17:48:23.885843Z","caller":"traceutil/trace.go:171","msg":"trace[1327818017] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"106.43809ms","start":"2026-02-22T17:48:23.779364Z","end":"2026-02-22T17:48:23.885802Z","steps":["trace[1327818017] 'process raft request' (duration: 106.235946ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:24.392192Z","caller":"traceutil/trace.go:171","msg":"trace[921517985] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"410.200401ms","start":"2026-02-22T17:48:23.981943Z","end":"2026-02-22T17:48:24.392143Z","steps":["trace[921517985] 'read index received' (duration: 340.91548ms)","trace[921517985] 'applied index is now lower than readState.Index' (duration: 69.283331ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:24.392181Z","caller":"traceutil/trace.go:171","msg":"trace[258904551] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"492.637367ms","start":"2026-02-22T17:48:23.899519Z","end":"2026-02-22T17:48:24.392156Z","steps":["trace[258904551] 'process raft request' (duration: 423.301204ms)","trace[258904551] 'compare' (duration: 69.088987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:24.392319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.366156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:24.39239Z","caller":"traceutil/trace.go:171","msg":"trace[1518003667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"410.500759ms","start":"2026-02-22T17:48:23.981879Z","end":"2026-02-22T17:48:24.392379Z","steps":["trace[1518003667] 'agreement among raft nodes before linearized reading' (duration: 410.380826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:24.392392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.979387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-22T17:48:24.392427Z","caller":"traceutil/trace.go:171","msg":"trace[1899776287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"375.046809ms","start":"2026-02-22T17:48:24.017369Z","end":"2026-02-22T17:48:24.392416Z","steps":["trace[1899776287] 'agreement among raft nodes before linearized reading' (duration: 374.953016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:24.392421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:23.981852Z","time spent":"410.56083ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:48:24.392469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:24.01735Z","time spent":"375.110721ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:48:24.392582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.621092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:48:24.392661Z","caller":"traceutil/trace.go:171","msg":"trace[1912862658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"243.750175ms","start":"2026-02-22T17:48:24.148891Z","end":"2026-02-22T17:48:24.392641Z","steps":["trace[1912862658] 'agreement among raft nodes before linearized reading' (duration: 243.527739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:24.393591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:23.899492Z","time spent":"492.850422ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:48:24.545177Z","caller":"traceutil/trace.go:171","msg":"trace[1737761125] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"147.383898ms","start":"2026-02-22T17:48:24.397771Z","end":"2026-02-22T17:48:24.545154Z","steps":["trace[1737761125] 'process raft request' (duration: 144.775407ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:24.576177Z","caller":"traceutil/trace.go:171","msg":"trace[92602938] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"177.726497ms","start":"2026-02-22T17:48:24.398435Z","end":"2026-02-22T17:48:24.576161Z","steps":["trace[92602938] 'process raft request' (duration: 177.681406ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:24.576515Z","caller":"traceutil/trace.go:171","msg":"trace[674739341] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"178.084676ms","start":"2026-02-22T17:48:24.398417Z","end":"2026-02-22T17:48:24.576502Z","steps":["trace[674739341] 'process raft request' (duration: 177.307918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:29.710145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.047428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-22T17:48:29.710209Z","caller":"traceutil/trace.go:171","msg":"trace[695574273] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:519; }","duration":"137.14515ms","start":"2026-02-22T17:48:29.57305Z","end":"2026-02-22T17:48:29.710195Z","steps":["trace[695574273] 'range keys from in-memory index tree' (duration: 136.935775ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:30.575932Z","caller":"traceutil/trace.go:171","msg":"trace[1179244428] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"844.991363ms","start":"2026-02-22T17:48:29.73092Z","end":"2026-02-22T17:48:30.575912Z","steps":["trace[1179244428] 'read index received' (duration: 47.671672ms)","trace[1179244428] 'applied index is now lower than readState.Index' (duration: 797.319061ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:30.575991Z","caller":"traceutil/trace.go:171","msg":"trace[212904269] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"858.436464ms","start":"2026-02-22T17:48:29.717524Z","end":"2026-02-22T17:48:30.575961Z","steps":["trace[212904269] 'process raft request' (duration: 858.28255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:30.576054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.109275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:30.576083Z","caller":"traceutil/trace.go:171","msg":"trace[2082904709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:521; }","duration":"845.179747ms","start":"2026-02-22T17:48:29.730894Z","end":"2026-02-22T17:48:30.576074Z","steps":["trace[2082904709] 'agreement among raft nodes before linearized reading' (duration: 845.115536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:30.576105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:29.730881Z","time spent":"845.218978ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:48:30.576154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:29.717513Z","time spent":"858.558226ms","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:30.576159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.360466ms","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-22T17:48:30.576228Z","caller":"traceutil/trace.go:171","msg":"trace[536836354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"667.439278ms","start":"2026-02-22T17:48:29.908771Z","end":"2026-02-22T17:48:30.576211Z","steps":["trace[536836354] 'agreement among raft nodes before linearized reading' (duration: 667.303795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:30.576268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:29.908717Z","time spent":"667.542771ms","remote":"127.0.0.1:54414","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-22T17:48:30.898698Z","caller":"traceutil/trace.go:171","msg":"trace[508806236] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"318.686307ms","start":"2026-02-22T17:48:30.579966Z","end":"2026-02-22T17:48:30.898652Z","steps":["trace[508806236] 'process raft request' (duration: 297.354713ms)","trace[508806236] 'compare' (duration: 20.989866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:30.89891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:30.579948Z","time spent":"318.891011ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:48:30.902911Z","caller":"traceutil/trace.go:171","msg":"trace[1811486516] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:536; }","duration":"321.943202ms","start":"2026-02-22T17:48:30.580944Z","end":"2026-02-22T17:48:30.902887Z","steps":["trace[1811486516] 'read index received' (duration: 296.38771ms)","trace[1811486516] 'applied index is now lower than readState.Index' (duration: 25.554402ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:30.90296Z","caller":"traceutil/trace.go:171","msg":"trace[1957298411] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"322.69261ms","start":"2026-02-22T17:48:30.580253Z","end":"2026-02-22T17:48:30.902945Z","steps":["trace[1957298411] 'process raft request' (duration: 322.024723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:30.903014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.057295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:30.903073Z","caller":"traceutil/trace.go:171","msg":"trace[2135862491] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:523; }","duration":"322.128686ms","start":"2026-02-22T17:48:30.580918Z","end":"2026-02-22T17:48:30.903047Z","steps":["trace[2135862491] 'agreement among raft nodes before linearized reading' (duration: 322.044564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:30.903054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:30.580235Z","time spent":"322.757082ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:30.903093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.315874ms","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-22T17:48:30.903144Z","caller":"traceutil/trace.go:171","msg":"trace[540545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"307.411857ms","start":"2026-02-22T17:48:30.595718Z","end":"2026-02-22T17:48:30.90313Z","steps":["trace[540545] 'agreement among raft nodes before linearized reading' (duration: 307.300184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:30.903165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.276112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-hdz8z\" ","response":"range_response_count:1 size:4818"} {"level":"warn","ts":"2026-02-22T17:48:30.903185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:30.595711Z","time spent":"307.464548ms","remote":"127.0.0.1:54414","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":"info","ts":"2026-02-22T17:48:30.903198Z","caller":"traceutil/trace.go:171","msg":"trace[1364317706] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-hdz8z; range_end:; response_count:1; response_revision:523; }","duration":"191.345334ms","start":"2026-02-22T17:48:30.711847Z","end":"2026-02-22T17:48:30.903192Z","steps":["trace[1364317706] 'agreement among raft nodes before linearized reading' (duration: 191.284773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:30.903104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:30.580911Z","time spent":"322.186907ms","remote":"127.0.0.1:54222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-22T17:48:30.90329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.814613ms","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-22T17:48:30.903396Z","caller":"traceutil/trace.go:171","msg":"trace[1512240467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"306.973256ms","start":"2026-02-22T17:48:30.596403Z","end":"2026-02-22T17:48:30.903376Z","steps":["trace[1512240467] 'agreement among raft nodes before linearized reading' (duration: 306.798452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:30.903471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:30.596391Z","time spent":"307.068198ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:48:33.196726Z","caller":"traceutil/trace.go:171","msg":"trace[847846367] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"290.050614ms","start":"2026-02-22T17:48:32.906651Z","end":"2026-02-22T17:48:33.196702Z","steps":["trace[847846367] 'read index received' (duration: 289.84646ms)","trace[847846367] 'applied index is now lower than readState.Index' (duration: 203.104µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:33.196832Z","caller":"traceutil/trace.go:171","msg":"trace[25364223] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"290.390353ms","start":"2026-02-22T17:48:32.906412Z","end":"2026-02-22T17:48:33.196802Z","steps":["trace[25364223] 'process raft request' (duration: 290.143277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:33.196885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.218878ms","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-22T17:48:33.196922Z","caller":"traceutil/trace.go:171","msg":"trace[27135623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"290.31073ms","start":"2026-02-22T17:48:32.9066Z","end":"2026-02-22T17:48:33.196911Z","steps":["trace[27135623] 'agreement among raft nodes before linearized reading' (duration: 290.224218ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:33.203285Z","caller":"traceutil/trace.go:171","msg":"trace[1184708814] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"282.776517ms","start":"2026-02-22T17:48:32.920486Z","end":"2026-02-22T17:48:33.203262Z","steps":["trace[1184708814] 'process raft request' (duration: 282.617663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:33.203359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.270219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-22T17:48:33.203412Z","caller":"traceutil/trace.go:171","msg":"trace[205527778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:560; }","duration":"277.365532ms","start":"2026-02-22T17:48:32.926031Z","end":"2026-02-22T17:48:33.203397Z","steps":["trace[205527778] 'agreement among raft nodes before linearized reading' (duration: 277.202898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:33.203496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.794725ms","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-22T17:48:33.203604Z","caller":"traceutil/trace.go:171","msg":"trace[1435237831] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"291.946069ms","start":"2026-02-22T17:48:32.911631Z","end":"2026-02-22T17:48:33.203577Z","steps":["trace[1435237831] 'agreement among raft nodes before linearized reading' (duration: 291.670093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:33.203992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.362358ms","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-22T17:48:33.204114Z","caller":"traceutil/trace.go:171","msg":"trace[1089702737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"292.502852ms","start":"2026-02-22T17:48:32.911595Z","end":"2026-02-22T17:48:33.204098Z","steps":["trace[1089702737] 'agreement among raft nodes before linearized reading' (duration: 291.601641ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:39.389612Z","caller":"traceutil/trace.go:171","msg":"trace[1423873237] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"411.217045ms","start":"2026-02-22T17:48:38.978373Z","end":"2026-02-22T17:48:39.38959Z","steps":["trace[1423873237] 'read index received' (duration: 411.049441ms)","trace[1423873237] 'applied index is now lower than readState.Index' (duration: 164.303µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:39.389675Z","caller":"traceutil/trace.go:171","msg":"trace[1810803831] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"636.984553ms","start":"2026-02-22T17:48:38.752653Z","end":"2026-02-22T17:48:39.389638Z","steps":["trace[1810803831] 'process raft request' (duration: 636.746327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:39.389742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.353317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:39.389815Z","caller":"traceutil/trace.go:171","msg":"trace[178793324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"411.420629ms","start":"2026-02-22T17:48:38.978341Z","end":"2026-02-22T17:48:39.389761Z","steps":["trace[178793324] 'agreement among raft nodes before linearized reading' (duration: 411.358478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:39.390205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:38.978327Z","time spent":"411.867909ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:48:39.389843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:38.752619Z","time spent":"637.137646ms","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:39.390153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.651264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:48:39.390428Z","caller":"traceutil/trace.go:171","msg":"trace[1049522445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"171.161226ms","start":"2026-02-22T17:48:39.219249Z","end":"2026-02-22T17:48:39.39041Z","steps":["trace[1049522445] 'agreement among raft nodes before linearized reading' (duration: 170.557302ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:39.89089Z","caller":"traceutil/trace.go:171","msg":"trace[636596246] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"496.155597ms","start":"2026-02-22T17:48:39.394714Z","end":"2026-02-22T17:48:39.89087Z","steps":["trace[636596246] 'process raft request' (duration: 496.073605ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:39.890965Z","caller":"traceutil/trace.go:171","msg":"trace[1602117181] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"496.569097ms","start":"2026-02-22T17:48:39.394368Z","end":"2026-02-22T17:48:39.890937Z","steps":["trace[1602117181] 'process raft request' (duration: 496.291721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:39.891011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:39.394708Z","time spent":"496.241239ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:39.891128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:39.39434Z","time spent":"496.687289ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:48:39.891162Z","caller":"traceutil/trace.go:171","msg":"trace[1462243950] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"495.790009ms","start":"2026-02-22T17:48:39.395355Z","end":"2026-02-22T17:48:39.891145Z","steps":["trace[1462243950] 'process raft request' (duration: 495.683506ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:39.891222Z","caller":"traceutil/trace.go:171","msg":"trace[425980737] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"496.29717ms","start":"2026-02-22T17:48:39.394887Z","end":"2026-02-22T17:48:39.891184Z","steps":["trace[425980737] 'process raft request' (duration: 495.931002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:39.891265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:39.395349Z","time spent":"495.86888ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:39.891321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:39.394878Z","time spent":"496.394913ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:40.396566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.718659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:40.396671Z","caller":"traceutil/trace.go:171","msg":"trace[1644459935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"415.869352ms","start":"2026-02-22T17:48:39.980783Z","end":"2026-02-22T17:48:40.396653Z","steps":["trace[1644459935] 'range keys from in-memory index tree' (duration: 415.628037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:40.39671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:39.980767Z","time spent":"415.933075ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:48:42.000716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.730735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-22T17:48:42.000774Z","caller":"traceutil/trace.go:171","msg":"trace[2025772325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:595; }","duration":"105.839617ms","start":"2026-02-22T17:48:41.894922Z","end":"2026-02-22T17:48:42.000762Z","steps":["trace[2025772325] 'range keys from in-memory index tree' (duration: 105.473759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:42.000774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.465275ms","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-22T17:48:42.000835Z","caller":"traceutil/trace.go:171","msg":"trace[598086932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"104.543796ms","start":"2026-02-22T17:48:41.896277Z","end":"2026-02-22T17:48:42.000821Z","steps":["trace[598086932] 'range keys from in-memory index tree' (duration: 104.311651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:42.000851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.046709ms","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-22T17:48:42.00093Z","caller":"traceutil/trace.go:171","msg":"trace[1627155502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"580.169432ms","start":"2026-02-22T17:48:41.420743Z","end":"2026-02-22T17:48:42.000913Z","steps":["trace[1627155502] 'range keys from in-memory index tree' (duration: 579.873934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:42.000968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:41.420717Z","time spent":"580.241523ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:48:42.001026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.955002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-22T17:48:42.00107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.94282ms","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-22T17:48:42.001076Z","caller":"traceutil/trace.go:171","msg":"trace[1494281635] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:595; }","duration":"113.046813ms","start":"2026-02-22T17:48:41.888017Z","end":"2026-02-22T17:48:42.001064Z","steps":["trace[1494281635] 'range keys from in-memory index tree' (duration: 112.872989ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:42.001092Z","caller":"traceutil/trace.go:171","msg":"trace[902662010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"105.97657ms","start":"2026-02-22T17:48:41.895109Z","end":"2026-02-22T17:48:42.001085Z","steps":["trace[902662010] 'range keys from in-memory index tree' (duration: 105.887008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:42.001376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.356959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:48:42.00141Z","caller":"traceutil/trace.go:171","msg":"trace[372491350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"106.405701ms","start":"2026-02-22T17:48:41.894994Z","end":"2026-02-22T17:48:42.0014Z","steps":["trace[372491350] 'range keys from in-memory index tree' (duration: 106.267527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:46.678083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.782684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-22T17:48:46.678188Z","caller":"traceutil/trace.go:171","msg":"trace[1410503943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"615.903437ms","start":"2026-02-22T17:48:46.06226Z","end":"2026-02-22T17:48:46.678163Z","steps":["trace[1410503943] 'range keys from in-memory index tree' (duration: 615.60419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:46.6782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.173842ms","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-22T17:48:46.678273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:46.062255Z","time spent":"615.971058ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-22T17:48:46.678325Z","caller":"traceutil/trace.go:171","msg":"trace[1313363669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:623; }","duration":"622.355106ms","start":"2026-02-22T17:48:46.055946Z","end":"2026-02-22T17:48:46.678301Z","steps":["trace[1313363669] 'range keys from in-memory index tree' (duration: 621.979277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:46.678386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:46.055926Z","time spent":"622.449318ms","remote":"127.0.0.1:54414","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-22T17:48:46.678451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.199274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-22T17:48:46.678456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.307806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-22T17:48:46.678487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.42779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:48:46.678526Z","caller":"traceutil/trace.go:171","msg":"trace[643900386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"616.289936ms","start":"2026-02-22T17:48:46.062212Z","end":"2026-02-22T17:48:46.678502Z","steps":["trace[643900386] 'range keys from in-memory index tree' (duration: 616.02273ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:46.678533Z","caller":"traceutil/trace.go:171","msg":"trace[1321149967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"616.409899ms","start":"2026-02-22T17:48:46.062103Z","end":"2026-02-22T17:48:46.678513Z","steps":["trace[1321149967] 'range keys from in-memory index tree' (duration: 616.130342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:46.678572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:46.062207Z","time spent":"616.354127ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:48:46.678576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:46.062093Z","time spent":"616.47434ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:48:46.678587Z","caller":"traceutil/trace.go:171","msg":"trace[1600342956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"616.566562ms","start":"2026-02-22T17:48:46.061998Z","end":"2026-02-22T17:48:46.678565Z","steps":["trace[1600342956] 'range keys from in-memory index tree' (duration: 616.243395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:46.678632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:46.061981Z","time spent":"616.641564ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:48:46.868652Z","caller":"traceutil/trace.go:171","msg":"trace[697870509] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"145.001373ms","start":"2026-02-22T17:48:46.723635Z","end":"2026-02-22T17:48:46.868637Z","steps":["trace[697870509] 'process raft request' (duration: 144.961662ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:46.868704Z","caller":"traceutil/trace.go:171","msg":"trace[372857073] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"145.447993ms","start":"2026-02-22T17:48:46.72321Z","end":"2026-02-22T17:48:46.868658Z","steps":["trace[372857073] 'process raft request' (duration: 145.279569ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:46.868908Z","caller":"traceutil/trace.go:171","msg":"trace[1731912363] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"145.209618ms","start":"2026-02-22T17:48:46.723692Z","end":"2026-02-22T17:48:46.868902Z","steps":["trace[1731912363] 'process raft request' (duration: 144.922161ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:48.937526Z","caller":"traceutil/trace.go:171","msg":"trace[271463073] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"140.931497ms","start":"2026-02-22T17:48:48.796574Z","end":"2026-02-22T17:48:48.937506Z","steps":["trace[271463073] 'process raft request' (duration: 140.817025ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:48.975744Z","caller":"traceutil/trace.go:171","msg":"trace[323778418] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"174.701658ms","start":"2026-02-22T17:48:48.801026Z","end":"2026-02-22T17:48:48.975727Z","steps":["trace[323778418] 'read index received' (duration: 136.29836ms)","trace[323778418] 'applied index is now lower than readState.Index' (duration: 38.402668ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:48.975911Z","caller":"traceutil/trace.go:171","msg":"trace[559725743] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"178.61693ms","start":"2026-02-22T17:48:48.797249Z","end":"2026-02-22T17:48:48.975866Z","steps":["trace[559725743] 'process raft request' (duration: 178.358274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:48.975988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.940754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-z8p4v\" ","response":"range_response_count:1 size:17914"} {"level":"warn","ts":"2026-02-22T17:48:48.976036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.688257ms","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-22T17:48:48.976028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.81577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:48:48.976078Z","caller":"traceutil/trace.go:171","msg":"trace[280461305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:635; }","duration":"103.758288ms","start":"2026-02-22T17:48:48.872313Z","end":"2026-02-22T17:48:48.976071Z","steps":["trace[280461305] 'agreement among raft nodes before linearized reading' (duration: 103.613285ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:48.976081Z","caller":"traceutil/trace.go:171","msg":"trace[1171762999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"103.914312ms","start":"2026-02-22T17:48:48.872155Z","end":"2026-02-22T17:48:48.976069Z","steps":["trace[1171762999] 'agreement among raft nodes before linearized reading' (duration: 103.739908ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:48.976073Z","caller":"traceutil/trace.go:171","msg":"trace[1707006759] range","detail":"{range_begin:/registry/pods/kube-system/cilium-z8p4v; range_end:; response_count:1; response_revision:635; }","duration":"175.044516ms","start":"2026-02-22T17:48:48.801006Z","end":"2026-02-22T17:48:48.976051Z","steps":["trace[1707006759] 'agreement among raft nodes before linearized reading' (duration: 174.832051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:48.976165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.919022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:48:48.976251Z","caller":"traceutil/trace.go:171","msg":"trace[775349338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"104.049215ms","start":"2026-02-22T17:48:48.87218Z","end":"2026-02-22T17:48:48.976229Z","steps":["trace[775349338] 'agreement among raft nodes before linearized reading' (duration: 103.780789ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:49.095216Z","caller":"traceutil/trace.go:171","msg":"trace[1482780168] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"114.185809ms","start":"2026-02-22T17:48:48.98101Z","end":"2026-02-22T17:48:49.095195Z","steps":["trace[1482780168] 'read index received' (duration: 51.222053ms)","trace[1482780168] 'applied index is now lower than readState.Index' (duration: 62.962606ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:49.095306Z","caller":"traceutil/trace.go:171","msg":"trace[2063786944] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"114.990758ms","start":"2026-02-22T17:48:48.980296Z","end":"2026-02-22T17:48:49.095287Z","steps":["trace[2063786944] 'process raft request' (duration: 51.92118ms)","trace[2063786944] 'compare' (duration: 62.894754ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:49.095349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.307793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:49.095376Z","caller":"traceutil/trace.go:171","msg":"trace[1783574560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"114.406875ms","start":"2026-02-22T17:48:48.980961Z","end":"2026-02-22T17:48:49.095368Z","steps":["trace[1783574560] 'agreement among raft nodes before linearized reading' (duration: 114.306683ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:49.16787Z","caller":"traceutil/trace.go:171","msg":"trace[23633381] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"186.760668ms","start":"2026-02-22T17:48:48.981081Z","end":"2026-02-22T17:48:49.167841Z","steps":["trace[23633381] 'process raft request' (duration: 186.266485ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:49.167896Z","caller":"traceutil/trace.go:171","msg":"trace[424510299] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"177.616577ms","start":"2026-02-22T17:48:48.990267Z","end":"2026-02-22T17:48:49.167884Z","steps":["trace[424510299] 'process raft request' (duration: 177.226687ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:49.167883Z","caller":"traceutil/trace.go:171","msg":"trace[1034953222] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"186.779468ms","start":"2026-02-22T17:48:48.981081Z","end":"2026-02-22T17:48:49.167861Z","steps":["trace[1034953222] 'process raft request' (duration: 186.380078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:50.29269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.271225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:50.292757Z","caller":"traceutil/trace.go:171","msg":"trace[546437103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"311.375268ms","start":"2026-02-22T17:48:49.981366Z","end":"2026-02-22T17:48:50.292741Z","steps":["trace[546437103] 'range keys from in-memory index tree' (duration: 311.202524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:50.292796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:49.981352Z","time spent":"311.435659ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:49:05.107625Z","caller":"traceutil/trace.go:171","msg":"trace[1622093946] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"107.607114ms","start":"2026-02-22T17:49:04.999998Z","end":"2026-02-22T17:49:05.107605Z","steps":["trace[1622093946] 'read index received' (duration: 107.498012ms)","trace[1622093946] 'applied index is now lower than readState.Index' (duration: 108.122µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:49:05.107671Z","caller":"traceutil/trace.go:171","msg":"trace[144263359] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"178.076424ms","start":"2026-02-22T17:49:04.929564Z","end":"2026-02-22T17:49:05.107641Z","steps":["trace[144263359] 'process raft request' (duration: 177.904279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:05.107839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.82042ms","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-22T17:49:05.107894Z","caller":"traceutil/trace.go:171","msg":"trace[525952779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"107.937762ms","start":"2026-02-22T17:49:04.999942Z","end":"2026-02-22T17:49:05.10788Z","steps":["trace[525952779] 'agreement among raft nodes before linearized reading' (duration: 107.764258ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:05.437762Z","caller":"traceutil/trace.go:171","msg":"trace[2076513690] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"204.551548ms","start":"2026-02-22T17:49:05.233191Z","end":"2026-02-22T17:49:05.437743Z","steps":["trace[2076513690] 'read index received' (duration: 204.390425ms)","trace[2076513690] 'applied index is now lower than readState.Index' (duration: 160.453µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:05.438129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.540271ms","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-22T17:49:05.438131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.923426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:49:05.438165Z","caller":"traceutil/trace.go:171","msg":"trace[1910926481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"168.601262ms","start":"2026-02-22T17:49:05.269551Z","end":"2026-02-22T17:49:05.438152Z","steps":["trace[1910926481] 'agreement among raft nodes before linearized reading' (duration: 168.485199ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:05.438185Z","caller":"traceutil/trace.go:171","msg":"trace[360659198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"205.022118ms","start":"2026-02-22T17:49:05.233146Z","end":"2026-02-22T17:49:05.438168Z","steps":["trace[360659198] 'agreement among raft nodes before linearized reading' (duration: 204.858705ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:05.438283Z","caller":"traceutil/trace.go:171","msg":"trace[1077845186] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"211.369026ms","start":"2026-02-22T17:49:05.226904Z","end":"2026-02-22T17:49:05.438273Z","steps":["trace[1077845186] 'process raft request' (duration: 210.719081ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:05.634351Z","caller":"traceutil/trace.go:171","msg":"trace[253913872] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"192.58512ms","start":"2026-02-22T17:49:05.441741Z","end":"2026-02-22T17:49:05.634326Z","steps":["trace[253913872] 'process raft request' (duration: 192.427525ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:05.705974Z","caller":"traceutil/trace.go:171","msg":"trace[525508470] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"263.726956ms","start":"2026-02-22T17:49:05.442229Z","end":"2026-02-22T17:49:05.705956Z","steps":["trace[525508470] 'process raft request' (duration: 263.587584ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:05.706119Z","caller":"traceutil/trace.go:171","msg":"trace[1126776722] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"181.869539ms","start":"2026-02-22T17:49:05.524243Z","end":"2026-02-22T17:49:05.706113Z","steps":["trace[1126776722] 'process raft request' (duration: 181.674885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:07.442939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218036705220417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:49:07.811924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:06.942266Z","time spent":"869.652985ms","remote":"127.0.0.1:54218","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-22T17:49:07.859327Z","caller":"traceutil/trace.go:171","msg":"trace[313178300] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:777; }","duration":"916.433685ms","start":"2026-02-22T17:49:06.942871Z","end":"2026-02-22T17:49:07.859304Z","steps":["trace[313178300] 'read index received' (duration: 868.782615ms)","trace[313178300] 'applied index is now lower than readState.Index' (duration: 47.64834ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:07.859504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.632888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-w4hfs\" ","response":"range_response_count:1 size:5025"} {"level":"warn","ts":"2026-02-22T17:49:07.859618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.284767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-22T17:49:07.859629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.179949ms","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-22T17:49:07.859647Z","caller":"traceutil/trace.go:171","msg":"trace[1033252158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:755; }","duration":"150.338418ms","start":"2026-02-22T17:49:07.7093Z","end":"2026-02-22T17:49:07.859638Z","steps":["trace[1033252158] 'agreement among raft nodes before linearized reading' (duration: 150.234055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:07.859647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.609305ms","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-22T17:49:07.859665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.64956ms","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-22T17:49:07.859681Z","caller":"traceutil/trace.go:171","msg":"trace[423218160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"720.684076ms","start":"2026-02-22T17:49:07.138987Z","end":"2026-02-22T17:49:07.859671Z","steps":["trace[423218160] 'agreement among raft nodes before linearized reading' (duration: 720.592174ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:07.859677Z","caller":"traceutil/trace.go:171","msg":"trace[954333256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:755; }","duration":"222.27033ms","start":"2026-02-22T17:49:07.637393Z","end":"2026-02-22T17:49:07.859663Z","steps":["trace[954333256] 'agreement among raft nodes before linearized reading' (duration: 222.113067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:07.859707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:07.138964Z","time spent":"720.738487ms","remote":"127.0.0.1:54414","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":"info","ts":"2026-02-22T17:49:07.85971Z","caller":"traceutil/trace.go:171","msg":"trace[1105887658] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"723.725172ms","start":"2026-02-22T17:49:07.135971Z","end":"2026-02-22T17:49:07.859696Z","steps":["trace[1105887658] 'agreement among raft nodes before linearized reading' (duration: 723.611939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:07.859744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:07.135955Z","time spent":"723.782133ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:49:07.859802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.402474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:49:07.859821Z","caller":"traceutil/trace.go:171","msg":"trace[378820281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"416.476485ms","start":"2026-02-22T17:49:07.443338Z","end":"2026-02-22T17:49:07.859815Z","steps":["trace[378820281] 'agreement among raft nodes before linearized reading' (duration: 416.423174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:07.859838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:07.443314Z","time spent":"416.518897ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:49:07.859857Z","caller":"traceutil/trace.go:171","msg":"trace[794310610] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-w4hfs; range_end:; response_count:1; response_revision:755; }","duration":"916.738101ms","start":"2026-02-22T17:49:06.942807Z","end":"2026-02-22T17:49:07.859545Z","steps":["trace[794310610] 'agreement among raft nodes before linearized reading' (duration: 916.563567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:07.859882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:06.942789Z","time spent":"917.086008ms","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5047,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-w4hfs\" "} {"level":"warn","ts":"2026-02-22T17:49:07.859493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.673027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:49:07.860012Z","caller":"traceutil/trace.go:171","msg":"trace[982437360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"878.236918ms","start":"2026-02-22T17:49:06.981769Z","end":"2026-02-22T17:49:07.860006Z","steps":["trace[982437360] 'agreement among raft nodes before linearized reading' (duration: 877.664916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:07.860031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:06.981756Z","time spent":"878.27045ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:49:08.026104Z","caller":"traceutil/trace.go:171","msg":"trace[1108510830] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"158.741388ms","start":"2026-02-22T17:49:07.86734Z","end":"2026-02-22T17:49:08.026081Z","steps":["trace[1108510830] 'process raft request' (duration: 158.711238ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:08.026127Z","caller":"traceutil/trace.go:171","msg":"trace[1412152145] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"161.046618ms","start":"2026-02-22T17:49:07.865062Z","end":"2026-02-22T17:49:08.026108Z","steps":["trace[1412152145] 'process raft request' (duration: 160.969586ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:08.026127Z","caller":"traceutil/trace.go:171","msg":"trace[149318124] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"161.61032ms","start":"2026-02-22T17:49:07.864482Z","end":"2026-02-22T17:49:08.026093Z","steps":["trace[149318124] 'process raft request' (duration: 161.522569ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:08.026091Z","caller":"traceutil/trace.go:171","msg":"trace[568260002] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"161.646871ms","start":"2026-02-22T17:49:07.864421Z","end":"2026-02-22T17:49:08.026068Z","steps":["trace[568260002] 'process raft request' (duration: 161.470477ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:08.154767Z","caller":"traceutil/trace.go:171","msg":"trace[453294901] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"283.994854ms","start":"2026-02-22T17:49:07.87075Z","end":"2026-02-22T17:49:08.154745Z","steps":["trace[453294901] 'process raft request' (duration: 283.863721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:09.482829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218036705220424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:49:09.893146Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.732743006s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T17:49:09.89346Z","caller":"traceutil/trace.go:171","msg":"trace[1919267822] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.733175456s","start":"2026-02-22T17:49:08.160266Z","end":"2026-02-22T17:49:09.893441Z","steps":["trace[1919267822] 'process raft request' (duration: 1.733056673s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:09.893585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:08.160229Z","time spent":"1.733292218s","remote":"127.0.0.1:54290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:09.897805Z","caller":"traceutil/trace.go:171","msg":"trace[956588054] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:786; }","duration":"915.909783ms","start":"2026-02-22T17:49:08.981874Z","end":"2026-02-22T17:49:09.897783Z","steps":["trace[956588054] 'read index received' (duration: 911.512608ms)","trace[956588054] 'applied index is now lower than readState.Index' (duration: 4.396285ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:49:09.897836Z","caller":"traceutil/trace.go:171","msg":"trace[154908877] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"1.737153961s","start":"2026-02-22T17:49:08.160658Z","end":"2026-02-22T17:49:09.897812Z","steps":["trace[154908877] 'process raft request' (duration: 1.736966487s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:09.897886Z","caller":"traceutil/trace.go:171","msg":"trace[1314268563] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.733717616s","start":"2026-02-22T17:49:08.164152Z","end":"2026-02-22T17:49:09.89787Z","steps":["trace[1314268563] 'process raft request' (duration: 1.733587393s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:09.897924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.041026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:49:09.897944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:08.160641Z","time spent":"1.737250753s","remote":"127.0.0.1:54424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1613,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:49:09.897961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:08.164134Z","time spent":"1.733789998s","remote":"127.0.0.1:54602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:09.897973Z","caller":"traceutil/trace.go:171","msg":"trace[3334656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"916.135118ms","start":"2026-02-22T17:49:08.981816Z","end":"2026-02-22T17:49:09.897951Z","steps":["trace[3334656] 'agreement among raft nodes before linearized reading' (duration: 916.060097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:09.898014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:08.981796Z","time spent":"916.2084ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:49:10.762409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.829395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:49:10.762483Z","caller":"traceutil/trace.go:171","msg":"trace[569223040] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"729.056827ms","start":"2026-02-22T17:49:10.033414Z","end":"2026-02-22T17:49:10.762471Z","steps":["trace[569223040] 'process raft request' (duration: 560.964886ms)","trace[569223040] 'compare' (duration: 167.665252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:10.762522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:10.033402Z","time spent":"729.102529ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:10.8309Z","caller":"traceutil/trace.go:171","msg":"trace[1422817184] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"796.616936ms","start":"2026-02-22T17:49:10.034249Z","end":"2026-02-22T17:49:10.830866Z","steps":["trace[1422817184] 'process raft request' (duration: 796.444021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:10.831085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:10.034224Z","time spent":"796.750229ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:10.831125Z","caller":"traceutil/trace.go:171","msg":"trace[1101851082] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"796.738229ms","start":"2026-02-22T17:49:10.034361Z","end":"2026-02-22T17:49:10.831099Z","steps":["trace[1101851082] 'process raft request' (duration: 796.464643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:10.831228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.551911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:49:10.831254Z","caller":"traceutil/trace.go:171","msg":"trace[1758573273] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:770; }","duration":"493.588022ms","start":"2026-02-22T17:49:10.33766Z","end":"2026-02-22T17:49:10.831248Z","steps":["trace[1758573273] 'agreement among raft nodes before linearized reading' (duration: 493.507741ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:10.831221Z","caller":"traceutil/trace.go:171","msg":"trace[1922207430] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"796.037744ms","start":"2026-02-22T17:49:10.035155Z","end":"2026-02-22T17:49:10.831192Z","steps":["trace[1922207430] 'process raft request' (duration: 795.86476ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:10.831145Z","caller":"traceutil/trace.go:171","msg":"trace[1979860649] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:791; }","duration":"493.465059ms","start":"2026-02-22T17:49:10.337668Z","end":"2026-02-22T17:49:10.831133Z","steps":["trace[1979860649] 'read index received' (duration: 256.795417ms)","trace[1979860649] 'applied index is now lower than readState.Index' (duration: 236.668242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:10.831251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:10.034341Z","time spent":"796.842531ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:49:10.831275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:10.337615Z","time spent":"493.654124ms","remote":"127.0.0.1:54568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:49:10.831366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:10.035142Z","time spent":"796.144316ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:21.161732Z","caller":"traceutil/trace.go:171","msg":"trace[2049736281] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"284.30532ms","start":"2026-02-22T17:49:20.877404Z","end":"2026-02-22T17:49:21.16171Z","steps":["trace[2049736281] 'process raft request' (duration: 284.155977ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:21.380555Z","caller":"traceutil/trace.go:171","msg":"trace[521372803] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"502.862151ms","start":"2026-02-22T17:49:20.87767Z","end":"2026-02-22T17:49:21.380532Z","steps":["trace[521372803] 'process raft request' (duration: 449.201722ms)","trace[521372803] 'compare' (duration: 53.504405ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:49:21.380636Z","caller":"traceutil/trace.go:171","msg":"trace[330517026] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:827; }","duration":"399.297954ms","start":"2026-02-22T17:49:20.98133Z","end":"2026-02-22T17:49:21.380628Z","steps":["trace[330517026] 'read index received' (duration: 180.304704ms)","trace[330517026] 'applied index is now lower than readState.Index' (duration: 218.99276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:21.380694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:20.877654Z","time spent":"502.962594ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:21.380733Z","caller":"traceutil/trace.go:171","msg":"trace[512442042] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"502.493823ms","start":"2026-02-22T17:49:20.878233Z","end":"2026-02-22T17:49:21.380727Z","steps":["trace[512442042] 'process raft request' (duration: 502.260828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:21.380765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:20.878218Z","time spent":"502.528304ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:49:21.380856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.527781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:49:21.380877Z","caller":"traceutil/trace.go:171","msg":"trace[1330626025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"399.597892ms","start":"2026-02-22T17:49:20.981274Z","end":"2026-02-22T17:49:21.380872Z","steps":["trace[1330626025] 'agreement among raft nodes before linearized reading' (duration: 399.379726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:21.38089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:20.981259Z","time spent":"399.628062ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:49:22.482585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218036705220541,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:49:22.77455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.998292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:49:22.774764Z","caller":"traceutil/trace.go:171","msg":"trace[2033101783] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"792.854985ms","start":"2026-02-22T17:49:21.981796Z","end":"2026-02-22T17:49:22.774651Z","steps":["trace[2033101783] 'read index received' (duration: 653.671509ms)","trace[2033101783] 'applied index is now lower than readState.Index' (duration: 139.181906ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:49:22.774829Z","caller":"traceutil/trace.go:171","msg":"trace[1460506948] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"844.977002ms","start":"2026-02-22T17:49:21.929837Z","end":"2026-02-22T17:49:22.774814Z","steps":["trace[1460506948] 'process raft request' (duration: 705.616852ms)","trace[1460506948] 'compare' (duration: 138.779168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:22.774887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:21.929813Z","time spent":"845.046323ms","remote":"127.0.0.1:54156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:49:22.775237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.427028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:49:22.775322Z","caller":"traceutil/trace.go:171","msg":"trace[949946098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"793.513909ms","start":"2026-02-22T17:49:21.981773Z","end":"2026-02-22T17:49:22.775287Z","steps":["trace[949946098] 'agreement among raft nodes before linearized reading' (duration: 793.066679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:22.775372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:21.981764Z","time spent":"793.595671ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:49:22.798132Z","caller":"traceutil/trace.go:171","msg":"trace[1092758232] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"234.962455ms","start":"2026-02-22T17:49:22.563146Z","end":"2026-02-22T17:49:22.798109Z","steps":["trace[1092758232] 'process raft request' (duration: 234.688059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:22.798182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.297325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-22T17:49:22.798366Z","caller":"traceutil/trace.go:171","msg":"trace[895064341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"775.508051ms","start":"2026-02-22T17:49:22.022835Z","end":"2026-02-22T17:49:22.798343Z","steps":["trace[895064341] 'agreement among raft nodes before linearized reading' (duration: 775.238964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:22.79843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:22.022819Z","time spent":"775.592222ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:49:23.561768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.992326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:49:23.561888Z","caller":"traceutil/trace.go:171","msg":"trace[74101487] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"759.621797ms","start":"2026-02-22T17:49:22.802241Z","end":"2026-02-22T17:49:23.561863Z","steps":["trace[74101487] 'read index received' (duration: 399.453138ms)","trace[74101487] 'applied index is now lower than readState.Index' (duration: 360.167379ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:49:23.562123Z","caller":"traceutil/trace.go:171","msg":"trace[81221408] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"760.156469ms","start":"2026-02-22T17:49:22.801954Z","end":"2026-02-22T17:49:23.56211Z","steps":["trace[81221408] 'process raft request' (duration: 399.740884ms)","trace[81221408] 'compare' (duration: 359.71108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:23.562195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:22.801935Z","time spent":"760.22528ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:49:23.56222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.068606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-22T17:49:23.562231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.965575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-22T17:49:23.562242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.430339ms","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-22T17:49:23.562268Z","caller":"traceutil/trace.go:171","msg":"trace[198784218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"178.137618ms","start":"2026-02-22T17:49:23.384115Z","end":"2026-02-22T17:49:23.562253Z","steps":["trace[198784218] 'agreement among raft nodes before linearized reading' (duration: 177.993535ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:23.562309Z","caller":"traceutil/trace.go:171","msg":"trace[1911271974] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:806; }","duration":"760.052696ms","start":"2026-02-22T17:49:22.802236Z","end":"2026-02-22T17:49:23.562289Z","steps":["trace[1911271974] 'agreement among raft nodes before linearized reading' (duration: 759.907153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:23.562291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.125786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-22T17:49:23.562325Z","caller":"traceutil/trace.go:171","msg":"trace[1221428483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:806; }","duration":"713.543062ms","start":"2026-02-22T17:49:22.848761Z","end":"2026-02-22T17:49:23.562305Z","steps":["trace[1221428483] 'agreement among raft nodes before linearized reading' (duration: 713.319447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:23.562351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:22.802207Z","time spent":"760.136178ms","remote":"127.0.0.1:54424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-22T17:49:23.562369Z","caller":"traceutil/trace.go:171","msg":"trace[18876611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"177.218018ms","start":"2026-02-22T17:49:23.385132Z","end":"2026-02-22T17:49:23.56235Z","steps":["trace[18876611] 'agreement among raft nodes before linearized reading' (duration: 176.970443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:23.562383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:22.848751Z","time spent":"713.622623ms","remote":"127.0.0.1:54414","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-22T17:49:23.562428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.32929ms","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-22T17:49:23.562513Z","caller":"traceutil/trace.go:171","msg":"trace[1333732315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"396.457863ms","start":"2026-02-22T17:49:23.166033Z","end":"2026-02-22T17:49:23.562491Z","steps":["trace[1333732315] 'agreement among raft nodes before linearized reading' (duration: 396.204377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:23.562561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:23.166014Z","time spent":"396.536284ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:49:24.317712Z","caller":"traceutil/trace.go:171","msg":"trace[2121289186] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"750.589712ms","start":"2026-02-22T17:49:23.567101Z","end":"2026-02-22T17:49:24.317691Z","steps":["trace[2121289186] 'process raft request' (duration: 666.932435ms)","trace[2121289186] 'compare' (duration: 83.561385ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:24.317842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:23.567045Z","time spent":"750.724936ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:24.689657Z","caller":"traceutil/trace.go:171","msg":"trace[2114224843] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"1.122456655s","start":"2026-02-22T17:49:23.567176Z","end":"2026-02-22T17:49:24.689632Z","steps":["trace[2114224843] 'process raft request' (duration: 1.122362163s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:24.689677Z","caller":"traceutil/trace.go:171","msg":"trace[386603959] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.122487365s","start":"2026-02-22T17:49:23.567155Z","end":"2026-02-22T17:49:24.689642Z","steps":["trace[386603959] 'process raft request' (duration: 1.122190399s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:24.68976Z","caller":"traceutil/trace.go:171","msg":"trace[810344838] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:834; }","duration":"699.650103ms","start":"2026-02-22T17:49:23.990096Z","end":"2026-02-22T17:49:24.689746Z","steps":["trace[810344838] 'read index received' (duration: 243.935239ms)","trace[810344838] 'applied index is now lower than readState.Index' (duration: 455.713454ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:24.68981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:23.56715Z","time spent":"1.122582817s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:49:24.689852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:23.567131Z","time spent":"1.122624349s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:24.689938Z","caller":"traceutil/trace.go:171","msg":"trace[403442102] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.122597518s","start":"2026-02-22T17:49:23.567331Z","end":"2026-02-22T17:49:24.689928Z","steps":["trace[403442102] 'process raft request' (duration: 1.122266511s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:24.689909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.788945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:49:24.689999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:23.56731Z","time spent":"1.122659729s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:24.690002Z","caller":"traceutil/trace.go:171","msg":"trace[828424496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"699.919997ms","start":"2026-02-22T17:49:23.990059Z","end":"2026-02-22T17:49:24.689979Z","steps":["trace[828424496] 'agreement among raft nodes before linearized reading' (duration: 699.774154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:24.690039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:23.990043Z","time spent":"699.99059ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:49:24.690053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.979489ms","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-22T17:49:24.690093Z","caller":"traceutil/trace.go:171","msg":"trace[1511744897] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:810; }","duration":"637.045571ms","start":"2026-02-22T17:49:24.053037Z","end":"2026-02-22T17:49:24.690082Z","steps":["trace[1511744897] 'agreement among raft nodes before linearized reading' (duration: 636.968019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:24.690099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.371775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:49:24.690134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:24.053023Z","time spent":"637.103782ms","remote":"127.0.0.1:54434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:49:24.69014Z","caller":"traceutil/trace.go:171","msg":"trace[500126114] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:810; }","duration":"286.450407ms","start":"2026-02-22T17:49:24.403678Z","end":"2026-02-22T17:49:24.690128Z","steps":["trace[500126114] 'agreement among raft nodes before linearized reading' (duration: 286.390485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:37.174059Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218036705220734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:49:37.674377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218036705220734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:49:38.175497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680218036705220734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:49:38.279483Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.873391793s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T17:49:38.279961Z","caller":"traceutil/trace.go:171","msg":"trace[1415500895] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"1.873916065s","start":"2026-02-22T17:49:36.406022Z","end":"2026-02-22T17:49:38.279938Z","steps":["trace[1415500895] 'process raft request' (duration: 1.873735971s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.280323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:36.406009Z","time spent":"1.873999167s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:49:38.924636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.794313ms","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-22T17:49:38.924744Z","caller":"traceutil/trace.go:171","msg":"trace[1642997590] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:890; }","duration":"348.918596ms","start":"2026-02-22T17:49:38.57581Z","end":"2026-02-22T17:49:38.924728Z","steps":["trace[1642997590] 'range keys from in-memory index tree' (duration: 348.778093ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:38.927958Z","caller":"traceutil/trace.go:171","msg":"trace[1603681879] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"2.397706718s","start":"2026-02-22T17:49:36.530223Z","end":"2026-02-22T17:49:38.92793Z","steps":["trace[1603681879] 'process raft request' (duration: 2.397565365s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.928198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:36.530186Z","time spent":"2.397845671s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:38.929476Z","caller":"traceutil/trace.go:171","msg":"trace[1261438748] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:920; }","duration":"2.256069239s","start":"2026-02-22T17:49:36.673397Z","end":"2026-02-22T17:49:38.929466Z","steps":["trace[1261438748] 'read index received' (duration: 1.606383637s)","trace[1261438748] 'applied index is now lower than readState.Index' (duration: 649.684682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:38.929673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.256271744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:49:38.929697Z","caller":"traceutil/trace.go:171","msg":"trace[661925479] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:891; }","duration":"2.256345615s","start":"2026-02-22T17:49:36.673343Z","end":"2026-02-22T17:49:38.929689Z","steps":["trace[661925479] 'agreement among raft nodes before linearized reading' (duration: 2.256284744s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.929717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:36.673322Z","time spent":"2.256389487s","remote":"127.0.0.1:54548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:49:38.929906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.547593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:49:38.929926Z","caller":"traceutil/trace.go:171","msg":"trace[20445133] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"951.602964ms","start":"2026-02-22T17:49:37.978317Z","end":"2026-02-22T17:49:38.92992Z","steps":["trace[20445133] 'agreement among raft nodes before linearized reading' (duration: 951.571933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.929943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:37.978298Z","time spent":"951.640464ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:49:38.929929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.174281822s","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-22T17:49:38.930015Z","caller":"traceutil/trace.go:171","msg":"trace[1140335550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:891; }","duration":"2.174371734s","start":"2026-02-22T17:49:36.755619Z","end":"2026-02-22T17:49:38.929991Z","steps":["trace[1140335550] 'agreement among raft nodes before linearized reading' (duration: 2.174141459s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.930042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.022003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-22T17:49:38.930056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:36.755579Z","time spent":"2.174468356s","remote":"127.0.0.1:54414","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-22T17:49:38.930066Z","caller":"traceutil/trace.go:171","msg":"trace[1551401886] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:891; }","duration":"633.089894ms","start":"2026-02-22T17:49:38.296967Z","end":"2026-02-22T17:49:38.930057Z","steps":["trace[1551401886] 'agreement among raft nodes before linearized reading' (duration: 633.053113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.930107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:38.296942Z","time spent":"633.156786ms","remote":"127.0.0.1:54542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:49:38.930227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209886282s","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-22T17:49:38.930249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.200738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:49:38.930256Z","caller":"traceutil/trace.go:171","msg":"trace[1275149753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:891; }","duration":"1.209963224s","start":"2026-02-22T17:49:37.720283Z","end":"2026-02-22T17:49:38.930247Z","steps":["trace[1275149753] 'agreement among raft nodes before linearized reading' (duration: 1.209876542s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:38.930267Z","caller":"traceutil/trace.go:171","msg":"trace[2139543982] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:891; }","duration":"840.254488ms","start":"2026-02-22T17:49:38.090008Z","end":"2026-02-22T17:49:38.930262Z","steps":["trace[2139543982] 'agreement among raft nodes before linearized reading' (duration: 840.181307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.930285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:38.089989Z","time spent":"840.29123ms","remote":"127.0.0.1:54218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":110,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:49:38.930284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:37.720259Z","time spent":"1.210018776s","remote":"127.0.0.1:54414","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-22T17:49:38.9305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.113416437s","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-22T17:49:38.930519Z","caller":"traceutil/trace.go:171","msg":"trace[297384701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:891; }","duration":"2.113473679s","start":"2026-02-22T17:49:36.81704Z","end":"2026-02-22T17:49:38.930513Z","steps":["trace[297384701] 'agreement among raft nodes before linearized reading' (duration: 2.113393687s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.930538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:36.817019Z","time spent":"2.113514529s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-22T17:49:38.930624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.109635616s","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-22T17:49:38.930655Z","caller":"traceutil/trace.go:171","msg":"trace[242102254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"2.109705278s","start":"2026-02-22T17:49:36.82094Z","end":"2026-02-22T17:49:38.930646Z","steps":["trace[242102254] 'agreement among raft nodes before linearized reading' (duration: 2.109618696s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:38.930684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:36.820923Z","time spent":"2.109754518s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:49:54.644801Z","caller":"traceutil/trace.go:171","msg":"trace[1323045427] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"299.973959ms","start":"2026-02-22T17:49:54.344809Z","end":"2026-02-22T17:49:54.644783Z","steps":["trace[1323045427] 'process raft request' (duration: 299.832916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:54.644937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:54.344797Z","time spent":"300.068302ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:57.332824Z","caller":"traceutil/trace.go:171","msg":"trace[589073598] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"238.496011ms","start":"2026-02-22T17:49:57.094303Z","end":"2026-02-22T17:49:57.332799Z","steps":["trace[589073598] 'process raft request' (duration: 238.352278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:57.820253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.101225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:49:57.82084Z","caller":"traceutil/trace.go:171","msg":"trace[2003071531] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"726.47805ms","start":"2026-02-22T17:49:57.09433Z","end":"2026-02-22T17:49:57.820808Z","steps":["trace[2003071531] 'process raft request' (duration: 479.445985ms)","trace[2003071531] 'compare' (duration: 245.969193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:57.820968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:57.094312Z","time spent":"726.597284ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:57.907683Z","caller":"traceutil/trace.go:171","msg":"trace[548380900] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:984; }","duration":"807.807798ms","start":"2026-02-22T17:49:57.099853Z","end":"2026-02-22T17:49:57.907661Z","steps":["trace[548380900] 'read index received' (duration: 232.828569ms)","trace[548380900] 'applied index is now lower than readState.Index' (duration: 574.977739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:57.908151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.062864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:49:57.908209Z","caller":"traceutil/trace.go:171","msg":"trace[797129988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"808.359871ms","start":"2026-02-22T17:49:57.099835Z","end":"2026-02-22T17:49:57.908195Z","steps":["trace[797129988] 'agreement among raft nodes before linearized reading' (duration: 807.969902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:57.908256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:57.099827Z","time spent":"808.414922ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:49:57.908709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.713338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:49:57.908855Z","caller":"traceutil/trace.go:171","msg":"trace[584589691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"808.865761ms","start":"2026-02-22T17:49:57.099919Z","end":"2026-02-22T17:49:57.908784Z","steps":["trace[584589691] 'agreement among raft nodes before linearized reading' (duration: 808.575095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:57.909057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:57.099897Z","time spent":"809.141127ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-22T17:49:57.908682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.639882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:49:57.909267Z","caller":"traceutil/trace.go:171","msg":"trace[1939020885] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:951; }","duration":"519.252445ms","start":"2026-02-22T17:49:57.389999Z","end":"2026-02-22T17:49:57.909251Z","steps":["trace[1939020885] 'agreement among raft nodes before linearized reading' (duration: 518.651543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:57.909311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:57.389982Z","time spent":"519.314636ms","remote":"127.0.0.1:35702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:49:58.012331Z","caller":"traceutil/trace.go:171","msg":"trace[253680486] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"100.141733ms","start":"2026-02-22T17:49:57.912166Z","end":"2026-02-22T17:49:58.012308Z","steps":["trace[253680486] 'process raft request' (duration: 99.97885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:58.705301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.655111ms","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-22T17:49:58.705445Z","caller":"traceutil/trace.go:171","msg":"trace[2003886902] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:952; }","duration":"129.810654ms","start":"2026-02-22T17:49:58.57562Z","end":"2026-02-22T17:49:58.705431Z","steps":["trace[2003886902] 'range keys from in-memory index tree' (duration: 129.644551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:58.705729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.826335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:49:58.705795Z","caller":"traceutil/trace.go:171","msg":"trace[613303765] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:987; }","duration":"722.624589ms","start":"2026-02-22T17:49:57.983159Z","end":"2026-02-22T17:49:58.705784Z","steps":["trace[613303765] 'read index received' (duration: 29.054738ms)","trace[613303765] 'applied index is now lower than readState.Index' (duration: 693.5686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:58.705909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.746521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:49:58.70588Z","caller":"traceutil/trace.go:171","msg":"trace[2051285208] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"793.026189ms","start":"2026-02-22T17:49:57.912813Z","end":"2026-02-22T17:49:58.70584Z","steps":["trace[2051285208] 'process raft request' (duration: 612.01806ms)","trace[2051285208] 'compare' (duration: 180.369545ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:49:58.705941Z","caller":"traceutil/trace.go:171","msg":"trace[1797592933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"722.806923ms","start":"2026-02-22T17:49:57.983125Z","end":"2026-02-22T17:49:58.705932Z","steps":["trace[1797592933] 'agreement among raft nodes before linearized reading' (duration: 722.752101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:58.705989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:57.983111Z","time spent":"722.863034ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:49:58.705986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.63861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-22T17:49:58.706035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:57.912786Z","time spent":"793.170122ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:58.706125Z","caller":"traceutil/trace.go:171","msg":"trace[1218554267] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:953; }","duration":"479.771993ms","start":"2026-02-22T17:49:58.226277Z","end":"2026-02-22T17:49:58.70605Z","steps":["trace[1218554267] 'agreement among raft nodes before linearized reading' (duration: 479.6084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:58.706186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:58.226259Z","time spent":"479.915966ms","remote":"127.0.0.1:54306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:49:58.706272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.945824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:49:58.706312Z","caller":"traceutil/trace.go:171","msg":"trace[299304295] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:953; }","duration":"153.015836ms","start":"2026-02-22T17:49:58.553286Z","end":"2026-02-22T17:49:58.706302Z","steps":["trace[299304295] 'agreement among raft nodes before linearized reading' (duration: 152.926773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:59.09867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.459689ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:49:59.098785Z","caller":"traceutil/trace.go:171","msg":"trace[587099514] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"140.827502ms","start":"2026-02-22T17:49:58.957942Z","end":"2026-02-22T17:49:59.098769Z","steps":["trace[587099514] 'read index received' (duration: 34.160799ms)","trace[587099514] 'applied index is now lower than readState.Index' (duration: 106.665623ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:49:59.09887Z","caller":"traceutil/trace.go:171","msg":"trace[1622405178] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"388.051052ms","start":"2026-02-22T17:49:58.710794Z","end":"2026-02-22T17:49:59.098846Z","steps":["trace[1622405178] 'process raft request' (duration: 281.328457ms)","trace[1622405178] 'compare' (duration: 106.339727ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:59.099017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:58.710771Z","time spent":"388.176015ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:49:59.099025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.730458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:49:59.099088Z","caller":"traceutil/trace.go:171","msg":"trace[1310695629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"108.80344ms","start":"2026-02-22T17:49:58.990248Z","end":"2026-02-22T17:49:59.099051Z","steps":["trace[1310695629] 'agreement among raft nodes before linearized reading' (duration: 108.743979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:59.099135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.147679ms","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-22T17:49:59.099233Z","caller":"traceutil/trace.go:171","msg":"trace[772316194] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:954; }","duration":"141.296392ms","start":"2026-02-22T17:49:58.957897Z","end":"2026-02-22T17:49:59.099194Z","steps":["trace[772316194] 'agreement among raft nodes before linearized reading' (duration: 141.106458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:59.590586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.803866ms","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-22T17:49:59.590663Z","caller":"traceutil/trace.go:171","msg":"trace[1610241700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"243.923088ms","start":"2026-02-22T17:49:59.346717Z","end":"2026-02-22T17:49:59.59064Z","steps":["trace[1610241700] 'range keys from in-memory index tree' (duration: 243.638502ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:49:59.627928Z","caller":"traceutil/trace.go:171","msg":"trace[425163603] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"156.198934ms","start":"2026-02-22T17:49:59.471699Z","end":"2026-02-22T17:49:59.627898Z","steps":["trace[425163603] 'process raft request' (duration: 156.04481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:59.98263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.855974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:49:59.982721Z","caller":"traceutil/trace.go:171","msg":"trace[131341101] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"157.507142ms","start":"2026-02-22T17:49:59.8252Z","end":"2026-02-22T17:49:59.982707Z","steps":["trace[131341101] 'read index received' (duration: 46.551µs)","trace[131341101] 'applied index is now lower than readState.Index' (duration: 157.459601ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:49:59.98294Z","caller":"traceutil/trace.go:171","msg":"trace[166988978] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"388.580072ms","start":"2026-02-22T17:49:59.594347Z","end":"2026-02-22T17:49:59.982927Z","steps":["trace[166988978] 'process raft request' (duration: 146.357701ms)","trace[166988978] 'compare' (duration: 241.766832ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:49:59.982925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.441619ms","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-22T17:49:59.982988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.784337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-22T17:49:59.982998Z","caller":"traceutil/trace.go:171","msg":"trace[1061900809] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:956; }","duration":"112.550661ms","start":"2026-02-22T17:49:59.870432Z","end":"2026-02-22T17:49:59.982982Z","steps":["trace[1061900809] 'agreement among raft nodes before linearized reading' (duration: 112.425308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:49:59.983008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:59.594328Z","time spent":"388.650133ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:49:59.983036Z","caller":"traceutil/trace.go:171","msg":"trace[1520674375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"157.872869ms","start":"2026-02-22T17:49:59.825151Z","end":"2026-02-22T17:49:59.983023Z","steps":["trace[1520674375] 'agreement among raft nodes before linearized reading' (duration: 157.682175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:00.658616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.355151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:50:00.658735Z","caller":"traceutil/trace.go:171","msg":"trace[2114229404] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"671.79456ms","start":"2026-02-22T17:49:59.986922Z","end":"2026-02-22T17:50:00.658717Z","steps":["trace[2114229404] 'process raft request' (duration: 112.262755ms)","trace[2114229404] 'compare' (duration: 558.974453ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:00.6588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:59.986905Z","time spent":"671.864371ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:01.594201Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.4950205s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T17:50:01.594522Z","caller":"traceutil/trace.go:171","msg":"trace[1659463281] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.606597052s","start":"2026-02-22T17:49:59.987898Z","end":"2026-02-22T17:50:01.594495Z","steps":["trace[1659463281] 'process raft request' (duration: 1.606453408s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:01.594485Z","caller":"traceutil/trace.go:171","msg":"trace[1976597168] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:992; }","duration":"1.57922639s","start":"2026-02-22T17:50:00.015236Z","end":"2026-02-22T17:50:01.594462Z","steps":["trace[1976597168] 'read index received' (duration: 83.962003ms)","trace[1976597168] 'applied index is now lower than readState.Index' (duration: 1.495263397s)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:01.594654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:49:59.987883Z","time spent":"1.606711314s","remote":"127.0.0.1:54218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T17:50:01.594753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579489426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-22T17:50:01.594817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.152446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:50:01.594835Z","caller":"traceutil/trace.go:171","msg":"trace[5129799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"1.579607459s","start":"2026-02-22T17:50:00.015208Z","end":"2026-02-22T17:50:01.594816Z","steps":["trace[5129799] 'agreement among raft nodes before linearized reading' (duration: 1.579341383s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:01.594826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.291067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:50:01.594889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:00.015198Z","time spent":"1.57968307s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:50:01.594919Z","caller":"traceutil/trace.go:171","msg":"trace[653798445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"613.43571ms","start":"2026-02-22T17:50:00.981464Z","end":"2026-02-22T17:50:01.5949Z","steps":["trace[653798445] 'agreement among raft nodes before linearized reading' (duration: 613.265467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:01.594978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:00.981439Z","time spent":"613.523442ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:50:01.595003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.576369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-22T17:50:01.594969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.594668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:50:01.595132Z","caller":"traceutil/trace.go:171","msg":"trace[1352123336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"885.794943ms","start":"2026-02-22T17:50:00.709309Z","end":"2026-02-22T17:50:01.595104Z","steps":["trace[1352123336] 'agreement among raft nodes before linearized reading' (duration: 885.479446ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:01.59486Z","caller":"traceutil/trace.go:171","msg":"trace[1791318673] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:958; }","duration":"670.240087ms","start":"2026-02-22T17:50:00.92461Z","end":"2026-02-22T17:50:01.59485Z","steps":["trace[1791318673] 'agreement among raft nodes before linearized reading' (duration: 670.149205ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:01.595045Z","caller":"traceutil/trace.go:171","msg":"trace[328076877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:958; }","duration":"492.655781ms","start":"2026-02-22T17:50:01.102379Z","end":"2026-02-22T17:50:01.595034Z","steps":["trace[328076877] 'agreement among raft nodes before linearized reading' (duration: 492.501248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:01.595203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:00.709292Z","time spent":"885.897444ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-22T17:50:01.595252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:00.924593Z","time spent":"670.614786ms","remote":"127.0.0.1:54526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:50:01.595279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:01.102359Z","time spent":"492.889596ms","remote":"127.0.0.1:54414","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-22T17:50:01.595915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.579107838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:01.596Z","caller":"traceutil/trace.go:171","msg":"trace[1879606217] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:958; }","duration":"1.580413405s","start":"2026-02-22T17:50:00.015572Z","end":"2026-02-22T17:50:01.595986Z","steps":["trace[1879606217] 'agreement among raft nodes before linearized reading' (duration: 1.579109978s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:01.596039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:00.015558Z","time spent":"1.580467726s","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:50:02.326572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.723694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:50:02.326889Z","caller":"traceutil/trace.go:171","msg":"trace[1739488787] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"727.682268ms","start":"2026-02-22T17:50:01.599193Z","end":"2026-02-22T17:50:02.326876Z","steps":["trace[1739488787] 'process raft request' (duration: 270.577235ms)","trace[1739488787] 'compare' (duration: 456.616452ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:50:02.326919Z","caller":"traceutil/trace.go:171","msg":"trace[1190454673] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"726.731946ms","start":"2026-02-22T17:50:01.60016Z","end":"2026-02-22T17:50:02.326892Z","steps":["trace[1190454673] 'process raft request' (duration: 726.509141ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:02.326928Z","caller":"traceutil/trace.go:171","msg":"trace[2073111370] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"726.268636ms","start":"2026-02-22T17:50:01.600642Z","end":"2026-02-22T17:50:02.32691Z","steps":["trace[2073111370] 'process raft request' (duration: 726.072802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:02.326971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:01.599171Z","time spent":"727.764319ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:02.327096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:01.600137Z","time spent":"726.851079ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:02.327192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.987705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-22T17:50:02.327248Z","caller":"traceutil/trace.go:171","msg":"trace[758946739] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:961; }","duration":"435.084587ms","start":"2026-02-22T17:50:01.89215Z","end":"2026-02-22T17:50:02.327235Z","steps":["trace[758946739] 'agreement among raft nodes before linearized reading' (duration: 434.865083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:02.327287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:01.89213Z","time spent":"435.148788ms","remote":"127.0.0.1:54156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"info","ts":"2026-02-22T17:50:02.32676Z","caller":"traceutil/trace.go:171","msg":"trace[1225692863] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:994; }","duration":"434.541356ms","start":"2026-02-22T17:50:01.892202Z","end":"2026-02-22T17:50:02.326743Z","steps":["trace[1225692863] 'read index received' (duration: 50.818778ms)","trace[1225692863] 'applied index is now lower than readState.Index' (duration: 383.721548ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:02.32751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.71735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:02.327552Z","caller":"traceutil/trace.go:171","msg":"trace[1105469905] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:961; }","duration":"297.786182ms","start":"2026-02-22T17:50:02.029755Z","end":"2026-02-22T17:50:02.327541Z","steps":["trace[1105469905] 'agreement among raft nodes before linearized reading' (duration: 297.7132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:02.327602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.630928ms","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-22T17:50:02.327658Z","caller":"traceutil/trace.go:171","msg":"trace[1638867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:961; }","duration":"341.717141ms","start":"2026-02-22T17:50:01.985929Z","end":"2026-02-22T17:50:02.327646Z","steps":["trace[1638867] 'agreement among raft nodes before linearized reading' (duration: 341.565648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:02.327686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:01.985922Z","time spent":"341.759922ms","remote":"127.0.0.1:54414","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-22T17:50:02.327923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:01.60062Z","time spent":"726.382158ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:02.327855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.360669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:02.327986Z","caller":"traceutil/trace.go:171","msg":"trace[61174198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"345.528673ms","start":"2026-02-22T17:50:01.982448Z","end":"2026-02-22T17:50:02.327976Z","steps":["trace[61174198] 'agreement among raft nodes before linearized reading' (duration: 344.996162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:02.328025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:01.982429Z","time spent":"345.588034ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:50:02.477399Z","caller":"traceutil/trace.go:171","msg":"trace[1620918381] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"146.562057ms","start":"2026-02-22T17:50:02.330813Z","end":"2026-02-22T17:50:02.477375Z","steps":["trace[1620918381] 'process raft request' (duration: 146.26548ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:04.385826Z","caller":"traceutil/trace.go:171","msg":"trace[28907413] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"404.042407ms","start":"2026-02-22T17:50:03.981765Z","end":"2026-02-22T17:50:04.385808Z","steps":["trace[28907413] 'read index received' (duration: 403.868383ms)","trace[28907413] 'applied index is now lower than readState.Index' (duration: 172.834µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:04.385958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.188819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:04.385941Z","caller":"traceutil/trace.go:171","msg":"trace[1704038968] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"544.940781ms","start":"2026-02-22T17:50:03.840967Z","end":"2026-02-22T17:50:04.385907Z","steps":["trace[1704038968] 'process raft request' (duration: 544.720346ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:04.385994Z","caller":"traceutil/trace.go:171","msg":"trace[1484797803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"404.278561ms","start":"2026-02-22T17:50:03.981705Z","end":"2026-02-22T17:50:04.385984Z","steps":["trace[1484797803] 'agreement among raft nodes before linearized reading' (duration: 404.21096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:04.386028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:03.981683Z","time spent":"404.338483ms","remote":"127.0.0.1:54136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:50:04.386183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:03.840936Z","time spent":"545.074494ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:05.423706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.937395ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:50:05.423931Z","caller":"traceutil/trace.go:171","msg":"trace[1065131320] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.034509604s","start":"2026-02-22T17:50:04.389394Z","end":"2026-02-22T17:50:05.423904Z","steps":["trace[1065131320] 'process raft request' (duration: 304.218931ms)","trace[1065131320] 'compare' (duration: 729.291222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:05.424048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:04.389379Z","time spent":"1.034610767s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:05.424269Z","caller":"traceutil/trace.go:171","msg":"trace[144607437] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1002; }","duration":"942.63156ms","start":"2026-02-22T17:50:04.48161Z","end":"2026-02-22T17:50:05.424242Z","steps":["trace[144607437] 'read index received' (duration: 212.017629ms)","trace[144607437] 'applied index is now lower than readState.Index' (duration: 730.612341ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:50:05.42442Z","caller":"traceutil/trace.go:171","msg":"trace[368769785] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"1.034408113s","start":"2026-02-22T17:50:04.390001Z","end":"2026-02-22T17:50:05.424409Z","steps":["trace[368769785] 'process raft request' (duration: 1.033834239s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:05.424545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:04.389994Z","time spent":"1.034456833s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:05.424815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.320288ms","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-22T17:50:05.424805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.162142ms","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-22T17:50:05.424852Z","caller":"traceutil/trace.go:171","msg":"trace[346393248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"626.38014ms","start":"2026-02-22T17:50:04.79846Z","end":"2026-02-22T17:50:05.42484Z","steps":["trace[346393248] 'agreement among raft nodes before linearized reading' (duration: 626.216256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:05.424889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:04.798446Z","time spent":"626.434881ms","remote":"127.0.0.1:54414","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":"info","ts":"2026-02-22T17:50:05.424889Z","caller":"traceutil/trace.go:171","msg":"trace[1743466836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"943.312214ms","start":"2026-02-22T17:50:04.481556Z","end":"2026-02-22T17:50:05.424868Z","steps":["trace[1743466836] 'agreement among raft nodes before linearized reading' (duration: 942.999568ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:05.424897Z","caller":"traceutil/trace.go:171","msg":"trace[2129156601] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.034838472s","start":"2026-02-22T17:50:04.39004Z","end":"2026-02-22T17:50:05.424879Z","steps":["trace[2129156601] 'process raft request' (duration: 1.034072565s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:05.424936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:04.481535Z","time spent":"943.392537ms","remote":"127.0.0.1:54414","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-22T17:50:05.425014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:04.390036Z","time spent":"1.034909183s","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:05.809615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.419185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:50:05.809694Z","caller":"traceutil/trace.go:171","msg":"trace[641389192] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"380.963799ms","start":"2026-02-22T17:50:05.428719Z","end":"2026-02-22T17:50:05.809682Z","steps":["trace[641389192] 'process raft request' (duration: 176.423261ms)","trace[641389192] 'compare' (duration: 204.345064ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:05.809725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:05.428692Z","time spent":"381.01908ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:06.152244Z","caller":"traceutil/trace.go:171","msg":"trace[1058422059] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"722.698209ms","start":"2026-02-22T17:50:05.429531Z","end":"2026-02-22T17:50:06.152229Z","steps":["trace[1058422059] 'process raft request' (duration: 660.900194ms)","trace[1058422059] 'compare' (duration: 61.403897ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:50:06.152267Z","caller":"traceutil/trace.go:171","msg":"trace[481941197] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"381.97854ms","start":"2026-02-22T17:50:05.770273Z","end":"2026-02-22T17:50:06.152251Z","steps":["trace[481941197] 'read index received' (duration: 320.195915ms)","trace[481941197] 'applied index is now lower than readState.Index' (duration: 61.781535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:06.152327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:05.429522Z","time spent":"722.760071ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:06.152393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.119334ms","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-22T17:50:06.152426Z","caller":"traceutil/trace.go:171","msg":"trace[1003489416] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:971; }","duration":"382.188635ms","start":"2026-02-22T17:50:05.770226Z","end":"2026-02-22T17:50:06.152415Z","steps":["trace[1003489416] 'agreement among raft nodes before linearized reading' (duration: 382.116074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:06.152457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:05.770208Z","time spent":"382.240297ms","remote":"127.0.0.1:54624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:50:06.152507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.73683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:06.152521Z","caller":"traceutil/trace.go:171","msg":"trace[513288546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"171.848542ms","start":"2026-02-22T17:50:05.980668Z","end":"2026-02-22T17:50:06.152517Z","steps":["trace[513288546] 'agreement among raft nodes before linearized reading' (duration: 171.825132ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:06.612038Z","caller":"traceutil/trace.go:171","msg":"trace[1509401998] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"276.952472ms","start":"2026-02-22T17:50:06.335057Z","end":"2026-02-22T17:50:06.61201Z","steps":["trace[1509401998] 'process raft request' (duration: 267.355734ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:06.632783Z","caller":"traceutil/trace.go:171","msg":"trace[254477760] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"154.495826ms","start":"2026-02-22T17:50:06.478257Z","end":"2026-02-22T17:50:06.632753Z","steps":["trace[254477760] 'process raft request' (duration: 154.350843ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:06.874074Z","caller":"traceutil/trace.go:171","msg":"trace[1155316699] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"237.166903ms","start":"2026-02-22T17:50:06.636877Z","end":"2026-02-22T17:50:06.874044Z","steps":["trace[1155316699] 'process raft request' (duration: 137.253464ms)","trace[1155316699] 'compare' (duration: 99.723415ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:07.37589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.150562ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:50:07.376134Z","caller":"traceutil/trace.go:171","msg":"trace[1638416349] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"493.135082ms","start":"2026-02-22T17:50:06.882976Z","end":"2026-02-22T17:50:07.376112Z","steps":["trace[1638416349] 'process raft request' (duration: 129.703303ms)","trace[1638416349] 'compare' (duration: 363.064711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:07.376248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:06.882954Z","time spent":"493.229483ms","remote":"127.0.0.1:54306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5204,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:07.465782Z","caller":"traceutil/trace.go:171","msg":"trace[1171344109] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"128.484735ms","start":"2026-02-22T17:50:07.337238Z","end":"2026-02-22T17:50:07.465723Z","steps":["trace[1171344109] 'read index received' (duration: 59.722µs)","trace[1171344109] 'applied index is now lower than readState.Index' (duration: 128.422893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:07.46618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.967357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:07.466439Z","caller":"traceutil/trace.go:171","msg":"trace[1884143887] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:975; }","duration":"129.156051ms","start":"2026-02-22T17:50:07.337133Z","end":"2026-02-22T17:50:07.466289Z","steps":["trace[1884143887] 'agreement among raft nodes before linearized reading' (duration: 128.725851ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:07.902467Z","caller":"traceutil/trace.go:171","msg":"trace[1579270090] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"432.261856ms","start":"2026-02-22T17:50:07.470188Z","end":"2026-02-22T17:50:07.90245Z","steps":["trace[1579270090] 'process raft request' (duration: 432.139284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:07.90266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:07.470164Z","time spent":"432.429529ms","remote":"127.0.0.1:54506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":797,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:08.160395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.068653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:50:08.160585Z","caller":"traceutil/trace.go:171","msg":"trace[181599524] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1014; }","duration":"346.724158ms","start":"2026-02-22T17:50:07.813847Z","end":"2026-02-22T17:50:08.160571Z","steps":["trace[181599524] 'read index received' (duration: 89.173855ms)","trace[181599524] 'applied index is now lower than readState.Index' (duration: 257.549503ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:50:08.160669Z","caller":"traceutil/trace.go:171","msg":"trace[548570410] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"690.35023ms","start":"2026-02-22T17:50:07.470308Z","end":"2026-02-22T17:50:08.160658Z","steps":["trace[548570410] 'process raft request' (duration: 550.94572ms)","trace[548570410] 'compare' (duration: 138.979391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:08.160726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:07.470286Z","time spent":"690.411122ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:08.160898Z","caller":"traceutil/trace.go:171","msg":"trace[1056729656] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"688.700475ms","start":"2026-02-22T17:50:07.47217Z","end":"2026-02-22T17:50:08.16087Z","steps":["trace[1056729656] 'process raft request' (duration: 688.365087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:08.16099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.147758ms","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-22T17:50:08.160996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.667809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:08.16103Z","caller":"traceutil/trace.go:171","msg":"trace[1325786898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"347.21265ms","start":"2026-02-22T17:50:07.813803Z","end":"2026-02-22T17:50:08.161016Z","steps":["trace[1325786898] 'agreement among raft nodes before linearized reading' (duration: 347.045476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:08.161071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:07.813785Z","time spent":"347.279951ms","remote":"127.0.0.1:54414","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":"info","ts":"2026-02-22T17:50:08.161075Z","caller":"traceutil/trace.go:171","msg":"trace[301958517] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"178.807872ms","start":"2026-02-22T17:50:07.982249Z","end":"2026-02-22T17:50:08.161057Z","steps":["trace[301958517] 'agreement among raft nodes before linearized reading' (duration: 178.651889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:08.161248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:07.472158Z","time spent":"688.813818ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:08.161228Z","caller":"traceutil/trace.go:171","msg":"trace[1270624112] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"690.76526ms","start":"2026-02-22T17:50:07.470426Z","end":"2026-02-22T17:50:08.161192Z","steps":["trace[1270624112] 'process raft request' (duration: 690.064104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:08.161538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:07.470416Z","time spent":"691.031516ms","remote":"127.0.0.1:54414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:08.395787Z","caller":"traceutil/trace.go:171","msg":"trace[918707644] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"229.519897ms","start":"2026-02-22T17:50:08.166246Z","end":"2026-02-22T17:50:08.395766Z","steps":["trace[918707644] 'process raft request' (duration: 229.471506ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:08.396019Z","caller":"traceutil/trace.go:171","msg":"trace[1832326782] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"230.11026ms","start":"2026-02-22T17:50:08.165894Z","end":"2026-02-22T17:50:08.396005Z","steps":["trace[1832326782] 'process raft request' (duration: 148.191971ms)","trace[1832326782] 'compare' (duration: 81.519561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:09.220054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.215405ms","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-22T17:50:09.220216Z","caller":"traceutil/trace.go:171","msg":"trace[810179496] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:981; }","duration":"644.375138ms","start":"2026-02-22T17:50:08.575811Z","end":"2026-02-22T17:50:09.220186Z","steps":["trace[810179496] 'range keys from in-memory index tree' (duration: 644.200614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:09.220169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.351968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:50:09.220941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.069404ms","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-22T17:50:09.221119Z","caller":"traceutil/trace.go:171","msg":"trace[828588501] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:981; }","duration":"239.544735ms","start":"2026-02-22T17:50:08.981565Z","end":"2026-02-22T17:50:09.221109Z","steps":["trace[828588501] 'count revisions from in-memory index tree' (duration: 239.035934ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:09.220652Z","caller":"traceutil/trace.go:171","msg":"trace[1282013542] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:981; }","duration":"244.861899ms","start":"2026-02-22T17:50:08.97576Z","end":"2026-02-22T17:50:09.220622Z","steps":["trace[1282013542] 'count revisions from in-memory index tree' (duration: 244.290697ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:10.297645Z","caller":"traceutil/trace.go:171","msg":"trace[2036984837] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"101.757044ms","start":"2026-02-22T17:50:10.195852Z","end":"2026-02-22T17:50:10.297609Z","steps":["trace[2036984837] 'process raft request' (duration: 101.514839ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:10.301982Z","caller":"traceutil/trace.go:171","msg":"trace[940404609] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"105.470537ms","start":"2026-02-22T17:50:10.196492Z","end":"2026-02-22T17:50:10.301962Z","steps":["trace[940404609] 'process raft request' (duration: 105.290893ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:10.3021Z","caller":"traceutil/trace.go:171","msg":"trace[1538570332] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"105.669142ms","start":"2026-02-22T17:50:10.196426Z","end":"2026-02-22T17:50:10.302095Z","steps":["trace[1538570332] 'process raft request' (duration: 105.246233ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:12.210278Z","caller":"traceutil/trace.go:171","msg":"trace[1287429320] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"183.073098ms","start":"2026-02-22T17:50:12.027177Z","end":"2026-02-22T17:50:12.21025Z","steps":["trace[1287429320] 'process raft request' (duration: 182.553777ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:24.077329Z","caller":"traceutil/trace.go:171","msg":"trace[515225151] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"125.406997ms","start":"2026-02-22T17:50:23.951897Z","end":"2026-02-22T17:50:24.077304Z","steps":["trace[515225151] 'process raft request' (duration: 125.134791ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:24.295338Z","caller":"traceutil/trace.go:171","msg":"trace[271077114] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"213.567631ms","start":"2026-02-22T17:50:24.081736Z","end":"2026-02-22T17:50:24.295303Z","steps":["trace[271077114] 'process raft request' (duration: 187.581628ms)","trace[271077114] 'compare' (duration: 25.727168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:53:13.519526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.889602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:53:13.519724Z","caller":"traceutil/trace.go:171","msg":"trace[842321182] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"258.648424ms","start":"2026-02-22T17:53:13.261019Z","end":"2026-02-22T17:53:13.519667Z","steps":["trace[842321182] 'process raft request' (duration: 65.443046ms)","trace[842321182] 'compare' (duration: 192.726018ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:13.519788Z","caller":"traceutil/trace.go:171","msg":"trace[2036985965] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"189.417717ms","start":"2026-02-22T17:53:13.330334Z","end":"2026-02-22T17:53:13.519752Z","steps":["trace[2036985965] 'read index received' (duration: 38.921µs)","trace[2036985965] 'applied index is now lower than readState.Index' (duration: 189.374906ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:53:13.519969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.626232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-22T17:53:13.520022Z","caller":"traceutil/trace.go:171","msg":"trace[2084921789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1794; }","duration":"189.739035ms","start":"2026-02-22T17:53:13.330271Z","end":"2026-02-22T17:53:13.52001Z","steps":["trace[2084921789] 'agreement among raft nodes before linearized reading' (duration: 189.556561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:53:13.521325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.247243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-22T17:53:13.521394Z","caller":"traceutil/trace.go:171","msg":"trace[231543496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1795; }","duration":"189.333944ms","start":"2026-02-22T17:53:13.332039Z","end":"2026-02-22T17:53:13.521373Z","steps":["trace[231543496] 'agreement among raft nodes before linearized reading' (duration: 189.095649ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:21.511211Z","caller":"traceutil/trace.go:171","msg":"trace[1290402528] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"162.852546ms","start":"2026-02-22T17:53:21.34833Z","end":"2026-02-22T17:53:21.511182Z","steps":["trace[1290402528] 'process raft request' (duration: 162.789825ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:21.511283Z","caller":"traceutil/trace.go:171","msg":"trace[1412250616] transaction","detail":"{read_only:false; response_revision:1830; number_of_response:1; }","duration":"163.839377ms","start":"2026-02-22T17:53:21.347409Z","end":"2026-02-22T17:53:21.511249Z","steps":["trace[1412250616] 'process raft request' (duration: 129.908609ms)","trace[1412250616] 'compare' (duration: 33.403057ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:53:21.511403Z","caller":"traceutil/trace.go:171","msg":"trace[701443502] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"163.100611ms","start":"2026-02-22T17:53:21.348287Z","end":"2026-02-22T17:53:21.511388Z","steps":["trace[701443502] 'process raft request' (duration: 162.714773ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:53:21.511514Z","caller":"traceutil/trace.go:171","msg":"trace[2055116033] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"163.46228ms","start":"2026-02-22T17:53:21.348004Z","end":"2026-02-22T17:53:21.511466Z","steps":["trace[2055116033] 'process raft request' (duration: 162.930398ms)"],"step_count":1}