{"level":"warn","ts":"2026-03-10T22:38:34.730161Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-10T22:38:34.730234Z","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-03-10T22:38:34.730294Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-10T22:38:34.730304Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-03-10T22:38:34.73032Z","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-03-10T22:38:34.730916Z","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-03-10T22:38:34.730985Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.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-03-10T22:38:34.734632Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.476993ms"} {"level":"info","ts":"2026-03-10T22:38:34.739543Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-03-10T22:38:34.739616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-03-10T22:38:34.739634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-03-10T22:38:34.739642Z","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-03-10T22:38:34.739652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-03-10T22:38:34.739682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-03-10T22:38:34.745726Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-10T22:38:34.749668Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-10T22:38:34.751246Z","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-03-10T22:38:34.754643Z","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-03-10T22:38:34.75479Z","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-03-10T22:38:34.755517Z","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-03-10T22:38:34.755611Z","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-03-10T22:38:34.755624Z","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-03-10T22:38:34.756157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-03-10T22:38:34.756711Z","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-03-10T22:38:34.760163Z","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-03-10T22:38:34.760405Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-03-10T22:38:34.760471Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-03-10T22:38:34.760615Z","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-03-10T22:38:34.760682Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-10T22:38:35.241981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-03-10T22:38:35.242064Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-10T22:38:35.242085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-03-10T22:38:35.242101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-03-10T22:38:35.242129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-03-10T22:38:35.242146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-03-10T22:38:35.242155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-03-10T22:38:35.249003Z","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-03-10T22:38:35.249037Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T22:38:35.249224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-10T22:38:35.249343Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-10T22:38:35.249972Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-10T22:38:35.250026Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-10T22:38:35.251078Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-03-10T22:38:35.252274Z","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-03-10T22:38:35.252381Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T22:38:35.252417Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T22:38:35.25312Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-03-10T22:38:43.792218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.955378ms","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-03-10T22:38:43.792324Z","caller":"traceutil/trace.go:171","msg":"trace[1519880522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:289; }","duration":"261.139184ms","start":"2026-03-10T22:38:43.531162Z","end":"2026-03-10T22:38:43.792302Z","steps":["trace[1519880522] 'range keys from in-memory index tree' (duration: 260.822005ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:38:46.233707Z","caller":"traceutil/trace.go:171","msg":"trace[859147378] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:342; }","duration":"154.944295ms","start":"2026-03-10T22:38:46.078706Z","end":"2026-03-10T22:38:46.23365Z","steps":["trace[859147378] 'read index received' (duration: 34.242954ms)","trace[859147378] 'applied index is now lower than readState.Index' (duration: 120.700561ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:38:46.233878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.12417ms","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-03-10T22:38:46.233904Z","caller":"traceutil/trace.go:171","msg":"trace[153178545] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:337; }","duration":"155.217922ms","start":"2026-03-10T22:38:46.078677Z","end":"2026-03-10T22:38:46.233894Z","steps":["trace[153178545] 'agreement among raft nodes before linearized reading' (duration: 155.1035ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:38:46.233978Z","caller":"traceutil/trace.go:171","msg":"trace[1877506261] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"169.01041ms","start":"2026-03-10T22:38:46.064939Z","end":"2026-03-10T22:38:46.233949Z","steps":["trace[1877506261] 'process raft request' (duration: 168.54801ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:38:46.233886Z","caller":"traceutil/trace.go:171","msg":"trace[583544230] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"168.864497ms","start":"2026-03-10T22:38:46.064863Z","end":"2026-03-10T22:38:46.233728Z","steps":["trace[583544230] 'process raft request' (duration: 165.065727ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:38:46.23409Z","caller":"traceutil/trace.go:171","msg":"trace[1770694902] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"163.743535ms","start":"2026-03-10T22:38:46.070331Z","end":"2026-03-10T22:38:46.234074Z","steps":["trace[1770694902] 'process raft request' (duration: 163.279365ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:38:53.205243Z","caller":"traceutil/trace.go:171","msg":"trace[1585057617] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"692.338411ms","start":"2026-03-10T22:38:52.512879Z","end":"2026-03-10T22:38:53.205217Z","steps":["trace[1585057617] 'process raft request' (duration: 692.150827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.205367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.518424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T22:38:53.205413Z","caller":"traceutil/trace.go:171","msg":"trace[593514051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"478.634736ms","start":"2026-03-10T22:38:52.726767Z","end":"2026-03-10T22:38:53.205401Z","steps":["trace[593514051] 'agreement among raft nodes before linearized reading' (duration: 478.524444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.205478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:52.726746Z","time spent":"478.720499ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-10T22:38:53.205204Z","caller":"traceutil/trace.go:171","msg":"trace[437175013] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"478.35689ms","start":"2026-03-10T22:38:52.726821Z","end":"2026-03-10T22:38:53.205178Z","steps":["trace[437175013] 'read index received' (duration: 478.081384ms)","trace[437175013] 'applied index is now lower than readState.Index' (duration: 274.556µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:38:53.205625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:52.512867Z","time spent":"692.442873ms","remote":"127.0.0.1:54344","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-03-10T22:38:53.205756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.434665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:38:53.205782Z","caller":"traceutil/trace.go:171","msg":"trace[655007347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:401; }","duration":"302.496876ms","start":"2026-03-10T22:38:52.903278Z","end":"2026-03-10T22:38:53.205775Z","steps":["trace[655007347] 'agreement among raft nodes before linearized reading' (duration: 302.450235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.205799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:52.903255Z","time spent":"302.539938ms","remote":"127.0.0.1:54076","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-03-10T22:38:53.20591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.620942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T22:38:53.206016Z","caller":"traceutil/trace.go:171","msg":"trace[2134761510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"242.825477ms","start":"2026-03-10T22:38:52.963163Z","end":"2026-03-10T22:38:53.205988Z","steps":["trace[2134761510] 'agreement among raft nodes before linearized reading' (duration: 242.558101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.208552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.868883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-10T22:38:53.208644Z","caller":"traceutil/trace.go:171","msg":"trace[1537679828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"476.556727ms","start":"2026-03-10T22:38:52.732068Z","end":"2026-03-10T22:38:53.208624Z","steps":["trace[1537679828] 'agreement among raft nodes before linearized reading' (duration: 473.649268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.208691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:52.732039Z","time spent":"476.638989ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-10T22:38:53.71212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986421079872173695,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-10T22:38:53.84412Z","caller":"traceutil/trace.go:171","msg":"trace[985359322] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"633.402048ms","start":"2026-03-10T22:38:53.210685Z","end":"2026-03-10T22:38:53.844087Z","steps":["trace[985359322] 'process raft request' (duration: 588.054378ms)","trace[985359322] 'compare' (duration: 45.097534ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:38:53.844304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:53.210658Z","time spent":"633.547192ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:38:53.844934Z","caller":"traceutil/trace.go:171","msg":"trace[163520202] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"632.042486ms","start":"2026-03-10T22:38:53.212871Z","end":"2026-03-10T22:38:53.844914Z","steps":["trace[163520202] 'process raft request' (duration: 631.900323ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.845063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:53.21286Z","time spent":"632.130758ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:38:53.845041Z","caller":"traceutil/trace.go:171","msg":"trace[1493808065] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"632.820215ms","start":"2026-03-10T22:38:53.212154Z","end":"2026-03-10T22:38:53.844974Z","steps":["trace[1493808065] 'process raft request' (duration: 631.866722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.84536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:53.212139Z","time spent":"633.08979ms","remote":"127.0.0.1:54344","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-03-10T22:38:53.913082Z","caller":"traceutil/trace.go:171","msg":"trace[712742018] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:412; }","duration":"701.616841ms","start":"2026-03-10T22:38:53.211425Z","end":"2026-03-10T22:38:53.913042Z","steps":["trace[712742018] 'read index received' (duration: 587.388534ms)","trace[712742018] 'applied index is now lower than readState.Index' (duration: 114.227347ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:38:53.913226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.789355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"warn","ts":"2026-03-10T22:38:53.913227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.686592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:38:53.913278Z","caller":"traceutil/trace.go:171","msg":"trace[1078884911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"696.772485ms","start":"2026-03-10T22:38:53.216493Z","end":"2026-03-10T22:38:53.913266Z","steps":["trace[1078884911] 'agreement among raft nodes before linearized reading' (duration: 696.678783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.91332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:53.216484Z","time spent":"696.827326ms","remote":"127.0.0.1:54088","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-03-10T22:38:53.913247Z","caller":"traceutil/trace.go:171","msg":"trace[1464743447] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:404; }","duration":"701.820865ms","start":"2026-03-10T22:38:53.21142Z","end":"2026-03-10T22:38:53.913241Z","steps":["trace[1464743447] 'agreement among raft nodes before linearized reading' (duration: 701.754174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:38:53.913538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:38:53.211342Z","time spent":"702.187224ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7317,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-03-10T22:38:54.035301Z","caller":"traceutil/trace.go:171","msg":"trace[121645366] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"101.74313ms","start":"2026-03-10T22:38:53.933534Z","end":"2026-03-10T22:38:54.035277Z","steps":["trace[121645366] 'process raft request' (duration: 101.606347ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:11.585481Z","caller":"traceutil/trace.go:171","msg":"trace[1906614378] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:497; }","duration":"113.680765ms","start":"2026-03-10T22:39:11.471774Z","end":"2026-03-10T22:39:11.585455Z","steps":["trace[1906614378] 'read index received' (duration: 113.642644ms)","trace[1906614378] 'applied index is now lower than readState.Index' (duration: 37.041µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:39:11.585629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.836559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-10T22:39:11.585661Z","caller":"traceutil/trace.go:171","msg":"trace[444658205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:483; }","duration":"113.915571ms","start":"2026-03-10T22:39:11.471735Z","end":"2026-03-10T22:39:11.585651Z","steps":["trace[444658205] 'agreement among raft nodes before linearized reading' (duration: 113.825619ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:11.585795Z","caller":"traceutil/trace.go:171","msg":"trace[1259443383] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"320.931495ms","start":"2026-03-10T22:39:11.26482Z","end":"2026-03-10T22:39:11.585751Z","steps":["trace[1259443383] 'process raft request' (duration: 320.499585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:11.586017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:11.264799Z","time spent":"321.120249ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:39:11.702249Z","caller":"traceutil/trace.go:171","msg":"trace[1834961682] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"108.253454ms","start":"2026-03-10T22:39:11.593969Z","end":"2026-03-10T22:39:11.702222Z","steps":["trace[1834961682] 'process raft request' (duration: 96.209898ms)","trace[1834961682] 'compare' (duration: 11.937524ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:39:12.431745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.532029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T22:39:12.431803Z","caller":"traceutil/trace.go:171","msg":"trace[1276226828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"445.635702ms","start":"2026-03-10T22:39:11.986154Z","end":"2026-03-10T22:39:12.43179Z","steps":["trace[1276226828] 'range keys from in-memory index tree' (duration: 445.416366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:12.43183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:11.986131Z","time spent":"445.693192ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-10T22:39:12.432058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.939982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-10T22:39:12.432106Z","caller":"traceutil/trace.go:171","msg":"trace[92531062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:484; }","duration":"444.010734ms","start":"2026-03-10T22:39:11.988084Z","end":"2026-03-10T22:39:12.432094Z","steps":["trace[92531062] 'range keys from in-memory index tree' (duration: 443.886469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:12.432132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:11.988074Z","time spent":"444.051435ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-10T22:39:12.432165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.202235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T22:39:12.4322Z","caller":"traceutil/trace.go:171","msg":"trace[93649709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"441.279608ms","start":"2026-03-10T22:39:11.990914Z","end":"2026-03-10T22:39:12.432194Z","steps":["trace[93649709] 'range keys from in-memory index tree' (duration: 441.047872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:12.432222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:11.990877Z","time spent":"441.34034ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-10T22:39:12.904948Z","caller":"traceutil/trace.go:171","msg":"trace[1012376319] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"470.082683ms","start":"2026-03-10T22:39:12.434835Z","end":"2026-03-10T22:39:12.904918Z","steps":["trace[1012376319] 'process raft request' (duration: 469.872838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:12.905115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:12.434818Z","time spent":"470.209985ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:39:12.937206Z","caller":"traceutil/trace.go:171","msg":"trace[1571052642] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:499; }","duration":"444.440602ms","start":"2026-03-10T22:39:12.492746Z","end":"2026-03-10T22:39:12.937186Z","steps":["trace[1571052642] 'read index received' (duration: 411.97614ms)","trace[1571052642] 'applied index is now lower than readState.Index' (duration: 32.463512ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:39:12.937271Z","caller":"traceutil/trace.go:171","msg":"trace[1669562035] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"501.325735ms","start":"2026-03-10T22:39:12.435928Z","end":"2026-03-10T22:39:12.937254Z","steps":["trace[1669562035] 'process raft request' (duration: 500.574338ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:12.937325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.557165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:39:12.937352Z","caller":"traceutil/trace.go:171","msg":"trace[1535018610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"444.628157ms","start":"2026-03-10T22:39:12.492715Z","end":"2026-03-10T22:39:12.937343Z","steps":["trace[1535018610] 'agreement among raft nodes before linearized reading' (duration: 444.546785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:12.937351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:12.435911Z","time spent":"501.392418ms","remote":"127.0.0.1:54344","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-03-10T22:39:12.937376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:12.4927Z","time spent":"444.671367ms","remote":"127.0.0.1:54076","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-03-10T22:39:12.937445Z","caller":"traceutil/trace.go:171","msg":"trace[610510339] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"501.177423ms","start":"2026-03-10T22:39:12.436249Z","end":"2026-03-10T22:39:12.937427Z","steps":["trace[610510339] 'process raft request' (duration: 500.901186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:12.937532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:12.436121Z","time spent":"501.367517ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:39:13.411373Z","caller":"traceutil/trace.go:171","msg":"trace[1496004413] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"152.025478ms","start":"2026-03-10T22:39:13.259326Z","end":"2026-03-10T22:39:13.411351Z","steps":["trace[1496004413] 'process raft request' (duration: 151.899215ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:13.416438Z","caller":"traceutil/trace.go:171","msg":"trace[1980625392] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"143.944124ms","start":"2026-03-10T22:39:13.272451Z","end":"2026-03-10T22:39:13.416395Z","steps":["trace[1980625392] 'process raft request' (duration: 143.752269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:14.854761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.310479ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-10T22:39:14.854874Z","caller":"traceutil/trace.go:171","msg":"trace[203070559] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"497.746561ms","start":"2026-03-10T22:39:14.35711Z","end":"2026-03-10T22:39:14.854857Z","steps":["trace[203070559] 'process raft request' (duration: 251.156315ms)","trace[203070559] 'compare' (duration: 246.115535ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:39:14.85493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:14.35661Z","time spent":"498.298254ms","remote":"127.0.0.1:54182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T22:39:14.867419Z","caller":"traceutil/trace.go:171","msg":"trace[1433770063] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:519; }","duration":"375.111584ms","start":"2026-03-10T22:39:14.492283Z","end":"2026-03-10T22:39:14.867395Z","steps":["trace[1433770063] 'read index received' (duration: 115.99629ms)","trace[1433770063] 'applied index is now lower than readState.Index' (duration: 259.114104ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:39:14.867483Z","caller":"traceutil/trace.go:171","msg":"trace[725353013] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"472.814447ms","start":"2026-03-10T22:39:14.394636Z","end":"2026-03-10T22:39:14.867451Z","steps":["trace[725353013] 'process raft request' (duration: 472.639863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:14.867566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.258947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:39:14.867553Z","caller":"traceutil/trace.go:171","msg":"trace[451276610] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"328.859922ms","start":"2026-03-10T22:39:14.538649Z","end":"2026-03-10T22:39:14.867509Z","steps":["trace[451276610] 'process raft request' (duration: 328.692378ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:14.867606Z","caller":"traceutil/trace.go:171","msg":"trace[913081072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"375.346589ms","start":"2026-03-10T22:39:14.492249Z","end":"2026-03-10T22:39:14.867595Z","steps":["trace[913081072] 'agreement among raft nodes before linearized reading' (duration: 375.241507ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:14.867624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:14.394618Z","time spent":"472.92707ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T22:39:14.867641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:14.492236Z","time spent":"375.39768ms","remote":"127.0.0.1:54088","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-03-10T22:39:14.867526Z","caller":"traceutil/trace.go:171","msg":"trace[1113366920] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"475.06367ms","start":"2026-03-10T22:39:14.392402Z","end":"2026-03-10T22:39:14.867466Z","steps":["trace[1113366920] 'process raft request' (duration: 474.756083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:14.867719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:14.538614Z","time spent":"329.023676ms","remote":"127.0.0.1:54150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T22:39:14.867847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:14.39238Z","time spent":"475.378958ms","remote":"127.0.0.1:54566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T22:39:14.917495Z","caller":"traceutil/trace.go:171","msg":"trace[2043945456] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"179.757836ms","start":"2026-03-10T22:39:14.737716Z","end":"2026-03-10T22:39:14.917474Z","steps":["trace[2043945456] 'process raft request' (duration: 179.637603ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:15.00429Z","caller":"traceutil/trace.go:171","msg":"trace[245057623] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"128.825385ms","start":"2026-03-10T22:39:14.875442Z","end":"2026-03-10T22:39:15.004267Z","steps":["trace[245057623] 'process raft request' (duration: 128.665361ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:15.004595Z","caller":"traceutil/trace.go:171","msg":"trace[475182385] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"128.731593ms","start":"2026-03-10T22:39:14.875831Z","end":"2026-03-10T22:39:15.004562Z","steps":["trace[475182385] 'process raft request' (duration: 128.480937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:15.05375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.861769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T22:39:15.05377Z","caller":"traceutil/trace.go:171","msg":"trace[1876234169] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"115.537078ms","start":"2026-03-10T22:39:14.938166Z","end":"2026-03-10T22:39:15.053703Z","steps":["trace[1876234169] 'process raft request' (duration: 115.428176ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:15.053823Z","caller":"traceutil/trace.go:171","msg":"trace[622548633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"113.956191ms","start":"2026-03-10T22:39:14.939854Z","end":"2026-03-10T22:39:15.05381Z","steps":["trace[622548633] 'agreement among raft nodes before linearized reading' (duration: 113.813378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:15.054142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.315236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T22:39:15.054214Z","caller":"traceutil/trace.go:171","msg":"trace[1010485468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"113.703565ms","start":"2026-03-10T22:39:14.940493Z","end":"2026-03-10T22:39:15.054197Z","steps":["trace[1010485468] 'agreement among raft nodes before linearized reading' (duration: 113.231024ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:17.640917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.39626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:39:17.641084Z","caller":"traceutil/trace.go:171","msg":"trace[8780134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"148.536034ms","start":"2026-03-10T22:39:17.492459Z","end":"2026-03-10T22:39:17.640995Z","steps":["trace[8780134] 'range keys from in-memory index tree' (duration: 148.303248ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:17.641091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.187092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-03-10T22:39:17.64119Z","caller":"traceutil/trace.go:171","msg":"trace[345495503] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:556; }","duration":"105.350426ms","start":"2026-03-10T22:39:17.535818Z","end":"2026-03-10T22:39:17.641168Z","steps":["trace[345495503] 'range keys from in-memory index tree' (duration: 104.969187ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:19.605975Z","caller":"traceutil/trace.go:171","msg":"trace[1058108054] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"104.945967ms","start":"2026-03-10T22:39:19.500987Z","end":"2026-03-10T22:39:19.605932Z","steps":["trace[1058108054] 'process raft request' (duration: 104.796134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:31.771232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.652959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:39:31.771494Z","caller":"traceutil/trace.go:171","msg":"trace[797872446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"278.968917ms","start":"2026-03-10T22:39:31.492506Z","end":"2026-03-10T22:39:31.771475Z","steps":["trace[797872446] 'range keys from in-memory index tree' (duration: 278.508225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:32.69214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.046971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:39:32.69223Z","caller":"traceutil/trace.go:171","msg":"trace[771491849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"198.192605ms","start":"2026-03-10T22:39:32.494018Z","end":"2026-03-10T22:39:32.69221Z","steps":["trace[771491849] 'range keys from in-memory index tree' (duration: 197.824515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:32.693294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.715442ms","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-03-10T22:39:32.693407Z","caller":"traceutil/trace.go:171","msg":"trace[588471059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"216.818959ms","start":"2026-03-10T22:39:32.476562Z","end":"2026-03-10T22:39:32.693381Z","steps":["trace[588471059] 'range keys from in-memory index tree' (duration: 215.442346ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:32.862844Z","caller":"traceutil/trace.go:171","msg":"trace[904800999] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"162.065145ms","start":"2026-03-10T22:39:32.700753Z","end":"2026-03-10T22:39:32.862818Z","steps":["trace[904800999] 'process raft request' (duration: 161.854ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:59.450233Z","caller":"traceutil/trace.go:171","msg":"trace[518648709] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"423.539852ms","start":"2026-03-10T22:39:59.026669Z","end":"2026-03-10T22:39:59.450209Z","steps":["trace[518648709] 'process raft request' (duration: 423.348287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:39:59.450373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:39:59.026644Z","time spent":"423.659495ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:39:59.593211Z","caller":"traceutil/trace.go:171","msg":"trace[1541753909] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"137.633077ms","start":"2026-03-10T22:39:59.455549Z","end":"2026-03-10T22:39:59.593182Z","steps":["trace[1541753909] 'process raft request' (duration: 134.142474ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:39:59.593373Z","caller":"traceutil/trace.go:171","msg":"trace[2019236774] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"130.650042ms","start":"2026-03-10T22:39:59.462713Z","end":"2026-03-10T22:39:59.593363Z","steps":["trace[2019236774] 'process raft request' (duration: 130.11648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:06.078958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.961598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T22:40:06.079088Z","caller":"traceutil/trace.go:171","msg":"trace[577075074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"322.159904ms","start":"2026-03-10T22:40:05.756903Z","end":"2026-03-10T22:40:06.079063Z","steps":["trace[577075074] 'range keys from in-memory index tree' (duration: 321.730733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:06.078782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.908307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T22:40:06.079267Z","caller":"traceutil/trace.go:171","msg":"trace[974683364] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"322.42713ms","start":"2026-03-10T22:40:05.75682Z","end":"2026-03-10T22:40:06.079247Z","steps":["trace[974683364] 'range keys from in-memory index tree' (duration: 321.724363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:06.079352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:05.75681Z","time spent":"322.526252ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-10T22:40:06.079655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.140528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-10T22:40:06.079707Z","caller":"traceutil/trace.go:171","msg":"trace[11456725] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"329.24578ms","start":"2026-03-10T22:40:05.750448Z","end":"2026-03-10T22:40:06.079694Z","steps":["trace[11456725] 'range keys from in-memory index tree' (duration: 328.947073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:06.079184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:05.756875Z","time spent":"322.250835ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-10T22:40:06.07974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:05.75042Z","time spent":"329.311982ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-10T22:40:06.335401Z","caller":"traceutil/trace.go:171","msg":"trace[2028173654] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"251.42248ms","start":"2026-03-10T22:40:06.083949Z","end":"2026-03-10T22:40:06.335372Z","steps":["trace[2028173654] 'process raft request' (duration: 251.245056ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:06.651949Z","caller":"traceutil/trace.go:171","msg":"trace[2023188470] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"567.14365ms","start":"2026-03-10T22:40:06.084772Z","end":"2026-03-10T22:40:06.651916Z","steps":["trace[2023188470] 'process raft request' (duration: 567.052228ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:06.652004Z","caller":"traceutil/trace.go:171","msg":"trace[1483012394] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"567.936379ms","start":"2026-03-10T22:40:06.084029Z","end":"2026-03-10T22:40:06.651965Z","steps":["trace[1483012394] 'process raft request' (duration: 567.571121ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:06.652407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:06.083998Z","time spent":"568.252136ms","remote":"127.0.0.1:54344","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-03-10T22:40:06.652504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:06.084762Z","time spent":"567.382827ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:40:06.652889Z","caller":"traceutil/trace.go:171","msg":"trace[192347556] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:805; }","duration":"402.819642ms","start":"2026-03-10T22:40:06.250057Z","end":"2026-03-10T22:40:06.652877Z","steps":["trace[192347556] 'read index received' (duration: 85.094784ms)","trace[192347556] 'applied index is now lower than readState.Index' (duration: 317.723468ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:40:06.652972Z","caller":"traceutil/trace.go:171","msg":"trace[1008334744] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"568.105633ms","start":"2026-03-10T22:40:06.084853Z","end":"2026-03-10T22:40:06.652959Z","steps":["trace[1008334744] 'process raft request' (duration: 567.017077ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:06.65305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:06.084831Z","time spent":"568.184715ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T22:40:06.653193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.637905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-10T22:40:06.653161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.058477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-10T22:40:06.653239Z","caller":"traceutil/trace.go:171","msg":"trace[690783139] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:781; }","duration":"131.744538ms","start":"2026-03-10T22:40:06.521483Z","end":"2026-03-10T22:40:06.653228Z","steps":["trace[690783139] 'agreement among raft nodes before linearized reading' (duration: 131.655846ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:06.653291Z","caller":"traceutil/trace.go:171","msg":"trace[1038209407] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:781; }","duration":"403.283723ms","start":"2026-03-10T22:40:06.249984Z","end":"2026-03-10T22:40:06.653268Z","steps":["trace[1038209407] 'agreement among raft nodes before linearized reading' (duration: 402.999796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:06.653355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:06.249951Z","time spent":"403.390705ms","remote":"127.0.0.1:54406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":66,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-03-10T22:40:06.653217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.03125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:06.653526Z","caller":"traceutil/trace.go:171","msg":"trace[573709280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"161.346028ms","start":"2026-03-10T22:40:06.49213Z","end":"2026-03-10T22:40:06.653476Z","steps":["trace[573709280] 'agreement among raft nodes before linearized reading' (duration: 160.989679ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:08.735246Z","caller":"traceutil/trace.go:171","msg":"trace[10864356] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"395.962659ms","start":"2026-03-10T22:40:08.339256Z","end":"2026-03-10T22:40:08.735219Z","steps":["trace[10864356] 'read index received' (duration: 395.681722ms)","trace[10864356] 'applied index is now lower than readState.Index' (duration: 278.297µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:40:08.735321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.287322Z","time spent":"447.990551ms","remote":"127.0.0.1:54128","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-10T22:40:08.735627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.35968ms","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-03-10T22:40:08.7358Z","caller":"traceutil/trace.go:171","msg":"trace[888848525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:782; }","duration":"396.549064ms","start":"2026-03-10T22:40:08.3392Z","end":"2026-03-10T22:40:08.735749Z","steps":["trace[888848525] 'agreement among raft nodes before linearized reading' (duration: 396.218626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:08.735915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.339178Z","time spent":"396.722598ms","remote":"127.0.0.1:54344","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-03-10T22:40:08.893404Z","caller":"traceutil/trace.go:171","msg":"trace[1559522508] linearizableReadLoop","detail":"{readStateIndex:812; appliedIndex:811; }","duration":"158.055091ms","start":"2026-03-10T22:40:08.735322Z","end":"2026-03-10T22:40:08.893377Z","steps":["trace[1559522508] 'read index received' (duration: 141.800086ms)","trace[1559522508] 'applied index is now lower than readState.Index' (duration: 16.254045ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:40:08.893611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.946207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:08.893658Z","caller":"traceutil/trace.go:171","msg":"trace[1827539650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"401.05577ms","start":"2026-03-10T22:40:08.49259Z","end":"2026-03-10T22:40:08.893646Z","steps":["trace[1827539650] 'agreement among raft nodes before linearized reading' (duration: 400.906837ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:08.893629Z","caller":"traceutil/trace.go:171","msg":"trace[1245670102] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"450.395188ms","start":"2026-03-10T22:40:08.443206Z","end":"2026-03-10T22:40:08.893602Z","steps":["trace[1245670102] 'process raft request' (duration: 433.904048ms)","trace[1245670102] 'compare' (duration: 16.122191ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:40:08.893796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.802899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-10T22:40:08.893764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.492565Z","time spent":"401.188973ms","remote":"127.0.0.1:54088","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-03-10T22:40:08.893945Z","caller":"traceutil/trace.go:171","msg":"trace[1306830257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"233.567676ms","start":"2026-03-10T22:40:08.660351Z","end":"2026-03-10T22:40:08.893918Z","steps":["trace[1306830257] 'agreement among raft nodes before linearized reading' (duration: 233.113096ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:08.89406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.443185Z","time spent":"450.641853ms","remote":"127.0.0.1:54344","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-03-10T22:40:08.893796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.819788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-10T22:40:08.894223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.946924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-10T22:40:08.894283Z","caller":"traceutil/trace.go:171","msg":"trace[293363613] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"230.349591ms","start":"2026-03-10T22:40:08.663922Z","end":"2026-03-10T22:40:08.894271Z","steps":["trace[293363613] 'agreement among raft nodes before linearized reading' (duration: 229.48476ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:08.8943Z","caller":"traceutil/trace.go:171","msg":"trace[1198170082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:783; }","duration":"231.045857ms","start":"2026-03-10T22:40:08.66324Z","end":"2026-03-10T22:40:08.894285Z","steps":["trace[1198170082] 'agreement among raft nodes before linearized reading' (duration: 230.176157ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:09.274447Z","caller":"traceutil/trace.go:171","msg":"trace[453159923] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"534.661951ms","start":"2026-03-10T22:40:08.739763Z","end":"2026-03-10T22:40:09.274424Z","steps":["trace[453159923] 'process raft request' (duration: 534.6068ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:09.274503Z","caller":"traceutil/trace.go:171","msg":"trace[724143702] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"537.649112ms","start":"2026-03-10T22:40:08.736823Z","end":"2026-03-10T22:40:09.274472Z","steps":["trace[724143702] 'process raft request' (duration: 537.345445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:09.274698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.736807Z","time spent":"537.759975ms","remote":"127.0.0.1:54128","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-03-10T22:40:09.274786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.739748Z","time spent":"534.804645ms","remote":"127.0.0.1:54344","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-03-10T22:40:09.293343Z","caller":"traceutil/trace.go:171","msg":"trace[701124356] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"393.479421ms","start":"2026-03-10T22:40:08.899843Z","end":"2026-03-10T22:40:09.293322Z","steps":["trace[701124356] 'process raft request' (duration: 393.339948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:09.293462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.899816Z","time spent":"393.579693ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:40:09.293686Z","caller":"traceutil/trace.go:171","msg":"trace[2118050119] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"393.829339ms","start":"2026-03-10T22:40:08.899842Z","end":"2026-03-10T22:40:09.293671Z","steps":["trace[2118050119] 'process raft request' (duration: 393.43312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:09.293836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.899806Z","time spent":"393.999163ms","remote":"127.0.0.1:54344","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-03-10T22:40:09.29374Z","caller":"traceutil/trace.go:171","msg":"trace[1458153341] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"393.785578ms","start":"2026-03-10T22:40:08.899937Z","end":"2026-03-10T22:40:09.293722Z","steps":["trace[1458153341] 'process raft request' (duration: 393.672505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:09.294004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:08.899904Z","time spent":"394.058435ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T22:40:09.654183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.01723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:09.654308Z","caller":"traceutil/trace.go:171","msg":"trace[1611411373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"161.249165ms","start":"2026-03-10T22:40:09.493032Z","end":"2026-03-10T22:40:09.654281Z","steps":["trace[1611411373] 'range keys from in-memory index tree' (duration: 160.908727ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:21.587349Z","caller":"traceutil/trace.go:171","msg":"trace[1407769854] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"207.186923ms","start":"2026-03-10T22:40:21.380143Z","end":"2026-03-10T22:40:21.58733Z","steps":["trace[1407769854] 'read index received' (duration: 207.040209ms)","trace[1407769854] 'applied index is now lower than readState.Index' (duration: 145.734µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:40:21.587665Z","caller":"traceutil/trace.go:171","msg":"trace[399929569] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"226.825458ms","start":"2026-03-10T22:40:21.360802Z","end":"2026-03-10T22:40:21.587628Z","steps":["trace[399929569] 'process raft request' (duration: 226.432119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:21.587909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.751596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T22:40:21.587946Z","caller":"traceutil/trace.go:171","msg":"trace[1944012045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"207.815897ms","start":"2026-03-10T22:40:21.38012Z","end":"2026-03-10T22:40:21.587936Z","steps":["trace[1944012045] 'agreement among raft nodes before linearized reading' (duration: 207.684704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:21.588184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.202341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-10T22:40:21.588276Z","caller":"traceutil/trace.go:171","msg":"trace[1352655140] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:869; }","duration":"163.363525ms","start":"2026-03-10T22:40:21.424892Z","end":"2026-03-10T22:40:21.588256Z","steps":["trace[1352655140] 'agreement among raft nodes before linearized reading' (duration: 163.101299ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:21.588363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.610284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T22:40:21.588419Z","caller":"traceutil/trace.go:171","msg":"trace[841395885] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:869; }","duration":"131.705857ms","start":"2026-03-10T22:40:21.456698Z","end":"2026-03-10T22:40:21.588404Z","steps":["trace[841395885] 'agreement among raft nodes before linearized reading' (duration: 131.557072ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:23.901242Z","caller":"traceutil/trace.go:171","msg":"trace[1835825391] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:907; }","duration":"301.993176ms","start":"2026-03-10T22:40:23.599214Z","end":"2026-03-10T22:40:23.901207Z","steps":["trace[1835825391] 'read index received' (duration: 301.558356ms)","trace[1835825391] 'applied index is now lower than readState.Index' (duration: 432.92µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:40:23.901417Z","caller":"traceutil/trace.go:171","msg":"trace[2011384940] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"302.690003ms","start":"2026-03-10T22:40:23.598714Z","end":"2026-03-10T22:40:23.901404Z","steps":["trace[2011384940] 'process raft request' (duration: 302.072678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:23.901593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:23.598697Z","time spent":"302.755884ms","remote":"127.0.0.1:54344","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-03-10T22:40:23.902308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.057111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T22:40:23.902409Z","caller":"traceutil/trace.go:171","msg":"trace[1091986699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:875; }","duration":"303.239866ms","start":"2026-03-10T22:40:23.599144Z","end":"2026-03-10T22:40:23.902384Z","steps":["trace[1091986699] 'agreement among raft nodes before linearized reading' (duration: 302.888537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:23.902465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:23.599124Z","time spent":"303.331808ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-10T22:40:23.902611Z","caller":"traceutil/trace.go:171","msg":"trace[1353766789] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"301.644598ms","start":"2026-03-10T22:40:23.600936Z","end":"2026-03-10T22:40:23.902581Z","steps":["trace[1353766789] 'process raft request' (duration: 301.433873ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:23.902773Z","caller":"traceutil/trace.go:171","msg":"trace[1303032612] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"301.392272ms","start":"2026-03-10T22:40:23.601363Z","end":"2026-03-10T22:40:23.902755Z","steps":["trace[1303032612] 'process raft request' (duration: 301.146746ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:23.902819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:23.600926Z","time spent":"301.779051ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T22:40:23.902865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:23.601357Z","time spent":"301.461204ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T22:40:24.302318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.567765ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-10T22:40:24.302532Z","caller":"traceutil/trace.go:171","msg":"trace[558099921] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:908; }","duration":"401.154193ms","start":"2026-03-10T22:40:23.901353Z","end":"2026-03-10T22:40:24.302508Z","steps":["trace[558099921] 'read index received' (duration: 947.033µs)","trace[558099921] 'applied index is now lower than readState.Index' (duration: 400.20511ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:40:24.302785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.788897ms","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-03-10T22:40:24.302815Z","caller":"traceutil/trace.go:171","msg":"trace[1007516428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:877; }","duration":"483.856929ms","start":"2026-03-10T22:40:23.818945Z","end":"2026-03-10T22:40:24.302802Z","steps":["trace[1007516428] 'agreement among raft nodes before linearized reading' (duration: 483.658234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:24.302854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:23.818928Z","time spent":"483.916811ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-10T22:40:24.704606Z","caller":"traceutil/trace.go:171","msg":"trace[1808024234] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"402.011933ms","start":"2026-03-10T22:40:24.302564Z","end":"2026-03-10T22:40:24.704576Z","steps":["trace[1808024234] 'read index received' (duration: 401.802499ms)","trace[1808024234] 'applied index is now lower than readState.Index' (duration: 208.504µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:40:24.704747Z","caller":"traceutil/trace.go:171","msg":"trace[257212955] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"796.520168ms","start":"2026-03-10T22:40:23.908186Z","end":"2026-03-10T22:40:24.704706Z","steps":["trace[257212955] 'process raft request' (duration: 796.20755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:24.704808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.862391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:24.704851Z","caller":"traceutil/trace.go:171","msg":"trace[1231796603] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:878; }","duration":"718.932162ms","start":"2026-03-10T22:40:23.9859Z","end":"2026-03-10T22:40:24.704833Z","steps":["trace[1231796603] 'agreement among raft nodes before linearized reading' (duration: 718.83012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:24.704887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:23.985876Z","time spent":"719.000555ms","remote":"127.0.0.1:54378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-03-10T22:40:24.704909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:23.908153Z","time spent":"796.662631ms","remote":"127.0.0.1:54344","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-03-10T22:40:24.706231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.554255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:24.706301Z","caller":"traceutil/trace.go:171","msg":"trace[5315138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"211.688609ms","start":"2026-03-10T22:40:24.494597Z","end":"2026-03-10T22:40:24.706285Z","steps":["trace[5315138] 'agreement among raft nodes before linearized reading' (duration: 211.507094ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:24.706354Z","caller":"traceutil/trace.go:171","msg":"trace[826130352] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"398.763656ms","start":"2026-03-10T22:40:24.307561Z","end":"2026-03-10T22:40:24.706325Z","steps":["trace[826130352] 'process raft request' (duration: 397.471275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:24.706471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:24.307535Z","time spent":"398.855169ms","remote":"127.0.0.1:54344","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-03-10T22:40:40.929024Z","caller":"traceutil/trace.go:171","msg":"trace[519311379] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"344.066971ms","start":"2026-03-10T22:40:40.584916Z","end":"2026-03-10T22:40:40.928983Z","steps":["trace[519311379] 'read index received' (duration: 343.907787ms)","trace[519311379] 'applied index is now lower than readState.Index' (duration: 157.244µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:40:40.92905Z","caller":"traceutil/trace.go:171","msg":"trace[2095925942] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"428.836639ms","start":"2026-03-10T22:40:40.500169Z","end":"2026-03-10T22:40:40.929006Z","steps":["trace[2095925942] 'process raft request' (duration: 428.600132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:40.929279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.340238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-10T22:40:40.929357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:40.500142Z","time spent":"429.113664ms","remote":"127.0.0.1:54182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T22:40:40.929712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.049239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T22:40:40.929783Z","caller":"traceutil/trace.go:171","msg":"trace[338391622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"114.195472ms","start":"2026-03-10T22:40:40.81557Z","end":"2026-03-10T22:40:40.929765Z","steps":["trace[338391622] 'agreement among raft nodes before linearized reading' (duration: 113.833304ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:40.929964Z","caller":"traceutil/trace.go:171","msg":"trace[351382564] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:943; }","duration":"344.523813ms","start":"2026-03-10T22:40:40.584836Z","end":"2026-03-10T22:40:40.92936Z","steps":["trace[351382564] 'agreement among raft nodes before linearized reading' (duration: 344.278297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:40.930084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:40.584804Z","time spent":"345.263149ms","remote":"127.0.0.1:54270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-03-10T22:40:41.309406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.563553ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:41.309601Z","caller":"traceutil/trace.go:171","msg":"trace[486760083] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:943; }","duration":"112.800749ms","start":"2026-03-10T22:40:41.196772Z","end":"2026-03-10T22:40:41.309572Z","steps":["trace[486760083] 'range keys from in-memory index tree' (duration: 112.532293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:41.309693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.681313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T22:40:41.309838Z","caller":"traceutil/trace.go:171","msg":"trace[191666399] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"374.47641ms","start":"2026-03-10T22:40:40.935337Z","end":"2026-03-10T22:40:41.309813Z","steps":["trace[191666399] 'process raft request' (duration: 169.562352ms)","trace[191666399] 'compare' (duration: 204.503969ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:40:41.309942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:40.935302Z","time spent":"374.609344ms","remote":"127.0.0.1:54344","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-03-10T22:40:41.315285Z","caller":"traceutil/trace.go:171","msg":"trace[1678686177] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"138.733912ms","start":"2026-03-10T22:40:41.176494Z","end":"2026-03-10T22:40:41.315228Z","steps":["trace[1678686177] 'read index received' (duration: 137.975024ms)","trace[1678686177] 'applied index is now lower than readState.Index' (duration: 757.388µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:40:41.315317Z","caller":"traceutil/trace.go:171","msg":"trace[1125281943] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"378.764082ms","start":"2026-03-10T22:40:40.936522Z","end":"2026-03-10T22:40:41.315286Z","steps":["trace[1125281943] 'process raft request' (duration: 378.031455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:41.315524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:40.936498Z","time spent":"378.904636ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T22:40:41.315817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.310857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:41.315861Z","caller":"traceutil/trace.go:171","msg":"trace[1115964474] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:945; }","duration":"139.413299ms","start":"2026-03-10T22:40:41.176435Z","end":"2026-03-10T22:40:41.315849Z","steps":["trace[1115964474] 'agreement among raft nodes before linearized reading' (duration: 139.299527ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:42.352009Z","caller":"traceutil/trace.go:171","msg":"trace[1355532942] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"176.882216ms","start":"2026-03-10T22:40:42.175102Z","end":"2026-03-10T22:40:42.351984Z","steps":["trace[1355532942] 'process raft request' (duration: 176.743883ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:42.865639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.333198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:42.865728Z","caller":"traceutil/trace.go:171","msg":"trace[884572036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"372.485803ms","start":"2026-03-10T22:40:42.49322Z","end":"2026-03-10T22:40:42.865706Z","steps":["trace[884572036] 'range keys from in-memory index tree' (duration: 372.187505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:42.865765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:42.493176Z","time spent":"372.577825ms","remote":"127.0.0.1:54076","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-03-10T22:40:43.133752Z","caller":"traceutil/trace.go:171","msg":"trace[1755743231] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"267.236754ms","start":"2026-03-10T22:40:42.866486Z","end":"2026-03-10T22:40:43.133722Z","steps":["trace[1755743231] 'read index received' (duration: 267.07882ms)","trace[1755743231] 'applied index is now lower than readState.Index' (duration: 156.374µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:40:43.133807Z","caller":"traceutil/trace.go:171","msg":"trace[1661056809] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"275.846469ms","start":"2026-03-10T22:40:42.857937Z","end":"2026-03-10T22:40:43.133783Z","steps":["trace[1661056809] 'process raft request' (duration: 275.585142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:43.133985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.453949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:43.134035Z","caller":"traceutil/trace.go:171","msg":"trace[156677990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"267.578342ms","start":"2026-03-10T22:40:42.866445Z","end":"2026-03-10T22:40:43.134023Z","steps":["trace[156677990] 'agreement among raft nodes before linearized reading' (duration: 267.45952ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:43.21266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.527911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-10T22:40:43.212771Z","caller":"traceutil/trace.go:171","msg":"trace[15777284] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:956; }","duration":"166.688246ms","start":"2026-03-10T22:40:43.046061Z","end":"2026-03-10T22:40:43.212749Z","steps":["trace[15777284] 'agreement among raft nodes before linearized reading' (duration: 166.485681ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:40:44.279541Z","caller":"traceutil/trace.go:171","msg":"trace[2138531131] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"230.189808ms","start":"2026-03-10T22:40:44.049321Z","end":"2026-03-10T22:40:44.279511Z","steps":["trace[2138531131] 'read index received' (duration: 229.817309ms)","trace[2138531131] 'applied index is now lower than readState.Index' (duration: 370.559µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T22:40:44.279799Z","caller":"traceutil/trace.go:171","msg":"trace[1993767268] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"237.48415ms","start":"2026-03-10T22:40:44.042135Z","end":"2026-03-10T22:40:44.279619Z","steps":["trace[1993767268] 'process raft request' (duration: 237.09641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:44.279866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.512166ms","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-03-10T22:40:44.27999Z","caller":"traceutil/trace.go:171","msg":"trace[272025812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:959; }","duration":"230.673739ms","start":"2026-03-10T22:40:44.049303Z","end":"2026-03-10T22:40:44.279976Z","steps":["trace[272025812] 'agreement among raft nodes before linearized reading' (duration: 230.413783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:44.65113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.62327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:44.651643Z","caller":"traceutil/trace.go:171","msg":"trace[365389620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"158.211864ms","start":"2026-03-10T22:40:44.493397Z","end":"2026-03-10T22:40:44.651609Z","steps":["trace[365389620] 'range keys from in-memory index tree' (duration: 157.497177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:44.651486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.759377ms","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-03-10T22:40:44.65186Z","caller":"traceutil/trace.go:171","msg":"trace[2121488921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"296.226649ms","start":"2026-03-10T22:40:44.355619Z","end":"2026-03-10T22:40:44.651845Z","steps":["trace[2121488921] 'range keys from in-memory index tree' (duration: 295.518522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:54.240244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.922871ms","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:526"} {"level":"info","ts":"2026-03-10T22:40:54.240377Z","caller":"traceutil/trace.go:171","msg":"trace[1724414431] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1025; }","duration":"383.139336ms","start":"2026-03-10T22:40:53.857211Z","end":"2026-03-10T22:40:54.240351Z","steps":["trace[1724414431] 'range keys from in-memory index tree' (duration: 382.644923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:54.240432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:53.857187Z","time spent":"383.233068ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-10T22:40:54.770767Z","caller":"traceutil/trace.go:171","msg":"trace[883900255] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"525.429875ms","start":"2026-03-10T22:40:54.245311Z","end":"2026-03-10T22:40:54.770741Z","steps":["trace[883900255] 'process raft request' (duration: 524.296187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:54.770999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.483785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-10T22:40:54.771067Z","caller":"traceutil/trace.go:171","msg":"trace[1641155498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"451.614528ms","start":"2026-03-10T22:40:54.319436Z","end":"2026-03-10T22:40:54.771051Z","steps":["trace[1641155498] 'agreement among raft nodes before linearized reading' (duration: 451.390363ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:54.771113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:54.319416Z","time spent":"451.68363ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-10T22:40:54.771221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:54.245283Z","time spent":"525.645989ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T22:40:54.770715Z","caller":"traceutil/trace.go:171","msg":"trace[1327721552] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1065; }","duration":"451.178418ms","start":"2026-03-10T22:40:54.319502Z","end":"2026-03-10T22:40:54.770681Z","steps":["trace[1327721552] 'read index received' (duration: 450.118623ms)","trace[1327721552] 'applied index is now lower than readState.Index' (duration: 1.058415ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T22:40:54.771634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.505308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-10T22:40:54.771695Z","caller":"traceutil/trace.go:171","msg":"trace[372617431] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"430.651032ms","start":"2026-03-10T22:40:54.341026Z","end":"2026-03-10T22:40:54.771677Z","steps":["trace[372617431] 'agreement among raft nodes before linearized reading' (duration: 430.500548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T22:40:54.771738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T22:40:54.340999Z","time spent":"430.726944ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-10T22:40:54.771775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.368547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T22:40:54.771836Z","caller":"traceutil/trace.go:171","msg":"trace[2030832390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"278.48041ms","start":"2026-03-10T22:40:54.49334Z","end":"2026-03-10T22:40:54.77182Z","steps":["trace[2030832390] 'agreement among raft nodes before linearized reading' (duration: 278.364417ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T22:44:00.803958Z","caller":"traceutil/trace.go:171","msg":"trace[229571138] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"108.5493ms","start":"2026-03-10T22:44:00.695383Z","end":"2026-03-10T22:44:00.803932Z","steps":["trace[229571138] 'process raft request' (duration: 108.359286ms)"],"step_count":1}