{"level":"warn","ts":"2026-02-25T06:10:18.630017Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T06:10:18.630159Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162: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.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T06:10:18.630278Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T06:10:18.630292Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-02-25T06:10:18.630321Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T06:10:18.6321Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-02-25T06:10:18.632243Z","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.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T06:10:18.639437Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.631687ms"} {"level":"info","ts":"2026-02-25T06:10:18.648553Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-02-25T06:10:18.648673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:10:18.648712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-02-25T06:10:18.648741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:10:18.648756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-02-25T06:10:18.648836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-02-25T06:10:18.657633Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:10:18.660003Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:10:18.662761Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T06:10:18.665769Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:10:18.665987Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:10:18.666188Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T06:10:18.666269Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T06:10:18.666287Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T06:10:18.670116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-02-25T06:10:18.670519Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-02-25T06:10:18.674848Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T06:10:18.675088Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-02-25T06:10:18.675152Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:10:18.675153Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-02-25T06:10:18.67521Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:10:19.049247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:10:19.049325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:10:19.049349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-02-25T06:10:19.049369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:10:19.04938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-02-25T06:10:19.049396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-02-25T06:10:19.04941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-02-25T06:10:19.05103Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:10:19.051272Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:10:19.051299Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:10:19.051515Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:19.051502Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:10:19.051597Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:10:19.052264Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:19.05236Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:19.052383Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:19.053467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:10:19.075493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-02-25T06:10:54.48388Z","caller":"traceutil/trace.go:171","msg":"trace[1029463616] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"153.361975ms","start":"2026-02-25T06:10:54.330492Z","end":"2026-02-25T06:10:54.483854Z","steps":["trace[1029463616] 'process raft request' (duration: 153.152989ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:54.783103Z","caller":"traceutil/trace.go:171","msg":"trace[1012445226] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"124.573084ms","start":"2026-02-25T06:10:54.658503Z","end":"2026-02-25T06:10:54.783076Z","steps":["trace[1012445226] 'process raft request' (duration: 124.399919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:55.000154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.760662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:10:55.000272Z","caller":"traceutil/trace.go:171","msg":"trace[190002391] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"128.935917ms","start":"2026-02-25T06:10:54.871316Z","end":"2026-02-25T06:10:55.000252Z","steps":["trace[190002391] 'range keys from in-memory index tree' (duration: 128.584926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:55.587465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:10:56.088549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512193,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:10:56.551086Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.526239926s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T06:10:56.554806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267374335s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:56.554994Z","caller":"traceutil/trace.go:171","msg":"trace[503335789] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:527; }","duration":"1.267603921s","start":"2026-02-25T06:10:55.287364Z","end":"2026-02-25T06:10:56.554968Z","steps":["trace[503335789] 'range keys from in-memory index tree' (duration: 1.266588982s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:56.555293Z","caller":"traceutil/trace.go:171","msg":"trace[1858656085] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"1.467962481s","start":"2026-02-25T06:10:55.087297Z","end":"2026-02-25T06:10:56.55526Z","steps":["trace[1858656085] 'read index received' (duration: 1.464182105s)","trace[1858656085] 'applied index is now lower than readState.Index' (duration: 3.778976ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:56.555491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468197899s","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-02-25T06:10:56.55552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22681854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:56.555539Z","caller":"traceutil/trace.go:171","msg":"trace[606105941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"1.46824763s","start":"2026-02-25T06:10:55.087278Z","end":"2026-02-25T06:10:56.555526Z","steps":["trace[606105941] 'agreement among raft nodes before linearized reading' (duration: 1.468064865s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:56.555578Z","caller":"traceutil/trace.go:171","msg":"trace[2016233440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"1.226907273s","start":"2026-02-25T06:10:55.328653Z","end":"2026-02-25T06:10:56.55556Z","steps":["trace[2016233440] 'agreement among raft nodes before linearized reading' (duration: 1.226774779s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:56.555592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:55.087218Z","time spent":"1.468365123s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:10:56.555653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:55.3286Z","time spent":"1.227039787s","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:10:56.555672Z","caller":"traceutil/trace.go:171","msg":"trace[822153728] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.538238954s","start":"2026-02-25T06:10:55.017174Z","end":"2026-02-25T06:10:56.555413Z","steps":["trace[822153728] 'process raft request' (duration: 1.535096755s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:56.557146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:55.017164Z","time spent":"1.539010755s","remote":"127.0.0.1:34422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:15.242283Z","caller":"traceutil/trace.go:171","msg":"trace[928597215] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"109.377685ms","start":"2026-02-25T06:11:15.132879Z","end":"2026-02-25T06:11:15.242257Z","steps":["trace[928597215] 'read index received' (duration: 109.19735ms)","trace[928597215] 'applied index is now lower than readState.Index' (duration: 179.385µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:15.24234Z","caller":"traceutil/trace.go:171","msg":"trace[1076582784] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"112.897955ms","start":"2026-02-25T06:11:15.129418Z","end":"2026-02-25T06:11:15.242316Z","steps":["trace[1076582784] 'process raft request' (duration: 112.665978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:15.242484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.587291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T06:11:15.242579Z","caller":"traceutil/trace.go:171","msg":"trace[491914082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"109.716695ms","start":"2026-02-25T06:11:15.132849Z","end":"2026-02-25T06:11:15.242566Z","steps":["trace[491914082] 'agreement among raft nodes before linearized reading' (duration: 109.510749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.339511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512553,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:17.840188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512553,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:11:17.871575Z","caller":"traceutil/trace.go:171","msg":"trace[428563293] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.069246487s","start":"2026-02-25T06:11:16.80231Z","end":"2026-02-25T06:11:17.871556Z","steps":["trace[428563293] 'process raft request' (duration: 1.069127424s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.87168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:16.802289Z","time spent":"1.069326079s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:17.99099Z","caller":"traceutil/trace.go:171","msg":"trace[2069766572] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:737; }","duration":"1.152468884s","start":"2026-02-25T06:11:16.8385Z","end":"2026-02-25T06:11:17.990969Z","steps":["trace[2069766572] 'read index received' (duration: 1.032966743s)","trace[2069766572] 'applied index is now lower than readState.Index' (duration: 119.500761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:17.99121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.100046736s","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-02-25T06:11:17.99124Z","caller":"traceutil/trace.go:171","msg":"trace[1485276392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:716; }","duration":"1.100153608s","start":"2026-02-25T06:11:16.891079Z","end":"2026-02-25T06:11:17.991233Z","steps":["trace[1485276392] 'agreement among raft nodes before linearized reading' (duration: 1.100002774s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.991267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:16.891043Z","time spent":"1.10021777s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:17.991345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152789783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:11:17.991473Z","caller":"traceutil/trace.go:171","msg":"trace[1124137969] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:716; }","duration":"1.152973808s","start":"2026-02-25T06:11:16.83847Z","end":"2026-02-25T06:11:17.991443Z","steps":["trace[1124137969] 'agreement among raft nodes before linearized reading' (duration: 1.152571107s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.991517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.597095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T06:11:17.991539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:16.838457Z","time spent":"1.153071292s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:17.991611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.210541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T06:11:17.991635Z","caller":"traceutil/trace.go:171","msg":"trace[1333403267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:716; }","duration":"743.280293ms","start":"2026-02-25T06:11:17.248349Z","end":"2026-02-25T06:11:17.991629Z","steps":["trace[1333403267] 'agreement among raft nodes before linearized reading' (duration: 743.224172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.991658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:17.248323Z","time spent":"743.331225ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:11:17.991557Z","caller":"traceutil/trace.go:171","msg":"trace[1285333413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:716; }","duration":"730.661017ms","start":"2026-02-25T06:11:17.260889Z","end":"2026-02-25T06:11:17.99155Z","steps":["trace[1285333413] 'agreement among raft nodes before linearized reading' (duration: 730.575255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.99173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:17.260875Z","time spent":"730.850092ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T06:11:18.179163Z","caller":"traceutil/trace.go:171","msg":"trace[1853480729] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"182.367534ms","start":"2026-02-25T06:11:17.996775Z","end":"2026-02-25T06:11:18.179143Z","steps":["trace[1853480729] 'process raft request' (duration: 182.261071ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:18.179209Z","caller":"traceutil/trace.go:171","msg":"trace[1797426184] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"182.728993ms","start":"2026-02-25T06:11:17.996423Z","end":"2026-02-25T06:11:18.179152Z","steps":["trace[1797426184] 'process raft request' (duration: 182.495837ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:18.179347Z","caller":"traceutil/trace.go:171","msg":"trace[1295129022] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"182.126097ms","start":"2026-02-25T06:11:17.99718Z","end":"2026-02-25T06:11:18.179306Z","steps":["trace[1295129022] 'process raft request' (duration: 181.90071ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:42.208194Z","caller":"traceutil/trace.go:171","msg":"trace[454829466] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"224.109517ms","start":"2026-02-25T06:11:41.984049Z","end":"2026-02-25T06:11:42.208159Z","steps":["trace[454829466] 'read index received' (duration: 223.8293ms)","trace[454829466] 'applied index is now lower than readState.Index' (duration: 278.957µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:42.208312Z","caller":"traceutil/trace.go:171","msg":"trace[231561094] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"309.402999ms","start":"2026-02-25T06:11:41.898875Z","end":"2026-02-25T06:11:42.208278Z","steps":["trace[231561094] 'process raft request' (duration: 309.08623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:42.208506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:41.898827Z","time spent":"309.579043ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:42.208733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.602671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T06:11:42.208915Z","caller":"traceutil/trace.go:171","msg":"trace[1610900786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"224.869448ms","start":"2026-02-25T06:11:41.983984Z","end":"2026-02-25T06:11:42.208854Z","steps":["trace[1610900786] 'agreement among raft nodes before linearized reading' (duration: 224.356384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:42.692896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.660899ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-25T06:11:42.693031Z","caller":"traceutil/trace.go:171","msg":"trace[729336669] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"479.039814ms","start":"2026-02-25T06:11:42.213969Z","end":"2026-02-25T06:11:42.693009Z","steps":["trace[729336669] 'read index received' (duration: 26.923733ms)","trace[729336669] 'applied index is now lower than readState.Index' (duration: 452.113551ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:42.693156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.209863Z","time spent":"483.287508ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T06:11:42.693583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.603059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T06:11:42.693656Z","caller":"traceutil/trace.go:171","msg":"trace[507400684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"479.715252ms","start":"2026-02-25T06:11:42.213923Z","end":"2026-02-25T06:11:42.693639Z","steps":["trace[507400684] 'agreement among raft nodes before linearized reading' (duration: 479.452335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:42.693695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.213899Z","time spent":"479.787725ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:43.248607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.548626ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:43.248916Z","caller":"traceutil/trace.go:171","msg":"trace[561256715] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"555.815525ms","start":"2026-02-25T06:11:42.693062Z","end":"2026-02-25T06:11:43.248878Z","steps":["trace[561256715] 'read index received' (duration: 154.978842ms)","trace[561256715] 'applied index is now lower than readState.Index' (duration: 400.835093ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:43.248913Z","caller":"traceutil/trace.go:171","msg":"trace[1658925539] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"551.406857ms","start":"2026-02-25T06:11:42.697488Z","end":"2026-02-25T06:11:43.248895Z","steps":["trace[1658925539] 'process raft request' (duration: 551.352665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:43.24915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.675628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:11:43.249188Z","caller":"traceutil/trace.go:171","msg":"trace[678322374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"966.715699ms","start":"2026-02-25T06:11:42.282458Z","end":"2026-02-25T06:11:43.249174Z","steps":["trace[678322374] 'agreement among raft nodes before linearized reading' (duration: 966.524104ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:43.249185Z","caller":"traceutil/trace.go:171","msg":"trace[2102469642] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"554.477909ms","start":"2026-02-25T06:11:42.694665Z","end":"2026-02-25T06:11:43.249143Z","steps":["trace[2102469642] 'process raft request' (duration: 554.10996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:43.24932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.588961ms","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-02-25T06:11:43.249373Z","caller":"traceutil/trace.go:171","msg":"trace[509840467] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:791; }","duration":"583.675993ms","start":"2026-02-25T06:11:42.665688Z","end":"2026-02-25T06:11:43.249364Z","steps":["trace[509840467] 'agreement among raft nodes before linearized reading' (duration: 583.600051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:43.249401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.025687ms","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-02-25T06:11:43.249403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.694638Z","time spent":"554.644764ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:43.249443Z","caller":"traceutil/trace.go:171","msg":"trace[1713183521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"961.099139ms","start":"2026-02-25T06:11:42.288333Z","end":"2026-02-25T06:11:43.249432Z","steps":["trace[1713183521] 'agreement among raft nodes before linearized reading' (duration: 960.993856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:43.249474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.288318Z","time spent":"961.14945ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:11:43.249493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.106817ms","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-02-25T06:11:43.24952Z","caller":"traceutil/trace.go:171","msg":"trace[1969412667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"965.185998ms","start":"2026-02-25T06:11:42.284325Z","end":"2026-02-25T06:11:43.249511Z","steps":["trace[1969412667] 'agreement among raft nodes before linearized reading' (duration: 965.098956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:43.249546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.284315Z","time spent":"965.22539ms","remote":"127.0.0.1:34498","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":"info","ts":"2026-02-25T06:11:43.24953Z","caller":"traceutil/trace.go:171","msg":"trace[1209499356] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"1.03485411s","start":"2026-02-25T06:11:42.213991Z","end":"2026-02-25T06:11:43.248846Z","steps":["trace[1209499356] 'process raft request' (duration: 633.948014ms)","trace[1209499356] 'compare' (duration: 400.304019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:43.249239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.282382Z","time spent":"966.849823ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:43.2497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.225478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:43.249743Z","caller":"traceutil/trace.go:171","msg":"trace[1651706938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"921.2861ms","start":"2026-02-25T06:11:42.328444Z","end":"2026-02-25T06:11:43.24973Z","steps":["trace[1651706938] 'agreement among raft nodes before linearized reading' (duration: 921.232639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:43.249809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.328431Z","time spent":"921.372412ms","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:11:43.249405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.665672Z","time spent":"583.725155ms","remote":"127.0.0.1:34326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:11:43.249912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.213968Z","time spent":"1.035684602s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:43.249182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:42.697473Z","time spent":"551.663774ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:43.645724Z","caller":"traceutil/trace.go:171","msg":"trace[1234518623] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"390.524507ms","start":"2026-02-25T06:11:43.255158Z","end":"2026-02-25T06:11:43.645683Z","steps":["trace[1234518623] 'process raft request' (duration: 390.376783ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:43.646Z","caller":"traceutil/trace.go:171","msg":"trace[313034088] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"391.962776ms","start":"2026-02-25T06:11:43.254008Z","end":"2026-02-25T06:11:43.64597Z","steps":["trace[313034088] 'process raft request' (duration: 316.111189ms)","trace[313034088] 'compare' (duration: 75.034995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:43.646165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:43.253992Z","time spent":"392.088449ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:43.646004Z","caller":"traceutil/trace.go:171","msg":"trace[1446573967] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"391.434581ms","start":"2026-02-25T06:11:43.254551Z","end":"2026-02-25T06:11:43.645986Z","steps":["trace[1446573967] 'read index received' (duration: 315.538033ms)","trace[1446573967] 'applied index is now lower than readState.Index' (duration: 75.894518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:43.646421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.73662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T06:11:43.64626Z","caller":"traceutil/trace.go:171","msg":"trace[2109986924] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"390.866217ms","start":"2026-02-25T06:11:43.255176Z","end":"2026-02-25T06:11:43.646043Z","steps":["trace[2109986924] 'process raft request' (duration: 390.457976ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:43.646512Z","caller":"traceutil/trace.go:171","msg":"trace[1370494525] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:794; }","duration":"392.020318ms","start":"2026-02-25T06:11:43.254475Z","end":"2026-02-25T06:11:43.646495Z","steps":["trace[1370494525] 'agreement among raft nodes before linearized reading' (duration: 391.586866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:43.646597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:43.254441Z","time spent":"392.1419ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-25T06:11:43.646781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:43.255142Z","time spent":"390.700662ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:43.646814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:43.255159Z","time spent":"391.472123ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:45.844536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512784,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:11:46.002318Z","caller":"traceutil/trace.go:171","msg":"trace[119814455] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"658.126993ms","start":"2026-02-25T06:11:45.344163Z","end":"2026-02-25T06:11:46.00229Z","steps":["trace[119814455] 'read index received' (duration: 657.968539ms)","trace[119814455] 'applied index is now lower than readState.Index' (duration: 156.894µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:46.002413Z","caller":"traceutil/trace.go:171","msg":"trace[652930446] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"740.273189ms","start":"2026-02-25T06:11:45.26209Z","end":"2026-02-25T06:11:46.002363Z","steps":["trace[652930446] 'process raft request' (duration: 740.038983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.003273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:45.262078Z","time spent":"741.065101ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:46.002485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.283617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:46.003551Z","caller":"traceutil/trace.go:171","msg":"trace[1923379348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"659.396647ms","start":"2026-02-25T06:11:45.344133Z","end":"2026-02-25T06:11:46.003529Z","steps":["trace[1923379348] 'agreement among raft nodes before linearized reading' (duration: 658.284297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.003604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:45.344119Z","time spent":"659.4745ms","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:11:46.059019Z","caller":"traceutil/trace.go:171","msg":"trace[1552058298] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"110.906858ms","start":"2026-02-25T06:11:45.948093Z","end":"2026-02-25T06:11:46.058999Z","steps":["trace[1552058298] 'process raft request' (duration: 91.212179ms)","trace[1552058298] 'compare' (duration: 18.98385ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:46.059352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.456109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:11:46.059405Z","caller":"traceutil/trace.go:171","msg":"trace[1665950723] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"408.553231ms","start":"2026-02-25T06:11:45.650838Z","end":"2026-02-25T06:11:46.059391Z","steps":["trace[1665950723] 'agreement among raft nodes before linearized reading' (duration: 408.406038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.059437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:45.650816Z","time spent":"408.613393ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:11:46.059428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.473769ms","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-02-25T06:11:46.059487Z","caller":"traceutil/trace.go:171","msg":"trace[50472320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"408.576502ms","start":"2026-02-25T06:11:45.650897Z","end":"2026-02-25T06:11:46.059473Z","steps":["trace[50472320] 'agreement among raft nodes before linearized reading' (duration: 408.377696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.059538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:45.650877Z","time spent":"408.649644ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:46.059577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.637744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:11:46.059668Z","caller":"traceutil/trace.go:171","msg":"trace[941460107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"408.797098ms","start":"2026-02-25T06:11:45.650852Z","end":"2026-02-25T06:11:46.059649Z","steps":["trace[941460107] 'agreement among raft nodes before linearized reading' (duration: 408.47685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:46.059721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:45.650752Z","time spent":"408.961722ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:11:57.730035Z","caller":"traceutil/trace.go:171","msg":"trace[1863419530] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"373.255253ms","start":"2026-02-25T06:11:57.356753Z","end":"2026-02-25T06:11:57.730008Z","steps":["trace[1863419530] 'process raft request' (duration: 373.082319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:57.730203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:57.356736Z","time spent":"373.383826ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:58.697193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:59.1978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:59.699251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:00.199592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085742941531512982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:00.279072Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.099046637s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:12:00.279451Z","caller":"traceutil/trace.go:171","msg":"trace[1669340690] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"2.082766971s","start":"2026-02-25T06:11:58.196659Z","end":"2026-02-25T06:12:00.279426Z","steps":["trace[1669340690] 'read index received' (duration: 2.082581976s)","trace[1669340690] 'applied index is now lower than readState.Index' (duration: 183.965µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:00.279535Z","caller":"traceutil/trace.go:171","msg":"trace[1804949258] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"2.099600282s","start":"2026-02-25T06:11:58.17992Z","end":"2026-02-25T06:12:00.279521Z","steps":["trace[1804949258] 'process raft request' (duration: 2.099371896s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:00.279619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.082958915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T06:12:00.27965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:58.179898Z","time spent":"2.099679975s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:00.279665Z","caller":"traceutil/trace.go:171","msg":"trace[1885473162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"2.083051678s","start":"2026-02-25T06:11:58.196598Z","end":"2026-02-25T06:12:00.27965Z","steps":["trace[1885473162] 'agreement among raft nodes before linearized reading' (duration: 2.082918694s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:00.279704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:58.196577Z","time spent":"2.08311911s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:00.279706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.031303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T06:12:00.279746Z","caller":"traceutil/trace.go:171","msg":"trace[360064009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"546.119875ms","start":"2026-02-25T06:11:59.733616Z","end":"2026-02-25T06:12:00.279736Z","steps":["trace[360064009] 'agreement among raft nodes before linearized reading' (duration: 546.012202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:00.279751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.064944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T06:12:00.279782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.135051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:00.279797Z","caller":"traceutil/trace.go:171","msg":"trace[45736491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"206.152956ms","start":"2026-02-25T06:12:00.073633Z","end":"2026-02-25T06:12:00.279786Z","steps":["trace[45736491] 'agreement among raft nodes before linearized reading' (duration: 206.010763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:00.279791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.950178279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:00.279859Z","caller":"traceutil/trace.go:171","msg":"trace[739408108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"1.950278343s","start":"2026-02-25T06:11:58.329563Z","end":"2026-02-25T06:12:00.279841Z","steps":["trace[739408108] 'agreement among raft nodes before linearized reading' (duration: 1.950199781s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:00.279783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:59.733601Z","time spent":"546.174317ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:12:00.279923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:58.329541Z","time spent":"1.950371705s","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:12:00.279818Z","caller":"traceutil/trace.go:171","msg":"trace[967845623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"894.199883ms","start":"2026-02-25T06:11:59.385607Z","end":"2026-02-25T06:12:00.279807Z","steps":["trace[967845623] 'agreement among raft nodes before linearized reading' (duration: 894.148091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:00.279989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:59.385586Z","time spent":"894.393688ms","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:12:00.279729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079450571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T06:12:00.280071Z","caller":"traceutil/trace.go:171","msg":"trace[604711568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:883; }","duration":"2.079812431s","start":"2026-02-25T06:11:58.200247Z","end":"2026-02-25T06:12:00.28006Z","steps":["trace[604711568] 'agreement among raft nodes before linearized reading' (duration: 2.079366319s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:00.280106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:58.200241Z","time spent":"2.079857072s","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:12:13.215379Z","caller":"traceutil/trace.go:171","msg":"trace[246058438] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"131.875781ms","start":"2026-02-25T06:12:13.083481Z","end":"2026-02-25T06:12:13.215356Z","steps":["trace[246058438] 'process raft request' (duration: 131.741597ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:16.424069Z","caller":"traceutil/trace.go:171","msg":"trace[1337357257] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"116.401076ms","start":"2026-02-25T06:12:16.307636Z","end":"2026-02-25T06:12:16.424037Z","steps":["trace[1337357257] 'process raft request' (duration: 116.195031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:18.067589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.670007ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T06:12:18.067687Z","caller":"traceutil/trace.go:171","msg":"trace[950430117] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"737.826964ms","start":"2026-02-25T06:12:17.329844Z","end":"2026-02-25T06:12:18.067671Z","steps":["trace[950430117] 'read index received' (duration: 292.064644ms)","trace[950430117] 'applied index is now lower than readState.Index' (duration: 445.76109ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:18.067756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.900206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:18.067781Z","caller":"traceutil/trace.go:171","msg":"trace[1366180228] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"737.962787ms","start":"2026-02-25T06:12:17.32981Z","end":"2026-02-25T06:12:18.067772Z","steps":["trace[1366180228] 'agreement among raft nodes before linearized reading' (duration: 737.900035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:18.067806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:17.329795Z","time spent":"738.005209ms","remote":"127.0.0.1:34216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T06:12:18.616117Z","caller":"traceutil/trace.go:171","msg":"trace[1580843868] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"134.291866ms","start":"2026-02-25T06:12:18.481789Z","end":"2026-02-25T06:12:18.616081Z","steps":["trace[1580843868] 'read index received' (duration: 134.026319ms)","trace[1580843868] 'applied index is now lower than readState.Index' (duration: 264.207µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:18.616589Z","caller":"traceutil/trace.go:171","msg":"trace[1228426275] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"161.26874ms","start":"2026-02-25T06:12:18.455305Z","end":"2026-02-25T06:12:18.616574Z","steps":["trace[1228426275] 'process raft request' (duration: 160.602712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:18.61693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.100828ms","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-02-25T06:12:18.616981Z","caller":"traceutil/trace.go:171","msg":"trace[677017813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"135.228002ms","start":"2026-02-25T06:12:18.481736Z","end":"2026-02-25T06:12:18.616964Z","steps":["trace[677017813] 'agreement among raft nodes before linearized reading' (duration: 135.045457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:18.617176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.256132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:12:18.617215Z","caller":"traceutil/trace.go:171","msg":"trace[950181032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"135.348994ms","start":"2026-02-25T06:12:18.481855Z","end":"2026-02-25T06:12:18.617204Z","steps":["trace[950181032] 'agreement among raft nodes before linearized reading' (duration: 135.256742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:18.61736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.007545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:12:18.617391Z","caller":"traceutil/trace.go:171","msg":"trace[428893095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"135.056857ms","start":"2026-02-25T06:12:18.482328Z","end":"2026-02-25T06:12:18.617385Z","steps":["trace[428893095] 'agreement among raft nodes before linearized reading' (duration: 134.974795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:18.617792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.813267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:12:18.617879Z","caller":"traceutil/trace.go:171","msg":"trace[1900844965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"135.91997ms","start":"2026-02-25T06:12:18.481939Z","end":"2026-02-25T06:12:18.617859Z","steps":["trace[1900844965] 'agreement among raft nodes before linearized reading' (duration: 135.645712ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:18.773182Z","caller":"traceutil/trace.go:171","msg":"trace[734586433] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"151.704604ms","start":"2026-02-25T06:12:18.621436Z","end":"2026-02-25T06:12:18.773141Z","steps":["trace[734586433] 'process raft request' (duration: 79.250608ms)","trace[734586433] 'compare' (duration: 72.245631ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:18.788391Z","caller":"traceutil/trace.go:171","msg":"trace[73590830] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:991; }","duration":"145.652682ms","start":"2026-02-25T06:12:18.64272Z","end":"2026-02-25T06:12:18.788373Z","steps":["trace[73590830] 'read index received' (duration: 57.954587ms)","trace[73590830] 'applied index is now lower than readState.Index' (duration: 87.696635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:18.788545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.798716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:18.788581Z","caller":"traceutil/trace.go:171","msg":"trace[186849606] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:958; }","duration":"145.874917ms","start":"2026-02-25T06:12:18.642687Z","end":"2026-02-25T06:12:18.788562Z","steps":["trace[186849606] 'agreement among raft nodes before linearized reading' (duration: 145.750914ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:18.78875Z","caller":"traceutil/trace.go:171","msg":"trace[1330625348] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"166.07826ms","start":"2026-02-25T06:12:18.622664Z","end":"2026-02-25T06:12:18.788742Z","steps":["trace[1330625348] 'process raft request' (duration: 165.619078ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:18.788698Z","caller":"traceutil/trace.go:171","msg":"trace[1480278991] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"166.356237ms","start":"2026-02-25T06:12:18.622323Z","end":"2026-02-25T06:12:18.788679Z","steps":["trace[1480278991] 'process raft request' (duration: 165.855154ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:18.78885Z","caller":"traceutil/trace.go:171","msg":"trace[348724425] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"165.420393ms","start":"2026-02-25T06:12:18.623424Z","end":"2026-02-25T06:12:18.788844Z","steps":["trace[348724425] 'process raft request' (duration: 164.902539ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:20.828348Z","caller":"traceutil/trace.go:171","msg":"trace[1698753272] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"202.439796ms","start":"2026-02-25T06:12:20.625862Z","end":"2026-02-25T06:12:20.828302Z","steps":["trace[1698753272] 'process raft request' (duration: 202.139738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.508055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.335235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:21.508235Z","caller":"traceutil/trace.go:171","msg":"trace[1746965684] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:996; }","duration":"179.609043ms","start":"2026-02-25T06:12:21.328612Z","end":"2026-02-25T06:12:21.508221Z","steps":["trace[1746965684] 'read index received' (duration: 38.019471ms)","trace[1746965684] 'applied index is now lower than readState.Index' (duration: 141.588572ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:21.508305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.700445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:21.508268Z","caller":"traceutil/trace.go:171","msg":"trace[1154689766] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"675.282723ms","start":"2026-02-25T06:12:20.832954Z","end":"2026-02-25T06:12:21.508237Z","steps":["trace[1154689766] 'process raft request' (duration: 675.226072ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:21.508268Z","caller":"traceutil/trace.go:171","msg":"trace[95227449] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"675.379575ms","start":"2026-02-25T06:12:20.832831Z","end":"2026-02-25T06:12:21.508211Z","steps":["trace[95227449] 'process raft request' (duration: 533.783223ms)","trace[95227449] 'compare' (duration: 141.169761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:21.508354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.779589ms","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-02-25T06:12:21.508371Z","caller":"traceutil/trace.go:171","msg":"trace[1010828321] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:963; }","duration":"137.829061ms","start":"2026-02-25T06:12:21.370537Z","end":"2026-02-25T06:12:21.508366Z","steps":["trace[1010828321] 'agreement among raft nodes before linearized reading' (duration: 137.80168ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:21.508319Z","caller":"traceutil/trace.go:171","msg":"trace[247312512] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"674.609515ms","start":"2026-02-25T06:12:20.833657Z","end":"2026-02-25T06:12:21.508267Z","steps":["trace[247312512] 'process raft request' (duration: 674.542993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.508431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.832926Z","time spent":"675.424667ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:21.508498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.832812Z","time spent":"675.561901ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:21.508519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.833651Z","time spent":"674.759489ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:21.508322Z","caller":"traceutil/trace.go:171","msg":"trace[86644593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"179.755806ms","start":"2026-02-25T06:12:21.328561Z","end":"2026-02-25T06:12:21.508317Z","steps":["trace[86644593] 'agreement among raft nodes before linearized reading' (duration: 179.723955ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:21.508294Z","caller":"traceutil/trace.go:171","msg":"trace[249577318] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"675.341345ms","start":"2026-02-25T06:12:20.832926Z","end":"2026-02-25T06:12:21.508267Z","steps":["trace[249577318] 'process raft request' (duration: 675.220552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.508645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.832908Z","time spent":"675.717105ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:22.360342Z","caller":"traceutil/trace.go:171","msg":"trace[834544067] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"188.347678ms","start":"2026-02-25T06:12:22.171969Z","end":"2026-02-25T06:12:22.360317Z","steps":["trace[834544067] 'read index received' (duration: 188.121162ms)","trace[834544067] 'applied index is now lower than readState.Index' (duration: 225.136µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:22.360505Z","caller":"traceutil/trace.go:171","msg":"trace[1005615992] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"397.519564ms","start":"2026-02-25T06:12:21.962974Z","end":"2026-02-25T06:12:22.360493Z","steps":["trace[1005615992] 'process raft request' (duration: 397.152385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:22.360627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:21.962949Z","time spent":"397.584347ms","remote":"127.0.0.1:34296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:12:22.360839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.840501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T06:12:22.360941Z","caller":"traceutil/trace.go:171","msg":"trace[88193677] range","detail":"{range_begin:/registry/masterleases/199.204.45.162; range_end:; response_count:1; response_revision:964; }","duration":"189.000105ms","start":"2026-02-25T06:12:22.171912Z","end":"2026-02-25T06:12:22.360912Z","steps":["trace[88193677] 'agreement among raft nodes before linearized reading' (duration: 188.666776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.013613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.409021ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-25T06:12:23.013727Z","caller":"traceutil/trace.go:171","msg":"trace[1285107924] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"325.994654ms","start":"2026-02-25T06:12:22.687712Z","end":"2026-02-25T06:12:23.013707Z","steps":["trace[1285107924] 'read index received' (duration: 38.421µs)","trace[1285107924] 'applied index is now lower than readState.Index' (duration: 325.954593ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:23.01381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:22.362011Z","time spent":"651.785874ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T06:12:23.013902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.18879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:23.013935Z","caller":"traceutil/trace.go:171","msg":"trace[345444427] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:964; }","duration":"326.230531ms","start":"2026-02-25T06:12:22.687693Z","end":"2026-02-25T06:12:23.013923Z","steps":["trace[345444427] 'agreement among raft nodes before linearized reading' (duration: 326.160289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.013974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:22.687615Z","time spent":"326.345824ms","remote":"127.0.0.1:34612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:23.014065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.399182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-25T06:12:23.014144Z","caller":"traceutil/trace.go:171","msg":"trace[593439594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"181.531636ms","start":"2026-02-25T06:12:22.832594Z","end":"2026-02-25T06:12:23.014126Z","steps":["trace[593439594] 'agreement among raft nodes before linearized reading' (duration: 181.33261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.85152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.443103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:23.85192Z","caller":"traceutil/trace.go:171","msg":"trace[1182075474] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"836.890405ms","start":"2026-02-25T06:12:23.014999Z","end":"2026-02-25T06:12:23.85189Z","steps":["trace[1182075474] 'process raft request' (duration: 463.98432ms)","trace[1182075474] 'compare' (duration: 372.310869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:23.852031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.014978Z","time spent":"837.006247ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:23.85217Z","caller":"traceutil/trace.go:171","msg":"trace[1608554570] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"833.304569ms","start":"2026-02-25T06:12:23.018848Z","end":"2026-02-25T06:12:23.852152Z","steps":["trace[1608554570] 'process raft request' (duration: 832.945959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.852252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.01883Z","time spent":"833.38337ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:23.852315Z","caller":"traceutil/trace.go:171","msg":"trace[132133111] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"430.351528ms","start":"2026-02-25T06:12:23.421923Z","end":"2026-02-25T06:12:23.852274Z","steps":["trace[132133111] 'process raft request' (duration: 429.936957ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:23.852466Z","caller":"traceutil/trace.go:171","msg":"trace[1315669383] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1002; }","duration":"524.184598ms","start":"2026-02-25T06:12:23.328269Z","end":"2026-02-25T06:12:23.852454Z","steps":["trace[1315669383] 'read index received' (duration: 150.998415ms)","trace[1315669383] 'applied index is now lower than readState.Index' (duration: 373.184973ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:23.852556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.29127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:12:23.852562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.421903Z","time spent":"430.524762ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:23.852593Z","caller":"traceutil/trace.go:171","msg":"trace[630529125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"524.380262ms","start":"2026-02-25T06:12:23.328202Z","end":"2026-02-25T06:12:23.852582Z","steps":["trace[630529125] 'agreement among raft nodes before linearized reading' (duration: 524.320751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.852625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.328177Z","time spent":"524.442084ms","remote":"127.0.0.1:34204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T06:12:23.852704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.535212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:12:23.852741Z","caller":"traceutil/trace.go:171","msg":"trace[1059908479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:967; }","duration":"325.611844ms","start":"2026-02-25T06:12:23.527121Z","end":"2026-02-25T06:12:23.852733Z","steps":["trace[1059908479] 'agreement among raft nodes before linearized reading' (duration: 325.491661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.85277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.527095Z","time spent":"325.668106ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:12:23.853084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.777317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T06:12:23.853135Z","caller":"traceutil/trace.go:171","msg":"trace[1578141331] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"316.84882ms","start":"2026-02-25T06:12:23.536273Z","end":"2026-02-25T06:12:23.853122Z","steps":["trace[1578141331] 'agreement among raft nodes before linearized reading' (duration: 316.635684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.853177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.536258Z","time spent":"316.911371ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:12:23.853173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.907602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:12:23.853321Z","caller":"traceutil/trace.go:171","msg":"trace[352056919] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"326.095297ms","start":"2026-02-25T06:12:23.52718Z","end":"2026-02-25T06:12:23.853275Z","steps":["trace[352056919] 'agreement among raft nodes before linearized reading' (duration: 325.747087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.853391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.151409ms","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-02-25T06:12:23.85341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.527149Z","time spent":"326.246781ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T06:12:23.853435Z","caller":"traceutil/trace.go:171","msg":"trace[199077306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"326.217531ms","start":"2026-02-25T06:12:23.527207Z","end":"2026-02-25T06:12:23.853424Z","steps":["trace[199077306] 'agreement among raft nodes before linearized reading' (duration: 326.122198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:23.85347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.527188Z","time spent":"326.275172ms","remote":"127.0.0.1:34498","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":"info","ts":"2026-02-25T06:12:24.346353Z","caller":"traceutil/trace.go:171","msg":"trace[1163735991] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"488.959001ms","start":"2026-02-25T06:12:23.857364Z","end":"2026-02-25T06:12:24.346323Z","steps":["trace[1163735991] 'process raft request' (duration: 459.079208ms)","trace[1163735991] 'compare' (duration: 29.601725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:24.346523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.857348Z","time spent":"489.085884ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:24.383649Z","caller":"traceutil/trace.go:171","msg":"trace[1318829655] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"525.559474ms","start":"2026-02-25T06:12:23.858063Z","end":"2026-02-25T06:12:24.383622Z","steps":["trace[1318829655] 'process raft request' (duration: 525.351108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:24.383837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.858038Z","time spent":"525.701817ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:24.383871Z","caller":"traceutil/trace.go:171","msg":"trace[713589817] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"524.790503ms","start":"2026-02-25T06:12:23.859055Z","end":"2026-02-25T06:12:24.383846Z","steps":["trace[713589817] 'process raft request' (duration: 524.527306ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:24.38399Z","caller":"traceutil/trace.go:171","msg":"trace[1640496239] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1005; }","duration":"525.184714ms","start":"2026-02-25T06:12:23.858778Z","end":"2026-02-25T06:12:24.383963Z","steps":["trace[1640496239] 'read index received' (duration: 457.682771ms)","trace[1640496239] 'applied index is now lower than readState.Index' (duration: 67.500813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:24.384044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.859039Z","time spent":"524.906316ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:24.384065Z","caller":"traceutil/trace.go:171","msg":"trace[516825318] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"524.920606ms","start":"2026-02-25T06:12:23.859118Z","end":"2026-02-25T06:12:24.384039Z","steps":["trace[516825318] 'process raft request' (duration: 524.657389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:24.384165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.369349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-25T06:12:24.3842Z","caller":"traceutil/trace.go:171","msg":"trace[2106557141] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:971; }","duration":"525.43953ms","start":"2026-02-25T06:12:23.858752Z","end":"2026-02-25T06:12:24.384191Z","steps":["trace[2106557141] 'agreement among raft nodes before linearized reading' (duration: 525.346697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:24.384225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.85874Z","time spent":"525.480212ms","remote":"127.0.0.1:34510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T06:12:24.384242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.089697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:24.384275Z","caller":"traceutil/trace.go:171","msg":"trace[963119837] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:971; }","duration":"345.147479ms","start":"2026-02-25T06:12:24.039117Z","end":"2026-02-25T06:12:24.384265Z","steps":["trace[963119837] 'agreement among raft nodes before linearized reading' (duration: 345.045016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:24.384298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:24.039106Z","time spent":"345.18756ms","remote":"127.0.0.1:34574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:24.385069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:23.859098Z","time spent":"525.025689ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:30.521049Z","caller":"traceutil/trace.go:171","msg":"trace[464690857] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"404.523044ms","start":"2026-02-25T06:12:30.116499Z","end":"2026-02-25T06:12:30.521022Z","steps":["trace[464690857] 'read index received' (duration: 404.282898ms)","trace[464690857] 'applied index is now lower than readState.Index' (duration: 239.056µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:30.521193Z","caller":"traceutil/trace.go:171","msg":"trace[948032540] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"641.288723ms","start":"2026-02-25T06:12:29.879888Z","end":"2026-02-25T06:12:30.521176Z","steps":["trace[948032540] 'process raft request' (duration: 640.980825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:30.521337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.959682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:12:30.521415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:29.879868Z","time spent":"641.361464ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:30.521443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.672892ms","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-02-25T06:12:30.521527Z","caller":"traceutil/trace.go:171","msg":"trace[476857226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1004; }","duration":"109.809626ms","start":"2026-02-25T06:12:30.4117Z","end":"2026-02-25T06:12:30.521509Z","steps":["trace[476857226] 'agreement among raft nodes before linearized reading' (duration: 109.607101ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:30.521501Z","caller":"traceutil/trace.go:171","msg":"trace[238998019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"193.128996ms","start":"2026-02-25T06:12:30.328311Z","end":"2026-02-25T06:12:30.52144Z","steps":["trace[238998019] 'agreement among raft nodes before linearized reading' (duration: 192.965112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:30.521709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.218412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-25T06:12:30.521771Z","caller":"traceutil/trace.go:171","msg":"trace[91209731] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1004; }","duration":"405.290204ms","start":"2026-02-25T06:12:30.116444Z","end":"2026-02-25T06:12:30.521734Z","steps":["trace[91209731] 'agreement among raft nodes before linearized reading' (duration: 405.189641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:30.521804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:30.116419Z","time spent":"405.376536ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T06:12:30.521921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.417098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T06:12:30.521982Z","caller":"traceutil/trace.go:171","msg":"trace[747183003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1004; }","duration":"111.501391ms","start":"2026-02-25T06:12:30.410466Z","end":"2026-02-25T06:12:30.521968Z","steps":["trace[747183003] 'agreement among raft nodes before linearized reading' (duration: 111.343127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:30.522006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.489717ms","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-02-25T06:12:30.522003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.156055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T06:12:30.52208Z","caller":"traceutil/trace.go:171","msg":"trace[13998641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"117.59089ms","start":"2026-02-25T06:12:30.404467Z","end":"2026-02-25T06:12:30.522058Z","steps":["trace[13998641] 'agreement among raft nodes before linearized reading' (duration: 117.403315ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:30.522086Z","caller":"traceutil/trace.go:171","msg":"trace[289025373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"110.277689ms","start":"2026-02-25T06:12:30.41179Z","end":"2026-02-25T06:12:30.522068Z","steps":["trace[289025373] 'agreement among raft nodes before linearized reading' (duration: 110.048422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:30.874152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.65846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:30.874266Z","caller":"traceutil/trace.go:171","msg":"trace[697313087] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"346.909978ms","start":"2026-02-25T06:12:30.527337Z","end":"2026-02-25T06:12:30.874247Z","steps":["trace[697313087] 'process raft request' (duration: 153.062583ms)","trace[697313087] 'compare' (duration: 193.528126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:30.874329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:30.527308Z","time spent":"346.99215ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:30.988124Z","caller":"traceutil/trace.go:171","msg":"trace[1690113167] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"460.698038ms","start":"2026-02-25T06:12:30.527396Z","end":"2026-02-25T06:12:30.988095Z","steps":["trace[1690113167] 'process raft request' (duration: 451.787644ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:30.988557Z","caller":"traceutil/trace.go:171","msg":"trace[912381732] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"460.164774ms","start":"2026-02-25T06:12:30.52838Z","end":"2026-02-25T06:12:30.988545Z","steps":["trace[912381732] 'process raft request' (duration: 460.077082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:30.988593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:30.527376Z","time spent":"460.872873ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:30.988545Z","caller":"traceutil/trace.go:171","msg":"trace[37205921] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"461.034467ms","start":"2026-02-25T06:12:30.527484Z","end":"2026-02-25T06:12:30.988519Z","steps":["trace[37205921] 'process raft request' (duration: 460.879363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:30.988791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:30.528363Z","time spent":"460.271237ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:30.988901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:30.527476Z","time spent":"461.306714ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:30.988554Z","caller":"traceutil/trace.go:171","msg":"trace[526002105] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"461.126409ms","start":"2026-02-25T06:12:30.527402Z","end":"2026-02-25T06:12:30.988529Z","steps":["trace[526002105] 'process raft request' (duration: 460.639936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:30.989194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:30.527379Z","time spent":"461.738845ms","remote":"127.0.0.1:34498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:33.625336Z","caller":"traceutil/trace.go:171","msg":"trace[642606917] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"230.432607ms","start":"2026-02-25T06:12:33.394878Z","end":"2026-02-25T06:12:33.62531Z","steps":["trace[642606917] 'process raft request' (duration: 230.308454ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:41.534789Z","caller":"traceutil/trace.go:171","msg":"trace[288354680] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"247.873898ms","start":"2026-02-25T06:15:41.286882Z","end":"2026-02-25T06:15:41.534756Z","steps":["trace[288354680] 'process raft request' (duration: 247.270412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:15:41.535444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.202285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:15:41.535558Z","caller":"traceutil/trace.go:171","msg":"trace[1530487469] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1831; }","duration":"211.387971ms","start":"2026-02-25T06:15:41.324141Z","end":"2026-02-25T06:15:41.535529Z","steps":["trace[1530487469] 'agreement among raft nodes before linearized reading' (duration: 211.025231ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:15:41.535165Z","caller":"traceutil/trace.go:171","msg":"trace[495476879] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1909; }","duration":"210.955229ms","start":"2026-02-25T06:15:41.324166Z","end":"2026-02-25T06:15:41.535121Z","steps":["trace[495476879] 'read index received' (duration: 210.927888ms)","trace[495476879] 'applied index is now lower than readState.Index' (duration: 23.581µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:15:41.538079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.48208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:15:41.538195Z","caller":"traceutil/trace.go:171","msg":"trace[445143462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1832; }","duration":"207.656094ms","start":"2026-02-25T06:15:41.330514Z","end":"2026-02-25T06:15:41.53817Z","steps":["trace[445143462] 'agreement among raft nodes before linearized reading' (duration: 207.46555ms)"],"step_count":1}