{"level":"warn","ts":"2026-02-07T02:28:39.886976Z","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-07T02:28:39.887221Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-07T02:28:39.887386Z","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-07T02:28:39.887448Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-07T02:28:39.887518Z","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-07T02:28:39.889227Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-02-07T02:28:39.890255Z","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":2,"max-cpu-available":2,"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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-07T02:28:39.902099Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.583649ms"} {"level":"info","ts":"2026-02-07T02:28:39.918892Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-02-07T02:28:39.918948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T02:28:39.918963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-02-07T02:28:39.918971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T02:28:39.918993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-02-07T02:28:39.919023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-02-07T02:28:39.944314Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T02:28:39.951491Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T02:28:39.954271Z","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-07T02:28:39.961035Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T02:28:39.961859Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T02:28:39.96196Z","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-07T02:28:39.961981Z","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-07T02:28:39.961987Z","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-07T02:28:39.962765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-02-07T02:28:39.962822Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-07T02:28:39.974022Z","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-07T02:28:39.974158Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T02:28:39.974183Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T02:28:39.974228Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-07T02:28:39.974239Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-07T02:28:40.022016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T02:28:40.022036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T02:28:40.02205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-02-07T02:28:40.022059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-02-07T02:28:40.022065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-07T02:28:40.022078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-02-07T02:28:40.022084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-07T02:28:40.069811Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:28:40.078756Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:28:40.078819Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:28:40.078841Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T02:28:40.078864Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T02:28:40.079297Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T02:28:40.079608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T02:28:40.081104Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-02-07T02:28:40.084263Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T02:28:40.093927Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T02:28:40.093944Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T02:28:46.545308Z","caller":"traceutil/trace.go:171","msg":"trace[756874856] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"268.270205ms","start":"2026-02-07T02:28:46.277025Z","end":"2026-02-07T02:28:46.545295Z","steps":["trace[756874856] 'process raft request' (duration: 267.766513ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:46.547618Z","caller":"traceutil/trace.go:171","msg":"trace[227196948] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:321; }","duration":"253.864919ms","start":"2026-02-07T02:28:46.293706Z","end":"2026-02-07T02:28:46.547571Z","steps":["trace[227196948] 'read index received' (duration: 251.106122ms)","trace[227196948] 'applied index is now lower than readState.Index' (duration: 2.757947ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:46.547729Z","caller":"traceutil/trace.go:171","msg":"trace[1031396796] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"268.446919ms","start":"2026-02-07T02:28:46.27927Z","end":"2026-02-07T02:28:46.547717Z","steps":["trace[1031396796] 'process raft request' (duration: 268.195643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:46.547877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.528481ms","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-07T02:28:46.547898Z","caller":"traceutil/trace.go:171","msg":"trace[1145664542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:313; }","duration":"229.592753ms","start":"2026-02-07T02:28:46.318299Z","end":"2026-02-07T02:28:46.547891Z","steps":["trace[1145664542] 'agreement among raft nodes before linearized reading' (duration: 229.538382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:46.547846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.078734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:46.547941Z","caller":"traceutil/trace.go:171","msg":"trace[1551072904] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:0; response_revision:313; }","duration":"254.245477ms","start":"2026-02-07T02:28:46.293682Z","end":"2026-02-07T02:28:46.547927Z","steps":["trace[1551072904] 'agreement among raft nodes before linearized reading' (duration: 254.069133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:46.547962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.426776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:46.547977Z","caller":"traceutil/trace.go:171","msg":"trace[1183480896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:313; }","duration":"177.468206ms","start":"2026-02-07T02:28:46.370504Z","end":"2026-02-07T02:28:46.547972Z","steps":["trace[1183480896] 'agreement among raft nodes before linearized reading' (duration: 177.445386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:46.548084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.082954ms","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-07T02:28:46.548099Z","caller":"traceutil/trace.go:171","msg":"trace[701492822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:313; }","duration":"225.120565ms","start":"2026-02-07T02:28:46.322974Z","end":"2026-02-07T02:28:46.548095Z","steps":["trace[701492822] 'agreement among raft nodes before linearized reading' (duration: 225.058264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:49.056062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420375617499646,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:28:49.345233Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.243866297s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-07T02:28:49.4966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.675611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:49.496697Z","caller":"traceutil/trace.go:171","msg":"trace[1355481769] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:346; }","duration":"941.645424ms","start":"2026-02-07T02:28:48.555036Z","end":"2026-02-07T02:28:49.496681Z","steps":["trace[1355481769] 'read index received' (duration: 790.329547ms)","trace[1355481769] 'applied index is now lower than readState.Index' (duration: 151.314667ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:49.496769Z","caller":"traceutil/trace.go:171","msg":"trace[2060709970] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"1.603042624s","start":"2026-02-07T02:28:47.893716Z","end":"2026-02-07T02:28:49.496759Z","steps":["trace[2060709970] 'process raft request' (duration: 1.451698856s)","trace[2060709970] 'compare' (duration: 150.560339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:49.49682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:47.893695Z","time spent":"1.603098714s","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:28:49.497141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.096265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T02:28:49.497174Z","caller":"traceutil/trace.go:171","msg":"trace[1017189442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:336; }","duration":"942.167696ms","start":"2026-02-07T02:28:48.554997Z","end":"2026-02-07T02:28:49.497165Z","steps":["trace[1017189442] 'agreement among raft nodes before linearized reading' (duration: 942.033703ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:49.497205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:48.554979Z","time spent":"942.219668ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T02:28:49.497269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.914506ms","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-07T02:28:49.497311Z","caller":"traceutil/trace.go:171","msg":"trace[1355916186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:336; }","duration":"918.981798ms","start":"2026-02-07T02:28:48.578317Z","end":"2026-02-07T02:28:49.497299Z","steps":["trace[1355916186] 'agreement among raft nodes before linearized reading' (duration: 918.886045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:49.497341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:48.578305Z","time spent":"919.029708ms","remote":"127.0.0.1:50056","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-07T02:28:49.497492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.122611ms","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-07T02:28:49.497522Z","caller":"traceutil/trace.go:171","msg":"trace[517682692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:336; }","duration":"919.159892ms","start":"2026-02-07T02:28:48.578353Z","end":"2026-02-07T02:28:49.497513Z","steps":["trace[517682692] 'agreement among raft nodes before linearized reading' (duration: 919.10597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:49.497551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:48.57835Z","time spent":"919.191722ms","remote":"127.0.0.1:50056","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-07T02:28:49.497609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.614302ms","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-07T02:28:49.497639Z","caller":"traceutil/trace.go:171","msg":"trace[848201771] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:336; }","duration":"909.656323ms","start":"2026-02-07T02:28:48.587974Z","end":"2026-02-07T02:28:49.49763Z","steps":["trace[848201771] 'agreement among raft nodes before linearized reading' (duration: 909.605771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:49.497665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:48.587967Z","time spent":"909.691243ms","remote":"127.0.0.1:50056","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-07T02:28:49.798088Z","caller":"traceutil/trace.go:171","msg":"trace[77901750] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"292.112021ms","start":"2026-02-07T02:28:49.505953Z","end":"2026-02-07T02:28:49.798065Z","steps":["trace[77901750] 'process raft request' (duration: 270.448718ms)","trace[77901750] 'compare' (duration: 21.513539ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:49.798918Z","caller":"traceutil/trace.go:171","msg":"trace[734956851] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"292.826537ms","start":"2026-02-07T02:28:49.506066Z","end":"2026-02-07T02:28:49.798892Z","steps":["trace[734956851] 'process raft request' (duration: 291.967627ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:49.799183Z","caller":"traceutil/trace.go:171","msg":"trace[259012150] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:348; }","duration":"286.396393ms","start":"2026-02-07T02:28:49.512778Z","end":"2026-02-07T02:28:49.799174Z","steps":["trace[259012150] 'read index received' (duration: 263.574303ms)","trace[259012150] 'applied index is now lower than readState.Index' (duration: 22.82152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:49.799374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.554777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/expand-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:49.799399Z","caller":"traceutil/trace.go:171","msg":"trace[1191554546] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/expand-controller; range_end:; response_count:0; response_revision:340; }","duration":"286.643469ms","start":"2026-02-07T02:28:49.51275Z","end":"2026-02-07T02:28:49.799394Z","steps":["trace[1191554546] 'agreement among raft nodes before linearized reading' (duration: 286.498605ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:49.799631Z","caller":"traceutil/trace.go:171","msg":"trace[1631769380] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"292.294055ms","start":"2026-02-07T02:28:49.507331Z","end":"2026-02-07T02:28:49.799625Z","steps":["trace[1631769380] 'process raft request' (duration: 291.099176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.014538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.722501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:50.014839Z","caller":"traceutil/trace.go:171","msg":"trace[916686649] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:0; response_revision:341; }","duration":"114.1142ms","start":"2026-02-07T02:28:49.900705Z","end":"2026-02-07T02:28:50.014819Z","steps":["trace[916686649] 'range keys from in-memory index tree' (duration: 113.658809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.417469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.701799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-07T02:28:50.417532Z","caller":"traceutil/trace.go:171","msg":"trace[589782457] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"169.795762ms","start":"2026-02-07T02:28:50.247719Z","end":"2026-02-07T02:28:50.417515Z","steps":["trace[589782457] 'range keys from in-memory index tree' (duration: 169.592317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.417803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.076671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-07T02:28:50.417829Z","caller":"traceutil/trace.go:171","msg":"trace[1469710602] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:343; }","duration":"168.118021ms","start":"2026-02-07T02:28:50.249704Z","end":"2026-02-07T02:28:50.417822Z","steps":["trace[1469710602] 'range keys from in-memory index tree' (duration: 168.021399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.41802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.808102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-07T02:28:50.418048Z","caller":"traceutil/trace.go:171","msg":"trace[1051287206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:343; }","duration":"169.875424ms","start":"2026-02-07T02:28:50.248164Z","end":"2026-02-07T02:28:50.41804Z","steps":["trace[1051287206] 'range keys from in-memory index tree' (duration: 169.206548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.418299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.693877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-07T02:28:50.418355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.778543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-07T02:28:50.418427Z","caller":"traceutil/trace.go:171","msg":"trace[251087556] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:343; }","duration":"128.940877ms","start":"2026-02-07T02:28:50.289479Z","end":"2026-02-07T02:28:50.41842Z","steps":["trace[251087556] 'range keys from in-memory index tree' (duration: 128.709172ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:50.418603Z","caller":"traceutil/trace.go:171","msg":"trace[307746439] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:343; }","duration":"154.844082ms","start":"2026-02-07T02:28:50.263569Z","end":"2026-02-07T02:28:50.418413Z","steps":["trace[307746439] 'range keys from in-memory index tree' (duration: 153.773886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.418624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.744222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-07T02:28:50.419075Z","caller":"traceutil/trace.go:171","msg":"trace[602421504] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:343; }","duration":"119.164591ms","start":"2026-02-07T02:28:50.299855Z","end":"2026-02-07T02:28:50.419019Z","steps":["trace[602421504] 'range keys from in-memory index tree' (duration: 118.69753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.419177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.358651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-07T02:28:50.419244Z","caller":"traceutil/trace.go:171","msg":"trace[1073525950] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:343; }","duration":"169.504345ms","start":"2026-02-07T02:28:50.249716Z","end":"2026-02-07T02:28:50.419221Z","steps":["trace[1073525950] 'range keys from in-memory index tree' (duration: 167.986388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.419054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.85159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-07T02:28:50.422963Z","caller":"traceutil/trace.go:171","msg":"trace[921020268] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:343; }","duration":"121.772935ms","start":"2026-02-07T02:28:50.301181Z","end":"2026-02-07T02:28:50.422953Z","steps":["trace[921020268] 'range keys from in-memory index tree' (duration: 117.765598ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:50.835002Z","caller":"traceutil/trace.go:171","msg":"trace[23019413] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"391.383471ms","start":"2026-02-07T02:28:50.443597Z","end":"2026-02-07T02:28:50.83498Z","steps":["trace[23019413] 'process raft request' (duration: 390.92515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.835211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.443588Z","time spent":"391.476724ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:50.836125Z","caller":"traceutil/trace.go:171","msg":"trace[157317723] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"392.447227ms","start":"2026-02-07T02:28:50.443665Z","end":"2026-02-07T02:28:50.836112Z","steps":["trace[157317723] 'process raft request' (duration: 390.985432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.836191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.443659Z","time spent":"392.505139ms","remote":"127.0.0.1:50296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3620,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:50.836453Z","caller":"traceutil/trace.go:171","msg":"trace[1222034354] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"389.67607ms","start":"2026-02-07T02:28:50.446749Z","end":"2026-02-07T02:28:50.836425Z","steps":["trace[1222034354] 'process raft request' (duration: 388.103602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.836551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.446742Z","time spent":"389.745292ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:50.836836Z","caller":"traceutil/trace.go:171","msg":"trace[1750928627] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"389.993388ms","start":"2026-02-07T02:28:50.446832Z","end":"2026-02-07T02:28:50.836825Z","steps":["trace[1750928627] 'process raft request' (duration: 388.962093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.837223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.446828Z","time spent":"390.043359ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:50.837821Z","caller":"traceutil/trace.go:171","msg":"trace[1372216877] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"388.773248ms","start":"2026-02-07T02:28:50.449036Z","end":"2026-02-07T02:28:50.837809Z","steps":["trace[1372216877] 'process raft request' (duration: 386.807511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.837883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.449029Z","time spent":"388.830119ms","remote":"127.0.0.1:50066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:50.838117Z","caller":"traceutil/trace.go:171","msg":"trace[1259954876] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"389.023654ms","start":"2026-02-07T02:28:50.449084Z","end":"2026-02-07T02:28:50.838107Z","steps":["trace[1259954876] 'process raft request' (duration: 386.801201ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:50.838457Z","caller":"traceutil/trace.go:171","msg":"trace[1694455763] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"386.698439ms","start":"2026-02-07T02:28:50.451748Z","end":"2026-02-07T02:28:50.838447Z","steps":["trace[1694455763] 'process raft request' (duration: 386.659978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.838561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.44908Z","time spent":"389.071095ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:50.83904Z","caller":"traceutil/trace.go:171","msg":"trace[290981524] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"389.027364ms","start":"2026-02-07T02:28:50.449988Z","end":"2026-02-07T02:28:50.839015Z","steps":["trace[290981524] 'process raft request' (duration: 385.976091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.840163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.451743Z","time spent":"386.75072ms","remote":"127.0.0.1:50048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:50.840464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.449985Z","time spent":"389.092716ms","remote":"127.0.0.1:50312","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:50.840734Z","caller":"traceutil/trace.go:171","msg":"trace[403099831] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"389.67397ms","start":"2026-02-07T02:28:50.451048Z","end":"2026-02-07T02:28:50.840721Z","steps":["trace[403099831] 'process raft request' (duration: 387.274913ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:50.840933Z","caller":"traceutil/trace.go:171","msg":"trace[1218023850] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"391.789631ms","start":"2026-02-07T02:28:50.449134Z","end":"2026-02-07T02:28:50.840923Z","steps":["trace[1218023850] 'process raft request' (duration: 386.778331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.841027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.451041Z","time spent":"389.727141ms","remote":"127.0.0.1:50132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:50.841104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.449111Z","time spent":"391.853842ms","remote":"127.0.0.1:50132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:50.841364Z","caller":"traceutil/trace.go:171","msg":"trace[440276988] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"391.403132ms","start":"2026-02-07T02:28:50.449951Z","end":"2026-02-07T02:28:50.841354Z","steps":["trace[440276988] 'process raft request' (duration: 385.990101ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:50.841557Z","caller":"traceutil/trace.go:171","msg":"trace[414089659] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"390.383897ms","start":"2026-02-07T02:28:50.451095Z","end":"2026-02-07T02:28:50.841479Z","steps":["trace[414089659] 'process raft request' (duration: 387.280662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:50.841638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.449945Z","time spent":"391.451403ms","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:28:50.841731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.451088Z","time spent":"390.50202ms","remote":"127.0.0.1:50132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:51.521114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.818033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:51.521488Z","caller":"traceutil/trace.go:171","msg":"trace[1742694641] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:368; }","duration":"663.263775ms","start":"2026-02-07T02:28:50.858207Z","end":"2026-02-07T02:28:51.521471Z","steps":["trace[1742694641] 'read index received' (duration: 31.027307ms)","trace[1742694641] 'applied index is now lower than readState.Index' (duration: 632.235608ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:51.521731Z","caller":"traceutil/trace.go:171","msg":"trace[861216993] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"664.012803ms","start":"2026-02-07T02:28:50.857705Z","end":"2026-02-07T02:28:51.521718Z","steps":["trace[861216993] 'process raft request' (duration: 393.509883ms)","trace[861216993] 'compare' (duration: 268.85076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:51.521792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.857681Z","time spent":"664.077895ms","remote":"127.0.0.1:50252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:51.522372Z","caller":"traceutil/trace.go:171","msg":"trace[79880989] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"661.293207ms","start":"2026-02-07T02:28:50.861064Z","end":"2026-02-07T02:28:51.522357Z","steps":["trace[79880989] 'process raft request' (duration: 660.340284ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.522449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.861057Z","time spent":"661.355599ms","remote":"127.0.0.1:50132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:51.522673Z","caller":"traceutil/trace.go:171","msg":"trace[602891094] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"664.6966ms","start":"2026-02-07T02:28:50.857966Z","end":"2026-02-07T02:28:51.522663Z","steps":["trace[602891094] 'process raft request' (duration: 663.291146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.522721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.857957Z","time spent":"664.74003ms","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:51.522836Z","caller":"traceutil/trace.go:171","msg":"trace[1396321194] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"664.672169ms","start":"2026-02-07T02:28:50.858156Z","end":"2026-02-07T02:28:51.522828Z","steps":["trace[1396321194] 'process raft request' (duration: 663.131121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.522885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.85815Z","time spent":"664.70745ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:51.523048Z","caller":"traceutil/trace.go:171","msg":"trace[1256483240] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"663.974242ms","start":"2026-02-07T02:28:50.859065Z","end":"2026-02-07T02:28:51.523039Z","steps":["trace[1256483240] 'process raft request' (duration: 662.25289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.523087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.859056Z","time spent":"664.012633ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3355,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:51.524564Z","caller":"traceutil/trace.go:171","msg":"trace[263516552] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"661.82141ms","start":"2026-02-07T02:28:50.862734Z","end":"2026-02-07T02:28:51.524555Z","steps":["trace[263516552] 'process raft request' (duration: 658.705315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.524611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.862728Z","time spent":"661.859871ms","remote":"127.0.0.1:50132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:51.524707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.497013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-07T02:28:51.524726Z","caller":"traceutil/trace.go:171","msg":"trace[228499884] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:364; }","duration":"666.531304ms","start":"2026-02-07T02:28:50.858188Z","end":"2026-02-07T02:28:51.524719Z","steps":["trace[228499884] 'agreement among raft nodes before linearized reading' (duration: 666.494763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.524743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.858184Z","time spent":"666.555515ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-07T02:28:51.524819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.047745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-02-07T02:28:51.524835Z","caller":"traceutil/trace.go:171","msg":"trace[1999607299] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:364; }","duration":"662.072527ms","start":"2026-02-07T02:28:50.862757Z","end":"2026-02-07T02:28:51.524829Z","steps":["trace[1999607299] 'agreement among raft nodes before linearized reading' (duration: 662.045556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.52485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.862754Z","time spent":"662.092067ms","remote":"127.0.0.1:50132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":2131,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"info","ts":"2026-02-07T02:28:51.52507Z","caller":"traceutil/trace.go:171","msg":"trace[1740682374] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"667.263561ms","start":"2026-02-07T02:28:50.857789Z","end":"2026-02-07T02:28:51.525053Z","steps":["trace[1740682374] 'process raft request' (duration: 663.416478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.525124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:50.857784Z","time spent":"667.307262ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:28:51.955119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.758402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:51.955187Z","caller":"traceutil/trace.go:171","msg":"trace[1900792047] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"417.024501ms","start":"2026-02-07T02:28:51.53815Z","end":"2026-02-07T02:28:51.955174Z","steps":["trace[1900792047] 'read index received' (duration: 293.165806ms)","trace[1900792047] 'applied index is now lower than readState.Index' (duration: 123.857885ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:51.95533Z","caller":"traceutil/trace.go:171","msg":"trace[1282223096] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"424.193173ms","start":"2026-02-07T02:28:51.531129Z","end":"2026-02-07T02:28:51.955322Z","steps":["trace[1282223096] 'process raft request' (duration: 300.176245ms)","trace[1282223096] 'compare' (duration: 123.600968ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:51.955368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.531114Z","time spent":"424.233834ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3401,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:51.957063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.887776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-07T02:28:51.957117Z","caller":"traceutil/trace.go:171","msg":"trace[1369189300] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:365; }","duration":"418.987917ms","start":"2026-02-07T02:28:51.538116Z","end":"2026-02-07T02:28:51.957104Z","steps":["trace[1369189300] 'agreement among raft nodes before linearized reading' (duration: 417.261976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:51.957146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.538099Z","time spent":"419.041379ms","remote":"127.0.0.1:50252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3707,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-07T02:28:52.456147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420375617499714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:28:52.62705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.675561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:52.627257Z","caller":"traceutil/trace.go:171","msg":"trace[414768596] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.088141425s","start":"2026-02-07T02:28:51.539085Z","end":"2026-02-07T02:28:52.627227Z","steps":["trace[414768596] 'process raft request' (duration: 954.211047ms)","trace[414768596] 'compare' (duration: 133.134998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:52.627379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.539074Z","time spent":"1.088246517s","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:52.627467Z","caller":"traceutil/trace.go:171","msg":"trace[860726933] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"1.07966355s","start":"2026-02-07T02:28:51.547784Z","end":"2026-02-07T02:28:52.627447Z","steps":["trace[860726933] 'process raft request' (duration: 1.079617199s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:52.627563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.547766Z","time spent":"1.079756803s","remote":"127.0.0.1:50132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:52.62774Z","caller":"traceutil/trace.go:171","msg":"trace[1547634045] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"1.081505805s","start":"2026-02-07T02:28:51.546224Z","end":"2026-02-07T02:28:52.62773Z","steps":["trace[1547634045] 'process raft request' (duration: 1.081055704s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:52.627792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.546218Z","time spent":"1.081544675s","remote":"127.0.0.1:50056","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-07T02:28:52.627852Z","caller":"traceutil/trace.go:171","msg":"trace[1784058681] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.081779181s","start":"2026-02-07T02:28:51.546061Z","end":"2026-02-07T02:28:52.62784Z","steps":["trace[1784058681] 'process raft request' (duration: 1.081080254s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:52.627908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.546049Z","time spent":"1.081833142s","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:52.627918Z","caller":"traceutil/trace.go:171","msg":"trace[481409232] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.081657108s","start":"2026-02-07T02:28:51.546252Z","end":"2026-02-07T02:28:52.627909Z","steps":["trace[481409232] 'process raft request' (duration: 1.081063814s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:52.628019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.54625Z","time spent":"1.08174523s","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:52.628164Z","caller":"traceutil/trace.go:171","msg":"trace[1815801606] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"1.082044727s","start":"2026-02-07T02:28:51.546108Z","end":"2026-02-07T02:28:52.628153Z","steps":["trace[1815801606] 'process raft request' (duration: 1.081112194s)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:52.628199Z","caller":"traceutil/trace.go:171","msg":"trace[592220520] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.081909744s","start":"2026-02-07T02:28:51.54628Z","end":"2026-02-07T02:28:52.628189Z","steps":["trace[592220520] 'process raft request' (duration: 1.081088254s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:52.62823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.546103Z","time spent":"1.082087089s","remote":"127.0.0.1:50282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2864,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:52.628244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.546277Z","time spent":"1.081946025s","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:28:53.263154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.572363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:53.263329Z","caller":"traceutil/trace.go:171","msg":"trace[1550835095] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"1.303565056s","start":"2026-02-07T02:28:51.959729Z","end":"2026-02-07T02:28:53.263294Z","steps":["trace[1550835095] 'process raft request' (duration: 768.789178ms)","trace[1550835095] 'compare' (duration: 534.411769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:53.263459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.959713Z","time spent":"1.303682859s","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:53.263567Z","caller":"traceutil/trace.go:171","msg":"trace[1740167649] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"612.496551ms","start":"2026-02-07T02:28:52.651052Z","end":"2026-02-07T02:28:53.263549Z","steps":["trace[1740167649] 'process raft request' (duration: 612.46166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.263673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:52.651045Z","time spent":"612.561542ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:53.26392Z","caller":"traceutil/trace.go:171","msg":"trace[1751237032] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"622.389219ms","start":"2026-02-07T02:28:52.641521Z","end":"2026-02-07T02:28:53.26391Z","steps":["trace[1751237032] 'process raft request' (duration: 621.856437ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:53.263979Z","caller":"traceutil/trace.go:171","msg":"trace[1119809021] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"1.298716169s","start":"2026-02-07T02:28:51.965247Z","end":"2026-02-07T02:28:53.263963Z","steps":["trace[1119809021] 'process raft request' (duration: 1.298020562s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.264011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:52.641503Z","time spent":"622.43998ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:28:53.26406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.965236Z","time spent":"1.2987847s","remote":"127.0.0.1:50252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3980,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:53.264127Z","caller":"traceutil/trace.go:171","msg":"trace[2088256846] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"622.539222ms","start":"2026-02-07T02:28:52.641579Z","end":"2026-02-07T02:28:53.264119Z","steps":["trace[2088256846] 'process raft request' (duration: 621.853006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.264168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:52.641573Z","time spent":"622.576564ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:53.264324Z","caller":"traceutil/trace.go:171","msg":"trace[1846758778] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"775.1122ms","start":"2026-02-07T02:28:52.4892Z","end":"2026-02-07T02:28:53.264312Z","steps":["trace[1846758778] 'process raft request' (duration: 774.134567ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:53.264372Z","caller":"traceutil/trace.go:171","msg":"trace[2065700691] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"618.013784ms","start":"2026-02-07T02:28:52.646347Z","end":"2026-02-07T02:28:53.26436Z","steps":["trace[2065700691] 'process raft request' (duration: 617.109172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.264385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:52.489172Z","time spent":"775.182112ms","remote":"127.0.0.1:50056","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-07T02:28:53.264417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:52.646335Z","time spent":"618.061235ms","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:53.264511Z","caller":"traceutil/trace.go:171","msg":"trace[2014122732] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:376; }","duration":"1.309298714s","start":"2026-02-07T02:28:51.955203Z","end":"2026-02-07T02:28:53.264501Z","steps":["trace[2014122732] 'read index received' (duration: 538.150719ms)","trace[2014122732] 'applied index is now lower than readState.Index' (duration: 771.146995ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:53.264569Z","caller":"traceutil/trace.go:171","msg":"trace[752782763] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"618.119446ms","start":"2026-02-07T02:28:52.64644Z","end":"2026-02-07T02:28:53.264559Z","steps":["trace[752782763] 'process raft request' (duration: 617.04407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.264613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:52.646435Z","time spent":"618.160587ms","remote":"127.0.0.1:49930","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:28:53.264692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46060259s","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-07T02:28:53.264724Z","caller":"traceutil/trace.go:171","msg":"trace[424758887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"1.460653002s","start":"2026-02-07T02:28:51.804061Z","end":"2026-02-07T02:28:53.264714Z","steps":["trace[424758887] 'agreement among raft nodes before linearized reading' (duration: 1.46059091s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.264751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.804051Z","time spent":"1.460693572s","remote":"127.0.0.1:50056","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-07T02:28:53.264876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461320757s","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-07T02:28:53.264901Z","caller":"traceutil/trace.go:171","msg":"trace[13734500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"1.46142294s","start":"2026-02-07T02:28:51.80347Z","end":"2026-02-07T02:28:53.264893Z","steps":["trace[13734500] 'agreement among raft nodes before linearized reading' (duration: 1.461381809s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.264925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.803463Z","time spent":"1.461456501s","remote":"127.0.0.1:50056","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-07T02:28:53.265038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461583934s","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-07T02:28:53.265063Z","caller":"traceutil/trace.go:171","msg":"trace[290548484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:380; }","duration":"1.461642865s","start":"2026-02-07T02:28:51.803413Z","end":"2026-02-07T02:28:53.265055Z","steps":["trace[290548484] 'agreement among raft nodes before linearized reading' (duration: 1.461601054s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.265087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.803395Z","time spent":"1.461686287s","remote":"127.0.0.1:50056","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-07T02:28:53.265184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301032985s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-twz4j\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-07T02:28:53.26522Z","caller":"traceutil/trace.go:171","msg":"trace[2003937357] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-twz4j; range_end:; response_count:1; response_revision:380; }","duration":"1.301093096s","start":"2026-02-07T02:28:51.964117Z","end":"2026-02-07T02:28:53.26521Z","steps":["trace[2003937357] 'agreement among raft nodes before linearized reading' (duration: 1.301029444s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.265247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:51.964104Z","time spent":"1.301136687s","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3439,"request content":"key:\"/registry/pods/kube-system/kube-proxy-twz4j\" "} {"level":"warn","ts":"2026-02-07T02:28:53.265578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17512036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-07T02:28:53.265611Z","caller":"traceutil/trace.go:171","msg":"trace[1361595632] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:380; }","duration":"1.175178631s","start":"2026-02-07T02:28:52.090423Z","end":"2026-02-07T02:28:53.265602Z","steps":["trace[1361595632] 'agreement among raft nodes before linearized reading' (duration: 1.17512144s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.265637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:52.090408Z","time spent":"1.175222953s","remote":"127.0.0.1:50008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-02-07T02:28:53.672351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.890482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:53.672518Z","caller":"traceutil/trace.go:171","msg":"trace[897135662] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"395.4399ms","start":"2026-02-07T02:28:53.277065Z","end":"2026-02-07T02:28:53.672505Z","steps":["trace[897135662] 'process raft request' (duration: 145.329362ms)","trace[897135662] 'compare' (duration: 249.78093ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:53.672571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.277055Z","time spent":"395.494882ms","remote":"127.0.0.1:50056","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-07T02:28:53.672834Z","caller":"traceutil/trace.go:171","msg":"trace[1809080010] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"395.523273ms","start":"2026-02-07T02:28:53.277303Z","end":"2026-02-07T02:28:53.672826Z","steps":["trace[1809080010] 'read index received' (duration: 145.101317ms)","trace[1809080010] 'applied index is now lower than readState.Index' (duration: 250.420975ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:53.67354Z","caller":"traceutil/trace.go:171","msg":"trace[393633628] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"385.325467ms","start":"2026-02-07T02:28:53.288188Z","end":"2026-02-07T02:28:53.673514Z","steps":["trace[393633628] 'process raft request' (duration: 384.253981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.674262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.287906Z","time spent":"386.284279ms","remote":"127.0.0.1:50296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3737,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:53.674543Z","caller":"traceutil/trace.go:171","msg":"trace[25534321] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"380.142792ms","start":"2026-02-07T02:28:53.294391Z","end":"2026-02-07T02:28:53.674534Z","steps":["trace[25534321] 'process raft request' (duration: 380.117361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.674318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.006968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3995"} {"level":"info","ts":"2026-02-07T02:28:53.674673Z","caller":"traceutil/trace.go:171","msg":"trace[697294824] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:382; }","duration":"397.374257ms","start":"2026-02-07T02:28:53.277282Z","end":"2026-02-07T02:28:53.674657Z","steps":["trace[697294824] 'agreement among raft nodes before linearized reading' (duration: 395.576793ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:53.674752Z","caller":"traceutil/trace.go:171","msg":"trace[1024598607] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"386.089385ms","start":"2026-02-07T02:28:53.288656Z","end":"2026-02-07T02:28:53.674746Z","steps":["trace[1024598607] 'process raft request' (duration: 385.711166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.674807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.277279Z","time spent":"397.484549ms","remote":"127.0.0.1:50252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4018,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-07T02:28:53.674614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.294387Z","time spent":"380.187423ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:53.674637Z","caller":"traceutil/trace.go:171","msg":"trace[1918039425] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"386.179087ms","start":"2026-02-07T02:28:53.288452Z","end":"2026-02-07T02:28:53.674631Z","steps":["trace[1918039425] 'process raft request' (duration: 384.912966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.675623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.288444Z","time spent":"387.098909ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:28:53.674795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.28859Z","time spent":"386.184647ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:53.676552Z","caller":"traceutil/trace.go:171","msg":"trace[973338470] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"386.739701ms","start":"2026-02-07T02:28:53.289799Z","end":"2026-02-07T02:28:53.676539Z","steps":["trace[973338470] 'process raft request' (duration: 384.604259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.676612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.289793Z","time spent":"386.792052ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:53.676958Z","caller":"traceutil/trace.go:171","msg":"trace[1309517318] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"384.977168ms","start":"2026-02-07T02:28:53.291966Z","end":"2026-02-07T02:28:53.676943Z","steps":["trace[1309517318] 'process raft request' (duration: 382.496958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.68033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.291959Z","time spent":"388.338779ms","remote":"127.0.0.1:50056","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-07T02:28:53.677003Z","caller":"traceutil/trace.go:171","msg":"trace[1699107541] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"382.702103ms","start":"2026-02-07T02:28:53.294293Z","end":"2026-02-07T02:28:53.676995Z","steps":["trace[1699107541] 'process raft request' (duration: 380.194503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:53.6862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.294286Z","time spent":"391.828813ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4402,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:54.147686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.371612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:54.14791Z","caller":"traceutil/trace.go:171","msg":"trace[1153396067] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:399; }","duration":"457.494275ms","start":"2026-02-07T02:28:53.690399Z","end":"2026-02-07T02:28:54.147893Z","steps":["trace[1153396067] 'read index received' (duration: 316.836266ms)","trace[1153396067] 'applied index is now lower than readState.Index' (duration: 140.657189ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:54.147998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.591007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-07T02:28:54.148023Z","caller":"traceutil/trace.go:171","msg":"trace[102020322] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:390; }","duration":"457.625848ms","start":"2026-02-07T02:28:53.69039Z","end":"2026-02-07T02:28:54.148015Z","steps":["trace[102020322] 'agreement among raft nodes before linearized reading' (duration: 457.552086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:54.148055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.690386Z","time spent":"457.661918ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4279,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-07T02:28:54.14829Z","caller":"traceutil/trace.go:171","msg":"trace[961912819] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"458.293264ms","start":"2026-02-07T02:28:53.689985Z","end":"2026-02-07T02:28:54.148278Z","steps":["trace[961912819] 'process raft request' (duration: 317.241556ms)","trace[961912819] 'compare' (duration: 140.277669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:54.148352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.689974Z","time spent":"458.345105ms","remote":"127.0.0.1:50296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:54.148444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.690195Z","time spent":"458.248203ms","remote":"127.0.0.1:49884","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T02:28:54.148573Z","caller":"traceutil/trace.go:171","msg":"trace[649970246] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"456.722786ms","start":"2026-02-07T02:28:53.69184Z","end":"2026-02-07T02:28:54.148563Z","steps":["trace[649970246] 'process raft request' (duration: 455.995978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:54.148621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:53.691833Z","time spent":"456.762467ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:28:54.735772Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420375617499740,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T02:28:54.890262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.941893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:54.890426Z","caller":"traceutil/trace.go:171","msg":"trace[1438409488] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:403; }","duration":"655.039167ms","start":"2026-02-07T02:28:54.235372Z","end":"2026-02-07T02:28:54.890411Z","steps":["trace[1438409488] 'read index received' (duration: 526.842017ms)","trace[1438409488] 'applied index is now lower than readState.Index' (duration: 128.19632ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:54.890506Z","caller":"traceutil/trace.go:171","msg":"trace[1354005776] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"733.500508ms","start":"2026-02-07T02:28:54.156995Z","end":"2026-02-07T02:28:54.890495Z","steps":["trace[1354005776] 'process raft request' (duration: 605.253887ms)","trace[1354005776] 'compare' (duration: 127.79595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:54.890591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:54.156971Z","time spent":"733.582729ms","remote":"127.0.0.1:50252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4019,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:54.890717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.321073ms","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-07T02:28:54.890787Z","caller":"traceutil/trace.go:171","msg":"trace[1583261190] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:393; }","duration":"655.430586ms","start":"2026-02-07T02:28:54.235337Z","end":"2026-02-07T02:28:54.890768Z","steps":["trace[1583261190] 'agreement among raft nodes before linearized reading' (duration: 655.247862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:54.890825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:54.235243Z","time spent":"655.573599ms","remote":"127.0.0.1:49976","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-07T02:28:54.890837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.772313ms","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-07T02:28:54.890868Z","caller":"traceutil/trace.go:171","msg":"trace[2047166304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"240.828374ms","start":"2026-02-07T02:28:54.65003Z","end":"2026-02-07T02:28:54.890859Z","steps":["trace[2047166304] 'agreement among raft nodes before linearized reading' (duration: 240.769583ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:54.89107Z","caller":"traceutil/trace.go:171","msg":"trace[1180244762] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"732.460712ms","start":"2026-02-07T02:28:54.158597Z","end":"2026-02-07T02:28:54.891058Z","steps":["trace[1180244762] 'process raft request' (duration: 731.754225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:54.891161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:54.158591Z","time spent":"732.514443ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4049,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:55.316997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.348936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:55.317063Z","caller":"traceutil/trace.go:171","msg":"trace[339888147] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:405; }","duration":"418.490786ms","start":"2026-02-07T02:28:54.898563Z","end":"2026-02-07T02:28:55.317054Z","steps":["trace[339888147] 'read index received' (duration: 103.970876ms)","trace[339888147] 'applied index is now lower than readState.Index' (duration: 314.51934ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:55.317138Z","caller":"traceutil/trace.go:171","msg":"trace[1687751120] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"418.782452ms","start":"2026-02-07T02:28:54.89835Z","end":"2026-02-07T02:28:55.317133Z","steps":["trace[1687751120] 'process raft request' (duration: 104.240962ms)","trace[1687751120] 'compare' (duration: 314.253254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:55.317177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:54.898333Z","time spent":"418.823304ms","remote":"127.0.0.1:50056","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-07T02:28:55.317373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.808233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T02:28:55.317391Z","caller":"traceutil/trace.go:171","msg":"trace[1653127626] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:394; }","duration":"418.843035ms","start":"2026-02-07T02:28:54.898543Z","end":"2026-02-07T02:28:55.317386Z","steps":["trace[1653127626] 'agreement among raft nodes before linearized reading' (duration: 418.805204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:55.31741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:54.898537Z","time spent":"418.865355ms","remote":"127.0.0.1:49884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-07T02:28:55.317491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.738352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-07T02:28:55.31751Z","caller":"traceutil/trace.go:171","msg":"trace[147312122] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:394; }","duration":"418.764982ms","start":"2026-02-07T02:28:54.898739Z","end":"2026-02-07T02:28:55.317504Z","steps":["trace[147312122] 'agreement among raft nodes before linearized reading' (duration: 418.735242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:55.317522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:54.898734Z","time spent":"418.784893ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-07T02:28:55.869009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.82929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:55.869108Z","caller":"traceutil/trace.go:171","msg":"trace[839729354] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"313.6884ms","start":"2026-02-07T02:28:55.555404Z","end":"2026-02-07T02:28:55.869093Z","steps":["trace[839729354] 'read index received' (duration: 129.651705ms)","trace[839729354] 'applied index is now lower than readState.Index' (duration: 184.035455ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:55.869389Z","caller":"traceutil/trace.go:171","msg":"trace[1698445875] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"545.545478ms","start":"2026-02-07T02:28:55.323829Z","end":"2026-02-07T02:28:55.869374Z","steps":["trace[1698445875] 'process raft request' (duration: 361.273507ms)","trace[1698445875] 'compare' (duration: 183.700017ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:55.869453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:55.323816Z","time spent":"545.602519ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:55.869659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.255823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:55.869692Z","caller":"traceutil/trace.go:171","msg":"trace[2064410355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:395; }","duration":"314.316345ms","start":"2026-02-07T02:28:55.555366Z","end":"2026-02-07T02:28:55.869683Z","steps":["trace[2064410355] 'agreement among raft nodes before linearized reading' (duration: 314.254954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:55.869721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:55.555351Z","time spent":"314.364336ms","remote":"127.0.0.1:49862","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-07T02:28:55.906644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.258677ms","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-07T02:28:55.906726Z","caller":"traceutil/trace.go:171","msg":"trace[429017836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:396; }","duration":"217.338478ms","start":"2026-02-07T02:28:55.689353Z","end":"2026-02-07T02:28:55.906692Z","steps":["trace[429017836] 'agreement among raft nodes before linearized reading' (duration: 217.246366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:55.906874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.328704ms","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-07T02:28:55.906914Z","caller":"traceutil/trace.go:171","msg":"trace[438528773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:396; }","duration":"221.414716ms","start":"2026-02-07T02:28:55.685489Z","end":"2026-02-07T02:28:55.906904Z","steps":["trace[438528773] 'agreement among raft nodes before linearized reading' (duration: 221.359415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:55.907049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.149537ms","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-07T02:28:55.907075Z","caller":"traceutil/trace.go:171","msg":"trace[830798677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"230.209468ms","start":"2026-02-07T02:28:55.676858Z","end":"2026-02-07T02:28:55.907067Z","steps":["trace[830798677] 'agreement among raft nodes before linearized reading' (duration: 230.167457ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:28:56.334787Z","caller":"traceutil/trace.go:171","msg":"trace[381336673] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"452.197658ms","start":"2026-02-07T02:28:55.882563Z","end":"2026-02-07T02:28:56.334761Z","steps":["trace[381336673] 'process raft request' (duration: 452.064615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:56.334936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:55.882547Z","time spent":"452.319741ms","remote":"127.0.0.1:49986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:56.335345Z","caller":"traceutil/trace.go:171","msg":"trace[1876698797] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:409; }","duration":"428.757724ms","start":"2026-02-07T02:28:55.906577Z","end":"2026-02-07T02:28:56.335334Z","steps":["trace[1876698797] 'read index received' (duration: 428.752623ms)","trace[1876698797] 'applied index is now lower than readState.Index' (duration: 4.021µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:56.335412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.73537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-07T02:28:56.335439Z","caller":"traceutil/trace.go:171","msg":"trace[517398021] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:397; }","duration":"459.789911ms","start":"2026-02-07T02:28:55.875641Z","end":"2026-02-07T02:28:56.335431Z","steps":["trace[517398021] 'agreement among raft nodes before linearized reading' (duration: 459.73264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:56.335468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:55.875627Z","time spent":"459.832293ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7251,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-07T02:28:56.546672Z","caller":"traceutil/trace.go:171","msg":"trace[1578817818] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"310.451791ms","start":"2026-02-07T02:28:56.236199Z","end":"2026-02-07T02:28:56.546651Z","steps":["trace[1578817818] 'process raft request' (duration: 310.41269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:56.546787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:56.236176Z","time spent":"310.558414ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":755,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-07T02:28:56.547071Z","caller":"traceutil/trace.go:171","msg":"trace[857506369] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"629.651055ms","start":"2026-02-07T02:28:55.917409Z","end":"2026-02-07T02:28:56.54706Z","steps":["trace[857506369] 'process raft request' (duration: 628.874536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:56.547134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:55.91734Z","time spent":"629.757257ms","remote":"127.0.0.1:50056","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-07T02:28:56.54724Z","caller":"traceutil/trace.go:171","msg":"trace[346668058] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"629.657945ms","start":"2026-02-07T02:28:55.917572Z","end":"2026-02-07T02:28:56.54723Z","steps":["trace[346668058] 'process raft request' (duration: 628.812385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:56.547284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:55.917564Z","time spent":"629.695786ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:28:56.547379Z","caller":"traceutil/trace.go:171","msg":"trace[1792136989] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"629.319667ms","start":"2026-02-07T02:28:55.918052Z","end":"2026-02-07T02:28:56.547371Z","steps":["trace[1792136989] 'process raft request' (duration: 628.493347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:56.547444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:55.918043Z","time spent":"629.377018ms","remote":"127.0.0.1:50056","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-07T02:28:57.813309Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.267689921s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-07T02:28:58.166398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.886215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:28:58.166669Z","caller":"traceutil/trace.go:171","msg":"trace[2113797866] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"1.794519308s","start":"2026-02-07T02:28:56.372134Z","end":"2026-02-07T02:28:58.166654Z","steps":["trace[2113797866] 'read index received' (duration: 175.726815ms)","trace[2113797866] 'applied index is now lower than readState.Index' (duration: 1.618791513s)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:28:58.166777Z","caller":"traceutil/trace.go:171","msg":"trace[378992799] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"1.820129334s","start":"2026-02-07T02:28:56.346637Z","end":"2026-02-07T02:28:58.166767Z","steps":["trace[378992799] 'process raft request' (duration: 1.466802079s)","trace[378992799] 'compare' (duration: 352.793902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:28:58.166831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:56.346622Z","time spent":"1.820176416s","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7021,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:28:58.166867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.922362ms","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-07T02:28:58.166899Z","caller":"traceutil/trace.go:171","msg":"trace[218388391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"844.985693ms","start":"2026-02-07T02:28:57.321904Z","end":"2026-02-07T02:28:58.16689Z","steps":["trace[218388391] 'agreement among raft nodes before linearized reading' (duration: 844.916112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:58.166922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:57.321886Z","time spent":"845.029554ms","remote":"127.0.0.1:50056","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-07T02:28:58.167049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794911167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:28:58.167076Z","caller":"traceutil/trace.go:171","msg":"trace[335026108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:402; }","duration":"1.794969568s","start":"2026-02-07T02:28:56.372098Z","end":"2026-02-07T02:28:58.167067Z","steps":["trace[335026108] 'agreement among raft nodes before linearized reading' (duration: 1.794925787s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:28:58.167101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:28:56.372084Z","time spent":"1.795008329s","remote":"127.0.0.1:49874","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-07T02:29:06.109223Z","caller":"traceutil/trace.go:171","msg":"trace[318550072] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"137.7431ms","start":"2026-02-07T02:29:05.971455Z","end":"2026-02-07T02:29:06.109198Z","steps":["trace[318550072] 'process raft request' (duration: 93.74817ms)","trace[318550072] 'compare' (duration: 43.798415ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:06.109426Z","caller":"traceutil/trace.go:171","msg":"trace[171273578] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"136.361377ms","start":"2026-02-07T02:29:05.973022Z","end":"2026-02-07T02:29:06.109383Z","steps":["trace[171273578] 'process raft request' (duration: 136.316415ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:06.109627Z","caller":"traceutil/trace.go:171","msg":"trace[97219036] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"138.038057ms","start":"2026-02-07T02:29:05.971582Z","end":"2026-02-07T02:29:06.10962Z","steps":["trace[97219036] 'process raft request' (duration: 137.599387ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:06.10976Z","caller":"traceutil/trace.go:171","msg":"trace[44513548] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"136.902479ms","start":"2026-02-07T02:29:05.972846Z","end":"2026-02-07T02:29:06.109749Z","steps":["trace[44513548] 'process raft request' (duration: 136.426828ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:06.142944Z","caller":"traceutil/trace.go:171","msg":"trace[24906147] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"123.513937ms","start":"2026-02-07T02:29:06.019401Z","end":"2026-02-07T02:29:06.142915Z","steps":["trace[24906147] 'process raft request' (duration: 122.950973ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:06.146214Z","caller":"traceutil/trace.go:171","msg":"trace[923551460] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"124.245865ms","start":"2026-02-07T02:29:06.021956Z","end":"2026-02-07T02:29:06.146202Z","steps":["trace[923551460] 'process raft request' (duration: 120.523435ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:08.492002Z","caller":"traceutil/trace.go:171","msg":"trace[1455722337] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"181.000681ms","start":"2026-02-07T02:29:08.310982Z","end":"2026-02-07T02:29:08.491983Z","steps":["trace[1455722337] 'process raft request' (duration: 180.902188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:10.784023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.538966ms","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-07T02:29:10.784089Z","caller":"traceutil/trace.go:171","msg":"trace[935889485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"125.626677ms","start":"2026-02-07T02:29:10.658436Z","end":"2026-02-07T02:29:10.784063Z","steps":["trace[935889485] 'range keys from in-memory index tree' (duration: 125.387482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:11.113793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.371973ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:11.113967Z","caller":"traceutil/trace.go:171","msg":"trace[1921747631] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"325.099705ms","start":"2026-02-07T02:29:10.788857Z","end":"2026-02-07T02:29:11.113957Z","steps":["trace[1921747631] 'process raft request' (duration: 325.009473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:11.114017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:10.788851Z","time spent":"325.135955ms","remote":"127.0.0.1:50056","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-07T02:29:11.114358Z","caller":"traceutil/trace.go:171","msg":"trace[494879200] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"326.865637ms","start":"2026-02-07T02:29:10.787485Z","end":"2026-02-07T02:29:11.114351Z","steps":["trace[494879200] 'process raft request' (duration: 119.870929ms)","trace[494879200] 'compare' (duration: 206.22619ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:11.114389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:10.78747Z","time spent":"326.903188ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:16.752829Z","caller":"traceutil/trace.go:171","msg":"trace[1574307741] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"162.492516ms","start":"2026-02-07T02:29:16.590319Z","end":"2026-02-07T02:29:16.752811Z","steps":["trace[1574307741] 'read index received' (duration: 162.384274ms)","trace[1574307741] 'applied index is now lower than readState.Index' (duration: 107.422µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:16.752955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.62899ms","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-07T02:29:16.752978Z","caller":"traceutil/trace.go:171","msg":"trace[264148115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"162.700491ms","start":"2026-02-07T02:29:16.59027Z","end":"2026-02-07T02:29:16.75297Z","steps":["trace[264148115] 'agreement among raft nodes before linearized reading' (duration: 162.609929ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:16.75308Z","caller":"traceutil/trace.go:171","msg":"trace[175392764] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"303.227229ms","start":"2026-02-07T02:29:16.449847Z","end":"2026-02-07T02:29:16.753074Z","steps":["trace[175392764] 'process raft request' (duration: 302.86841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:16.753134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:16.449832Z","time spent":"303.262129ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:21.016306Z","caller":"traceutil/trace.go:171","msg":"trace[1994041561] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"150.623269ms","start":"2026-02-07T02:29:20.865665Z","end":"2026-02-07T02:29:21.016288Z","steps":["trace[1994041561] 'read index received' (duration: 150.493136ms)","trace[1994041561] 'applied index is now lower than readState.Index' (duration: 129.563µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:21.016406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.722682ms","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-07T02:29:21.016423Z","caller":"traceutil/trace.go:171","msg":"trace[644344904] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"150.775103ms","start":"2026-02-07T02:29:20.865642Z","end":"2026-02-07T02:29:21.016417Z","steps":["trace[644344904] 'agreement among raft nodes before linearized reading' (duration: 150.709182ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:21.016649Z","caller":"traceutil/trace.go:171","msg":"trace[2114210652] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"244.379859ms","start":"2026-02-07T02:29:20.77223Z","end":"2026-02-07T02:29:21.016609Z","steps":["trace[2114210652] 'process raft request' (duration: 243.930768ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:21.62418Z","caller":"traceutil/trace.go:171","msg":"trace[145929752] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"459.834292ms","start":"2026-02-07T02:29:21.164324Z","end":"2026-02-07T02:29:21.624159Z","steps":["trace[145929752] 'process raft request' (duration: 459.698879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:21.6243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:21.164316Z","time spent":"459.923764ms","remote":"127.0.0.1:50056","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-07T02:29:22.014984Z","caller":"traceutil/trace.go:171","msg":"trace[935193928] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"849.671937ms","start":"2026-02-07T02:29:21.165298Z","end":"2026-02-07T02:29:22.01497Z","steps":["trace[935193928] 'process raft request' (duration: 849.603255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:22.015067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:21.165292Z","time spent":"849.732688ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:26.526345Z","caller":"traceutil/trace.go:171","msg":"trace[65796473] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"493.793529ms","start":"2026-02-07T02:29:26.03253Z","end":"2026-02-07T02:29:26.526324Z","steps":["trace[65796473] 'process raft request' (duration: 493.657616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:26.526484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:26.032513Z","time spent":"493.896342ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:29:27.374038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.510509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p9thh\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-07T02:29:27.374089Z","caller":"traceutil/trace.go:171","msg":"trace[1415978196] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p9thh; range_end:; response_count:1; response_revision:542; }","duration":"643.602091ms","start":"2026-02-07T02:29:26.730476Z","end":"2026-02-07T02:29:27.374078Z","steps":["trace[1415978196] 'range keys from in-memory index tree' (duration: 643.376005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:27.374112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:26.730456Z","time spent":"643.650762ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-p9thh\" "} {"level":"warn","ts":"2026-02-07T02:29:27.377259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.710254ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:27.377342Z","caller":"traceutil/trace.go:171","msg":"trace[2087345252] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:563; }","duration":"342.470763ms","start":"2026-02-07T02:29:27.034856Z","end":"2026-02-07T02:29:27.377327Z","steps":["trace[2087345252] 'read index received' (duration: 40.231µs)","trace[2087345252] 'applied index is now lower than readState.Index' (duration: 342.427462ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:27.377617Z","caller":"traceutil/trace.go:171","msg":"trace[1120307749] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"346.363177ms","start":"2026-02-07T02:29:27.031243Z","end":"2026-02-07T02:29:27.377606Z","steps":["trace[1120307749] 'compare' (duration: 340.848495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:27.377679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:27.031219Z","time spent":"346.427269ms","remote":"127.0.0.1:50056","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-07T02:29:27.377833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.976576ms","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-07T02:29:27.377846Z","caller":"traceutil/trace.go:171","msg":"trace[96698900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"343.026597ms","start":"2026-02-07T02:29:27.034815Z","end":"2026-02-07T02:29:27.377841Z","steps":["trace[96698900] 'agreement among raft nodes before linearized reading' (duration: 342.993406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:27.377859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:27.034795Z","time spent":"343.061288ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T02:29:27.378178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.373869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T02:29:27.378211Z","caller":"traceutil/trace.go:171","msg":"trace[994506029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:543; }","duration":"339.398409ms","start":"2026-02-07T02:29:27.038797Z","end":"2026-02-07T02:29:27.378196Z","steps":["trace[994506029] 'agreement among raft nodes before linearized reading' (duration: 339.115203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:27.378227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:27.038762Z","time spent":"339.461621ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-07T02:29:27.830267Z","caller":"traceutil/trace.go:171","msg":"trace[1998386600] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"447.177717ms","start":"2026-02-07T02:29:27.383069Z","end":"2026-02-07T02:29:27.830246Z","steps":["trace[1998386600] 'process raft request' (duration: 370.630672ms)","trace[1998386600] 'compare' (duration: 76.193886ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:27.830371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:27.383053Z","time spent":"447.26529ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:27.866532Z","caller":"traceutil/trace.go:171","msg":"trace[171988033] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"480.9319ms","start":"2026-02-07T02:29:27.385557Z","end":"2026-02-07T02:29:27.866489Z","steps":["trace[171988033] 'process raft request' (duration: 480.798737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:27.866648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:27.385535Z","time spent":"481.047404ms","remote":"127.0.0.1:50056","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-07T02:29:27.866653Z","caller":"traceutil/trace.go:171","msg":"trace[598313311] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:564; }","duration":"225.3075ms","start":"2026-02-07T02:29:27.641329Z","end":"2026-02-07T02:29:27.866636Z","steps":["trace[598313311] 'read index received' (duration: 112.379438ms)","trace[598313311] 'applied index is now lower than readState.Index' (duration: 112.926982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:27.866746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.416533ms","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-07T02:29:27.866775Z","caller":"traceutil/trace.go:171","msg":"trace[931991697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"225.488114ms","start":"2026-02-07T02:29:27.641279Z","end":"2026-02-07T02:29:27.866767Z","steps":["trace[931991697] 'agreement among raft nodes before linearized reading' (duration: 225.413712ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:27.866918Z","caller":"traceutil/trace.go:171","msg":"trace[436728119] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"474.25685ms","start":"2026-02-07T02:29:27.392651Z","end":"2026-02-07T02:29:27.866908Z","steps":["trace[436728119] 'process raft request' (duration: 473.799418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:27.866978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:27.392629Z","time spent":"474.310091ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:30.152577Z","caller":"traceutil/trace.go:171","msg":"trace[1675645992] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"316.570649ms","start":"2026-02-07T02:29:29.835976Z","end":"2026-02-07T02:29:30.152547Z","steps":["trace[1675645992] 'process raft request' (duration: 316.490937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:30.152845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:29.835949Z","time spent":"316.753072ms","remote":"127.0.0.1:50056","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-07T02:29:30.153729Z","caller":"traceutil/trace.go:171","msg":"trace[654115901] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"358.978111ms","start":"2026-02-07T02:29:29.794732Z","end":"2026-02-07T02:29:30.15371Z","steps":["trace[654115901] 'process raft request' (duration: 357.544276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:30.154354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:29.794714Z","time spent":"359.603565ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17152,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:30.154599Z","caller":"traceutil/trace.go:171","msg":"trace[286724875] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"340.70408ms","start":"2026-02-07T02:29:29.813887Z","end":"2026-02-07T02:29:30.154591Z","steps":["trace[286724875] 'read index received' (duration: 338.287802ms)","trace[286724875] 'applied index is now lower than readState.Index' (duration: 2.415468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:30.155117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.224792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:30.161982Z","caller":"traceutil/trace.go:171","msg":"trace[1267727710] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:562; }","duration":"347.548264ms","start":"2026-02-07T02:29:29.81386Z","end":"2026-02-07T02:29:30.161409Z","steps":["trace[1267727710] 'agreement among raft nodes before linearized reading' (duration: 341.231582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:30.162121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:29.813849Z","time spent":"348.18741ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-07T02:29:30.162557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:30.163856Z","caller":"traceutil/trace.go:171","msg":"trace[732082827] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:562; }","duration":"349.922312ms","start":"2026-02-07T02:29:29.813913Z","end":"2026-02-07T02:29:30.163836Z","steps":["trace[732082827] 'agreement among raft nodes before linearized reading' (duration: 348.60442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:30.162861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.841705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-07T02:29:30.162893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.912667ms","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-07T02:29:30.16291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.29335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T02:29:30.162924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.022296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T02:29:30.162963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.908722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-07T02:29:30.162979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.975459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-07T02:29:30.164794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:29.81391Z","time spent":"350.875015ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-07T02:29:30.165115Z","caller":"traceutil/trace.go:171","msg":"trace[1451193507] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:562; }","duration":"328.22727ms","start":"2026-02-07T02:29:29.836882Z","end":"2026-02-07T02:29:30.16511Z","steps":["trace[1451193507] 'agreement among raft nodes before linearized reading' (duration: 326.035087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:30.165197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:29.836877Z","time spent":"328.313502ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-02-07T02:29:30.164816Z","caller":"traceutil/trace.go:171","msg":"trace[612461194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"130.820963ms","start":"2026-02-07T02:29:30.033988Z","end":"2026-02-07T02:29:30.164809Z","steps":["trace[612461194] 'agreement among raft nodes before linearized reading' (duration: 128.800304ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:30.164959Z","caller":"traceutil/trace.go:171","msg":"trace[861547918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"131.010308ms","start":"2026-02-07T02:29:30.033943Z","end":"2026-02-07T02:29:30.164953Z","steps":["trace[861547918] 'agreement among raft nodes before linearized reading' (duration: 128.932327ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:30.165049Z","caller":"traceutil/trace.go:171","msg":"trace[18065990] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:562; }","duration":"132.38096ms","start":"2026-02-07T02:29:30.03259Z","end":"2026-02-07T02:29:30.164971Z","steps":["trace[18065990] 'agreement among raft nodes before linearized reading' (duration: 130.30958ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:30.165829Z","caller":"traceutil/trace.go:171","msg":"trace[1649719422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:562; }","duration":"330.797282ms","start":"2026-02-07T02:29:29.835019Z","end":"2026-02-07T02:29:30.165817Z","steps":["trace[1649719422] 'agreement among raft nodes before linearized reading' (duration: 327.919532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:30.166139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:29.835003Z","time spent":"331.123959ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-07T02:29:30.166031Z","caller":"traceutil/trace.go:171","msg":"trace[1559229874] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:562; }","duration":"352.069204ms","start":"2026-02-07T02:29:29.813953Z","end":"2026-02-07T02:29:30.166022Z","steps":["trace[1559229874] 'agreement among raft nodes before linearized reading' (duration: 349.01627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:30.166351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:29.813938Z","time spent":"352.403282ms","remote":"127.0.0.1:49896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-07T02:29:31.165498Z","caller":"traceutil/trace.go:171","msg":"trace[2588173] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"118.564338ms","start":"2026-02-07T02:29:31.046919Z","end":"2026-02-07T02:29:31.165484Z","steps":["trace[2588173] 'process raft request' (duration: 118.479216ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:31.267942Z","caller":"traceutil/trace.go:171","msg":"trace[1158908535] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"100.292046ms","start":"2026-02-07T02:29:31.167624Z","end":"2026-02-07T02:29:31.267916Z","steps":["trace[1158908535] 'process raft request' (duration: 81.398141ms)","trace[1158908535] 'compare' (duration: 18.775643ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:31.277201Z","caller":"traceutil/trace.go:171","msg":"trace[1601760303] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"102.982572ms","start":"2026-02-07T02:29:31.1742Z","end":"2026-02-07T02:29:31.277182Z","steps":["trace[1601760303] 'process raft request' (duration: 102.878219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:33.066528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.04957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-07T02:29:33.066699Z","caller":"traceutil/trace.go:171","msg":"trace[857407143] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:622; }","duration":"107.279576ms","start":"2026-02-07T02:29:32.959389Z","end":"2026-02-07T02:29:33.066669Z","steps":["trace[857407143] 'range keys from in-memory index tree' (duration: 106.817244ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:42.539375Z","caller":"traceutil/trace.go:171","msg":"trace[2125259824] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"247.029929ms","start":"2026-02-07T02:29:42.292322Z","end":"2026-02-07T02:29:42.539352Z","steps":["trace[2125259824] 'read index received' (duration: 246.717241ms)","trace[2125259824] 'applied index is now lower than readState.Index' (duration: 311.558µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:29:42.54017Z","caller":"traceutil/trace.go:171","msg":"trace[1468481489] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"254.30225ms","start":"2026-02-07T02:29:42.285854Z","end":"2026-02-07T02:29:42.540156Z","steps":["trace[1468481489] 'process raft request' (duration: 253.268163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:42.540573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.24016ms","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-07T02:29:42.540702Z","caller":"traceutil/trace.go:171","msg":"trace[85139198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"248.363914ms","start":"2026-02-07T02:29:42.292293Z","end":"2026-02-07T02:29:42.540657Z","steps":["trace[85139198] 'agreement among raft nodes before linearized reading' (duration: 248.160009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:42.541098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.099894ms","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-07T02:29:42.541187Z","caller":"traceutil/trace.go:171","msg":"trace[907541642] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:719; }","duration":"238.239868ms","start":"2026-02-07T02:29:42.302937Z","end":"2026-02-07T02:29:42.541177Z","steps":["trace[907541642] 'agreement among raft nodes before linearized reading' (duration: 238.071603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:42.54157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.033961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:42.541659Z","caller":"traceutil/trace.go:171","msg":"trace[1280711338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"168.161394ms","start":"2026-02-07T02:29:42.373488Z","end":"2026-02-07T02:29:42.541649Z","steps":["trace[1280711338] 'agreement among raft nodes before linearized reading' (duration: 168.028361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:42.542054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.559581ms","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-07T02:29:42.542146Z","caller":"traceutil/trace.go:171","msg":"trace[306980346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:719; }","duration":"234.679994ms","start":"2026-02-07T02:29:42.307455Z","end":"2026-02-07T02:29:42.542135Z","steps":["trace[306980346] 'agreement among raft nodes before linearized reading' (duration: 234.477899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:43.22431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.120918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:29:43.22437Z","caller":"traceutil/trace.go:171","msg":"trace[322506062] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"665.17992ms","start":"2026-02-07T02:29:42.559179Z","end":"2026-02-07T02:29:43.224359Z","steps":["trace[322506062] 'process raft request' (duration: 462.9611ms)","trace[322506062] 'compare' (duration: 202.027305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:43.224399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:42.559175Z","time spent":"665.210991ms","remote":"127.0.0.1:50056","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-07T02:29:43.27046Z","caller":"traceutil/trace.go:171","msg":"trace[1696741534] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"711.294434ms","start":"2026-02-07T02:29:42.55915Z","end":"2026-02-07T02:29:43.270444Z","steps":["trace[1696741534] 'process raft request' (duration: 710.725109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:43.270549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:42.559134Z","time spent":"711.367385ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:29:43.270838Z","caller":"traceutil/trace.go:171","msg":"trace[155638812] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"710.525004ms","start":"2026-02-07T02:29:42.560308Z","end":"2026-02-07T02:29:43.270833Z","steps":["trace[155638812] 'process raft request' (duration: 710.080572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:43.270867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:42.560302Z","time spent":"710.547844ms","remote":"127.0.0.1:50056","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-07T02:29:43.270962Z","caller":"traceutil/trace.go:171","msg":"trace[1179703947] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"412.120322ms","start":"2026-02-07T02:29:42.858837Z","end":"2026-02-07T02:29:43.270957Z","steps":["trace[1179703947] 'process raft request' (duration: 411.580738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:43.270985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:42.85879Z","time spent":"412.182335ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-07T02:29:43.271612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.796186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:29:43.271651Z","caller":"traceutil/trace.go:171","msg":"trace[928492592] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:723; }","duration":"668.906468ms","start":"2026-02-07T02:29:42.602739Z","end":"2026-02-07T02:29:43.271645Z","steps":["trace[928492592] 'agreement among raft nodes before linearized reading' (duration: 668.787585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:43.271685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:42.602707Z","time spent":"668.95577ms","remote":"127.0.0.1:50178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-02-07T02:29:43.270771Z","caller":"traceutil/trace.go:171","msg":"trace[2098892982] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:745; }","duration":"667.971754ms","start":"2026-02-07T02:29:42.602792Z","end":"2026-02-07T02:29:43.270764Z","steps":["trace[2098892982] 'read index received' (duration: 419.301832ms)","trace[2098892982] 'applied index is now lower than readState.Index' (duration: 248.669402ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:43.272441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.735695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-czvgw\" ","response":"range_response_count:1 size:4820"} {"level":"info","ts":"2026-02-07T02:29:43.272487Z","caller":"traceutil/trace.go:171","msg":"trace[2109062464] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-czvgw; range_end:; response_count:1; response_revision:723; }","duration":"413.778016ms","start":"2026-02-07T02:29:42.858698Z","end":"2026-02-07T02:29:43.272452Z","steps":["trace[2109062464] 'agreement among raft nodes before linearized reading' (duration: 413.701634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:43.272502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:42.858643Z","time spent":"413.855308ms","remote":"127.0.0.1:50002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4843,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-czvgw\" "} {"level":"warn","ts":"2026-02-07T02:29:43.273185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.79474ms","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-07T02:29:43.273203Z","caller":"traceutil/trace.go:171","msg":"trace[1226646049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"656.837801ms","start":"2026-02-07T02:29:42.61636Z","end":"2026-02-07T02:29:43.273198Z","steps":["trace[1226646049] 'agreement among raft nodes before linearized reading' (duration: 655.968868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:43.273216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:29:42.616346Z","time spent":"656.866642ms","remote":"127.0.0.1:50056","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-07T02:29:48.757709Z","caller":"traceutil/trace.go:171","msg":"trace[1118768779] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"120.733956ms","start":"2026-02-07T02:29:48.636957Z","end":"2026-02-07T02:29:48.757691Z","steps":["trace[1118768779] 'process raft request' (duration: 120.636344ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:58.027631Z","caller":"traceutil/trace.go:171","msg":"trace[1894094445] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"288.284944ms","start":"2026-02-07T02:29:57.739328Z","end":"2026-02-07T02:29:58.027613Z","steps":["trace[1894094445] 'process raft request' (duration: 288.163541ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:59.083692Z","caller":"traceutil/trace.go:171","msg":"trace[617676220] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"275.54207ms","start":"2026-02-07T02:29:58.808132Z","end":"2026-02-07T02:29:59.083674Z","steps":["trace[617676220] 'process raft request' (duration: 275.428267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:59.441985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.816972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-07T02:29:59.442159Z","caller":"traceutil/trace.go:171","msg":"trace[789494743] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"172.650872ms","start":"2026-02-07T02:29:59.269485Z","end":"2026-02-07T02:29:59.442136Z","steps":["trace[789494743] 'read index received' (duration: 26.55µs)","trace[789494743] 'applied index is now lower than readState.Index' (duration: 172.622222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:29:59.442459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.956671ms","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-07T02:29:59.442514Z","caller":"traceutil/trace.go:171","msg":"trace[594541585] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"173.037992ms","start":"2026-02-07T02:29:59.269462Z","end":"2026-02-07T02:29:59.4425Z","steps":["trace[594541585] 'agreement among raft nodes before linearized reading' (duration: 172.869748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:59.443012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.390697ms","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-07T02:29:59.443047Z","caller":"traceutil/trace.go:171","msg":"trace[91079576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"112.453828ms","start":"2026-02-07T02:29:59.330585Z","end":"2026-02-07T02:29:59.443039Z","steps":["trace[91079576] 'agreement among raft nodes before linearized reading' (duration: 112.360836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:29:59.443598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.557919ms","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-07T02:29:59.44364Z","caller":"traceutil/trace.go:171","msg":"trace[675915598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:773; }","duration":"110.61372ms","start":"2026-02-07T02:29:59.333018Z","end":"2026-02-07T02:29:59.443632Z","steps":["trace[675915598] 'agreement among raft nodes before linearized reading' (duration: 110.522978ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:59.588068Z","caller":"traceutil/trace.go:171","msg":"trace[1524780515] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"142.011226ms","start":"2026-02-07T02:29:59.446041Z","end":"2026-02-07T02:29:59.588053Z","steps":["trace[1524780515] 'process raft request' (duration: 141.885302ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:59.630369Z","caller":"traceutil/trace.go:171","msg":"trace[58698842] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"180.795006ms","start":"2026-02-07T02:29:59.449554Z","end":"2026-02-07T02:29:59.630349Z","steps":["trace[58698842] 'process raft request' (duration: 180.765716ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:59.63066Z","caller":"traceutil/trace.go:171","msg":"trace[66139397] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"183.022645ms","start":"2026-02-07T02:29:59.44763Z","end":"2026-02-07T02:29:59.630653Z","steps":["trace[66139397] 'process raft request' (duration: 182.507282ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:29:59.630736Z","caller":"traceutil/trace.go:171","msg":"trace[1663589099] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"181.222828ms","start":"2026-02-07T02:29:59.449508Z","end":"2026-02-07T02:29:59.63073Z","steps":["trace[1663589099] 'process raft request' (duration: 180.774607ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:09.907369Z","caller":"traceutil/trace.go:171","msg":"trace[541321352] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"225.471782ms","start":"2026-02-07T02:30:09.68188Z","end":"2026-02-07T02:30:09.907352Z","steps":["trace[541321352] 'read index received' (duration: 225.245106ms)","trace[541321352] 'applied index is now lower than readState.Index' (duration: 226.056µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:09.907462Z","caller":"traceutil/trace.go:171","msg":"trace[1609571308] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"282.746629ms","start":"2026-02-07T02:30:09.624708Z","end":"2026-02-07T02:30:09.907455Z","steps":["trace[1609571308] 'process raft request' (duration: 282.459421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:09.90765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.757239ms","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-07T02:30:09.907674Z","caller":"traceutil/trace.go:171","msg":"trace[863293726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"225.823711ms","start":"2026-02-07T02:30:09.681842Z","end":"2026-02-07T02:30:09.907665Z","steps":["trace[863293726] 'agreement among raft nodes before linearized reading' (duration: 225.717448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:09.907936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.964433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-07T02:30:09.907967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.87719ms","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-07T02:30:09.908006Z","caller":"traceutil/trace.go:171","msg":"trace[805362700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"224.029794ms","start":"2026-02-07T02:30:09.683966Z","end":"2026-02-07T02:30:09.907996Z","steps":["trace[805362700] 'agreement among raft nodes before linearized reading' (duration: 223.956512ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:09.908009Z","caller":"traceutil/trace.go:171","msg":"trace[1825146377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"224.061355ms","start":"2026-02-07T02:30:09.683928Z","end":"2026-02-07T02:30:09.907989Z","steps":["trace[1825146377] 'agreement among raft nodes before linearized reading' (duration: 223.86444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:10.505346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.543264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:30:10.508231Z","caller":"traceutil/trace.go:171","msg":"trace[1125881960] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:835; }","duration":"134.480958ms","start":"2026-02-07T02:30:10.373735Z","end":"2026-02-07T02:30:10.508216Z","steps":["trace[1125881960] 'read index received' (duration: 37.601µs)","trace[1125881960] 'applied index is now lower than readState.Index' (duration: 134.442287ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:10.508522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.784956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:10.508674Z","caller":"traceutil/trace.go:171","msg":"trace[334205764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"134.93664ms","start":"2026-02-07T02:30:10.373725Z","end":"2026-02-07T02:30:10.508661Z","steps":["trace[334205764] 'agreement among raft nodes before linearized reading' (duration: 134.713444ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:10.509011Z","caller":"traceutil/trace.go:171","msg":"trace[805716878] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"595.346013ms","start":"2026-02-07T02:30:09.913651Z","end":"2026-02-07T02:30:10.508997Z","steps":["trace[805716878] 'process raft request' (duration: 255.05422ms)","trace[805716878] 'compare' (duration: 335.764224ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:10.5092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:09.913634Z","time spent":"595.527098ms","remote":"127.0.0.1:50056","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-07T02:30:10.509615Z","caller":"traceutil/trace.go:171","msg":"trace[1885653437] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"594.153502ms","start":"2026-02-07T02:30:09.915449Z","end":"2026-02-07T02:30:10.509603Z","steps":["trace[1885653437] 'process raft request' (duration: 592.666083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:10.509798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:09.915439Z","time spent":"594.325306ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:10.510064Z","caller":"traceutil/trace.go:171","msg":"trace[596852232] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"593.264039ms","start":"2026-02-07T02:30:09.916787Z","end":"2026-02-07T02:30:10.510051Z","steps":["trace[596852232] 'process raft request' (duration: 591.383799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:10.510239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:09.916778Z","time spent":"593.430773ms","remote":"127.0.0.1:50056","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-07T02:30:21.572271Z","caller":"traceutil/trace.go:171","msg":"trace[1569172507] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"243.238139ms","start":"2026-02-07T02:30:21.329014Z","end":"2026-02-07T02:30:21.572252Z","steps":["trace[1569172507] 'process raft request' (duration: 243.134156ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:22.716802Z","caller":"traceutil/trace.go:171","msg":"trace[1061847760] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"129.96453ms","start":"2026-02-07T02:30:22.586814Z","end":"2026-02-07T02:30:22.716779Z","steps":["trace[1061847760] 'read index received' (duration: 129.869448ms)","trace[1061847760] 'applied index is now lower than readState.Index' (duration: 93.992µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:22.717262Z","caller":"traceutil/trace.go:171","msg":"trace[1142754397] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"131.186182ms","start":"2026-02-07T02:30:22.586062Z","end":"2026-02-07T02:30:22.717248Z","steps":["trace[1142754397] 'process raft request' (duration: 130.599776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:22.718396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.561752ms","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-07T02:30:22.718445Z","caller":"traceutil/trace.go:171","msg":"trace[1365286382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:887; }","duration":"131.621264ms","start":"2026-02-07T02:30:22.586811Z","end":"2026-02-07T02:30:22.718433Z","steps":["trace[1365286382] 'agreement among raft nodes before linearized reading' (duration: 130.059933ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:23.144042Z","caller":"traceutil/trace.go:171","msg":"trace[271441839] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"555.742662ms","start":"2026-02-07T02:30:22.588281Z","end":"2026-02-07T02:30:23.144024Z","steps":["trace[271441839] 'process raft request' (duration: 555.255109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:23.144175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:22.588272Z","time spent":"555.832074ms","remote":"127.0.0.1:50056","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-07T02:30:23.14489Z","caller":"traceutil/trace.go:171","msg":"trace[1247464371] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"422.470105ms","start":"2026-02-07T02:30:22.722407Z","end":"2026-02-07T02:30:23.144877Z","steps":["trace[1247464371] 'process raft request' (duration: 422.222179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:23.144955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:22.72239Z","time spent":"422.529106ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T02:30:24.139434Z","caller":"traceutil/trace.go:171","msg":"trace[341449691] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"120.777637ms","start":"2026-02-07T02:30:24.018643Z","end":"2026-02-07T02:30:24.13942Z","steps":["trace[341449691] 'process raft request' (duration: 120.529951ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:31.088792Z","caller":"traceutil/trace.go:171","msg":"trace[502647605] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"277.574953ms","start":"2026-02-07T02:30:30.8112Z","end":"2026-02-07T02:30:31.088775Z","steps":["trace[502647605] 'process raft request' (duration: 277.441309ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:31.456687Z","caller":"traceutil/trace.go:171","msg":"trace[346251480] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"136.629275ms","start":"2026-02-07T02:30:31.320032Z","end":"2026-02-07T02:30:31.456662Z","steps":["trace[346251480] 'process raft request' (duration: 136.522942ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:31.739404Z","caller":"traceutil/trace.go:171","msg":"trace[1114730018] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:948; }","duration":"367.994012ms","start":"2026-02-07T02:30:31.371267Z","end":"2026-02-07T02:30:31.739261Z","steps":["trace[1114730018] 'read index received' (duration: 85.291434ms)","trace[1114730018] 'applied index is now lower than readState.Index' (duration: 282.701237ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:31.740601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.87109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:30:31.740646Z","caller":"traceutil/trace.go:171","msg":"trace[942709788] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:912; }","duration":"147.967523ms","start":"2026-02-07T02:30:31.592669Z","end":"2026-02-07T02:30:31.740636Z","steps":["trace[942709788] 'agreement among raft nodes before linearized reading' (duration: 147.85828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:31.741085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.813119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:31.741129Z","caller":"traceutil/trace.go:171","msg":"trace[231669868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"369.893851ms","start":"2026-02-07T02:30:31.371226Z","end":"2026-02-07T02:30:31.74112Z","steps":["trace[231669868] 'agreement among raft nodes before linearized reading' (duration: 369.826059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:31.741156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:31.371216Z","time spent":"369.933042ms","remote":"127.0.0.1:49862","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-07T02:30:31.741461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.883985ms","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-07T02:30:31.741489Z","caller":"traceutil/trace.go:171","msg":"trace[1750027310] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"143.946047ms","start":"2026-02-07T02:30:31.597535Z","end":"2026-02-07T02:30:31.741481Z","steps":["trace[1750027310] 'agreement among raft nodes before linearized reading' (duration: 143.801873ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:31.740459Z","caller":"traceutil/trace.go:171","msg":"trace[1172086995] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"419.554797ms","start":"2026-02-07T02:30:31.320883Z","end":"2026-02-07T02:30:31.740438Z","steps":["trace[1172086995] 'process raft request' (duration: 418.081309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:31.742763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:31.320876Z","time spent":"421.674314ms","remote":"127.0.0.1:50056","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-07T02:30:32.038934Z","caller":"traceutil/trace.go:171","msg":"trace[712064373] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"289.956478ms","start":"2026-02-07T02:30:31.74896Z","end":"2026-02-07T02:30:32.038917Z","steps":["trace[712064373] 'process raft request' (duration: 218.18835ms)","trace[712064373] 'compare' (duration: 71.712747ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:34.591544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.119946ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:30:34.591618Z","caller":"traceutil/trace.go:171","msg":"trace[1294995652] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"757.486757ms","start":"2026-02-07T02:30:33.83412Z","end":"2026-02-07T02:30:34.591607Z","steps":["trace[1294995652] 'process raft request' (duration: 311.251488ms)","trace[1294995652] 'compare' (duration: 445.658314ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:34.591649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:33.834109Z","time spent":"757.526489ms","remote":"127.0.0.1:49884","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-07T02:30:34.988996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.294922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:30:34.989056Z","caller":"traceutil/trace.go:171","msg":"trace[1849336131] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:955; }","duration":"947.671472ms","start":"2026-02-07T02:30:34.041373Z","end":"2026-02-07T02:30:34.989045Z","steps":["trace[1849336131] 'read index received' (duration: 104.12808ms)","trace[1849336131] 'applied index is now lower than readState.Index' (duration: 843.542792ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:34.989174Z","caller":"traceutil/trace.go:171","msg":"trace[113815276] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"1.153762494s","start":"2026-02-07T02:30:33.835405Z","end":"2026-02-07T02:30:34.989168Z","steps":["trace[113815276] 'process raft request' (duration: 875.249226ms)","trace[113815276] 'compare' (duration: 278.21169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:34.989203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:33.8354Z","time spent":"1.153787974s","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:34.989329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.960169ms","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-07T02:30:34.989344Z","caller":"traceutil/trace.go:171","msg":"trace[569770480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"947.996309ms","start":"2026-02-07T02:30:34.041342Z","end":"2026-02-07T02:30:34.989338Z","steps":["trace[569770480] 'agreement among raft nodes before linearized reading' (duration: 947.938028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:34.989357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:34.041328Z","time spent":"948.026301ms","remote":"127.0.0.1:50056","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-07T02:30:34.989545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.38158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T02:30:34.989591Z","caller":"traceutil/trace.go:171","msg":"trace[1906842831] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:918; }","duration":"769.472013ms","start":"2026-02-07T02:30:34.220107Z","end":"2026-02-07T02:30:34.989579Z","steps":["trace[1906842831] 'agreement among raft nodes before linearized reading' (duration: 769.38603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:34.989616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:34.220089Z","time spent":"769.521084ms","remote":"127.0.0.1:50162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-07T02:30:34.989737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.938168ms","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-07T02:30:34.98975Z","caller":"traceutil/trace.go:171","msg":"trace[1775220688] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:918; }","duration":"395.983518ms","start":"2026-02-07T02:30:34.593762Z","end":"2026-02-07T02:30:34.989746Z","steps":["trace[1775220688] 'agreement among raft nodes before linearized reading' (duration: 395.949007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:34.989761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:34.593746Z","time spent":"396.012159ms","remote":"127.0.0.1:49976","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-07T02:30:34.989847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.928975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:34.98986Z","caller":"traceutil/trace.go:171","msg":"trace[980760766] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:918; }","duration":"485.978246ms","start":"2026-02-07T02:30:34.503878Z","end":"2026-02-07T02:30:34.989856Z","steps":["trace[980760766] 'agreement among raft nodes before linearized reading' (duration: 485.956505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:34.989872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:34.503859Z","time spent":"486.009547ms","remote":"127.0.0.1:49970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-07T02:30:34.989957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.457132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:34.989986Z","caller":"traceutil/trace.go:171","msg":"trace[1853614937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:918; }","duration":"615.488013ms","start":"2026-02-07T02:30:34.374494Z","end":"2026-02-07T02:30:34.989982Z","steps":["trace[1853614937] 'agreement among raft nodes before linearized reading' (duration: 615.448542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:34.990053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:34.374438Z","time spent":"615.611816ms","remote":"127.0.0.1:49862","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-07T02:30:34.990157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.36031ms","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-07T02:30:34.990169Z","caller":"traceutil/trace.go:171","msg":"trace[1755858346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:918; }","duration":"761.39591ms","start":"2026-02-07T02:30:34.228769Z","end":"2026-02-07T02:30:34.990165Z","steps":["trace[1755858346] 'agreement among raft nodes before linearized reading' (duration: 761.336568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:34.990181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:34.228756Z","time spent":"761.423231ms","remote":"127.0.0.1:50056","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-07T02:30:41.86497Z","caller":"traceutil/trace.go:171","msg":"trace[81688520] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"111.186125ms","start":"2026-02-07T02:30:41.753763Z","end":"2026-02-07T02:30:41.864949Z","steps":["trace[81688520] 'process raft request' (duration: 78.735851ms)","trace[81688520] 'compare' (duration: 32.371332ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:42.890122Z","caller":"traceutil/trace.go:171","msg":"trace[1159349083] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"119.159656ms","start":"2026-02-07T02:30:42.770947Z","end":"2026-02-07T02:30:42.890107Z","steps":["trace[1159349083] 'process raft request' (duration: 119.064384ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:49.332253Z","caller":"traceutil/trace.go:171","msg":"trace[2109254017] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"292.71813ms","start":"2026-02-07T02:30:49.039509Z","end":"2026-02-07T02:30:49.332227Z","steps":["trace[2109254017] 'read index received' (duration: 270.411196ms)","trace[2109254017] 'applied index is now lower than readState.Index' (duration: 22.305784ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T02:30:49.33234Z","caller":"traceutil/trace.go:171","msg":"trace[126285597] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"645.477598ms","start":"2026-02-07T02:30:48.686852Z","end":"2026-02-07T02:30:49.33233Z","steps":["trace[126285597] 'process raft request' (duration: 623.061061ms)","trace[126285597] 'compare' (duration: 22.021407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:49.332417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:48.68681Z","time spent":"645.546211ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T02:30:49.332428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.904905ms","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-07T02:30:49.332477Z","caller":"traceutil/trace.go:171","msg":"trace[1860124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:992; }","duration":"292.990027ms","start":"2026-02-07T02:30:49.039474Z","end":"2026-02-07T02:30:49.332464Z","steps":["trace[1860124] 'agreement among raft nodes before linearized reading' (duration: 292.868284ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:49.484657Z","caller":"traceutil/trace.go:171","msg":"trace[1094471762] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"152.334529ms","start":"2026-02-07T02:30:49.332305Z","end":"2026-02-07T02:30:49.48464Z","steps":["trace[1094471762] 'read index received' (duration: 149.610116ms)","trace[1094471762] 'applied index is now lower than readState.Index' (duration: 2.722933ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:30:49.484797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.10316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T02:30:49.484818Z","caller":"traceutil/trace.go:171","msg":"trace[1215159389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:992; }","duration":"439.173092ms","start":"2026-02-07T02:30:49.04564Z","end":"2026-02-07T02:30:49.484813Z","steps":["trace[1215159389] 'agreement among raft nodes before linearized reading' (duration: 439.057969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:49.484836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:49.045626Z","time spent":"439.205933ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T02:30:49.484963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.456121ms","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-07T02:30:49.484979Z","caller":"traceutil/trace.go:171","msg":"trace[123508560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:992; }","duration":"173.508223ms","start":"2026-02-07T02:30:49.311467Z","end":"2026-02-07T02:30:49.484975Z","steps":["trace[123508560] 'agreement among raft nodes before linearized reading' (duration: 173.465562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:49.485093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.460949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T02:30:49.48511Z","caller":"traceutil/trace.go:171","msg":"trace[1816006414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:992; }","duration":"305.52377ms","start":"2026-02-07T02:30:49.179583Z","end":"2026-02-07T02:30:49.485107Z","steps":["trace[1816006414] 'agreement among raft nodes before linearized reading' (duration: 305.457698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:49.485125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:49.179567Z","time spent":"305.552352ms","remote":"127.0.0.1:50056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T02:30:49.490233Z","caller":"traceutil/trace.go:171","msg":"trace[840984519] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"153.577133ms","start":"2026-02-07T02:30:49.336647Z","end":"2026-02-07T02:30:49.490224Z","steps":["trace[840984519] 'process raft request' (duration: 152.101993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:52.775269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.439638ms","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":"warn","ts":"2026-02-07T02:30:52.775366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.036752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:52.790742Z","caller":"traceutil/trace.go:171","msg":"trace[1195129896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"402.151115ms","start":"2026-02-07T02:30:52.373276Z","end":"2026-02-07T02:30:52.775428Z","steps":["trace[1195129896] 'range keys from in-memory index tree' (duration: 401.96696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:52.790937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T02:30:52.373261Z","time spent":"417.658258ms","remote":"127.0.0.1:49862","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-07T02:30:52.791892Z","caller":"traceutil/trace.go:171","msg":"trace[472674455] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1011; }","duration":"177.555281ms","start":"2026-02-07T02:30:52.597766Z","end":"2026-02-07T02:30:52.775321Z","steps":["trace[472674455] 'count revisions from in-memory index tree' (duration: 177.355295ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:30:56.332451Z","caller":"traceutil/trace.go:171","msg":"trace[994817994] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"172.928068ms","start":"2026-02-07T02:30:56.159347Z","end":"2026-02-07T02:30:56.332275Z","steps":["trace[994817994] 'process raft request' (duration: 172.701022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:30:56.600838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.420703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T02:30:56.600911Z","caller":"traceutil/trace.go:171","msg":"trace[279499729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"226.534986ms","start":"2026-02-07T02:30:56.374358Z","end":"2026-02-07T02:30:56.600893Z","steps":["trace[279499729] 'range keys from in-memory index tree' (duration: 226.30439ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:33:54.028409Z","caller":"traceutil/trace.go:171","msg":"trace[1129744275] transaction","detail":"{read_only:false; response_revision:1793; number_of_response:1; }","duration":"247.871118ms","start":"2026-02-07T02:33:53.780499Z","end":"2026-02-07T02:33:54.02837Z","steps":["trace[1129744275] 'process raft request' (duration: 247.597541ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T02:33:54.02829Z","caller":"traceutil/trace.go:171","msg":"trace[1883130298] linearizableReadLoop","detail":"{readStateIndex:1874; appliedIndex:1873; }","duration":"169.052619ms","start":"2026-02-07T02:33:53.859214Z","end":"2026-02-07T02:33:54.028267Z","steps":["trace[1883130298] 'read index received' (duration: 168.889494ms)","trace[1883130298] 'applied index is now lower than readState.Index' (duration: 162.185µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T02:33:54.029111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.87467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-07T02:33:54.029149Z","caller":"traceutil/trace.go:171","msg":"trace[172146772] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1793; }","duration":"169.972242ms","start":"2026-02-07T02:33:53.859164Z","end":"2026-02-07T02:33:54.029136Z","steps":["trace[172146772] 'agreement among raft nodes before linearized reading' (duration: 169.725466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T02:34:02.739436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.311614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T02:34:02.740651Z","caller":"traceutil/trace.go:171","msg":"trace[678595698] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"154.752271ms","start":"2026-02-07T02:34:02.585884Z","end":"2026-02-07T02:34:02.740637Z","steps":["trace[678595698] 'process raft request' (duration: 20.17354ms)","trace[678595698] 'compare' (duration: 133.069708ms)"],"step_count":2}