{"level":"warn","ts":"2026-02-25T06:10:49.21426Z","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-25T06:10:49.214466Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35: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.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35: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-25T06:10:49.214565Z","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-25T06:10:49.214573Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-25T06:10:49.214594Z","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-25T06:10:49.215379Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-02-25T06:10:49.215459Z","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.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35: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-25T06:10:49.251513Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"35.781022ms"} {"level":"info","ts":"2026-02-25T06:10:49.260384Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-02-25T06:10:49.260517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:10:49.260558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-02-25T06:10:49.260578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:10:49.260597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-02-25T06:10:49.2607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-02-25T06:10:49.34302Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:10:49.34479Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:10:49.347327Z","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-25T06:10:49.350636Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:10:49.350948Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:10:49.35115Z","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-25T06:10:49.351269Z","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-25T06:10:49.351287Z","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-25T06:10:49.355302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-02-25T06:10:49.355551Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-02-25T06:10:49.35711Z","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-25T06:10:49.35726Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-25T06:10:49.357311Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-02-25T06:10:49.35748Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:10:49.357545Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:10:50.061265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:10:50.061367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:10:50.061406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-02-25T06:10:50.061427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:10:50.061438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-25T06:10:50.061455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-02-25T06:10:50.061474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-02-25T06:10:50.062549Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:10:50.062578Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:10:50.062695Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:50.062888Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:10:50.063093Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:10:50.063145Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:10:50.063878Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:50.064066Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:50.064123Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:50.065864Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:10:50.072604Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-02-25T06:11:14.038608Z","caller":"traceutil/trace.go:171","msg":"trace[2127668400] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"239.930625ms","start":"2026-02-25T06:11:13.798643Z","end":"2026-02-25T06:11:14.038574Z","steps":["trace[2127668400] 'process raft request' (duration: 239.706819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:14.563471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.452131ms","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-25T06:11:14.563672Z","caller":"traceutil/trace.go:171","msg":"trace[1391951805] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"239.715469ms","start":"2026-02-25T06:11:14.323914Z","end":"2026-02-25T06:11:14.563629Z","steps":["trace[1391951805] 'range keys from in-memory index tree' (duration: 239.318848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:14.563543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.981973ms","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-25T06:11:14.563805Z","caller":"traceutil/trace.go:171","msg":"trace[1058656379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"374.323122ms","start":"2026-02-25T06:11:14.18945Z","end":"2026-02-25T06:11:14.563774Z","steps":["trace[1058656379] 'range keys from in-memory index tree' (duration: 373.842359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:14.563874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:14.189422Z","time spent":"374.438754ms","remote":"127.0.0.1:41414","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-25T06:11:21.477176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.765119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:21.477527Z","caller":"traceutil/trace.go:171","msg":"trace[2146664001] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"638.891185ms","start":"2026-02-25T06:11:20.838554Z","end":"2026-02-25T06:11:21.477446Z","steps":["trace[2146664001] 'process raft request' (duration: 31.250413ms)","trace[2146664001] 'compare' (duration: 605.67504ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:21.477578Z","caller":"traceutil/trace.go:171","msg":"trace[2035951834] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"632.857895ms","start":"2026-02-25T06:11:20.844681Z","end":"2026-02-25T06:11:21.477539Z","steps":["trace[2035951834] 'process raft request' (duration: 632.642359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:21.477814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.838518Z","time spent":"639.176832ms","remote":"127.0.0.1:41204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:11:21.477985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.844647Z","time spent":"633.03049ms","remote":"127.0.0.1:41614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:21.47841Z","caller":"traceutil/trace.go:171","msg":"trace[1762309299] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"633.596475ms","start":"2026-02-25T06:11:20.844777Z","end":"2026-02-25T06:11:21.478373Z","steps":["trace[1762309299] 'process raft request' (duration: 632.6356ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:21.478468Z","caller":"traceutil/trace.go:171","msg":"trace[1306691294] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"624.759333ms","start":"2026-02-25T06:11:20.853682Z","end":"2026-02-25T06:11:21.478441Z","steps":["trace[1306691294] 'process raft request' (duration: 624.569118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:21.478489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.844753Z","time spent":"633.697097ms","remote":"127.0.0.1:41604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14294,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:21.478615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.853663Z","time spent":"624.869015ms","remote":"127.0.0.1:41330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16637,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:21.503063Z","caller":"traceutil/trace.go:171","msg":"trace[2091546158] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"630.859093ms","start":"2026-02-25T06:11:20.872157Z","end":"2026-02-25T06:11:21.503016Z","steps":["trace[2091546158] 'process raft request' (duration: 630.107773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:21.503263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.856263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T06:11:21.503303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.872133Z","time spent":"631.052648ms","remote":"127.0.0.1:41232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317247,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:21.503346Z","caller":"traceutil/trace.go:171","msg":"trace[1636771948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"543.953176ms","start":"2026-02-25T06:11:20.959369Z","end":"2026-02-25T06:11:21.503323Z","steps":["trace[1636771948] 'agreement among raft nodes before linearized reading' (duration: 543.760041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:21.503415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.959325Z","time spent":"544.075169ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:11:21.503895Z","caller":"traceutil/trace.go:171","msg":"trace[1463081240] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:520; }","duration":"543.491904ms","start":"2026-02-25T06:11:20.959377Z","end":"2026-02-25T06:11:21.502869Z","steps":["trace[1463081240] 'read index received' (duration: 45.418736ms)","trace[1463081240] 'applied index is now lower than readState.Index' (duration: 498.071768ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:21.666194Z","caller":"traceutil/trace.go:171","msg":"trace[82421917] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"178.858997ms","start":"2026-02-25T06:11:21.487291Z","end":"2026-02-25T06:11:21.66615Z","steps":["trace[82421917] 'process raft request' (duration: 175.763556ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:21.666374Z","caller":"traceutil/trace.go:171","msg":"trace[2103056667] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"157.67142ms","start":"2026-02-25T06:11:21.508675Z","end":"2026-02-25T06:11:21.666346Z","steps":["trace[2103056667] 'process raft request' (duration: 157.418703ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:21.666579Z","caller":"traceutil/trace.go:171","msg":"trace[121719735] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"162.556389ms","start":"2026-02-25T06:11:21.50398Z","end":"2026-02-25T06:11:21.666536Z","steps":["trace[121719735] 'read index received' (duration: 159.066477ms)","trace[121719735] 'applied index is now lower than readState.Index' (duration: 3.482321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:21.666827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.614423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd\" ","response":"range_response_count:1 size:4332"} {"level":"warn","ts":"2026-02-25T06:11:21.666854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.37726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-2ttjh\" ","response":"range_response_count:1 size:3615"} {"level":"info","ts":"2026-02-25T06:11:21.666866Z","caller":"traceutil/trace.go:171","msg":"trace[2053876097] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-7fcd8d6ffd; range_end:; response_count:1; response_revision:512; }","duration":"180.689626ms","start":"2026-02-25T06:11:21.486163Z","end":"2026-02-25T06:11:21.666852Z","steps":["trace[2053876097] 'agreement among raft nodes before linearized reading' (duration: 180.547812ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:21.666904Z","caller":"traceutil/trace.go:171","msg":"trace[1828319924] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-2ttjh; range_end:; response_count:1; response_revision:512; }","duration":"182.479913ms","start":"2026-02-25T06:11:21.484411Z","end":"2026-02-25T06:11:21.666891Z","steps":["trace[1828319924] 'agreement among raft nodes before linearized reading' (duration: 182.37191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:21.666929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.461546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-25T06:11:21.666989Z","caller":"traceutil/trace.go:171","msg":"trace[1307685422] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:512; }","duration":"667.661762ms","start":"2026-02-25T06:11:20.999306Z","end":"2026-02-25T06:11:21.666968Z","steps":["trace[1307685422] 'agreement among raft nodes before linearized reading' (duration: 667.444596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:21.667038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.999291Z","time spent":"667.732203ms","remote":"127.0.0.1:41346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-25T06:11:21.668276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.713463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-25T06:11:21.668371Z","caller":"traceutil/trace.go:171","msg":"trace[572533834] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:512; }","duration":"667.801386ms","start":"2026-02-25T06:11:21.000526Z","end":"2026-02-25T06:11:21.668328Z","steps":["trace[572533834] 'agreement among raft nodes before linearized reading' (duration: 666.496391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:21.668415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:21.000515Z","time spent":"667.886968ms","remote":"127.0.0.1:41346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-25T06:11:21.669374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.05962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:21.669426Z","caller":"traceutil/trace.go:171","msg":"trace[1793888461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"704.127291ms","start":"2026-02-25T06:11:20.965282Z","end":"2026-02-25T06:11:21.669409Z","steps":["trace[1793888461] 'agreement among raft nodes before linearized reading' (duration: 704.025698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:21.669474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.965272Z","time spent":"704.193334ms","remote":"127.0.0.1:41122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:11:23.241385Z","caller":"traceutil/trace.go:171","msg":"trace[655320903] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"276.860806ms","start":"2026-02-25T06:11:22.964499Z","end":"2026-02-25T06:11:23.241359Z","steps":["trace[655320903] 'read index received' (duration: 276.700241ms)","trace[655320903] 'applied index is now lower than readState.Index' (duration: 159.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:23.241448Z","caller":"traceutil/trace.go:171","msg":"trace[415176190] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"545.344052ms","start":"2026-02-25T06:11:22.696061Z","end":"2026-02-25T06:11:23.241405Z","steps":["trace[415176190] 'process raft request' (duration: 545.139336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:23.241508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.998359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:23.241573Z","caller":"traceutil/trace.go:171","msg":"trace[695097768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"277.123903ms","start":"2026-02-25T06:11:22.964439Z","end":"2026-02-25T06:11:23.241563Z","steps":["trace[695097768] 'agreement among raft nodes before linearized reading' (duration: 277.01887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:23.241708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:22.696028Z","time spent":"545.545427ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:29.704876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.686341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T06:11:29.705006Z","caller":"traceutil/trace.go:171","msg":"trace[736461529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"439.863016ms","start":"2026-02-25T06:11:29.265111Z","end":"2026-02-25T06:11:29.704974Z","steps":["trace[736461529] 'range keys from in-memory index tree' (duration: 439.501167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:29.705059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:29.265089Z","time spent":"439.954859ms","remote":"127.0.0.1:41414","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-25T06:11:30.016104Z","caller":"traceutil/trace.go:171","msg":"trace[2067358080] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"306.572299ms","start":"2026-02-25T06:11:29.709507Z","end":"2026-02-25T06:11:30.016079Z","steps":["trace[2067358080] 'process raft request' (duration: 306.415815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.016272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:29.709468Z","time spent":"306.714652ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:30.069175Z","caller":"traceutil/trace.go:171","msg":"trace[392960382] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:553; }","duration":"116.177607ms","start":"2026-02-25T06:11:29.952975Z","end":"2026-02-25T06:11:30.069153Z","steps":["trace[392960382] 'read index received' (duration: 62.948207ms)","trace[392960382] 'applied index is now lower than readState.Index' (duration: 53.22826ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:30.069295Z","caller":"traceutil/trace.go:171","msg":"trace[1956649526] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"358.863955ms","start":"2026-02-25T06:11:29.710419Z","end":"2026-02-25T06:11:30.069283Z","steps":["trace[1956649526] 'process raft request' (duration: 358.619649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.069497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:29.710397Z","time spent":"358.930446ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:30.069624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.415278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:30.069727Z","caller":"traceutil/trace.go:171","msg":"trace[2072296830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"104.571382ms","start":"2026-02-25T06:11:29.965132Z","end":"2026-02-25T06:11:30.069704Z","steps":["trace[2072296830] 'agreement among raft nodes before linearized reading' (duration: 104.407618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.069733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.764563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xnr57\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T06:11:30.069778Z","caller":"traceutil/trace.go:171","msg":"trace[535924646] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xnr57; range_end:; response_count:1; response_revision:538; }","duration":"116.853286ms","start":"2026-02-25T06:11:29.952914Z","end":"2026-02-25T06:11:30.069768Z","steps":["trace[535924646] 'agreement among raft nodes before linearized reading' (duration: 116.680701ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:30.462041Z","caller":"traceutil/trace.go:171","msg":"trace[824692741] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"335.951742ms","start":"2026-02-25T06:11:30.126063Z","end":"2026-02-25T06:11:30.462015Z","steps":["trace[824692741] 'read index received' (duration: 335.628304ms)","trace[824692741] 'applied index is now lower than readState.Index' (duration: 322.048µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:30.462142Z","caller":"traceutil/trace.go:171","msg":"trace[1777765282] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"375.293906ms","start":"2026-02-25T06:11:30.086801Z","end":"2026-02-25T06:11:30.462094Z","steps":["trace[1777765282] 'process raft request' (duration: 375.04545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.462362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.2792ms","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-25T06:11:30.462447Z","caller":"traceutil/trace.go:171","msg":"trace[586681977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"336.424944ms","start":"2026-02-25T06:11:30.126001Z","end":"2026-02-25T06:11:30.462426Z","steps":["trace[586681977] 'agreement among raft nodes before linearized reading' (duration: 336.178997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.462498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.125961Z","time spent":"336.527928ms","remote":"127.0.0.1:41414","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-25T06:11:30.462418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.086755Z","time spent":"375.560723ms","remote":"127.0.0.1:41330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:31.87196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"906.290882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:31.872071Z","caller":"traceutil/trace.go:171","msg":"trace[249374958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"906.462636ms","start":"2026-02-25T06:11:30.965581Z","end":"2026-02-25T06:11:31.872044Z","steps":["trace[249374958] 'range keys from in-memory index tree' (duration: 906.142097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:31.872148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:30.965559Z","time spent":"906.574409ms","remote":"127.0.0.1:41122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:11:33.443943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.903833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T06:11:33.444031Z","caller":"traceutil/trace.go:171","msg":"trace[1907315323] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:550; }","duration":"126.048798ms","start":"2026-02-25T06:11:33.31796Z","end":"2026-02-25T06:11:33.444009Z","steps":["trace[1907315323] 'range keys from in-memory index tree' (duration: 125.79871ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:45.645411Z","caller":"traceutil/trace.go:171","msg":"trace[1658236179] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"152.661959ms","start":"2026-02-25T06:11:45.492703Z","end":"2026-02-25T06:11:45.645365Z","steps":["trace[1658236179] 'process raft request' (duration: 152.491294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.037974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.897512ms","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-25T06:11:46.038041Z","caller":"traceutil/trace.go:171","msg":"trace[185747284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:713; }","duration":"277.029646ms","start":"2026-02-25T06:11:45.760996Z","end":"2026-02-25T06:11:46.038026Z","steps":["trace[185747284] 'range keys from in-memory index tree' (duration: 276.701456ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:52.462064Z","caller":"traceutil/trace.go:171","msg":"trace[770077138] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"388.286962ms","start":"2026-02-25T06:11:52.073755Z","end":"2026-02-25T06:11:52.462042Z","steps":["trace[770077138] 'process raft request' (duration: 388.146378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:52.46224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:52.073723Z","time spent":"388.420836ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:52.462246Z","caller":"traceutil/trace.go:171","msg":"trace[907160381] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:752; }","duration":"289.966041ms","start":"2026-02-25T06:11:52.172244Z","end":"2026-02-25T06:11:52.46221Z","steps":["trace[907160381] 'read index received' (duration: 289.9489ms)","trace[907160381] 'applied index is now lower than readState.Index' (duration: 14.561µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:52.462463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.186977ms","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-25T06:11:52.462527Z","caller":"traceutil/trace.go:171","msg":"trace[1186555226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:730; }","duration":"290.316071ms","start":"2026-02-25T06:11:52.172192Z","end":"2026-02-25T06:11:52.462508Z","steps":["trace[1186555226] 'agreement among raft nodes before linearized reading' (duration: 290.072414ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:52.499182Z","caller":"traceutil/trace.go:171","msg":"trace[148720152] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"102.673824ms","start":"2026-02-25T06:11:52.396489Z","end":"2026-02-25T06:11:52.499163Z","steps":["trace[148720152] 'process raft request' (duration: 102.55629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:52.499501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.858293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T06:11:52.499566Z","caller":"traceutil/trace.go:171","msg":"trace[755424822] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:731; }","duration":"223.933736ms","start":"2026-02-25T06:11:52.275621Z","end":"2026-02-25T06:11:52.499554Z","steps":["trace[755424822] 'agreement among raft nodes before linearized reading' (duration: 223.618586ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:52.915433Z","caller":"traceutil/trace.go:171","msg":"trace[533786894] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"269.3138ms","start":"2026-02-25T06:11:52.646096Z","end":"2026-02-25T06:11:52.91541Z","steps":["trace[533786894] 'read index received' (duration: 269.029592ms)","trace[533786894] 'applied index is now lower than readState.Index' (duration: 283.308µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:52.91575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.63866ms","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-25T06:11:52.915796Z","caller":"traceutil/trace.go:171","msg":"trace[1454340802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"269.743723ms","start":"2026-02-25T06:11:52.646039Z","end":"2026-02-25T06:11:52.915783Z","steps":["trace[1454340802] 'agreement among raft nodes before linearized reading' (duration: 269.495875ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:52.915886Z","caller":"traceutil/trace.go:171","msg":"trace[256308440] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"411.8126ms","start":"2026-02-25T06:11:52.504031Z","end":"2026-02-25T06:11:52.915844Z","steps":["trace[256308440] 'process raft request' (duration: 411.238802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:52.916077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:52.504006Z","time spent":"411.973465ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:53.369265Z","caller":"traceutil/trace.go:171","msg":"trace[2089091082] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"381.637199ms","start":"2026-02-25T06:11:52.987602Z","end":"2026-02-25T06:11:53.369239Z","steps":["trace[2089091082] 'process raft request' (duration: 381.436933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:53.369474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:52.98758Z","time spent":"381.778133ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:53.406073Z","caller":"traceutil/trace.go:171","msg":"trace[524367149] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"417.329208ms","start":"2026-02-25T06:11:52.988704Z","end":"2026-02-25T06:11:53.406033Z","steps":["trace[524367149] 'process raft request' (duration: 417.07581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:53.406458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:52.98869Z","time spent":"417.484543ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:53.574535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.478046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:53.574701Z","caller":"traceutil/trace.go:171","msg":"trace[1202964582] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"122.723066ms","start":"2026-02-25T06:11:53.451946Z","end":"2026-02-25T06:11:53.574669Z","steps":["trace[1202964582] 'process raft request' (duration: 21.959981ms)","trace[1202964582] 'compare' (duration: 100.292491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:55.045416Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119990647763486,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:55.546264Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119990647763486,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:55.918601Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.448906834s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:11:55.918927Z","caller":"traceutil/trace.go:171","msg":"trace[142145027] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"1.373534784s","start":"2026-02-25T06:11:54.545371Z","end":"2026-02-25T06:11:55.918905Z","steps":["trace[142145027] 'read index received' (duration: 1.373366409s)","trace[142145027] 'applied index is now lower than readState.Index' (duration: 167.455µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:55.919026Z","caller":"traceutil/trace.go:171","msg":"trace[829455885] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.449363078s","start":"2026-02-25T06:11:54.469629Z","end":"2026-02-25T06:11:55.918992Z","steps":["trace[829455885] 'process raft request' (duration: 1.449132321s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:55.91917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.393234ms","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":"warn","ts":"2026-02-25T06:11:55.919193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:54.469617Z","time spent":"1.449478822s","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:55.919204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.852907ms","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-25T06:11:55.91924Z","caller":"traceutil/trace.go:171","msg":"trace[1815534064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:738; }","duration":"998.9466ms","start":"2026-02-25T06:11:54.92028Z","end":"2026-02-25T06:11:55.919227Z","steps":["trace[1815534064] 'agreement among raft nodes before linearized reading' (duration: 998.782905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:55.919274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:54.920257Z","time spent":"999.011702ms","remote":"127.0.0.1:41414","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-25T06:11:55.91933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.130059ms","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-25T06:11:55.919207Z","caller":"traceutil/trace.go:171","msg":"trace[1988273354] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:738; }","duration":"635.499547ms","start":"2026-02-25T06:11:55.283698Z","end":"2026-02-25T06:11:55.919198Z","steps":["trace[1988273354] 'agreement among raft nodes before linearized reading' (duration: 635.406974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:55.919382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.752101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:55.919353Z","caller":"traceutil/trace.go:171","msg":"trace[417416136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"545.194591ms","start":"2026-02-25T06:11:55.374152Z","end":"2026-02-25T06:11:55.919347Z","steps":["trace[417416136] 'agreement among raft nodes before linearized reading' (duration: 545.074228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:55.919413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.853404ms","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-25T06:11:55.919465Z","caller":"traceutil/trace.go:171","msg":"trace[1195041092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"953.870604ms","start":"2026-02-25T06:11:54.965576Z","end":"2026-02-25T06:11:55.919446Z","steps":["trace[1195041092] 'agreement among raft nodes before linearized reading' (duration: 953.74227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:55.91947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:55.37413Z","time spent":"545.328785ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:55.919498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:54.965558Z","time spent":"953.934776ms","remote":"127.0.0.1:41122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:11:55.919429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37405807s","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-25T06:11:55.919495Z","caller":"traceutil/trace.go:171","msg":"trace[186863017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"935.966077ms","start":"2026-02-25T06:11:54.983507Z","end":"2026-02-25T06:11:55.919473Z","steps":["trace[186863017] 'agreement among raft nodes before linearized reading' (duration: 935.643417ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:55.919538Z","caller":"traceutil/trace.go:171","msg":"trace[1987409646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"1.374185503s","start":"2026-02-25T06:11:54.545344Z","end":"2026-02-25T06:11:55.919529Z","steps":["trace[1987409646] 'agreement among raft nodes before linearized reading' (duration: 1.373670807s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:55.919568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:54.983499Z","time spent":"936.059461ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:11:55.919581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:54.545332Z","time spent":"1.374230835s","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:55.919424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:55.283678Z","time spent":"635.718504ms","remote":"127.0.0.1:41622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:11:56.150533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.856128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:56.150675Z","caller":"traceutil/trace.go:171","msg":"trace[1729386363] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"217.832639ms","start":"2026-02-25T06:11:55.932818Z","end":"2026-02-25T06:11:56.150651Z","steps":["trace[1729386363] 'read index received' (duration: 80.703374ms)","trace[1729386363] 'applied index is now lower than readState.Index' (duration: 137.127515ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:56.150721Z","caller":"traceutil/trace.go:171","msg":"trace[1622894161] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"226.701559ms","start":"2026-02-25T06:11:55.923976Z","end":"2026-02-25T06:11:56.150677Z","steps":["trace[1622894161] 'process raft request' (duration: 89.46868ms)","trace[1622894161] 'compare' (duration: 136.658722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:56.150848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.970163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:11:56.150896Z","caller":"traceutil/trace.go:171","msg":"trace[1744598471] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:739; }","duration":"218.072907ms","start":"2026-02-25T06:11:55.932811Z","end":"2026-02-25T06:11:56.150883Z","steps":["trace[1744598471] 'agreement among raft nodes before linearized reading' (duration: 217.925492ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:56.151969Z","caller":"traceutil/trace.go:171","msg":"trace[29502907] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"174.335721ms","start":"2026-02-25T06:11:55.977619Z","end":"2026-02-25T06:11:56.151955Z","steps":["trace[29502907] 'process raft request' (duration: 174.29089ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:56.152144Z","caller":"traceutil/trace.go:171","msg":"trace[771992158] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"211.858387ms","start":"2026-02-25T06:11:55.940274Z","end":"2026-02-25T06:11:56.152133Z","steps":["trace[771992158] 'process raft request' (duration: 211.599829ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:56.152153Z","caller":"traceutil/trace.go:171","msg":"trace[1623984960] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"211.96923ms","start":"2026-02-25T06:11:55.940154Z","end":"2026-02-25T06:11:56.152123Z","steps":["trace[1623984960] 'process raft request' (duration: 211.652411ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:56.152335Z","caller":"traceutil/trace.go:171","msg":"trace[2700761] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"212.814656ms","start":"2026-02-25T06:11:55.939498Z","end":"2026-02-25T06:11:56.152313Z","steps":["trace[2700761] 'process raft request' (duration: 211.847637ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:56.822271Z","caller":"traceutil/trace.go:171","msg":"trace[1808130573] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"513.646978ms","start":"2026-02-25T06:11:56.308592Z","end":"2026-02-25T06:11:56.822239Z","steps":["trace[1808130573] 'process raft request' (duration: 513.426161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:56.822405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.342229ms","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":"warn","ts":"2026-02-25T06:11:56.822445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:56.308566Z","time spent":"513.772892ms","remote":"127.0.0.1:41324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4727,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:56.822244Z","caller":"traceutil/trace.go:171","msg":"trace[2078660197] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"360.177353ms","start":"2026-02-25T06:11:56.462035Z","end":"2026-02-25T06:11:56.822213Z","steps":["trace[2078660197] 'read index received' (duration: 359.880644ms)","trace[2078660197] 'applied index is now lower than readState.Index' (duration: 295.309µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:56.822457Z","caller":"traceutil/trace.go:171","msg":"trace[1433952625] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:744; }","duration":"360.476742ms","start":"2026-02-25T06:11:56.461969Z","end":"2026-02-25T06:11:56.822446Z","steps":["trace[1433952625] 'agreement among raft nodes before linearized reading' (duration: 360.359069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:56.822509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:56.461945Z","time spent":"360.554245ms","remote":"127.0.0.1:41436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:11:56.822625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.833534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:11:56.822699Z","caller":"traceutil/trace.go:171","msg":"trace[402367071] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:744; }","duration":"267.983039ms","start":"2026-02-25T06:11:56.554696Z","end":"2026-02-25T06:11:56.822679Z","steps":["trace[402367071] 'agreement among raft nodes before linearized reading' (duration: 267.845175ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:18.674559Z","caller":"traceutil/trace.go:171","msg":"trace[1434872163] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"101.781676ms","start":"2026-02-25T06:12:18.572391Z","end":"2026-02-25T06:12:18.674173Z","steps":["trace[1434872163] 'process raft request' (duration: 97.189456ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:20.584846Z","caller":"traceutil/trace.go:171","msg":"trace[1007666303] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"265.35083ms","start":"2026-02-25T06:12:20.319468Z","end":"2026-02-25T06:12:20.584819Z","steps":["trace[1007666303] 'read index received' (duration: 265.096892ms)","trace[1007666303] 'applied index is now lower than readState.Index' (duration: 253.008µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:20.585002Z","caller":"traceutil/trace.go:171","msg":"trace[192378277] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"461.317589ms","start":"2026-02-25T06:12:20.123651Z","end":"2026-02-25T06:12:20.584968Z","steps":["trace[192378277] 'process raft request' (duration: 460.985829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:20.585169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.652144ms","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-25T06:12:20.585202Z","caller":"traceutil/trace.go:171","msg":"trace[332201418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"122.748717ms","start":"2026-02-25T06:12:20.462442Z","end":"2026-02-25T06:12:20.58519Z","steps":["trace[332201418] 'agreement among raft nodes before linearized reading' (duration: 122.594302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:20.585277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.123627Z","time spent":"461.452134ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:20.585446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.545825ms","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-25T06:12:20.58601Z","caller":"traceutil/trace.go:171","msg":"trace[564341734] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:811; }","duration":"266.601428ms","start":"2026-02-25T06:12:20.319387Z","end":"2026-02-25T06:12:20.585988Z","steps":["trace[564341734] 'agreement among raft nodes before linearized reading' (duration: 265.579376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.179031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.417044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:21.179279Z","caller":"traceutil/trace.go:171","msg":"trace[2069076157] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:839; }","duration":"499.496116ms","start":"2026-02-25T06:12:20.679765Z","end":"2026-02-25T06:12:21.179261Z","steps":["trace[2069076157] 'read index received' (duration: 361.70344ms)","trace[2069076157] 'applied index is now lower than readState.Index' (duration: 137.791445ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:21.179361Z","caller":"traceutil/trace.go:171","msg":"trace[1695348099] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"588.070989ms","start":"2026-02-25T06:12:20.591283Z","end":"2026-02-25T06:12:21.179354Z","steps":["trace[1695348099] 'process raft request' (duration: 587.869423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.179402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.591276Z","time spent":"588.10211ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:21.179469Z","caller":"traceutil/trace.go:171","msg":"trace[863865074] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"572.03418ms","start":"2026-02-25T06:12:20.607429Z","end":"2026-02-25T06:12:21.179464Z","steps":["trace[863865074] 'process raft request' (duration: 571.802083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.179494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.607403Z","time spent":"572.075041ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:21.179502Z","caller":"traceutil/trace.go:171","msg":"trace[738585473] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"588.43079ms","start":"2026-02-25T06:12:20.591036Z","end":"2026-02-25T06:12:21.179467Z","steps":["trace[738585473] 'process raft request' (duration: 450.47899ms)","trace[738585473] 'compare' (duration: 137.194037ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:21.179681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.591015Z","time spent":"588.560274ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:21.179782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.013012ms","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-25T06:12:21.179846Z","caller":"traceutil/trace.go:171","msg":"trace[364252322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"500.094684ms","start":"2026-02-25T06:12:20.679729Z","end":"2026-02-25T06:12:21.179824Z","steps":["trace[364252322] 'agreement among raft nodes before linearized reading' (duration: 499.890557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.179856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.837167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:21.179887Z","caller":"traceutil/trace.go:171","msg":"trace[1814818980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"214.924319ms","start":"2026-02-25T06:12:20.964956Z","end":"2026-02-25T06:12:21.179881Z","steps":["trace[1814818980] 'agreement among raft nodes before linearized reading' (duration: 214.875928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.179887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.679693Z","time spent":"500.186196ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T06:12:23.315952Z","caller":"traceutil/trace.go:171","msg":"trace[751729406] linearizableReadLoop","detail":"{readStateIndex:844; appliedIndex:843; }","duration":"471.181971ms","start":"2026-02-25T06:12:22.844739Z","end":"2026-02-25T06:12:23.315921Z","steps":["trace[751729406] 'read index received' (duration: 470.908463ms)","trace[751729406] 'applied index is now lower than readState.Index' (duration: 272.438µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:23.316076Z","caller":"traceutil/trace.go:171","msg":"trace[1258858547] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"722.351869ms","start":"2026-02-25T06:12:22.593681Z","end":"2026-02-25T06:12:23.316033Z","steps":["trace[1258858547] 'process raft request' (duration: 722.07391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.316129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.979823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:12:23.316243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:22.593651Z","time spent":"722.506234ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:23.316245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.300968ms","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-25T06:12:23.316303Z","caller":"traceutil/trace.go:171","msg":"trace[357504731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:816; }","duration":"131.379841ms","start":"2026-02-25T06:12:23.184909Z","end":"2026-02-25T06:12:23.316289Z","steps":["trace[357504731] 'agreement among raft nodes before linearized reading' (duration: 131.229886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.316294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.409769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T06:12:23.316316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.580395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:12:23.316169Z","caller":"traceutil/trace.go:171","msg":"trace[677318400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"350.070726ms","start":"2026-02-25T06:12:22.966086Z","end":"2026-02-25T06:12:23.316157Z","steps":["trace[677318400] 'agreement among raft nodes before linearized reading' (duration: 349.992043ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:23.316356Z","caller":"traceutil/trace.go:171","msg":"trace[614054632] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:816; }","duration":"471.660586ms","start":"2026-02-25T06:12:22.844679Z","end":"2026-02-25T06:12:23.316339Z","steps":["trace[614054632] 'agreement among raft nodes before linearized reading' (duration: 471.388718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.316378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.437172ms","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-25T06:12:23.316391Z","caller":"traceutil/trace.go:171","msg":"trace[2036052963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"110.700469ms","start":"2026-02-25T06:12:23.205671Z","end":"2026-02-25T06:12:23.316371Z","steps":["trace[2036052963] 'agreement among raft nodes before linearized reading' (duration: 110.449102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.316395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:22.966071Z","time spent":"350.316013ms","remote":"127.0.0.1:41122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:12:23.316409Z","caller":"traceutil/trace.go:171","msg":"trace[209667844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"132.506985ms","start":"2026-02-25T06:12:23.183894Z","end":"2026-02-25T06:12:23.316401Z","steps":["trace[209667844] 'agreement among raft nodes before linearized reading' (duration: 132.384551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.316417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:22.844629Z","time spent":"471.77251ms","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:12:23.50402Z","caller":"traceutil/trace.go:171","msg":"trace[581983100] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"182.356776ms","start":"2026-02-25T06:12:23.321621Z","end":"2026-02-25T06:12:23.503978Z","steps":["trace[581983100] 'process raft request' (duration: 92.662099ms)","trace[581983100] 'compare' (duration: 89.331486ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:23.504105Z","caller":"traceutil/trace.go:171","msg":"trace[89827458] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"181.255583ms","start":"2026-02-25T06:12:23.322798Z","end":"2026-02-25T06:12:23.504054Z","steps":["trace[89827458] 'process raft request' (duration: 180.985704ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:23.504139Z","caller":"traceutil/trace.go:171","msg":"trace[830376793] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"180.454388ms","start":"2026-02-25T06:12:23.323644Z","end":"2026-02-25T06:12:23.504098Z","steps":["trace[830376793] 'process raft request' (duration: 180.19577ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:23.812245Z","caller":"traceutil/trace.go:171","msg":"trace[420159214] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"306.747853ms","start":"2026-02-25T06:12:23.505473Z","end":"2026-02-25T06:12:23.812221Z","steps":["trace[420159214] 'process raft request' (duration: 215.059604ms)","trace[420159214] 'compare' (duration: 91.548184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:23.812378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.505447Z","time spent":"306.861796ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:24.2383Z","caller":"traceutil/trace.go:171","msg":"trace[2141273530] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"157.89694ms","start":"2026-02-25T06:12:24.080371Z","end":"2026-02-25T06:12:24.238268Z","steps":["trace[2141273530] 'process raft request' (duration: 157.741055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:25.867432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.090399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:25.867551Z","caller":"traceutil/trace.go:171","msg":"trace[1211093870] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"359.204143ms","start":"2026-02-25T06:12:25.508327Z","end":"2026-02-25T06:12:25.867531Z","steps":["trace[1211093870] 'read index received' (duration: 173.995441ms)","trace[1211093870] 'applied index is now lower than readState.Index' (duration: 185.206582ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:25.86756Z","caller":"traceutil/trace.go:171","msg":"trace[322210948] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"470.710777ms","start":"2026-02-25T06:12:25.396822Z","end":"2026-02-25T06:12:25.867532Z","steps":["trace[322210948] 'process raft request' (duration: 285.420722ms)","trace[322210948] 'compare' (duration: 184.958065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:25.867718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.389839ms","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-25T06:12:25.867715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:25.396799Z","time spent":"470.821841ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:25.86774Z","caller":"traceutil/trace.go:171","msg":"trace[416121422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:824; }","duration":"359.463711ms","start":"2026-02-25T06:12:25.508269Z","end":"2026-02-25T06:12:25.867732Z","steps":["trace[416121422] 'agreement among raft nodes before linearized reading' (duration: 359.336978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:25.867764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:25.508217Z","time spent":"359.541604ms","remote":"127.0.0.1:41414","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-25T06:12:25.867848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.245784ms","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-25T06:12:25.867904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.960386ms","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-25T06:12:25.867904Z","caller":"traceutil/trace.go:171","msg":"trace[1004238366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"359.358988ms","start":"2026-02-25T06:12:25.508529Z","end":"2026-02-25T06:12:25.867888Z","steps":["trace[1004238366] 'agreement among raft nodes before linearized reading' (duration: 359.167322ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:25.86792Z","caller":"traceutil/trace.go:171","msg":"trace[2010262056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:824; }","duration":"358.993097ms","start":"2026-02-25T06:12:25.508922Z","end":"2026-02-25T06:12:25.867915Z","steps":["trace[2010262056] 'agreement among raft nodes before linearized reading' (duration: 358.945236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:25.867939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:25.508906Z","time spent":"359.029408ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:25.867947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:25.508375Z","time spent":"359.564645ms","remote":"127.0.0.1:41414","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-25T06:12:26.115685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.52303ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:26.115892Z","caller":"traceutil/trace.go:171","msg":"trace[198925471] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"243.612165ms","start":"2026-02-25T06:12:25.872236Z","end":"2026-02-25T06:12:26.115848Z","steps":["trace[198925471] 'process raft request' (duration: 137.861888ms)","trace[198925471] 'compare' (duration: 105.405887ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:26.155445Z","caller":"traceutil/trace.go:171","msg":"trace[86041523] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:853; }","duration":"185.260265ms","start":"2026-02-25T06:12:25.970159Z","end":"2026-02-25T06:12:26.155419Z","steps":["trace[86041523] 'read index received' (duration: 39.952009ms)","trace[86041523] 'applied index is now lower than readState.Index' (duration: 145.307296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:26.15556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.392669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:26.155598Z","caller":"traceutil/trace.go:171","msg":"trace[14482898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:827; }","duration":"185.472802ms","start":"2026-02-25T06:12:25.970113Z","end":"2026-02-25T06:12:26.155586Z","steps":["trace[14482898] 'agreement among raft nodes before linearized reading' (duration: 185.397809ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:26.155623Z","caller":"traceutil/trace.go:171","msg":"trace[32749975] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"281.7538ms","start":"2026-02-25T06:12:25.87384Z","end":"2026-02-25T06:12:26.155594Z","steps":["trace[32749975] 'process raft request' (duration: 281.522393ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:26.155615Z","caller":"traceutil/trace.go:171","msg":"trace[379221145] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"283.038039ms","start":"2026-02-25T06:12:25.872437Z","end":"2026-02-25T06:12:26.155475Z","steps":["trace[379221145] 'process raft request' (duration: 282.238475ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:33.492073Z","caller":"traceutil/trace.go:171","msg":"trace[956006908] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"164.067688ms","start":"2026-02-25T06:12:33.327976Z","end":"2026-02-25T06:12:33.492044Z","steps":["trace[956006908] 'process raft request' (duration: 163.45454ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:38.466108Z","caller":"traceutil/trace.go:171","msg":"trace[1749940936] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"242.743663ms","start":"2026-02-25T06:12:38.223335Z","end":"2026-02-25T06:12:38.466078Z","steps":["trace[1749940936] 'read index received' (duration: 242.498356ms)","trace[1749940936] 'applied index is now lower than readState.Index' (duration: 244.397µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:38.466525Z","caller":"traceutil/trace.go:171","msg":"trace[285832182] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"279.845138ms","start":"2026-02-25T06:12:38.186647Z","end":"2026-02-25T06:12:38.466492Z","steps":["trace[285832182] 'process raft request' (duration: 279.278553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:38.46665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.156139ms","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-25T06:12:38.466701Z","caller":"traceutil/trace.go:171","msg":"trace[1687944009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"238.243071ms","start":"2026-02-25T06:12:38.228444Z","end":"2026-02-25T06:12:38.466687Z","steps":["trace[1687944009] 'agreement among raft nodes before linearized reading' (duration: 238.029516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:38.466636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.266938ms","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-25T06:12:38.468243Z","caller":"traceutil/trace.go:171","msg":"trace[160011199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"244.931643ms","start":"2026-02-25T06:12:38.223281Z","end":"2026-02-25T06:12:38.468212Z","steps":["trace[160011199] 'agreement among raft nodes before linearized reading' (duration: 243.119774ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:40.215082Z","caller":"traceutil/trace.go:171","msg":"trace[800039385] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"264.205234ms","start":"2026-02-25T06:12:39.950854Z","end":"2026-02-25T06:12:40.215059Z","steps":["trace[800039385] 'read index received' (duration: 263.938627ms)","trace[800039385] 'applied index is now lower than readState.Index' (duration: 265.737µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:40.215287Z","caller":"traceutil/trace.go:171","msg":"trace[1745179371] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"646.23614ms","start":"2026-02-25T06:12:39.568994Z","end":"2026-02-25T06:12:40.21523Z","steps":["trace[1745179371] 'process raft request' (duration: 645.86121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:40.215341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.296063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:40.215556Z","caller":"traceutil/trace.go:171","msg":"trace[811964279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"252.550279ms","start":"2026-02-25T06:12:39.962978Z","end":"2026-02-25T06:12:40.215528Z","steps":["trace[811964279] 'agreement among raft nodes before linearized reading' (duration: 252.251281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:40.215577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:39.568978Z","time spent":"646.470617ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:40.215617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.990301ms","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-25T06:12:40.215743Z","caller":"traceutil/trace.go:171","msg":"trace[1326507733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:913; }","duration":"219.168665ms","start":"2026-02-25T06:12:39.996544Z","end":"2026-02-25T06:12:40.215713Z","steps":["trace[1326507733] 'agreement among raft nodes before linearized reading' (duration: 218.789375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:40.215997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.13915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:40.216252Z","caller":"traceutil/trace.go:171","msg":"trace[1972760164] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:913; }","duration":"265.397767ms","start":"2026-02-25T06:12:39.950838Z","end":"2026-02-25T06:12:40.216236Z","steps":["trace[1972760164] 'agreement among raft nodes before linearized reading' (duration: 264.341709ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:40.79957Z","caller":"traceutil/trace.go:171","msg":"trace[309219042] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"327.778006ms","start":"2026-02-25T06:12:40.471757Z","end":"2026-02-25T06:12:40.799535Z","steps":["trace[309219042] 'read index received' (duration: 323.663145ms)","trace[309219042] 'applied index is now lower than readState.Index' (duration: 4.113821ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:40.799909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.127123ms","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-25T06:12:40.79995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.174617ms","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-25T06:12:40.800031Z","caller":"traceutil/trace.go:171","msg":"trace[1634517254] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"579.234905ms","start":"2026-02-25T06:12:40.220765Z","end":"2026-02-25T06:12:40.8Z","steps":["trace[1634517254] 'process raft request' (duration: 574.670061ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:40.800072Z","caller":"traceutil/trace.go:171","msg":"trace[1178877320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"328.364802ms","start":"2026-02-25T06:12:40.471679Z","end":"2026-02-25T06:12:40.800044Z","steps":["trace[1178877320] 'agreement among raft nodes before linearized reading' (duration: 328.040793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:40.800112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.165443ms","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-25T06:12:40.800125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:40.22073Z","time spent":"579.338378ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:40.800123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:40.471638Z","time spent":"328.474815ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:12:40.800162Z","caller":"traceutil/trace.go:171","msg":"trace[792424081] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:914; }","duration":"206.274816ms","start":"2026-02-25T06:12:40.593871Z","end":"2026-02-25T06:12:40.800145Z","steps":["trace[792424081] 'agreement among raft nodes before linearized reading' (duration: 206.193054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:40.800388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.877676ms","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-25T06:12:40.800514Z","caller":"traceutil/trace.go:171","msg":"trace[1405511087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"322.064991ms","start":"2026-02-25T06:12:40.478423Z","end":"2026-02-25T06:12:40.800488Z","steps":["trace[1405511087] 'agreement among raft nodes before linearized reading' (duration: 321.710682ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:40.800575Z","caller":"traceutil/trace.go:171","msg":"trace[2125419485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:914; }","duration":"322.196555ms","start":"2026-02-25T06:12:40.47773Z","end":"2026-02-25T06:12:40.799927Z","steps":["trace[2125419485] 'agreement among raft nodes before linearized reading' (duration: 322.055701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:40.800574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:40.478401Z","time spent":"322.158944ms","remote":"127.0.0.1:41414","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-25T06:12:40.800603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:40.477718Z","time spent":"322.877483ms","remote":"127.0.0.1:41414","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-25T06:12:44.982131Z","caller":"traceutil/trace.go:171","msg":"trace[294710323] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"111.315711ms","start":"2026-02-25T06:12:44.870792Z","end":"2026-02-25T06:12:44.982107Z","steps":["trace[294710323] 'process raft request' (duration: 111.178047ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:45.007594Z","caller":"traceutil/trace.go:171","msg":"trace[1318363307] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"135.712656ms","start":"2026-02-25T06:12:44.871857Z","end":"2026-02-25T06:12:45.00757Z","steps":["trace[1318363307] 'process raft request' (duration: 135.533672ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:45.007609Z","caller":"traceutil/trace.go:171","msg":"trace[1142653047] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"133.332322ms","start":"2026-02-25T06:12:44.874258Z","end":"2026-02-25T06:12:45.00759Z","steps":["trace[1142653047] 'process raft request' (duration: 133.298651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:45.007752Z","caller":"traceutil/trace.go:171","msg":"trace[441077622] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"133.61271ms","start":"2026-02-25T06:12:44.874133Z","end":"2026-02-25T06:12:45.007746Z","steps":["trace[441077622] 'process raft request' (duration: 133.366883ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:45.007819Z","caller":"traceutil/trace.go:171","msg":"trace[677776810] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"133.61919ms","start":"2026-02-25T06:12:44.874194Z","end":"2026-02-25T06:12:45.007813Z","steps":["trace[677776810] 'process raft request' (duration: 133.335262ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:54.541295Z","caller":"traceutil/trace.go:171","msg":"trace[1952468640] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"261.078075ms","start":"2026-02-25T06:12:54.280201Z","end":"2026-02-25T06:12:54.541279Z","steps":["trace[1952468640] 'read index received' (duration: 260.929321ms)","trace[1952468640] 'applied index is now lower than readState.Index' (duration: 148.184µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:54.5414Z","caller":"traceutil/trace.go:171","msg":"trace[1961413535] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"266.813089ms","start":"2026-02-25T06:12:54.274549Z","end":"2026-02-25T06:12:54.541362Z","steps":["trace[1961413535] 'process raft request' (duration: 266.632894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.541443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.22854ms","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-25T06:12:54.541586Z","caller":"traceutil/trace.go:171","msg":"trace[355224634] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:952; }","duration":"261.423845ms","start":"2026-02-25T06:12:54.280156Z","end":"2026-02-25T06:12:54.541579Z","steps":["trace[355224634] 'agreement among raft nodes before linearized reading' (duration: 261.190429ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:55.286077Z","caller":"traceutil/trace.go:171","msg":"trace[343775672] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"118.881704ms","start":"2026-02-25T06:12:55.167179Z","end":"2026-02-25T06:12:55.28606Z","steps":["trace[343775672] 'process raft request' (duration: 118.760771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:56.071943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119990647764052,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:56.25479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.162656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:56.254937Z","caller":"traceutil/trace.go:171","msg":"trace[816526710] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.087676434s","start":"2026-02-25T06:12:55.167249Z","end":"2026-02-25T06:12:56.254925Z","steps":["trace[816526710] 'process raft request' (duration: 1.087622922s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:56.25498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:55.167222Z","time spent":"1.087737086s","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:56.255071Z","caller":"traceutil/trace.go:171","msg":"trace[1360126163] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:991; }","duration":"683.798732ms","start":"2026-02-25T06:12:55.571254Z","end":"2026-02-25T06:12:56.255053Z","steps":["trace[1360126163] 'read index received' (duration: 511.240296ms)","trace[1360126163] 'applied index is now lower than readState.Index' (duration: 172.557766ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:56.25513Z","caller":"traceutil/trace.go:171","msg":"trace[1816447855] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.074691185s","start":"2026-02-25T06:12:55.18041Z","end":"2026-02-25T06:12:56.255101Z","steps":["trace[1816447855] 'process raft request' (duration: 1.074493639s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:56.255134Z","caller":"traceutil/trace.go:171","msg":"trace[387619005] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.08789151s","start":"2026-02-25T06:12:55.167211Z","end":"2026-02-25T06:12:56.255102Z","steps":["trace[387619005] 'process raft request' (duration: 915.331583ms)","trace[387619005] 'compare' (duration: 172.093224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:56.255181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.913575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:56.255202Z","caller":"traceutil/trace.go:171","msg":"trace[29203793] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:957; }","duration":"683.982537ms","start":"2026-02-25T06:12:55.571214Z","end":"2026-02-25T06:12:56.255197Z","steps":["trace[29203793] 'agreement among raft nodes before linearized reading' (duration: 683.925026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:56.25522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:55.571193Z","time spent":"684.021778ms","remote":"127.0.0.1:41358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:56.255228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.060904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:56.255254Z","caller":"traceutil/trace.go:171","msg":"trace[442693827] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:957; }","duration":"494.122165ms","start":"2026-02-25T06:12:55.761124Z","end":"2026-02-25T06:12:56.255246Z","steps":["trace[442693827] 'agreement among raft nodes before linearized reading' (duration: 494.069004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:56.255279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:55.761106Z","time spent":"494.167906ms","remote":"127.0.0.1:41480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:56.255271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.501545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:12:56.255275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:55.180397Z","time spent":"1.074796628s","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:56.255288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:55.167194Z","time spent":"1.088011673s","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:56.255325Z","caller":"traceutil/trace.go:171","msg":"trace[904222838] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"290.567186ms","start":"2026-02-25T06:12:55.964744Z","end":"2026-02-25T06:12:56.255311Z","steps":["trace[904222838] 'agreement among raft nodes before linearized reading' (duration: 290.475934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:56.255395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.176313ms","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":"info","ts":"2026-02-25T06:12:56.255454Z","caller":"traceutil/trace.go:171","msg":"trace[898737573] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:957; }","duration":"291.267075ms","start":"2026-02-25T06:12:55.964169Z","end":"2026-02-25T06:12:56.255436Z","steps":["trace[898737573] 'agreement among raft nodes before linearized reading' (duration: 291.168342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:57.180865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.676229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T06:12:57.180958Z","caller":"traceutil/trace.go:171","msg":"trace[896966750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"102.822423ms","start":"2026-02-25T06:12:57.078117Z","end":"2026-02-25T06:12:57.180939Z","steps":["trace[896966750] 'range keys from in-memory index tree' (duration: 102.469534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:57.181096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.042545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:57.181162Z","caller":"traceutil/trace.go:171","msg":"trace[1582241065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"216.153878ms","start":"2026-02-25T06:12:56.964995Z","end":"2026-02-25T06:12:57.181149Z","steps":["trace[1582241065] 'range keys from in-memory index tree' (duration: 215.928791ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:57.578494Z","caller":"traceutil/trace.go:171","msg":"trace[1547971724] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"393.361009ms","start":"2026-02-25T06:12:57.185104Z","end":"2026-02-25T06:12:57.578465Z","steps":["trace[1547971724] 'process raft request' (duration: 393.173264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:57.578669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:57.18509Z","time spent":"393.480893ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:57.659822Z","caller":"traceutil/trace.go:171","msg":"trace[348497847] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:995; }","duration":"370.008492ms","start":"2026-02-25T06:12:57.289795Z","end":"2026-02-25T06:12:57.659804Z","steps":["trace[348497847] 'read index received' (duration: 288.518272ms)","trace[348497847] 'applied index is now lower than readState.Index' (duration: 81.48959ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:57.659978Z","caller":"traceutil/trace.go:171","msg":"trace[1016084385] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"394.693965ms","start":"2026-02-25T06:12:57.265233Z","end":"2026-02-25T06:12:57.659927Z","steps":["trace[1016084385] 'process raft request' (duration: 372.825217ms)","trace[1016084385] 'compare' (duration: 21.656892ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:57.660052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.418165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:57.660107Z","caller":"traceutil/trace.go:171","msg":"trace[254117499] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:960; }","duration":"232.576829ms","start":"2026-02-25T06:12:57.427519Z","end":"2026-02-25T06:12:57.660095Z","steps":["trace[254117499] 'agreement among raft nodes before linearized reading' (duration: 232.477766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:57.660132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.205892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:57.660169Z","caller":"traceutil/trace.go:171","msg":"trace[1923681550] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:960; }","duration":"148.295474ms","start":"2026-02-25T06:12:57.511864Z","end":"2026-02-25T06:12:57.66016Z","steps":["trace[1923681550] 'agreement among raft nodes before linearized reading' (duration: 148.234743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:57.6602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:57.265207Z","time spent":"394.8812ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:57.660063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.258818ms","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-25T06:12:57.660289Z","caller":"traceutil/trace.go:171","msg":"trace[374383684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"370.534486ms","start":"2026-02-25T06:12:57.289725Z","end":"2026-02-25T06:12:57.660259Z","steps":["trace[374383684] 'agreement among raft nodes before linearized reading' (duration: 370.191867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:57.660385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:57.289695Z","time spent":"370.66029ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:12:58.725748Z","caller":"traceutil/trace.go:171","msg":"trace[264292090] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"462.674472ms","start":"2026-02-25T06:12:58.26305Z","end":"2026-02-25T06:12:58.725725Z","steps":["trace[264292090] 'process raft request' (duration: 462.516708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:58.725897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:58.263027Z","time spent":"462.791854ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:59.45987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.413585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:59.460309Z","caller":"traceutil/trace.go:171","msg":"trace[66376180] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"1.196378895s","start":"2026-02-25T06:12:58.263879Z","end":"2026-02-25T06:12:59.460258Z","steps":["trace[66376180] 'process raft request' (duration: 749.498508ms)","trace[66376180] 'compare' (duration: 446.313452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:59.460569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:58.263859Z","time spent":"1.19654228s","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:00.658841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198864752s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T06:13:00.658938Z","caller":"traceutil/trace.go:171","msg":"trace[625382334] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:998; }","duration":"2.347383832s","start":"2026-02-25T06:12:58.311537Z","end":"2026-02-25T06:13:00.658921Z","steps":["trace[625382334] 'read index received' (duration: 414.008224ms)","trace[625382334] 'applied index is now lower than readState.Index' (duration: 1.933374218s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:00.659235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.347686519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T06:13:00.659288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916821423s","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-25T06:13:00.659285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69193485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:00.659338Z","caller":"traceutil/trace.go:171","msg":"trace[673381879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"1.916907085s","start":"2026-02-25T06:12:58.742415Z","end":"2026-02-25T06:13:00.659322Z","steps":["trace[673381879] 'agreement among raft nodes before linearized reading' (duration: 1.916768232s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:00.659361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:58.742399Z","time spent":"1.916956796s","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T06:13:00.659353Z","caller":"traceutil/trace.go:171","msg":"trace[17566124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"1.692048613s","start":"2026-02-25T06:12:58.967286Z","end":"2026-02-25T06:13:00.659335Z","steps":["trace[17566124] 'agreement among raft nodes before linearized reading' (duration: 1.691924429s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:00.659396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:58.967267Z","time spent":"1.692120285s","remote":"127.0.0.1:41122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:13:00.659492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075613441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T06:13:00.659296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.376385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:13:00.659521Z","caller":"traceutil/trace.go:171","msg":"trace[1557659930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"896.640093ms","start":"2026-02-25T06:12:59.762876Z","end":"2026-02-25T06:13:00.659516Z","steps":["trace[1557659930] 'agreement among raft nodes before linearized reading' (duration: 896.336374ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:00.659524Z","caller":"traceutil/trace.go:171","msg":"trace[1146955184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"1.075718693s","start":"2026-02-25T06:12:59.583794Z","end":"2026-02-25T06:13:00.659513Z","steps":["trace[1146955184] 'agreement among raft nodes before linearized reading' (duration: 1.075395145s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:00.659541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:59.762861Z","time spent":"896.674243ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:13:00.659601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:59.583772Z","time spent":"1.075819366s","remote":"127.0.0.1:41414","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-25T06:13:00.659307Z","caller":"traceutil/trace.go:171","msg":"trace[267239168] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:963; }","duration":"2.347774302s","start":"2026-02-25T06:12:58.311521Z","end":"2026-02-25T06:13:00.659296Z","steps":["trace[267239168] 'agreement among raft nodes before linearized reading' (duration: 2.347644978s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:00.659771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:58.311486Z","time spent":"2.348240114s","remote":"127.0.0.1:41338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:13:00.815556Z","caller":"traceutil/trace.go:171","msg":"trace[2030486622] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"150.473373ms","start":"2026-02-25T06:13:00.665064Z","end":"2026-02-25T06:13:00.815537Z","steps":["trace[2030486622] 'process raft request' (duration: 150.37142ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:00.93751Z","caller":"traceutil/trace.go:171","msg":"trace[1558507905] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1002; }","duration":"267.894548ms","start":"2026-02-25T06:13:00.6696Z","end":"2026-02-25T06:13:00.937495Z","steps":["trace[1558507905] 'read index received' (duration: 145.952381ms)","trace[1558507905] 'applied index is now lower than readState.Index' (duration: 121.941647ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:00.937539Z","caller":"traceutil/trace.go:171","msg":"trace[1336038218] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"271.020892ms","start":"2026-02-25T06:13:00.666495Z","end":"2026-02-25T06:13:00.937515Z","steps":["trace[1336038218] 'process raft request' (duration: 249.880724ms)","trace[1336038218] 'compare' (duration: 20.891472ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:00.93759Z","caller":"traceutil/trace.go:171","msg":"trace[559507135] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"271.000161ms","start":"2026-02-25T06:13:00.666585Z","end":"2026-02-25T06:13:00.937585Z","steps":["trace[559507135] 'process raft request' (duration: 270.844227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:00.937752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.143315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:00.937775Z","caller":"traceutil/trace.go:171","msg":"trace[1602504345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"268.190266ms","start":"2026-02-25T06:13:00.669579Z","end":"2026-02-25T06:13:00.93777Z","steps":["trace[1602504345] 'agreement among raft nodes before linearized reading' (duration: 268.144385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:00.937901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.138382ms","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-25T06:13:00.937917Z","caller":"traceutil/trace.go:171","msg":"trace[665206901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"208.191814ms","start":"2026-02-25T06:13:00.729722Z","end":"2026-02-25T06:13:00.937914Z","steps":["trace[665206901] 'agreement among raft nodes before linearized reading' (duration: 208.126222ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:01.639802Z","caller":"traceutil/trace.go:171","msg":"trace[2118529585] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"170.495061ms","start":"2026-02-25T06:13:01.46928Z","end":"2026-02-25T06:13:01.639775Z","steps":["trace[2118529585] 'process raft request' (duration: 170.344757ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:01.797904Z","caller":"traceutil/trace.go:171","msg":"trace[1046847410] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"231.971573ms","start":"2026-02-25T06:13:01.565915Z","end":"2026-02-25T06:13:01.797887Z","steps":["trace[1046847410] 'process raft request' (duration: 195.115312ms)","trace[1046847410] 'compare' (duration: 36.593424ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:02.999911Z","caller":"traceutil/trace.go:171","msg":"trace[601176793] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"175.918016ms","start":"2026-02-25T06:13:02.823427Z","end":"2026-02-25T06:13:02.999345Z","steps":["trace[601176793] 'process raft request' (duration: 175.631269ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:03.749489Z","caller":"traceutil/trace.go:171","msg":"trace[1795358652] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"744.71384ms","start":"2026-02-25T06:13:03.004737Z","end":"2026-02-25T06:13:03.749451Z","steps":["trace[1795358652] 'process raft request' (duration: 739.715876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:03.749796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:03.004709Z","time spent":"744.974956ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:13:03.849129Z","caller":"traceutil/trace.go:171","msg":"trace[1007849841] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"844.09763ms","start":"2026-02-25T06:13:03.004998Z","end":"2026-02-25T06:13:03.849096Z","steps":["trace[1007849841] 'process raft request' (duration: 822.669724ms)","trace[1007849841] 'compare' (duration: 21.169759ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:03.849334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:03.004976Z","time spent":"844.259064ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:13:03.849397Z","caller":"traceutil/trace.go:171","msg":"trace[999482773] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"844.060239ms","start":"2026-02-25T06:13:03.005321Z","end":"2026-02-25T06:13:03.849382Z","steps":["trace[999482773] 'process raft request' (duration: 843.715639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:03.849482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:03.005307Z","time spent":"844.13618ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:03.849777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.099102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T06:13:03.849824Z","caller":"traceutil/trace.go:171","msg":"trace[653934284] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:973; }","duration":"527.186604ms","start":"2026-02-25T06:13:03.322625Z","end":"2026-02-25T06:13:03.849811Z","steps":["trace[653934284] 'agreement among raft nodes before linearized reading' (duration: 527.02796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:03.849854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:03.322599Z","time spent":"527.249185ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-02-25T06:13:03.849239Z","caller":"traceutil/trace.go:171","msg":"trace[778737003] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1008; }","duration":"526.525396ms","start":"2026-02-25T06:13:03.322694Z","end":"2026-02-25T06:13:03.849219Z","steps":["trace[778737003] 'read index received' (duration: 422.04766ms)","trace[778737003] 'applied index is now lower than readState.Index' (duration: 104.476566ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:03.850533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.273236ms","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-25T06:13:03.850603Z","caller":"traceutil/trace.go:171","msg":"trace[745523337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"192.372258ms","start":"2026-02-25T06:13:03.658216Z","end":"2026-02-25T06:13:03.850588Z","steps":["trace[745523337] 'agreement among raft nodes before linearized reading' (duration: 192.205784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:04.236484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.202827ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-25T06:13:04.236732Z","caller":"traceutil/trace.go:171","msg":"trace[1914401703] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"257.627432ms","start":"2026-02-25T06:13:03.97909Z","end":"2026-02-25T06:13:04.236717Z","steps":["trace[1914401703] 'read index received' (duration: 47.150207ms)","trace[1914401703] 'applied index is now lower than readState.Index' (duration: 210.476235ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:04.236829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.741215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:04.236853Z","caller":"traceutil/trace.go:171","msg":"trace[1337487640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"257.793577ms","start":"2026-02-25T06:13:03.979053Z","end":"2026-02-25T06:13:04.236847Z","steps":["trace[1337487640] 'agreement among raft nodes before linearized reading' (duration: 257.709015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:04.236999Z","caller":"traceutil/trace.go:171","msg":"trace[846379848] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"383.100743ms","start":"2026-02-25T06:13:03.853872Z","end":"2026-02-25T06:13:04.236972Z","steps":["trace[846379848] 'process raft request' (duration: 382.707063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:04.237153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:03.853859Z","time spent":"383.219146ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:04.237665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:03.852825Z","time spent":"384.83728ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T06:13:04.817559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.024539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:13:04.817676Z","caller":"traceutil/trace.go:171","msg":"trace[1201320817] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"509.11171ms","start":"2026-02-25T06:13:04.308544Z","end":"2026-02-25T06:13:04.817656Z","steps":["trace[1201320817] 'read index received' (duration: 300.585218ms)","trace[1201320817] 'applied index is now lower than readState.Index' (duration: 208.524772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:04.817827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.284165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:04.817879Z","caller":"traceutil/trace.go:171","msg":"trace[1313861279] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:975; }","duration":"509.376277ms","start":"2026-02-25T06:13:04.308487Z","end":"2026-02-25T06:13:04.817864Z","steps":["trace[1313861279] 'agreement among raft nodes before linearized reading' (duration: 509.224223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:04.817932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:04.308467Z","time spent":"509.451099ms","remote":"127.0.0.1:38408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:13:04.81813Z","caller":"traceutil/trace.go:171","msg":"trace[381789667] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"579.336447ms","start":"2026-02-25T06:13:04.23876Z","end":"2026-02-25T06:13:04.818096Z","steps":["trace[381789667] 'process raft request' (duration: 370.392384ms)","trace[381789667] 'compare' (duration: 207.922666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:04.818303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:04.238744Z","time spent":"579.46509ms","remote":"127.0.0.1:41150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:05.321224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219119990647764107,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:13:05.815773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.704241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:13:05.815925Z","caller":"traceutil/trace.go:171","msg":"trace[1231700530] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"994.983606ms","start":"2026-02-25T06:13:04.820923Z","end":"2026-02-25T06:13:05.815907Z","steps":["trace[1231700530] 'read index received' (duration: 818.960056ms)","trace[1231700530] 'applied index is now lower than readState.Index' (duration: 176.02216ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:05.815979Z","caller":"traceutil/trace.go:171","msg":"trace[1296698108] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.019717189s","start":"2026-02-25T06:13:04.796218Z","end":"2026-02-25T06:13:05.815935Z","steps":["trace[1296698108] 'process raft request' (duration: 843.752401ms)","trace[1296698108] 'compare' (duration: 174.278663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:05.817524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:04.79619Z","time spent":"1.019884794s","remote":"127.0.0.1:41204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:13:05.817642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.193049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:13:05.817738Z","caller":"traceutil/trace.go:171","msg":"trace[196146618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:976; }","duration":"813.302832ms","start":"2026-02-25T06:13:05.004389Z","end":"2026-02-25T06:13:05.817692Z","steps":["trace[196146618] 'agreement among raft nodes before linearized reading' (duration: 812.891061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:05.817794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:05.004375Z","time spent":"813.405396ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:13:05.818114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.151363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4741"} {"level":"info","ts":"2026-02-25T06:13:05.818181Z","caller":"traceutil/trace.go:171","msg":"trace[1058815725] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:976; }","duration":"914.265675ms","start":"2026-02-25T06:13:04.903896Z","end":"2026-02-25T06:13:05.818162Z","steps":["trace[1058815725] 'agreement among raft nodes before linearized reading' (duration: 912.158919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:05.818241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:04.903883Z","time spent":"914.348108ms","remote":"127.0.0.1:41324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4764,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T06:13:05.818705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.76786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-25T06:13:05.818753Z","caller":"traceutil/trace.go:171","msg":"trace[909982977] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:976; }","duration":"997.847673ms","start":"2026-02-25T06:13:04.820891Z","end":"2026-02-25T06:13:05.818739Z","steps":["trace[909982977] 'agreement among raft nodes before linearized reading' (duration: 995.057198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:05.818796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:04.82088Z","time spent":"997.899734ms","remote":"127.0.0.1:41312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T06:13:06.090737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.572896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:13:06.091419Z","caller":"traceutil/trace.go:171","msg":"trace[2067934001] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"258.829045ms","start":"2026-02-25T06:13:05.832524Z","end":"2026-02-25T06:13:06.091353Z","steps":["trace[2067934001] 'process raft request' (duration: 120.502098ms)","trace[2067934001] 'compare' (duration: 137.268368ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:06.091876Z","caller":"traceutil/trace.go:171","msg":"trace[215767243] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1015; }","duration":"230.926995ms","start":"2026-02-25T06:13:05.86092Z","end":"2026-02-25T06:13:06.091847Z","steps":["trace[215767243] 'read index received' (duration: 91.979612ms)","trace[215767243] 'applied index is now lower than readState.Index' (duration: 138.946003ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:06.091972Z","caller":"traceutil/trace.go:171","msg":"trace[2088446870] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"254.307093ms","start":"2026-02-25T06:13:05.837648Z","end":"2026-02-25T06:13:06.091955Z","steps":["trace[2088446870] 'process raft request' (duration: 253.799759ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:06.092239Z","caller":"traceutil/trace.go:171","msg":"trace[1666157603] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"254.238331ms","start":"2026-02-25T06:13:05.837953Z","end":"2026-02-25T06:13:06.092192Z","steps":["trace[1666157603] 'process raft request' (duration: 253.83453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:06.110796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.819502ms","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-25T06:13:06.111245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.700644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:06.111253Z","caller":"traceutil/trace.go:171","msg":"trace[2131077447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"250.334036ms","start":"2026-02-25T06:13:05.860891Z","end":"2026-02-25T06:13:06.111225Z","steps":["trace[2131077447] 'agreement among raft nodes before linearized reading' (duration: 231.445529ms)","trace[2131077447] 'range keys from bolt db' (duration: 18.352942ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:06.111301Z","caller":"traceutil/trace.go:171","msg":"trace[115315162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"145.778726ms","start":"2026-02-25T06:13:05.965499Z","end":"2026-02-25T06:13:06.111278Z","steps":["trace[115315162] 'agreement among raft nodes before linearized reading' (duration: 145.712524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:06.111324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.938356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:13:06.111156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.176861ms","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-25T06:13:06.111387Z","caller":"traceutil/trace.go:171","msg":"trace[766996977] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:979; }","duration":"157.006758ms","start":"2026-02-25T06:13:05.954362Z","end":"2026-02-25T06:13:06.111368Z","steps":["trace[766996977] 'agreement among raft nodes before linearized reading' (duration: 156.898315ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:06.111411Z","caller":"traceutil/trace.go:171","msg":"trace[929408474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"250.45776ms","start":"2026-02-25T06:13:05.860941Z","end":"2026-02-25T06:13:06.111399Z","steps":["trace[929408474] 'agreement among raft nodes before linearized reading' (duration: 250.044038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:06.767621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.422625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:13:06.767729Z","caller":"traceutil/trace.go:171","msg":"trace[2088543393] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"651.197456ms","start":"2026-02-25T06:13:06.116516Z","end":"2026-02-25T06:13:06.767713Z","steps":["trace[2088543393] 'process raft request' (duration: 457.631047ms)","trace[2088543393] 'compare' (duration: 193.273001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:06.767765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:06.116505Z","time spent":"651.246807ms","remote":"127.0.0.1:41204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":799,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:13:06.845758Z","caller":"traceutil/trace.go:171","msg":"trace[419930792] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1018; }","duration":"605.557738ms","start":"2026-02-25T06:13:06.240171Z","end":"2026-02-25T06:13:06.845729Z","steps":["trace[419930792] 'read index received' (duration: 297.059621ms)","trace[419930792] 'applied index is now lower than readState.Index' (duration: 308.497177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:06.846164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.96746ms","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-25T06:13:06.846144Z","caller":"traceutil/trace.go:171","msg":"trace[581596782] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"728.552233ms","start":"2026-02-25T06:13:06.117564Z","end":"2026-02-25T06:13:06.846116Z","steps":["trace[581596782] 'process raft request' (duration: 727.967068ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:06.846211Z","caller":"traceutil/trace.go:171","msg":"trace[1884723597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"606.077103ms","start":"2026-02-25T06:13:06.240115Z","end":"2026-02-25T06:13:06.846192Z","steps":["trace[1884723597] 'agreement among raft nodes before linearized reading' (duration: 605.721153ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:06.846162Z","caller":"traceutil/trace.go:171","msg":"trace[331983488] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"727.915786ms","start":"2026-02-25T06:13:06.118197Z","end":"2026-02-25T06:13:06.846113Z","steps":["trace[331983488] 'process raft request' (duration: 727.464444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:06.846242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:06.240088Z","time spent":"606.146735ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T06:13:06.846203Z","caller":"traceutil/trace.go:171","msg":"trace[1536048198] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"548.512715ms","start":"2026-02-25T06:13:06.297639Z","end":"2026-02-25T06:13:06.846151Z","steps":["trace[1536048198] 'process raft request' (duration: 548.054563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:06.846273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:06.11754Z","time spent":"728.667067ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:06.846389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:06.297603Z","time spent":"548.69796ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:06.846432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:06.118181Z","time spent":"728.110242ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:07.471551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.30177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:13:07.471718Z","caller":"traceutil/trace.go:171","msg":"trace[1200335914] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"621.075985ms","start":"2026-02-25T06:13:06.850611Z","end":"2026-02-25T06:13:07.471687Z","steps":["trace[1200335914] 'process raft request' (duration: 423.506318ms)","trace[1200335914] 'compare' (duration: 197.194317ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:07.47189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:06.850592Z","time spent":"621.169707ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:13:15.190217Z","caller":"traceutil/trace.go:171","msg":"trace[663092306] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"274.473085ms","start":"2026-02-25T06:13:14.915717Z","end":"2026-02-25T06:13:15.19019Z","steps":["trace[663092306] 'read index received' (duration: 274.068094ms)","trace[663092306] 'applied index is now lower than readState.Index' (duration: 404.141µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:15.190297Z","caller":"traceutil/trace.go:171","msg":"trace[535928716] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"304.979155ms","start":"2026-02-25T06:13:14.885279Z","end":"2026-02-25T06:13:15.190258Z","steps":["trace[535928716] 'process raft request' (duration: 304.739409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:15.190407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.861922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:15.190446Z","caller":"traceutil/trace.go:171","msg":"trace[1681345173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"224.978785ms","start":"2026-02-25T06:13:14.965457Z","end":"2026-02-25T06:13:15.190436Z","steps":["trace[1681345173] 'agreement among raft nodes before linearized reading' (duration: 224.892772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:15.190484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:14.885258Z","time spent":"305.124529ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:15.190602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.840145ms","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-25T06:13:15.190763Z","caller":"traceutil/trace.go:171","msg":"trace[1174248239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"275.054691ms","start":"2026-02-25T06:13:14.915686Z","end":"2026-02-25T06:13:15.19074Z","steps":["trace[1174248239] 'agreement among raft nodes before linearized reading' (duration: 274.64396ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:17.211131Z","caller":"traceutil/trace.go:171","msg":"trace[171246898] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"495.74554ms","start":"2026-02-25T06:13:16.715346Z","end":"2026-02-25T06:13:17.211091Z","steps":["trace[171246898] 'process raft request' (duration: 434.918425ms)","trace[171246898] 'compare' (duration: 60.64925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:17.21127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:16.715327Z","time spent":"495.878554ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:13:17.222225Z","caller":"traceutil/trace.go:171","msg":"trace[202075050] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1076; }","duration":"257.376505ms","start":"2026-02-25T06:13:16.964831Z","end":"2026-02-25T06:13:17.222207Z","steps":["trace[202075050] 'read index received' (duration: 185.620847ms)","trace[202075050] 'applied index is now lower than readState.Index' (duration: 71.754968ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:13:17.222396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.498778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:17.222281Z","caller":"traceutil/trace.go:171","msg":"trace[1459864504] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"265.682879ms","start":"2026-02-25T06:13:16.956572Z","end":"2026-02-25T06:13:17.222255Z","steps":["trace[1459864504] 'process raft request' (duration: 265.539225ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:17.222427Z","caller":"traceutil/trace.go:171","msg":"trace[1964453436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"257.646603ms","start":"2026-02-25T06:13:16.96477Z","end":"2026-02-25T06:13:17.222417Z","steps":["trace[1964453436] 'agreement among raft nodes before linearized reading' (duration: 257.53004ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:20.320157Z","caller":"traceutil/trace.go:171","msg":"trace[971125282] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"116.302335ms","start":"2026-02-25T06:13:20.203827Z","end":"2026-02-25T06:13:20.320129Z","steps":["trace[971125282] 'process raft request' (duration: 109.888853ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:21.608147Z","caller":"traceutil/trace.go:171","msg":"trace[1775929856] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"145.676405ms","start":"2026-02-25T06:13:21.462444Z","end":"2026-02-25T06:13:21.60812Z","steps":["trace[1775929856] 'read index received' (duration: 145.405898ms)","trace[1775929856] 'applied index is now lower than readState.Index' (duration: 269.607µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:21.608194Z","caller":"traceutil/trace.go:171","msg":"trace[996866492] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"337.283045ms","start":"2026-02-25T06:13:21.270885Z","end":"2026-02-25T06:13:21.608168Z","steps":["trace[996866492] 'process raft request' (duration: 337.073779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:21.608911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.450796ms","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-25T06:13:21.609038Z","caller":"traceutil/trace.go:171","msg":"trace[345116016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"146.59906ms","start":"2026-02-25T06:13:21.462389Z","end":"2026-02-25T06:13:21.608988Z","steps":["trace[345116016] 'agreement among raft nodes before linearized reading' (duration: 145.86993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:21.609058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:21.270865Z","time spent":"338.046855ms","remote":"127.0.0.1:41414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:13:21.612305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.597787ms","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-25T06:13:21.612378Z","caller":"traceutil/trace.go:171","msg":"trace[2031675013] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1059; }","duration":"147.74237ms","start":"2026-02-25T06:13:21.464618Z","end":"2026-02-25T06:13:21.61236Z","steps":["trace[2031675013] 'agreement among raft nodes before linearized reading' (duration: 147.593946ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:13:28.770513Z","caller":"traceutil/trace.go:171","msg":"trace[1997843668] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"165.264611ms","start":"2026-02-25T06:13:28.605218Z","end":"2026-02-25T06:13:28.770483Z","steps":["trace[1997843668] 'process raft request' (duration: 164.928252ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:14:08.023831Z","caller":"traceutil/trace.go:171","msg":"trace[711793238] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"109.576093ms","start":"2026-02-25T06:14:07.914215Z","end":"2026-02-25T06:14:08.023791Z","steps":["trace[711793238] 'process raft request' (duration: 60.073154ms)","trace[711793238] 'compare' (duration: 49.363055ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:16:34.717643Z","caller":"traceutil/trace.go:171","msg":"trace[314603530] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"180.096059ms","start":"2026-02-25T06:16:34.537525Z","end":"2026-02-25T06:16:34.717621Z","steps":["trace[314603530] 'process raft request' (duration: 179.952986ms)"],"step_count":1}