{"level":"warn","ts":"2026-03-03T00:53:35.965471Z","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-03T00:53:35.965575Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.35:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.35:2380","--initial-cluster=instance=https://199.204.45.35:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.35:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.35:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-03T00:53:35.965649Z","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-03T00:53:35.965661Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-03-03T00:53:35.965679Z","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-03T00:53:35.966353Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"]} {"level":"info","ts":"2026-03-03T00:53:35.966425Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.35:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-03T00:53:35.971117Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.449327ms"} {"level":"info","ts":"2026-03-03T00:53:35.994113Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fb85913747d92cac","cluster-id":"636a84d5a3ba9c31"} {"level":"info","ts":"2026-03-03T00:53:35.994233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T00:53:35.994264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 0"} {"level":"info","ts":"2026-03-03T00:53:35.994276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fb85913747d92cac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T00:53:35.994288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became follower at term 1"} {"level":"info","ts":"2026-03-03T00:53:35.994334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"warn","ts":"2026-03-03T00:53:36.002089Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T00:53:36.005942Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T00:53:36.007596Z","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-03T00:53:36.010653Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fb85913747d92cac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T00:53:36.01142Z","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-03T00:53:36.011523Z","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-03T00:53:36.011542Z","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-03T00:53:36.011739Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fb85913747d92cac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T00:53:36.026351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac switched to configuration voters=(18124051942037073068)"} {"level":"info","ts":"2026-03-03T00:53:36.02673Z","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-03T00:53:36.026825Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-03-03T00:53:36.026891Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.35:2380"} {"level":"info","ts":"2026-03-03T00:53:36.027659Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fb85913747d92cac","initial-advertise-peer-urls":["https://199.204.45.35:2380"],"listen-peer-urls":["https://199.204.45.35:2380"],"advertise-client-urls":["https://199.204.45.35:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.35:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T00:53:36.027711Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T00:53:36.027952Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","added-peer-id":"fb85913747d92cac","added-peer-peer-urls":["https://199.204.45.35:2380"]} {"level":"info","ts":"2026-03-03T00:53:36.994759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T00:53:36.994831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T00:53:36.994862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgPreVoteResp from fb85913747d92cac at term 1"} {"level":"info","ts":"2026-03-03T00:53:36.994882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became candidate at term 2"} {"level":"info","ts":"2026-03-03T00:53:36.994891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac received MsgVoteResp from fb85913747d92cac at term 2"} {"level":"info","ts":"2026-03-03T00:53:36.994905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fb85913747d92cac became leader at term 2"} {"level":"info","ts":"2026-03-03T00:53:36.994923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fb85913747d92cac elected leader fb85913747d92cac at term 2"} {"level":"info","ts":"2026-03-03T00:53:36.995865Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fb85913747d92cac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.35:2379]}","request-path":"/0/members/fb85913747d92cac/attributes","cluster-id":"636a84d5a3ba9c31","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T00:53:36.995919Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T00:53:36.996023Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T00:53:36.996047Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:53:36.996167Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T00:53:36.996208Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T00:53:36.997161Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"636a84d5a3ba9c31","local-member-id":"fb85913747d92cac","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:53:36.997336Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:53:36.997391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:53:37.000022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T00:53:37.00333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.35:2379"} {"level":"info","ts":"2026-03-03T00:53:38.653578Z","caller":"traceutil/trace.go:171","msg":"trace[1149741125] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"165.179313ms","start":"2026-03-03T00:53:38.48837Z","end":"2026-03-03T00:53:38.653549Z","steps":["trace[1149741125] 'process raft request' (duration: 95.691854ms)","trace[1149741125] 'compare' (duration: 69.164532ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:53:38.653884Z","caller":"traceutil/trace.go:171","msg":"trace[570314716] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"163.553934ms","start":"2026-03-03T00:53:38.490282Z","end":"2026-03-03T00:53:38.653836Z","steps":["trace[570314716] 'process raft request' (duration: 163.184965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:44.20078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.373997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-03T00:53:44.200998Z","caller":"traceutil/trace.go:171","msg":"trace[637490933] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:286; }","duration":"158.291999ms","start":"2026-03-03T00:53:44.042578Z","end":"2026-03-03T00:53:44.20087Z","steps":["trace[637490933] 'range keys from in-memory index tree' (duration: 157.234344ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:53:48.701247Z","caller":"traceutil/trace.go:171","msg":"trace[1987498905] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"143.447135ms","start":"2026-03-03T00:53:48.55775Z","end":"2026-03-03T00:53:48.701197Z","steps":["trace[1987498905] 'process raft request' (duration: 143.229559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:49.822747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.711644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:53:49.823034Z","caller":"traceutil/trace.go:171","msg":"trace[53839428] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"1.116945467s","start":"2026-03-03T00:53:48.706071Z","end":"2026-03-03T00:53:49.823016Z","steps":["trace[53839428] 'process raft request' (duration: 1.116815334s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:49.823114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:48.706044Z","time spent":"1.117034379s","remote":"127.0.0.1:35566","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-03T00:53:49.823184Z","caller":"traceutil/trace.go:171","msg":"trace[2007783705] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"1.117679494s","start":"2026-03-03T00:53:48.705448Z","end":"2026-03-03T00:53:49.823128Z","steps":["trace[2007783705] 'process raft request' (duration: 420.027638ms)","trace[2007783705] 'compare' (duration: 696.5545ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:53:49.823393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:48.70543Z","time spent":"1.117856438s","remote":"127.0.0.1:35566","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-03T00:53:50.811924Z","caller":"traceutil/trace.go:171","msg":"trace[111914065] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"171.195487ms","start":"2026-03-03T00:53:50.640648Z","end":"2026-03-03T00:53:50.811843Z","steps":["trace[111914065] 'process raft request' (duration: 170.953311ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:53:51.90846Z","caller":"traceutil/trace.go:171","msg":"trace[1576253834] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"138.21023ms","start":"2026-03-03T00:53:51.770226Z","end":"2026-03-03T00:53:51.908437Z","steps":["trace[1576253834] 'process raft request' (duration: 137.859872ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:52.469289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.757662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-03T00:53:52.469393Z","caller":"traceutil/trace.go:171","msg":"trace[423029440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:364; }","duration":"107.921116ms","start":"2026-03-03T00:53:52.361438Z","end":"2026-03-03T00:53:52.46936Z","steps":["trace[423029440] 'range keys from in-memory index tree' (duration: 107.580628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:53.79952Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.093254372s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-03T00:53:54.439954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024325576s","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-03T00:53:54.440108Z","caller":"traceutil/trace.go:171","msg":"trace[1809164673] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:366; }","duration":"1.024474949s","start":"2026-03-03T00:53:53.415597Z","end":"2026-03-03T00:53:54.440072Z","steps":["trace[1809164673] 'range keys from in-memory index tree' (duration: 1.024302375s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:54.440497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.631195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:53:54.440621Z","caller":"traceutil/trace.go:171","msg":"trace[1530674804] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"979.638739ms","start":"2026-03-03T00:53:53.460962Z","end":"2026-03-03T00:53:54.440601Z","steps":["trace[1530674804] 'read index received' (duration: 338.690276ms)","trace[1530674804] 'applied index is now lower than readState.Index' (duration: 640.947093ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:53:54.440743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.778833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:53:54.440784Z","caller":"traceutil/trace.go:171","msg":"trace[72649630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"979.842674ms","start":"2026-03-03T00:53:53.460928Z","end":"2026-03-03T00:53:54.44077Z","steps":["trace[72649630] 'agreement among raft nodes before linearized reading' (duration: 979.775652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:54.440827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:53.460912Z","time spent":"979.904056ms","remote":"127.0.0.1:35306","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-03T00:53:54.440881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.139804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-03T00:53:54.440933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.94484ms","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-03T00:53:54.440962Z","caller":"traceutil/trace.go:171","msg":"trace[982469648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:367; }","duration":"498.235526ms","start":"2026-03-03T00:53:53.942693Z","end":"2026-03-03T00:53:54.440944Z","steps":["trace[982469648] 'agreement among raft nodes before linearized reading' (duration: 498.097373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:54.441006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:53.942627Z","time spent":"498.371619ms","remote":"127.0.0.1:35566","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-03T00:53:54.44106Z","caller":"traceutil/trace.go:171","msg":"trace[1333030740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"501.106274ms","start":"2026-03-03T00:53:53.939901Z","end":"2026-03-03T00:53:54.441007Z","steps":["trace[1333030740] 'agreement among raft nodes before linearized reading' (duration: 500.846408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:54.441331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:53.939878Z","time spent":"501.435612ms","remote":"127.0.0.1:35566","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-03T00:53:54.441942Z","caller":"traceutil/trace.go:171","msg":"trace[1018789424] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.73444722s","start":"2026-03-03T00:53:52.706154Z","end":"2026-03-03T00:53:54.440602Z","steps":["trace[1018789424] 'process raft request' (duration: 1.093581019s)","trace[1018789424] 'compare' (duration: 640.123553ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:53:54.442715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:52.706127Z","time spent":"1.735906815s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:53:57.171019Z","caller":"traceutil/trace.go:171","msg":"trace[1524561088] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"321.352072ms","start":"2026-03-03T00:53:56.849639Z","end":"2026-03-03T00:53:57.170991Z","steps":["trace[1524561088] 'process raft request' (duration: 321.124997ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:53:57.171188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:53:56.849611Z","time spent":"321.496205ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4360,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:54:13.049389Z","caller":"traceutil/trace.go:171","msg":"trace[2035114382] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"464.722715ms","start":"2026-03-03T00:54:12.584639Z","end":"2026-03-03T00:54:13.049362Z","steps":["trace[2035114382] 'read index received' (duration: 464.591152ms)","trace[2035114382] 'applied index is now lower than readState.Index' (duration: 130.373µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:54:13.049478Z","caller":"traceutil/trace.go:171","msg":"trace[1929095779] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"790.110083ms","start":"2026-03-03T00:54:12.259314Z","end":"2026-03-03T00:54:13.049424Z","steps":["trace[1929095779] 'process raft request' (duration: 789.862577ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:13.049568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.98414ms","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-03T00:54:13.049607Z","caller":"traceutil/trace.go:171","msg":"trace[2007165062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"457.079563ms","start":"2026-03-03T00:54:12.592515Z","end":"2026-03-03T00:54:13.049595Z","steps":["trace[2007165062] 'agreement among raft nodes before linearized reading' (duration: 456.996931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:13.049642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:12.592495Z","time spent":"457.139115ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T00:54:13.049667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:12.259284Z","time spent":"790.283547ms","remote":"127.0.0.1:35488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4566,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:54:13.04971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.935079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-03T00:54:13.049679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.063504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:54:13.04978Z","caller":"traceutil/trace.go:171","msg":"trace[1709752399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"457.029161ms","start":"2026-03-03T00:54:12.592725Z","end":"2026-03-03T00:54:13.049754Z","steps":["trace[1709752399] 'agreement among raft nodes before linearized reading' (duration: 456.887138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:13.049816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:12.592711Z","time spent":"457.095923ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T00:54:13.049819Z","caller":"traceutil/trace.go:171","msg":"trace[1360287975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"331.245638ms","start":"2026-03-03T00:54:12.718528Z","end":"2026-03-03T00:54:13.049774Z","steps":["trace[1360287975] 'agreement among raft nodes before linearized reading' (duration: 331.020493ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:13.049887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:12.718503Z","time spent":"331.370781ms","remote":"127.0.0.1:35306","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-03T00:54:13.049986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.022012ms","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-03T00:54:13.050008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.371471ms","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-03T00:54:13.050066Z","caller":"traceutil/trace.go:171","msg":"trace[1970447279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"465.480624ms","start":"2026-03-03T00:54:12.584568Z","end":"2026-03-03T00:54:13.050049Z","steps":["trace[1970447279] 'agreement among raft nodes before linearized reading' (duration: 464.90378ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:13.05008Z","caller":"traceutil/trace.go:171","msg":"trace[503368716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"457.298208ms","start":"2026-03-03T00:54:12.592762Z","end":"2026-03-03T00:54:13.05006Z","steps":["trace[503368716] 'agreement among raft nodes before linearized reading' (duration: 456.819246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:13.050116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:12.584546Z","time spent":"465.558815ms","remote":"127.0.0.1:35566","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-03T00:54:13.050162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:12.592751Z","time spent":"457.39637ms","remote":"127.0.0.1:35566","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-03T00:54:20.58599Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120118485646971,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T00:54:20.59867Z","caller":"traceutil/trace.go:171","msg":"trace[933160145] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:517; }","duration":"513.048442ms","start":"2026-03-03T00:54:20.085594Z","end":"2026-03-03T00:54:20.598643Z","steps":["trace[933160145] 'read index received' (duration: 512.826807ms)","trace[933160145] 'applied index is now lower than readState.Index' (duration: 220.715µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:54:20.598807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.194736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.35\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-03T00:54:20.598849Z","caller":"traceutil/trace.go:171","msg":"trace[1694572400] range","detail":"{range_begin:/registry/masterleases/199.204.45.35; range_end:; response_count:1; response_revision:498; }","duration":"513.289238ms","start":"2026-03-03T00:54:20.085549Z","end":"2026-03-03T00:54:20.598838Z","steps":["trace[1694572400] 'agreement among raft nodes before linearized reading' (duration: 513.203576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:20.598887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:20.085524Z","time spent":"513.35455ms","remote":"127.0.0.1:35332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.35\" "} {"level":"info","ts":"2026-03-03T00:54:20.599011Z","caller":"traceutil/trace.go:171","msg":"trace[1804281995] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"752.755835ms","start":"2026-03-03T00:54:19.846238Z","end":"2026-03-03T00:54:20.598994Z","steps":["trace[1804281995] 'process raft request' (duration: 752.208782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:20.599109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:19.846208Z","time spent":"752.841657ms","remote":"127.0.0.1:35504","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-03T00:54:20.893858Z","caller":"traceutil/trace.go:171","msg":"trace[1438156270] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"177.943861ms","start":"2026-03-03T00:54:20.715889Z","end":"2026-03-03T00:54:20.893833Z","steps":["trace[1438156270] 'read index received' (duration: 177.668734ms)","trace[1438156270] 'applied index is now lower than readState.Index' (duration: 273.857µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:54:20.89402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.105614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:54:20.894071Z","caller":"traceutil/trace.go:171","msg":"trace[1186055465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"178.220697ms","start":"2026-03-03T00:54:20.715841Z","end":"2026-03-03T00:54:20.894061Z","steps":["trace[1186055465] 'agreement among raft nodes before linearized reading' (duration: 178.119325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:22.170229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.245032ms","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-03T00:54:22.170304Z","caller":"traceutil/trace.go:171","msg":"trace[889652037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"992.338635ms","start":"2026-03-03T00:54:21.177948Z","end":"2026-03-03T00:54:22.170286Z","steps":["trace[889652037] 'range keys from in-memory index tree' (duration: 992.069808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:22.170281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.870443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-03T00:54:22.17035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:21.177862Z","time spent":"992.478468ms","remote":"127.0.0.1:35566","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-03T00:54:22.170396Z","caller":"traceutil/trace.go:171","msg":"trace[527845616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"991.999636ms","start":"2026-03-03T00:54:21.178366Z","end":"2026-03-03T00:54:22.170366Z","steps":["trace[527845616] 'range keys from in-memory index tree' (duration: 991.681909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:22.170433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004680529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-03T00:54:22.17046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:21.178356Z","time spent":"992.091208ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T00:54:22.170481Z","caller":"traceutil/trace.go:171","msg":"trace[1815988678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"1.004739771s","start":"2026-03-03T00:54:21.165717Z","end":"2026-03-03T00:54:22.170457Z","steps":["trace[1815988678] 'range keys from in-memory index tree' (duration: 1.004504425s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:22.170514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:21.165703Z","time spent":"1.004804462s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T00:54:22.170285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066328082s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-03T00:54:22.170611Z","caller":"traceutil/trace.go:171","msg":"trace[1639645269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"1.066758892s","start":"2026-03-03T00:54:21.103842Z","end":"2026-03-03T00:54:22.170601Z","steps":["trace[1639645269] 'range keys from in-memory index tree' (duration: 1.066193918s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:22.170632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:21.103811Z","time spent":"1.066814694s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T00:54:22.336209Z","caller":"traceutil/trace.go:171","msg":"trace[769035588] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"160.720669ms","start":"2026-03-03T00:54:22.175456Z","end":"2026-03-03T00:54:22.336176Z","steps":["trace[769035588] 'process raft request' (duration: 160.585416ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:23.116854Z","caller":"traceutil/trace.go:171","msg":"trace[791399479] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:521; }","duration":"385.963259ms","start":"2026-03-03T00:54:22.730861Z","end":"2026-03-03T00:54:23.116824Z","steps":["trace[791399479] 'read index received' (duration: 298.253394ms)","trace[791399479] 'applied index is now lower than readState.Index' (duration: 87.708695ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:54:23.117062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.159324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:54:23.117103Z","caller":"traceutil/trace.go:171","msg":"trace[577484716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"386.306447ms","start":"2026-03-03T00:54:22.730786Z","end":"2026-03-03T00:54:23.117092Z","steps":["trace[577484716] 'agreement among raft nodes before linearized reading' (duration: 386.153223ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:23.117139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:22.730757Z","time spent":"386.374408ms","remote":"127.0.0.1:35306","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-03T00:54:23.117022Z","caller":"traceutil/trace.go:171","msg":"trace[1021600370] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"941.436218ms","start":"2026-03-03T00:54:22.175522Z","end":"2026-03-03T00:54:23.116958Z","steps":["trace[1021600370] 'process raft request' (duration: 853.564389ms)","trace[1021600370] 'compare' (duration: 87.49872ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:54:23.117399Z","caller":"traceutil/trace.go:171","msg":"trace[1565680354] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"940.906176ms","start":"2026-03-03T00:54:22.176456Z","end":"2026-03-03T00:54:23.117363Z","steps":["trace[1565680354] 'process raft request' (duration: 940.328762ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:23.117424Z","caller":"traceutil/trace.go:171","msg":"trace[2099939910] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"941.743616ms","start":"2026-03-03T00:54:22.175637Z","end":"2026-03-03T00:54:23.11738Z","steps":["trace[2099939910] 'process raft request' (duration: 941.102191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:23.117512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:22.175486Z","time spent":"941.90795ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:54:23.117583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:22.17644Z","time spent":"941.037779ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:54:23.117619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:22.17562Z","time spent":"941.89496ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:54:25.674573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.842567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T00:54:25.67466Z","caller":"traceutil/trace.go:171","msg":"trace[1541675751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"552.953019ms","start":"2026-03-03T00:54:25.121687Z","end":"2026-03-03T00:54:25.67464Z","steps":["trace[1541675751] 'range keys from in-memory index tree' (duration: 552.703733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:25.674717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:25.121626Z","time spent":"553.074012ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T00:54:25.674827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.792065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-03T00:54:25.674983Z","caller":"traceutil/trace.go:171","msg":"trace[914311164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:515; }","duration":"553.00058ms","start":"2026-03-03T00:54:25.12195Z","end":"2026-03-03T00:54:25.67495Z","steps":["trace[914311164] 'range keys from in-memory index tree' (duration: 552.613671ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:25.675049Z","caller":"traceutil/trace.go:171","msg":"trace[844018279] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"370.029899ms","start":"2026-03-03T00:54:25.304978Z","end":"2026-03-03T00:54:25.675008Z","steps":["trace[844018279] 'process raft request' (duration: 369.456316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:25.674862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.10298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-03T00:54:25.675166Z","caller":"traceutil/trace.go:171","msg":"trace[24549224] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:515; }","duration":"596.460499ms","start":"2026-03-03T00:54:25.078689Z","end":"2026-03-03T00:54:25.67515Z","steps":["trace[24549224] 'range keys from in-memory index tree' (duration: 595.954626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:25.675214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:25.078666Z","time spent":"596.53796ms","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-03T00:54:25.675263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:25.304952Z","time spent":"370.197624ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:54:25.674862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.689497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T00:54:25.675353Z","caller":"traceutil/trace.go:171","msg":"trace[1852643025] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:515; }","duration":"554.22088ms","start":"2026-03-03T00:54:25.121103Z","end":"2026-03-03T00:54:25.675324Z","steps":["trace[1852643025] 'range keys from in-memory index tree' (duration: 553.563713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:25.675457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:25.121079Z","time spent":"554.345992ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T00:54:25.675082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:25.121933Z","time spent":"553.133194ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T00:54:42.27511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120118485647332,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T00:54:42.41755Z","caller":"traceutil/trace.go:171","msg":"trace[1382163156] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"647.845905ms","start":"2026-03-03T00:54:41.769671Z","end":"2026-03-03T00:54:42.417516Z","steps":["trace[1382163156] 'read index received' (duration: 647.64878ms)","trace[1382163156] 'applied index is now lower than readState.Index' (duration: 195.295µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:54:42.41762Z","caller":"traceutil/trace.go:171","msg":"trace[1225063475] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"664.182615ms","start":"2026-03-03T00:54:41.753414Z","end":"2026-03-03T00:54:42.417597Z","steps":["trace[1225063475] 'process raft request' (duration: 663.97118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:42.417771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:41.753392Z","time spent":"664.275638ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:54:42.417812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.698798ms","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-03T00:54:42.41787Z","caller":"traceutil/trace.go:171","msg":"trace[1733970860] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"646.77762ms","start":"2026-03-03T00:54:41.771078Z","end":"2026-03-03T00:54:42.417855Z","steps":["trace[1733970860] 'agreement among raft nodes before linearized reading' (duration: 646.650407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:42.417789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.120002ms","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-03T00:54:42.417965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:41.771068Z","time spent":"646.855932ms","remote":"127.0.0.1:35566","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-03T00:54:42.417965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.521381ms","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-03T00:54:42.417999Z","caller":"traceutil/trace.go:171","msg":"trace[260405204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"648.343907ms","start":"2026-03-03T00:54:41.769626Z","end":"2026-03-03T00:54:42.41797Z","steps":["trace[260405204] 'agreement among raft nodes before linearized reading' (duration: 648.04548ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:42.41802Z","caller":"traceutil/trace.go:171","msg":"trace[1971853664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:685; }","duration":"604.653464ms","start":"2026-03-03T00:54:41.813348Z","end":"2026-03-03T00:54:42.418002Z","steps":["trace[1971853664] 'agreement among raft nodes before linearized reading' (duration: 604.47928ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:42.41807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:41.813337Z","time spent":"604.720515ms","remote":"127.0.0.1:35566","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":"warn","ts":"2026-03-03T00:54:42.418091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:41.769613Z","time spent":"648.44381ms","remote":"127.0.0.1:35566","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-03T00:54:42.788453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.885952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:54:42.788695Z","caller":"traceutil/trace.go:171","msg":"trace[2021594531] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"365.787228ms","start":"2026-03-03T00:54:42.42289Z","end":"2026-03-03T00:54:42.788677Z","steps":["trace[2021594531] 'process raft request' (duration: 365.658625ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:42.788691Z","caller":"traceutil/trace.go:171","msg":"trace[1447705719] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"365.8834ms","start":"2026-03-03T00:54:42.422767Z","end":"2026-03-03T00:54:42.78865Z","steps":["trace[1447705719] 'process raft request' (duration: 243.721802ms)","trace[1447705719] 'compare' (duration: 121.753499ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:54:42.788765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:42.422862Z","time spent":"365.871349ms","remote":"127.0.0.1:35566","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-03T00:54:42.788859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:42.422749Z","time spent":"366.008083ms","remote":"127.0.0.1:35566","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-03T00:54:42.788987Z","caller":"traceutil/trace.go:171","msg":"trace[1084066978] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"364.452506ms","start":"2026-03-03T00:54:42.424524Z","end":"2026-03-03T00:54:42.788977Z","steps":["trace[1084066978] 'process raft request' (duration: 364.074897ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:42.788989Z","caller":"traceutil/trace.go:171","msg":"trace[1058482633] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:711; }","duration":"290.337026ms","start":"2026-03-03T00:54:42.498623Z","end":"2026-03-03T00:54:42.78896Z","steps":["trace[1058482633] 'read index received' (duration: 167.880011ms)","trace[1058482633] 'applied index is now lower than readState.Index' (duration: 122.455735ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:54:42.789043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:42.424516Z","time spent":"364.491827ms","remote":"127.0.0.1:35566","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-03T00:54:42.789247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.617642ms","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-03T00:54:42.789294Z","caller":"traceutil/trace.go:171","msg":"trace[1806168551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"290.732295ms","start":"2026-03-03T00:54:42.49855Z","end":"2026-03-03T00:54:42.789282Z","steps":["trace[1806168551] 'agreement among raft nodes before linearized reading' (duration: 290.54939ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:43.266283Z","caller":"traceutil/trace.go:171","msg":"trace[133363269] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"473.42701ms","start":"2026-03-03T00:54:42.792832Z","end":"2026-03-03T00:54:43.266259Z","steps":["trace[133363269] 'process raft request' (duration: 473.288386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:43.266448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:42.792815Z","time spent":"473.558302ms","remote":"127.0.0.1:35566","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-03T00:54:43.600092Z","caller":"traceutil/trace.go:171","msg":"trace[258219915] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"174.438557ms","start":"2026-03-03T00:54:43.425634Z","end":"2026-03-03T00:54:43.600073Z","steps":["trace[258219915] 'read index received' (duration: 174.194292ms)","trace[258219915] 'applied index is now lower than readState.Index' (duration: 243.665µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:54:43.600239Z","caller":"traceutil/trace.go:171","msg":"trace[1756912235] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"184.566709ms","start":"2026-03-03T00:54:43.415619Z","end":"2026-03-03T00:54:43.600185Z","steps":["trace[1756912235] 'process raft request' (duration: 184.269962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:43.600295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.623602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T00:54:43.600347Z","caller":"traceutil/trace.go:171","msg":"trace[1683198309] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:690; }","duration":"174.730374ms","start":"2026-03-03T00:54:43.425605Z","end":"2026-03-03T00:54:43.600336Z","steps":["trace[1683198309] 'agreement among raft nodes before linearized reading' (duration: 174.543779ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:45.090645Z","caller":"traceutil/trace.go:171","msg":"trace[351745512] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"377.322073ms","start":"2026-03-03T00:54:44.713298Z","end":"2026-03-03T00:54:45.09062Z","steps":["trace[351745512] 'read index received' (duration: 377.128458ms)","trace[351745512] 'applied index is now lower than readState.Index' (duration: 192.065µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:54:45.090737Z","caller":"traceutil/trace.go:171","msg":"trace[803791571] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"663.035108ms","start":"2026-03-03T00:54:44.42766Z","end":"2026-03-03T00:54:45.090695Z","steps":["trace[803791571] 'process raft request' (duration: 662.780612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:45.090847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.533709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:54:45.090894Z","caller":"traceutil/trace.go:171","msg":"trace[2082668611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"377.630641ms","start":"2026-03-03T00:54:44.713244Z","end":"2026-03-03T00:54:45.090875Z","steps":["trace[2082668611] 'agreement among raft nodes before linearized reading' (duration: 377.528108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:45.090898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:44.42764Z","time spent":"663.15615ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:54:45.090931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:54:44.71322Z","time spent":"377.702822ms","remote":"127.0.0.1:35306","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-03T00:54:45.091278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.431279ms","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-03T00:54:45.091311Z","caller":"traceutil/trace.go:171","msg":"trace[1605638795] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:691; }","duration":"298.49691ms","start":"2026-03-03T00:54:44.792804Z","end":"2026-03-03T00:54:45.0913Z","steps":["trace[1605638795] 'agreement among raft nodes before linearized reading' (duration: 298.386827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:45.091459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.46902ms","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-03T00:54:45.091479Z","caller":"traceutil/trace.go:171","msg":"trace[935125308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:691; }","duration":"298.526711ms","start":"2026-03-03T00:54:44.792946Z","end":"2026-03-03T00:54:45.091473Z","steps":["trace[935125308] 'agreement among raft nodes before linearized reading' (duration: 298.46602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:54:45.091633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.690155ms","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-03T00:54:45.091652Z","caller":"traceutil/trace.go:171","msg":"trace[1273296503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:691; }","duration":"298.720125ms","start":"2026-03-03T00:54:44.792926Z","end":"2026-03-03T00:54:45.091647Z","steps":["trace[1273296503] 'agreement among raft nodes before linearized reading' (duration: 298.657854ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:45.296524Z","caller":"traceutil/trace.go:171","msg":"trace[905493021] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"183.640007ms","start":"2026-03-03T00:54:45.112663Z","end":"2026-03-03T00:54:45.296303Z","steps":["trace[905493021] 'process raft request' (duration: 178.835462ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:45.300566Z","caller":"traceutil/trace.go:171","msg":"trace[2103772352] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"187.49554ms","start":"2026-03-03T00:54:45.11306Z","end":"2026-03-03T00:54:45.300555Z","steps":["trace[2103772352] 'process raft request' (duration: 187.414828ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:54:45.30079Z","caller":"traceutil/trace.go:171","msg":"trace[286083389] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"186.222359ms","start":"2026-03-03T00:54:45.11456Z","end":"2026-03-03T00:54:45.300783Z","steps":["trace[286083389] 'process raft request' (duration: 185.971923ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:09.581374Z","caller":"traceutil/trace.go:171","msg":"trace[219754839] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"114.900394ms","start":"2026-03-03T00:55:09.466453Z","end":"2026-03-03T00:55:09.581353Z","steps":["trace[219754839] 'read index received' (duration: 114.7075ms)","trace[219754839] 'applied index is now lower than readState.Index' (duration: 191.864µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:09.581444Z","caller":"traceutil/trace.go:171","msg":"trace[292056083] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"382.153689ms","start":"2026-03-03T00:55:09.199271Z","end":"2026-03-03T00:55:09.581424Z","steps":["trace[292056083] 'process raft request' (duration: 381.940114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:09.581582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.12372ms","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-03T00:55:09.581603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:09.199249Z","time spent":"382.258401ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:55:09.581658Z","caller":"traceutil/trace.go:171","msg":"trace[132917352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"115.246613ms","start":"2026-03-03T00:55:09.466393Z","end":"2026-03-03T00:55:09.581639Z","steps":["trace[132917352] 'agreement among raft nodes before linearized reading' (duration: 115.056809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:09.581953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.433696ms","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-03T00:55:09.582014Z","caller":"traceutil/trace.go:171","msg":"trace[421983102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"107.521559ms","start":"2026-03-03T00:55:09.474478Z","end":"2026-03-03T00:55:09.582Z","steps":["trace[421983102] 'agreement among raft nodes before linearized reading' (duration: 107.026367ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:10.328195Z","caller":"traceutil/trace.go:171","msg":"trace[373370822] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"153.955127ms","start":"2026-03-03T00:55:10.17421Z","end":"2026-03-03T00:55:10.328165Z","steps":["trace[373370822] 'process raft request' (duration: 111.36952ms)","trace[373370822] 'compare' (duration: 42.453834ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:10.470701Z","caller":"traceutil/trace.go:171","msg":"trace[1903465796] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"139.720938ms","start":"2026-03-03T00:55:10.330952Z","end":"2026-03-03T00:55:10.470673Z","steps":["trace[1903465796] 'read index received' (duration: 135.142288ms)","trace[1903465796] 'applied index is now lower than readState.Index' (duration: 4.57746ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:10.470849Z","caller":"traceutil/trace.go:171","msg":"trace[385099692] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"253.912026ms","start":"2026-03-03T00:55:10.216827Z","end":"2026-03-03T00:55:10.470739Z","steps":["trace[385099692] 'process raft request' (duration: 249.274735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:10.470904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.935473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-03-03T00:55:10.470993Z","caller":"traceutil/trace.go:171","msg":"trace[1875168324] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:769; }","duration":"140.072966ms","start":"2026-03-03T00:55:10.330901Z","end":"2026-03-03T00:55:10.470974Z","steps":["trace[1875168324] 'agreement among raft nodes before linearized reading' (duration: 139.885372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:12.171433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120118485647570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T00:55:12.671569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120118485647570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T00:55:12.883856Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29317024s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T00:55:12.884154Z","caller":"traceutil/trace.go:171","msg":"trace[2129916866] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"1.213135758s","start":"2026-03-03T00:55:11.670999Z","end":"2026-03-03T00:55:12.884134Z","steps":["trace[2129916866] 'read index received' (duration: 1.212961033s)","trace[2129916866] 'applied index is now lower than readState.Index' (duration: 173.845µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:12.884293Z","caller":"traceutil/trace.go:171","msg":"trace[859321124] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"1.293674922s","start":"2026-03-03T00:55:11.590573Z","end":"2026-03-03T00:55:12.884248Z","steps":["trace[859321124] 'process raft request' (duration: 1.293435267s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:12.884345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.213338983s","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-03T00:55:12.884381Z","caller":"traceutil/trace.go:171","msg":"trace[2064478601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:770; }","duration":"1.213426355s","start":"2026-03-03T00:55:11.670943Z","end":"2026-03-03T00:55:12.88437Z","steps":["trace[2064478601] 'agreement among raft nodes before linearized reading' (duration: 1.213277812s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:12.884437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:11.670916Z","time spent":"1.213488756s","remote":"127.0.0.1:35566","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-03T00:55:12.884467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:11.590555Z","time spent":"1.293818065s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:55:12.884739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208574889s","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-03T00:55:12.884771Z","caller":"traceutil/trace.go:171","msg":"trace[76753724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"1.208635981s","start":"2026-03-03T00:55:11.676126Z","end":"2026-03-03T00:55:12.884762Z","steps":["trace[76753724] 'agreement among raft nodes before linearized reading' (duration: 1.208534568s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:12.884797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:11.676109Z","time spent":"1.208682292s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T00:55:12.884926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170651253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:55:12.884954Z","caller":"traceutil/trace.go:171","msg":"trace[258081953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"1.170678194s","start":"2026-03-03T00:55:11.714268Z","end":"2026-03-03T00:55:12.884946Z","steps":["trace[258081953] 'agreement among raft nodes before linearized reading' (duration: 1.170638913s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:12.884978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:11.714206Z","time spent":"1.170766576s","remote":"127.0.0.1:35306","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-03T00:55:12.885165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208507078s","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-03T00:55:12.885199Z","caller":"traceutil/trace.go:171","msg":"trace[669321019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:770; }","duration":"1.20857617s","start":"2026-03-03T00:55:11.676613Z","end":"2026-03-03T00:55:12.885189Z","steps":["trace[669321019] 'agreement among raft nodes before linearized reading' (duration: 1.208482257s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:12.885231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:11.676594Z","time spent":"1.208628871s","remote":"127.0.0.1:35566","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":"warn","ts":"2026-03-03T00:55:12.885432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209236515s","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-03T00:55:12.885475Z","caller":"traceutil/trace.go:171","msg":"trace[1003318466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"1.209285246s","start":"2026-03-03T00:55:11.676177Z","end":"2026-03-03T00:55:12.885462Z","steps":["trace[1003318466] 'agreement among raft nodes before linearized reading' (duration: 1.209192224s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:12.885508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:11.67617Z","time spent":"1.209332067s","remote":"127.0.0.1:35566","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-03T00:55:13.396023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120118485647574,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T00:55:13.699076Z","caller":"traceutil/trace.go:171","msg":"trace[1413594377] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"808.945963ms","start":"2026-03-03T00:55:12.890099Z","end":"2026-03-03T00:55:13.699045Z","steps":["trace[1413594377] 'process raft request' (duration: 769.732266ms)","trace[1413594377] 'compare' (duration: 38.583332ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:55:13.699035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.548604ms","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-03T00:55:13.699193Z","caller":"traceutil/trace.go:171","msg":"trace[800455869] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:770; }","duration":"283.719698ms","start":"2026-03-03T00:55:13.415451Z","end":"2026-03-03T00:55:13.699171Z","steps":["trace[800455869] 'range keys from in-memory index tree' (duration: 283.531653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:13.699247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:12.890082Z","time spent":"809.067085ms","remote":"127.0.0.1:35566","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-03T00:55:13.764819Z","caller":"traceutil/trace.go:171","msg":"trace[1086146107] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"874.396626ms","start":"2026-03-03T00:55:12.890399Z","end":"2026-03-03T00:55:13.764795Z","steps":["trace[1086146107] 'process raft request' (duration: 874.232032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:13.765266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.649662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T00:55:13.765499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:12.890385Z","time spent":"874.917888ms","remote":"127.0.0.1:35566","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-03T00:55:13.765136Z","caller":"traceutil/trace.go:171","msg":"trace[2138432793] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"874.310263ms","start":"2026-03-03T00:55:12.890798Z","end":"2026-03-03T00:55:13.765108Z","steps":["trace[2138432793] 'process raft request' (duration: 873.954615ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:13.765138Z","caller":"traceutil/trace.go:171","msg":"trace[1487182338] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:802; }","duration":"869.52855ms","start":"2026-03-03T00:55:12.895582Z","end":"2026-03-03T00:55:13.765111Z","steps":["trace[1487182338] 'read index received' (duration: 764.229924ms)","trace[1487182338] 'applied index is now lower than readState.Index' (duration: 105.297575ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:55:13.765989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:12.890782Z","time spent":"874.884967ms","remote":"127.0.0.1:35566","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-03T00:55:13.76521Z","caller":"traceutil/trace.go:171","msg":"trace[200064007] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"874.251902ms","start":"2026-03-03T00:55:12.89095Z","end":"2026-03-03T00:55:13.765202Z","steps":["trace[200064007] 'process raft request' (duration: 874.049707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:13.766244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:12.890941Z","time spent":"875.217206ms","remote":"127.0.0.1:35566","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-03T00:55:13.765332Z","caller":"traceutil/trace.go:171","msg":"trace[2135085836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"869.754274ms","start":"2026-03-03T00:55:12.895563Z","end":"2026-03-03T00:55:13.765318Z","steps":["trace[2135085836] 'agreement among raft nodes before linearized reading' (duration: 869.636562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:13.766302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.625135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:55:13.76636Z","caller":"traceutil/trace.go:171","msg":"trace[1196524890] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:774; }","duration":"499.711867ms","start":"2026-03-03T00:55:13.266633Z","end":"2026-03-03T00:55:13.766345Z","steps":["trace[1196524890] 'agreement among raft nodes before linearized reading' (duration: 499.623285ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:13.766397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:13.266615Z","time spent":"499.772768ms","remote":"127.0.0.1:35840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-03T00:55:13.766383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:12.895557Z","time spent":"870.78036ms","remote":"127.0.0.1:35306","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-03T00:55:19.026244Z","caller":"traceutil/trace.go:171","msg":"trace[944208853] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"106.894934ms","start":"2026-03-03T00:55:18.919319Z","end":"2026-03-03T00:55:19.026214Z","steps":["trace[944208853] 'process raft request' (duration: 106.72997ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:19.965446Z","caller":"traceutil/trace.go:171","msg":"trace[1980687483] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"170.309928ms","start":"2026-03-03T00:55:19.795106Z","end":"2026-03-03T00:55:19.965416Z","steps":["trace[1980687483] 'read index received' (duration: 170.061812ms)","trace[1980687483] 'applied index is now lower than readState.Index' (duration: 247.206µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:19.965558Z","caller":"traceutil/trace.go:171","msg":"trace[1632432458] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"195.324946ms","start":"2026-03-03T00:55:19.770176Z","end":"2026-03-03T00:55:19.9655Z","steps":["trace[1632432458] 'process raft request' (duration: 195.06393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:19.965726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.592125ms","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-03T00:55:19.96577Z","caller":"traceutil/trace.go:171","msg":"trace[1765576343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:787; }","duration":"170.701037ms","start":"2026-03-03T00:55:19.795055Z","end":"2026-03-03T00:55:19.965756Z","steps":["trace[1765576343] 'agreement among raft nodes before linearized reading' (duration: 170.504013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:19.965887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.567827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-03T00:55:19.965928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.637178ms","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-03T00:55:19.965996Z","caller":"traceutil/trace.go:171","msg":"trace[1549601199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"168.69671ms","start":"2026-03-03T00:55:19.797276Z","end":"2026-03-03T00:55:19.965973Z","steps":["trace[1549601199] 'agreement among raft nodes before linearized reading' (duration: 168.463304ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:19.966015Z","caller":"traceutil/trace.go:171","msg":"trace[993773686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:787; }","duration":"168.817783ms","start":"2026-03-03T00:55:19.797174Z","end":"2026-03-03T00:55:19.965992Z","steps":["trace[993773686] 'agreement among raft nodes before linearized reading' (duration: 168.539276ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:20.076006Z","caller":"traceutil/trace.go:171","msg":"trace[580290290] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"105.651383ms","start":"2026-03-03T00:55:19.970323Z","end":"2026-03-03T00:55:20.075974Z","steps":["trace[580290290] 'process raft request' (duration: 105.590752ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:20.076051Z","caller":"traceutil/trace.go:171","msg":"trace[14437562] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"105.847989ms","start":"2026-03-03T00:55:19.970179Z","end":"2026-03-03T00:55:20.076027Z","steps":["trace[14437562] 'process raft request' (duration: 103.754639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:20.456379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.642101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:55:20.456464Z","caller":"traceutil/trace.go:171","msg":"trace[1834840561] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"349.936838ms","start":"2026-03-03T00:55:20.106514Z","end":"2026-03-03T00:55:20.456451Z","steps":["trace[1834840561] 'process raft request' (duration: 188.158624ms)","trace[1834840561] 'compare' (duration: 161.538359ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:55:20.456527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:20.106485Z","time spent":"349.99931ms","remote":"127.0.0.1:35332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:55:20.659397Z","caller":"traceutil/trace.go:171","msg":"trace[126691829] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"178.017952ms","start":"2026-03-03T00:55:20.481342Z","end":"2026-03-03T00:55:20.65936Z","steps":["trace[126691829] 'process raft request' (duration: 102.467457ms)","trace[126691829] 'compare' (duration: 75.411032ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:55:30.379854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120118485647789,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T00:55:30.446728Z","caller":"traceutil/trace.go:171","msg":"trace[868660732] linearizableReadLoop","detail":"{readStateIndex:902; appliedIndex:901; }","duration":"731.029323ms","start":"2026-03-03T00:55:29.715668Z","end":"2026-03-03T00:55:30.446698Z","steps":["trace[868660732] 'read index received' (duration: 730.918711ms)","trace[868660732] 'applied index is now lower than readState.Index' (duration: 109.382µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:30.446817Z","caller":"traceutil/trace.go:171","msg":"trace[111018127] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"873.586322ms","start":"2026-03-03T00:55:29.573207Z","end":"2026-03-03T00:55:30.446794Z","steps":["trace[111018127] 'process raft request' (duration: 873.290875ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:30.446898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.198407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T00:55:30.446959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:29.573179Z","time spent":"873.689465ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:55:30.447772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.4831ms","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-03T00:55:30.447838Z","caller":"traceutil/trace.go:171","msg":"trace[1416549393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:865; }","duration":"439.580303ms","start":"2026-03-03T00:55:30.008234Z","end":"2026-03-03T00:55:30.447815Z","steps":["trace[1416549393] 'agreement among raft nodes before linearized reading' (duration: 438.674671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:30.447881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:30.008219Z","time spent":"439.650384ms","remote":"127.0.0.1:35566","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-03T00:55:30.447066Z","caller":"traceutil/trace.go:171","msg":"trace[691497290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"731.376921ms","start":"2026-03-03T00:55:29.715661Z","end":"2026-03-03T00:55:30.447038Z","steps":["trace[691497290] 'agreement among raft nodes before linearized reading' (duration: 731.148766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:30.4483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:29.715604Z","time spent":"732.677212ms","remote":"127.0.0.1:35306","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-03T00:55:31.917714Z","caller":"traceutil/trace.go:171","msg":"trace[1539741217] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"342.454783ms","start":"2026-03-03T00:55:31.575233Z","end":"2026-03-03T00:55:31.917688Z","steps":["trace[1539741217] 'read index received' (duration: 342.235068ms)","trace[1539741217] 'applied index is now lower than readState.Index' (duration: 216.865µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:31.918108Z","caller":"traceutil/trace.go:171","msg":"trace[257153473] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"433.999149ms","start":"2026-03-03T00:55:31.484094Z","end":"2026-03-03T00:55:31.918093Z","steps":["trace[257153473] 'process raft request' (duration: 433.447396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:31.91823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:31.484072Z","time spent":"434.076422ms","remote":"127.0.0.1:35566","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-03T00:55:31.918491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.250082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T00:55:31.918539Z","caller":"traceutil/trace.go:171","msg":"trace[677354356] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:871; }","duration":"343.331424ms","start":"2026-03-03T00:55:31.575193Z","end":"2026-03-03T00:55:31.918524Z","steps":["trace[677354356] 'agreement among raft nodes before linearized reading' (duration: 343.240311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:31.918583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:31.575177Z","time spent":"343.392306ms","remote":"127.0.0.1:35350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-03-03T00:55:31.918838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.354327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:55:31.918919Z","caller":"traceutil/trace.go:171","msg":"trace[183848430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:871; }","duration":"206.46526ms","start":"2026-03-03T00:55:31.71244Z","end":"2026-03-03T00:55:31.918905Z","steps":["trace[183848430] 'agreement among raft nodes before linearized reading' (duration: 206.364427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:34.969845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120118485647825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T00:55:35.463263Z","caller":"traceutil/trace.go:171","msg":"trace[777031321] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"994.589769ms","start":"2026-03-03T00:55:34.468648Z","end":"2026-03-03T00:55:35.463238Z","steps":["trace[777031321] 'process raft request' (duration: 994.440436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:35.463424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:34.468636Z","time spent":"994.702442ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:55:36.714872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000177361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-03T00:55:36.714963Z","caller":"traceutil/trace.go:171","msg":"trace[416037857] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000326265s","start":"2026-03-03T00:55:34.714617Z","end":"2026-03-03T00:55:36.714943Z","steps":["trace[416037857] 'agreement among raft nodes before linearized reading' (duration: 2.000207572s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:36.715058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:34.714597Z","time spent":"2.000447398s","remote":"127.0.0.1:35306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-03T00:55:36.74963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044530958s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:55:36.749894Z","caller":"traceutil/trace.go:171","msg":"trace[1865803813] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:914; }","duration":"2.28104485s","start":"2026-03-03T00:55:34.468838Z","end":"2026-03-03T00:55:36.749883Z","steps":["trace[1865803813] 'read index received' (duration: 994.304142ms)","trace[1865803813] 'applied index is now lower than readState.Index' (duration: 1.286739668s)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:36.749956Z","caller":"traceutil/trace.go:171","msg":"trace[1428816397] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"2.278385317s","start":"2026-03-03T00:55:34.471564Z","end":"2026-03-03T00:55:36.749949Z","steps":["trace[1428816397] 'process raft request' (duration: 1.23347583s)","trace[1428816397] 'compare' (duration: 1.044334353s)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:55:36.749992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:34.471556Z","time spent":"2.278415478s","remote":"127.0.0.1:35566","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-03T00:55:36.75013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.281311326s","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-03T00:55:36.750145Z","caller":"traceutil/trace.go:171","msg":"trace[403649244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"2.281348498s","start":"2026-03-03T00:55:34.46879Z","end":"2026-03-03T00:55:36.750139Z","steps":["trace[403649244] 'agreement among raft nodes before linearized reading' (duration: 2.281268456s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:36.750159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:34.468779Z","time spent":"2.281377248s","remote":"127.0.0.1:35566","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-03T00:55:36.756167Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.051365641s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-03T00:55:36.817069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.347396538s","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-03T00:55:36.817144Z","caller":"traceutil/trace.go:171","msg":"trace[1105796820] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:878; }","duration":"2.347502551s","start":"2026-03-03T00:55:34.469625Z","end":"2026-03-03T00:55:36.817127Z","steps":["trace[1105796820] 'agreement among raft nodes before linearized reading' (duration: 2.347313706s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:36.817185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:34.469614Z","time spent":"2.347561942s","remote":"127.0.0.1:35566","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-03T00:55:36.817277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.268848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:55:36.817345Z","caller":"traceutil/trace.go:171","msg":"trace[1653185095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:878; }","duration":"101.399631ms","start":"2026-03-03T00:55:36.715928Z","end":"2026-03-03T00:55:36.817327Z","steps":["trace[1653185095] 'agreement among raft nodes before linearized reading' (duration: 101.296569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:36.817409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.149799629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T00:55:36.817463Z","caller":"traceutil/trace.go:171","msg":"trace[1578539506] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:878; }","duration":"2.149893571s","start":"2026-03-03T00:55:34.667556Z","end":"2026-03-03T00:55:36.81745Z","steps":["trace[1578539506] 'agreement among raft nodes before linearized reading' (duration: 2.149815599s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:36.817492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:34.667531Z","time spent":"2.149953283s","remote":"127.0.0.1:35754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-03T00:55:36.817747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.345517393s","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-03T00:55:36.817787Z","caller":"traceutil/trace.go:171","msg":"trace[875066487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"2.345571024s","start":"2026-03-03T00:55:34.472205Z","end":"2026-03-03T00:55:36.817776Z","steps":["trace[875066487] 'agreement among raft nodes before linearized reading' (duration: 2.345434471s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:36.817815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:34.4722Z","time spent":"2.345608116s","remote":"127.0.0.1:35566","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-03T00:55:47.734699Z","caller":"traceutil/trace.go:171","msg":"trace[574876006] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"198.436339ms","start":"2026-03-03T00:55:47.536237Z","end":"2026-03-03T00:55:47.734673Z","steps":["trace[574876006] 'process raft request' (duration: 198.176953ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:51.92645Z","caller":"traceutil/trace.go:171","msg":"trace[818346944] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"177.557533ms","start":"2026-03-03T00:55:51.748862Z","end":"2026-03-03T00:55:51.926419Z","steps":["trace[818346944] 'process raft request' (duration: 177.397619ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:53.803578Z","caller":"traceutil/trace.go:171","msg":"trace[1050599850] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"104.441143ms","start":"2026-03-03T00:55:53.699118Z","end":"2026-03-03T00:55:53.80356Z","steps":["trace[1050599850] 'read index received' (duration: 104.168257ms)","trace[1050599850] 'applied index is now lower than readState.Index' (duration: 271.876µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:53.803681Z","caller":"traceutil/trace.go:171","msg":"trace[234550305] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"120.208398ms","start":"2026-03-03T00:55:53.683404Z","end":"2026-03-03T00:55:53.803612Z","steps":["trace[234550305] 'process raft request' (duration: 119.974063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:53.80376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.635548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:55:53.803787Z","caller":"traceutil/trace.go:171","msg":"trace[2045490757] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:943; }","duration":"104.685139ms","start":"2026-03-03T00:55:53.699094Z","end":"2026-03-03T00:55:53.803779Z","steps":["trace[2045490757] 'agreement among raft nodes before linearized reading' (duration: 104.618768ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:54.977569Z","caller":"traceutil/trace.go:171","msg":"trace[859634628] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"141.642028ms","start":"2026-03-03T00:55:54.835892Z","end":"2026-03-03T00:55:54.977534Z","steps":["trace[859634628] 'process raft request' (duration: 141.484014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:56.587825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252408451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T00:55:56.587917Z","caller":"traceutil/trace.go:171","msg":"trace[356060225] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:953; }","duration":"1.252550894s","start":"2026-03-03T00:55:55.335341Z","end":"2026-03-03T00:55:56.587892Z","steps":["trace[356060225] 'count revisions from in-memory index tree' (duration: 1.252099174s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:56.588038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:55.33532Z","time spent":"1.252649696s","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-03T00:55:57.323231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220228956s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T00:55:57.323425Z","caller":"traceutil/trace.go:171","msg":"trace[325451569] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"1.611322316s","start":"2026-03-03T00:55:55.712077Z","end":"2026-03-03T00:55:57.3234Z","steps":["trace[325451569] 'read index received' (duration: 390.917715ms)","trace[325451569] 'applied index is now lower than readState.Index' (duration: 1.220402611s)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:55:57.324316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.612234717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:55:57.324364Z","caller":"traceutil/trace.go:171","msg":"trace[2014073715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"1.61232947s","start":"2026-03-03T00:55:55.712023Z","end":"2026-03-03T00:55:57.324352Z","steps":["trace[2014073715] 'agreement among raft nodes before linearized reading' (duration: 1.612241378s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:57.3244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:55.712002Z","time spent":"1.612390551s","remote":"127.0.0.1:35298","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-03T00:55:57.379993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.01056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:55:57.380067Z","caller":"traceutil/trace.go:171","msg":"trace[249019735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"193.147523ms","start":"2026-03-03T00:55:57.186902Z","end":"2026-03-03T00:55:57.380049Z","steps":["trace[249019735] 'agreement among raft nodes before linearized reading' (duration: 192.991549ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:57.38008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.440128686s","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-03T00:55:57.380157Z","caller":"traceutil/trace.go:171","msg":"trace[1945317499] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:954; }","duration":"1.440273539s","start":"2026-03-03T00:55:55.939865Z","end":"2026-03-03T00:55:57.380139Z","steps":["trace[1945317499] 'agreement among raft nodes before linearized reading' (duration: 1.440047984s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:57.38016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.143302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-03T00:55:57.380204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:55.939821Z","time spent":"1.440375141s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-03T00:55:57.380236Z","caller":"traceutil/trace.go:171","msg":"trace[602087144] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"243.284125ms","start":"2026-03-03T00:55:57.136937Z","end":"2026-03-03T00:55:57.380221Z","steps":["trace[602087144] 'agreement among raft nodes before linearized reading' (duration: 243.09087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:57.380301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.569899ms","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-03T00:55:57.380385Z","caller":"traceutil/trace.go:171","msg":"trace[1551651448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"388.673322ms","start":"2026-03-03T00:55:56.991673Z","end":"2026-03-03T00:55:57.380346Z","steps":["trace[1551651448] 'agreement among raft nodes before linearized reading' (duration: 388.566699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:57.38042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:56.991657Z","time spent":"388.754614ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T00:55:57.38058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.267729ms","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-03T00:55:57.38062Z","caller":"traceutil/trace.go:171","msg":"trace[1584784750] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"350.342951ms","start":"2026-03-03T00:55:57.030266Z","end":"2026-03-03T00:55:57.380609Z","steps":["trace[1584784750] 'agreement among raft nodes before linearized reading' (duration: 349.612174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:57.380641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:57.030248Z","time spent":"350.387702ms","remote":"127.0.0.1:35566","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-03T00:55:57.380755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.541016ms","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-03T00:55:57.380811Z","caller":"traceutil/trace.go:171","msg":"trace[849011075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"308.602378ms","start":"2026-03-03T00:55:57.072179Z","end":"2026-03-03T00:55:57.380781Z","steps":["trace[849011075] 'agreement among raft nodes before linearized reading' (duration: 307.699047ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:57.380839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:57.072165Z","time spent":"308.6681ms","remote":"127.0.0.1:35566","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-03T00:55:57.522948Z","caller":"traceutil/trace.go:171","msg":"trace[1398957118] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"137.665373ms","start":"2026-03-03T00:55:57.385173Z","end":"2026-03-03T00:55:57.522839Z","steps":["trace[1398957118] 'process raft request' (duration: 137.625122ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:57.523378Z","caller":"traceutil/trace.go:171","msg":"trace[1158508975] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"139.077937ms","start":"2026-03-03T00:55:57.384288Z","end":"2026-03-03T00:55:57.523366Z","steps":["trace[1158508975] 'process raft request' (duration: 138.283858ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:57.523512Z","caller":"traceutil/trace.go:171","msg":"trace[72249816] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"138.588085ms","start":"2026-03-03T00:55:57.384915Z","end":"2026-03-03T00:55:57.523503Z","steps":["trace[72249816] 'process raft request' (duration: 137.805047ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:57.523619Z","caller":"traceutil/trace.go:171","msg":"trace[236476151] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"138.599276ms","start":"2026-03-03T00:55:57.385013Z","end":"2026-03-03T00:55:57.523613Z","steps":["trace[236476151] 'process raft request' (duration: 137.756386ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:58.277434Z","caller":"traceutil/trace.go:171","msg":"trace[204626892] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"102.464366ms","start":"2026-03-03T00:55:58.174947Z","end":"2026-03-03T00:55:58.277411Z","steps":["trace[204626892] 'process raft request' (duration: 101.935964ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:58.440538Z","caller":"traceutil/trace.go:171","msg":"trace[1457336462] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"112.080885ms","start":"2026-03-03T00:55:58.328437Z","end":"2026-03-03T00:55:58.440518Z","steps":["trace[1457336462] 'process raft request' (duration: 81.063417ms)","trace[1457336462] 'compare' (duration: 30.918535ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:58.459848Z","caller":"traceutil/trace.go:171","msg":"trace[34470828] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"125.838842ms","start":"2026-03-03T00:55:58.333984Z","end":"2026-03-03T00:55:58.459823Z","steps":["trace[34470828] 'process raft request' (duration: 125.793171ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:58.460148Z","caller":"traceutil/trace.go:171","msg":"trace[320324287] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"129.653833ms","start":"2026-03-03T00:55:58.330463Z","end":"2026-03-03T00:55:58.460117Z","steps":["trace[320324287] 'process raft request' (duration: 129.156701ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:58.995454Z","caller":"traceutil/trace.go:171","msg":"trace[465365427] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"280.410496ms","start":"2026-03-03T00:55:58.715017Z","end":"2026-03-03T00:55:58.995427Z","steps":["trace[465365427] 'read index received' (duration: 213.69134ms)","trace[465365427] 'applied index is now lower than readState.Index' (duration: 66.717376ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:55:58.995492Z","caller":"traceutil/trace.go:171","msg":"trace[1753861504] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"531.403205ms","start":"2026-03-03T00:55:58.464063Z","end":"2026-03-03T00:55:58.995466Z","steps":["trace[1753861504] 'process raft request' (duration: 464.71719ms)","trace[1753861504] 'compare' (duration: 66.310656ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:55:58.995581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.54983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:55:58.995613Z","caller":"traceutil/trace.go:171","msg":"trace[1182870648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"280.614631ms","start":"2026-03-03T00:55:58.714987Z","end":"2026-03-03T00:55:58.995602Z","steps":["trace[1182870648] 'agreement among raft nodes before linearized reading' (duration: 280.530589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:58.995615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:58.464042Z","time spent":"531.503038ms","remote":"127.0.0.1:35384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:55:59.813325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.685414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-03T00:55:59.813393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.555931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-03T00:55:59.813465Z","caller":"traceutil/trace.go:171","msg":"trace[1427151357] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"285.659364ms","start":"2026-03-03T00:55:59.527786Z","end":"2026-03-03T00:55:59.813445Z","steps":["trace[1427151357] 'range keys from in-memory index tree' (duration: 285.345756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:59.813494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.307728ms","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-03T00:55:59.813413Z","caller":"traceutil/trace.go:171","msg":"trace[2140689510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:966; }","duration":"285.843347ms","start":"2026-03-03T00:55:59.527549Z","end":"2026-03-03T00:55:59.813392Z","steps":["trace[2140689510] 'range keys from in-memory index tree' (duration: 285.472349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:59.813523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.001851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-03T00:55:59.813557Z","caller":"traceutil/trace.go:171","msg":"trace[1879289214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"286.065603ms","start":"2026-03-03T00:55:59.527481Z","end":"2026-03-03T00:55:59.813547Z","steps":["trace[1879289214] 'range keys from in-memory index tree' (duration: 285.876109ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:55:59.813553Z","caller":"traceutil/trace.go:171","msg":"trace[455599966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"389.404981ms","start":"2026-03-03T00:55:59.424131Z","end":"2026-03-03T00:55:59.813536Z","steps":["trace[455599966] 'range keys from in-memory index tree' (duration: 389.149925ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:55:59.813325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.934976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-03T00:55:59.813618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:59.424113Z","time spent":"389.491502ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T00:55:59.813683Z","caller":"traceutil/trace.go:171","msg":"trace[1122577754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"285.320945ms","start":"2026-03-03T00:55:59.528318Z","end":"2026-03-03T00:55:59.813639Z","steps":["trace[1122577754] 'range keys from in-memory index tree' (duration: 284.773572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:00.188336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.553455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:56:00.188635Z","caller":"traceutil/trace.go:171","msg":"trace[3935818] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"356.380345ms","start":"2026-03-03T00:55:59.832235Z","end":"2026-03-03T00:56:00.188616Z","steps":["trace[3935818] 'process raft request' (duration: 356.214041ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:56:00.188643Z","caller":"traceutil/trace.go:171","msg":"trace[1364514880] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"357.014771ms","start":"2026-03-03T00:55:59.831597Z","end":"2026-03-03T00:56:00.188611Z","steps":["trace[1364514880] 'process raft request' (duration: 255.073827ms)","trace[1364514880] 'compare' (duration: 101.271648ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:56:00.18873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:59.832222Z","time spent":"356.469448ms","remote":"127.0.0.1:35566","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-03T00:56:00.188797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:59.83158Z","time spent":"357.142704ms","remote":"127.0.0.1:35566","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-03T00:56:00.188926Z","caller":"traceutil/trace.go:171","msg":"trace[947158628] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"356.638642ms","start":"2026-03-03T00:55:59.832273Z","end":"2026-03-03T00:56:00.188912Z","steps":["trace[947158628] 'process raft request' (duration: 356.287514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:00.189006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:59.832269Z","time spent":"356.695454ms","remote":"127.0.0.1:35566","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-03T00:56:00.189002Z","caller":"traceutil/trace.go:171","msg":"trace[177861798] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"356.428497ms","start":"2026-03-03T00:55:59.832559Z","end":"2026-03-03T00:56:00.188988Z","steps":["trace[177861798] 'process raft request' (duration: 356.293414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:00.197913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:55:59.83255Z","time spent":"356.487168ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:56:02.020388Z","caller":"traceutil/trace.go:171","msg":"trace[1892292355] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"101.192786ms","start":"2026-03-03T00:56:01.919174Z","end":"2026-03-03T00:56:02.020367Z","steps":["trace[1892292355] 'process raft request' (duration: 101.064583ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:05.395003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.311961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:56:05.395278Z","caller":"traceutil/trace.go:171","msg":"trace[1984097679] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"527.748139ms","start":"2026-03-03T00:56:04.867508Z","end":"2026-03-03T00:56:05.395256Z","steps":["trace[1984097679] 'process raft request' (duration: 113.08582ms)","trace[1984097679] 'compare' (duration: 414.165177ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:56:05.395449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:04.867488Z","time spent":"527.923132ms","remote":"127.0.0.1:35572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:56:05.419097Z","caller":"traceutil/trace.go:171","msg":"trace[524998523] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"546.529635ms","start":"2026-03-03T00:56:04.872543Z","end":"2026-03-03T00:56:05.419073Z","steps":["trace[524998523] 'process raft request' (duration: 546.465534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:05.419257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:04.872506Z","time spent":"546.666949ms","remote":"127.0.0.1:35814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10921,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:56:05.419226Z","caller":"traceutil/trace.go:171","msg":"trace[1444108232] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"551.036822ms","start":"2026-03-03T00:56:04.868152Z","end":"2026-03-03T00:56:05.419189Z","steps":["trace[1444108232] 'process raft request' (duration: 550.632712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:05.419477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:04.868126Z","time spent":"551.277058ms","remote":"127.0.0.1:35486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:56:05.78803Z","caller":"traceutil/trace.go:171","msg":"trace[1752118300] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"359.664923ms","start":"2026-03-03T00:56:05.428333Z","end":"2026-03-03T00:56:05.787998Z","steps":["trace[1752118300] 'process raft request' (duration: 310.599567ms)","trace[1752118300] 'compare' (duration: 48.745229ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:56:05.788186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:05.428314Z","time spent":"359.791886ms","remote":"127.0.0.1:35774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11231,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:56:06.249634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.890043ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T00:56:06.24974Z","caller":"traceutil/trace.go:171","msg":"trace[1311679691] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"216.415566ms","start":"2026-03-03T00:56:06.033307Z","end":"2026-03-03T00:56:06.249723Z","steps":["trace[1311679691] 'read index received' (duration: 45.782µs)","trace[1311679691] 'applied index is now lower than readState.Index' (duration: 216.368444ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:56:06.250058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.121553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-03T00:56:06.250108Z","caller":"traceutil/trace.go:171","msg":"trace[1241429612] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1017; }","duration":"119.214005ms","start":"2026-03-03T00:56:06.130881Z","end":"2026-03-03T00:56:06.250095Z","steps":["trace[1241429612] 'agreement among raft nodes before linearized reading' (duration: 119.059351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:06.250174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.879437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-03T00:56:06.250196Z","caller":"traceutil/trace.go:171","msg":"trace[752521617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1017; }","duration":"216.939638ms","start":"2026-03-03T00:56:06.033251Z","end":"2026-03-03T00:56:06.250191Z","steps":["trace[752521617] 'agreement among raft nodes before linearized reading' (duration: 216.891137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:07.475044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.275332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:56:07.475407Z","caller":"traceutil/trace.go:171","msg":"trace[1304914491] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1063; }","duration":"1.029653844s","start":"2026-03-03T00:56:06.445731Z","end":"2026-03-03T00:56:07.475385Z","steps":["trace[1304914491] 'read index received' (duration: 312.87111ms)","trace[1304914491] 'applied index is now lower than readState.Index' (duration: 716.781364ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:56:07.47565Z","caller":"traceutil/trace.go:171","msg":"trace[924572316] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.219895617s","start":"2026-03-03T00:56:06.255724Z","end":"2026-03-03T00:56:07.475619Z","steps":["trace[924572316] 'process raft request' (duration: 1.21961058s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:07.475716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.034812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:56:07.475805Z","caller":"traceutil/trace.go:171","msg":"trace[1511592330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"760.151845ms","start":"2026-03-03T00:56:06.715618Z","end":"2026-03-03T00:56:07.47577Z","steps":["trace[1511592330] 'agreement among raft nodes before linearized reading' (duration: 760.039512ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:07.475847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:06.715598Z","time spent":"760.240647ms","remote":"127.0.0.1:35306","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-03T00:56:07.47584Z","caller":"traceutil/trace.go:171","msg":"trace[1716588921] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"1.221339522s","start":"2026-03-03T00:56:06.254475Z","end":"2026-03-03T00:56:07.475814Z","steps":["trace[1716588921] 'process raft request' (duration: 1.220675726s)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:56:07.475947Z","caller":"traceutil/trace.go:171","msg":"trace[1665704087] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.221169088s","start":"2026-03-03T00:56:06.254764Z","end":"2026-03-03T00:56:07.475933Z","steps":["trace[1665704087] 'process raft request' (duration: 1.220489732s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:07.475951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:06.254455Z","time spent":"1.221440764s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:56:07.475802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:06.25571Z","time spent":"1.220004881s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:56:07.476005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:06.254748Z","time spent":"1.221227429s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:56:07.475997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030238407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-03T00:56:07.47603Z","caller":"traceutil/trace.go:171","msg":"trace[1804264634] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"1.220544243s","start":"2026-03-03T00:56:06.255468Z","end":"2026-03-03T00:56:07.476012Z","steps":["trace[1804264634] 'process raft request' (duration: 1.219829116s)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:56:07.476086Z","caller":"traceutil/trace.go:171","msg":"trace[1892301745] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1023; }","duration":"1.030383911s","start":"2026-03-03T00:56:06.445676Z","end":"2026-03-03T00:56:07.47606Z","steps":["trace[1892301745] 'agreement among raft nodes before linearized reading' (duration: 1.030121515s)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:56:07.476123Z","caller":"traceutil/trace.go:171","msg":"trace[552876336] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.221696981s","start":"2026-03-03T00:56:06.254406Z","end":"2026-03-03T00:56:07.476103Z","steps":["trace[552876336] 'process raft request' (duration: 504.246892ms)","trace[552876336] 'compare' (duration: 716.082856ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:56:07.476138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:06.255457Z","time spent":"1.220630145s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:56:07.476142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:06.445653Z","time spent":"1.030476833s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-03T00:56:07.476207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:06.254374Z","time spent":"1.221788692s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:56:08.206698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3219120118485648125,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T00:56:08.48893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.462499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:56:08.489161Z","caller":"traceutil/trace.go:171","msg":"trace[637948631] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1068; }","duration":"782.682941ms","start":"2026-03-03T00:56:07.706458Z","end":"2026-03-03T00:56:08.489141Z","steps":["trace[637948631] 'read index received' (duration: 681.944336ms)","trace[637948631] 'applied index is now lower than readState.Index' (duration: 100.737385ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:56:08.489431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.972918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-03T00:56:08.489482Z","caller":"traceutil/trace.go:171","msg":"trace[119248101] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1025; }","duration":"783.06336ms","start":"2026-03-03T00:56:07.706404Z","end":"2026-03-03T00:56:08.489467Z","steps":["trace[119248101] 'agreement among raft nodes before linearized reading' (duration: 782.951937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:08.489504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:07.706386Z","time spent":"783.113512ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-03T00:56:08.489501Z","caller":"traceutil/trace.go:171","msg":"trace[1056050646] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"956.209317ms","start":"2026-03-03T00:56:07.533273Z","end":"2026-03-03T00:56:08.489482Z","steps":["trace[1056050646] 'process raft request' (duration: 955.765767ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:56:08.489503Z","caller":"traceutil/trace.go:171","msg":"trace[535612354] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.00888908s","start":"2026-03-03T00:56:07.480589Z","end":"2026-03-03T00:56:08.489478Z","steps":["trace[535612354] 'process raft request' (duration: 907.799327ms)","trace[535612354] 'compare' (duration: 100.11172ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:56:08.489601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:07.533243Z","time spent":"956.29975ms","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:56:08.489698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:56:07.480575Z","time spent":"1.008988202s","remote":"127.0.0.1:35566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:56:08.489831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.649709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-03-03T00:56:08.489896Z","caller":"traceutil/trace.go:171","msg":"trace[1174513082] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1025; }","duration":"152.749882ms","start":"2026-03-03T00:56:08.337129Z","end":"2026-03-03T00:56:08.489879Z","steps":["trace[1174513082] 'agreement among raft nodes before linearized reading' (duration: 152.347582ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:56:08.751951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.942499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:56:08.752092Z","caller":"traceutil/trace.go:171","msg":"trace[207263720] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"257.555274ms","start":"2026-03-03T00:56:08.494519Z","end":"2026-03-03T00:56:08.752074Z","steps":["trace[207263720] 'process raft request' (duration: 119.41832ms)","trace[207263720] 'compare' (duration: 137.836756ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:56:08.752475Z","caller":"traceutil/trace.go:171","msg":"trace[1030975452] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"257.778709ms","start":"2026-03-03T00:56:08.494672Z","end":"2026-03-03T00:56:08.752451Z","steps":["trace[1030975452] 'process raft request' (duration: 257.692316ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:56:08.752488Z","caller":"traceutil/trace.go:171","msg":"trace[207476058] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"257.83327ms","start":"2026-03-03T00:56:08.494625Z","end":"2026-03-03T00:56:08.752458Z","steps":["trace[207476058] 'process raft request' (duration: 257.41579ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:56:17.646973Z","caller":"traceutil/trace.go:171","msg":"trace[570543159] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"100.193993ms","start":"2026-03-03T00:56:17.546748Z","end":"2026-03-03T00:56:17.646942Z","steps":["trace[570543159] 'process raft request' (duration: 48.35544ms)","trace[570543159] 'compare' (duration: 51.587597ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:56:47.954727Z","caller":"traceutil/trace.go:171","msg":"trace[1124618277] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"122.148591ms","start":"2026-03-03T00:56:47.832549Z","end":"2026-03-03T00:56:47.954698Z","steps":["trace[1124618277] 'process raft request' (duration: 31.682112ms)","trace[1124618277] 'compare' (duration: 90.281464ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:56:47.954871Z","caller":"traceutil/trace.go:171","msg":"trace[1836516059] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"122.161371ms","start":"2026-03-03T00:56:47.832677Z","end":"2026-03-03T00:56:47.954838Z","steps":["trace[1836516059] 'process raft request' (duration: 121.980257ms)"],"step_count":1}