{"level":"warn","ts":"2026-02-25T17:03:50.568357Z","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-25T17:03:50.568435Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.54: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.19.213.54:2380","--initial-cluster=instance=https://199.19.213.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.54: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-25T17:03:50.568495Z","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-25T17:03:50.568508Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-02-25T17:03:50.568524Z","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-25T17:03:50.569145Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"]} {"level":"info","ts":"2026-02-25T17:03:50.56922Z","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":16,"max-cpu-available":16,"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.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.54: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-25T17:03:50.595803Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"26.380339ms"} {"level":"info","ts":"2026-02-25T17:03:50.653216Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"193b4dda7792855b","cluster-id":"dd6702a2f79ce697"} {"level":"info","ts":"2026-02-25T17:03:50.653359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T17:03:50.653393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 0"} {"level":"info","ts":"2026-02-25T17:03:50.653415Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 193b4dda7792855b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T17:03:50.653428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 1"} {"level":"info","ts":"2026-02-25T17:03:50.653487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"warn","ts":"2026-02-25T17:03:50.740098Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T17:03:50.748277Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T17:03:50.819317Z","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-25T17:03:50.88229Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"193b4dda7792855b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T17:03:50.882671Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"193b4dda7792855b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T17:03:50.882679Z","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-25T17:03:50.882842Z","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-25T17:03:50.883605Z","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-25T17:03:50.88805Z","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-25T17:03:50.888175Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-02-25T17:03:50.888236Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-02-25T17:03:50.888488Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"193b4dda7792855b","initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T17:03:50.888608Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T17:03:50.911652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"info","ts":"2026-02-25T17:03:50.911815Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","added-peer-id":"193b4dda7792855b","added-peer-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-02-25T17:03:51.253818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T17:03:51.2539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T17:03:51.253941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgPreVoteResp from 193b4dda7792855b at term 1"} {"level":"info","ts":"2026-02-25T17:03:51.253969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became candidate at term 2"} {"level":"info","ts":"2026-02-25T17:03:51.253985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgVoteResp from 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-02-25T17:03:51.254008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became leader at term 2"} {"level":"info","ts":"2026-02-25T17:03:51.254027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 193b4dda7792855b elected leader 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-02-25T17:03:51.294565Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:03:51.307561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:03:51.307602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:03:51.307554Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"193b4dda7792855b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.54:2379]}","request-path":"/0/members/193b4dda7792855b/attributes","cluster-id":"dd6702a2f79ce697","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T17:03:51.307961Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T17:03:51.308007Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T17:03:51.309179Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.54:2379"} {"level":"info","ts":"2026-02-25T17:03:51.310979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T17:03:51.361105Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:03:51.361256Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:03:51.361456Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:01.788445Z","caller":"traceutil/trace.go:171","msg":"trace[1314856998] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"126.254002ms","start":"2026-02-25T17:04:01.662172Z","end":"2026-02-25T17:04:01.788426Z","steps":["trace[1314856998] 'process raft request' (duration: 126.146278ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:07.355157Z","caller":"traceutil/trace.go:171","msg":"trace[38240480] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"122.458467ms","start":"2026-02-25T17:04:07.232653Z","end":"2026-02-25T17:04:07.355111Z","steps":["trace[38240480] 'process raft request' (duration: 122.169248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:08.448074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.894494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T17:04:08.448185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.030097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4024"} {"level":"info","ts":"2026-02-25T17:04:08.448211Z","caller":"traceutil/trace.go:171","msg":"trace[252856431] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:416; }","duration":"788.100139ms","start":"2026-02-25T17:04:07.660104Z","end":"2026-02-25T17:04:08.448204Z","steps":["trace[252856431] 'range keys from in-memory index tree' (duration: 787.896092ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:08.448213Z","caller":"traceutil/trace.go:171","msg":"trace[791457894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"917.063469ms","start":"2026-02-25T17:04:07.531123Z","end":"2026-02-25T17:04:08.448186Z","steps":["trace[791457894] 'range keys from in-memory index tree' (duration: 916.810471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:08.448232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:07.66008Z","time spent":"788.147321ms","remote":"127.0.0.1:33800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4047,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T17:04:08.448235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.778957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:04:08.448271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:07.531107Z","time spent":"917.150502ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:04:08.448365Z","caller":"traceutil/trace.go:171","msg":"trace[1030908996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"144.953042ms","start":"2026-02-25T17:04:08.303392Z","end":"2026-02-25T17:04:08.448345Z","steps":["trace[1030908996] 'range keys from in-memory index tree' (duration: 144.689664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:08.449347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.839019ms","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-25T17:04:08.449433Z","caller":"traceutil/trace.go:171","msg":"trace[531854359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"639.961353ms","start":"2026-02-25T17:04:07.809452Z","end":"2026-02-25T17:04:08.449413Z","steps":["trace[531854359] 'range keys from in-memory index tree' (duration: 638.593809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:08.449491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:07.809431Z","time spent":"640.050696ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T17:04:09.118408Z","caller":"traceutil/trace.go:171","msg":"trace[187218944] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"666.151707ms","start":"2026-02-25T17:04:08.452231Z","end":"2026-02-25T17:04:09.118383Z","steps":["trace[187218944] 'process raft request' (duration: 666.017193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:09.11902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:08.452202Z","time spent":"666.280482ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:09.123866Z","caller":"traceutil/trace.go:171","msg":"trace[944979968] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"671.4319ms","start":"2026-02-25T17:04:08.452417Z","end":"2026-02-25T17:04:09.123849Z","steps":["trace[944979968] 'process raft request' (duration: 671.352277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:09.123963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:08.452389Z","time spent":"671.528892ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:13.455039Z","caller":"traceutil/trace.go:171","msg":"trace[1415244483] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"143.431483ms","start":"2026-02-25T17:04:13.311594Z","end":"2026-02-25T17:04:13.455026Z","steps":["trace[1415244483] 'process raft request' (duration: 143.285698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:13.774876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.32964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:13.775311Z","caller":"traceutil/trace.go:171","msg":"trace[1068658955] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"316.09924ms","start":"2026-02-25T17:04:13.459191Z","end":"2026-02-25T17:04:13.77529Z","steps":["trace[1068658955] 'process raft request' (duration: 315.866182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:13.775404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:13.459166Z","time spent":"316.200164ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:13.775648Z","caller":"traceutil/trace.go:171","msg":"trace[1748199791] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"316.937477ms","start":"2026-02-25T17:04:13.458655Z","end":"2026-02-25T17:04:13.775593Z","steps":["trace[1748199791] 'process raft request' (duration: 99.476808ms)","trace[1748199791] 'compare' (duration: 215.100732ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:13.775852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:13.458642Z","time spent":"317.097843ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:16.155488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.269766ms","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-25T17:04:16.155546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852014002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:16.155574Z","caller":"traceutil/trace.go:171","msg":"trace[1301897914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"373.41061ms","start":"2026-02-25T17:04:15.782144Z","end":"2026-02-25T17:04:16.155554Z","steps":["trace[1301897914] 'range keys from in-memory index tree' (duration: 373.118011ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:16.155583Z","caller":"traceutil/trace.go:171","msg":"trace[1142000621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"1.852080654s","start":"2026-02-25T17:04:14.303489Z","end":"2026-02-25T17:04:16.155569Z","steps":["trace[1142000621] 'range keys from in-memory index tree' (duration: 1.851921909s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:16.15562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:15.782123Z","time spent":"373.488033ms","remote":"127.0.0.1:33874","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-25T17:04:16.15562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:14.303466Z","time spent":"1.852144786s","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:16.155488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.71256988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.54\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T17:04:16.155737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.471847ms","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-25T17:04:16.155754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244645424s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:04:16.155823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.732247ms","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-25T17:04:16.155788Z","caller":"traceutil/trace.go:171","msg":"trace[1373451462] range","detail":"{range_begin:/registry/masterleases/199.19.213.54; range_end:; response_count:1; response_revision:458; }","duration":"1.71288884s","start":"2026-02-25T17:04:14.442868Z","end":"2026-02-25T17:04:16.155757Z","steps":["trace[1373451462] 'range keys from in-memory index tree' (duration: 1.712470287s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:16.155845Z","caller":"traceutil/trace.go:171","msg":"trace[2065255440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:458; }","duration":"987.629802ms","start":"2026-02-25T17:04:15.168194Z","end":"2026-02-25T17:04:16.155823Z","steps":["trace[2065255440] 'range keys from in-memory index tree' (duration: 987.355754ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:16.155871Z","caller":"traceutil/trace.go:171","msg":"trace[2145584745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:458; }","duration":"980.784899ms","start":"2026-02-25T17:04:15.175071Z","end":"2026-02-25T17:04:16.155856Z","steps":["trace[2145584745] 'range keys from in-memory index tree' (duration: 980.688486ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:16.155871Z","caller":"traceutil/trace.go:171","msg":"trace[1748970655] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:458; }","duration":"1.244782858s","start":"2026-02-25T17:04:14.911067Z","end":"2026-02-25T17:04:16.15585Z","steps":["trace[1748970655] 'range keys from in-memory index tree' (duration: 1.244622952s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:16.15589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:15.168171Z","time spent":"987.708205ms","remote":"127.0.0.1:33874","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-25T17:04:16.155896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:14.44285Z","time spent":"1.713017374s","remote":"127.0.0.1:33636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.54\" "} {"level":"warn","ts":"2026-02-25T17:04:16.155903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:15.175061Z","time spent":"980.834651ms","remote":"127.0.0.1:33874","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-25T17:04:16.155767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.629788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:04:16.156046Z","caller":"traceutil/trace.go:171","msg":"trace[2131863262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:458; }","duration":"373.905877ms","start":"2026-02-25T17:04:15.78212Z","end":"2026-02-25T17:04:16.156026Z","steps":["trace[2131863262] 'range keys from in-memory index tree' (duration: 373.446892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:16.156149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:15.782073Z","time spent":"374.02057ms","remote":"127.0.0.1:33874","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-25T17:04:24.794168Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609446396940222831,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:04:24.95813Z","caller":"traceutil/trace.go:171","msg":"trace[736512831] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"664.289607ms","start":"2026-02-25T17:04:24.293817Z","end":"2026-02-25T17:04:24.958106Z","steps":["trace[736512831] 'read index received' (duration: 664.06982ms)","trace[736512831] 'applied index is now lower than readState.Index' (duration: 218.667µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:24.958221Z","caller":"traceutil/trace.go:171","msg":"trace[1273993456] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"797.094923ms","start":"2026-02-25T17:04:24.161104Z","end":"2026-02-25T17:04:24.958198Z","steps":["trace[1273993456] 'process raft request' (duration: 796.784033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:24.958337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.371586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T17:04:24.958342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:24.161086Z","time spent":"797.184055ms","remote":"127.0.0.1:33800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4216,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:24.958368Z","caller":"traceutil/trace.go:171","msg":"trace[98431735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"655.436888ms","start":"2026-02-25T17:04:24.302922Z","end":"2026-02-25T17:04:24.958359Z","steps":["trace[98431735] 'agreement among raft nodes before linearized reading' (duration: 655.319804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:24.958394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:24.3029Z","time spent":"655.48714ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T17:04:24.95844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.595617ms","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-25T17:04:24.958504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.780236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:04:24.958524Z","caller":"traceutil/trace.go:171","msg":"trace[1761374213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"664.762743ms","start":"2026-02-25T17:04:24.29374Z","end":"2026-02-25T17:04:24.958503Z","steps":["trace[1761374213] 'agreement among raft nodes before linearized reading' (duration: 664.500894ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:24.958552Z","caller":"traceutil/trace.go:171","msg":"trace[1486919300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"654.853089ms","start":"2026-02-25T17:04:24.303684Z","end":"2026-02-25T17:04:24.958538Z","steps":["trace[1486919300] 'agreement among raft nodes before linearized reading' (duration: 654.733685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:24.958579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:24.293709Z","time spent":"664.854896ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:04:24.958695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.88973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.54\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T17:04:24.958617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.923442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T17:04:24.958768Z","caller":"traceutil/trace.go:171","msg":"trace[1515445574] range","detail":"{range_begin:/registry/masterleases/199.19.213.54; range_end:; response_count:1; response_revision:512; }","duration":"514.997293ms","start":"2026-02-25T17:04:24.443758Z","end":"2026-02-25T17:04:24.958756Z","steps":["trace[1515445574] 'agreement among raft nodes before linearized reading' (duration: 514.89764ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:24.958792Z","caller":"traceutil/trace.go:171","msg":"trace[1182500001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"655.119658ms","start":"2026-02-25T17:04:24.303656Z","end":"2026-02-25T17:04:24.958776Z","steps":["trace[1182500001] 'agreement among raft nodes before linearized reading' (duration: 654.88344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:24.958806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:24.443738Z","time spent":"515.058024ms","remote":"127.0.0.1:33636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.54\" "} {"level":"warn","ts":"2026-02-25T17:04:24.958823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:24.303652Z","time spent":"655.161659ms","remote":"127.0.0.1:33874","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-25T17:04:24.958592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:24.303612Z","time spent":"654.971602ms","remote":"127.0.0.1:33874","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-25T17:04:24.958653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.104258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:24.958899Z","caller":"traceutil/trace.go:171","msg":"trace[675871497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"655.360096ms","start":"2026-02-25T17:04:24.303531Z","end":"2026-02-25T17:04:24.958891Z","steps":["trace[675871497] 'agreement among raft nodes before linearized reading' (duration: 655.104127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:24.958923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:24.303524Z","time spent":"655.392106ms","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:31.721056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.137812ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T17:04:31.721218Z","caller":"traceutil/trace.go:171","msg":"trace[985339663] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"299.784747ms","start":"2026-02-25T17:04:31.421408Z","end":"2026-02-25T17:04:31.721193Z","steps":["trace[985339663] 'read index received' (duration: 35.241µs)","trace[985339663] 'applied index is now lower than readState.Index' (duration: 299.746926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:31.721515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.093027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w6mnv\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T17:04:31.721602Z","caller":"traceutil/trace.go:171","msg":"trace[1156479155] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w6mnv; range_end:; response_count:1; response_revision:555; }","duration":"300.218282ms","start":"2026-02-25T17:04:31.421367Z","end":"2026-02-25T17:04:31.721585Z","steps":["trace[1156479155] 'agreement among raft nodes before linearized reading' (duration: 299.965724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:31.721669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:31.421349Z","time spent":"300.306024ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16675,"request content":"key:\"/registry/pods/kube-system/cilium-w6mnv\" "} {"level":"info","ts":"2026-02-25T17:04:32.579313Z","caller":"traceutil/trace.go:171","msg":"trace[1542770954] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"473.883391ms","start":"2026-02-25T17:04:32.105405Z","end":"2026-02-25T17:04:32.579289Z","steps":["trace[1542770954] 'read index received' (duration: 473.791408ms)","trace[1542770954] 'applied index is now lower than readState.Index' (duration: 91.113µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:32.579382Z","caller":"traceutil/trace.go:171","msg":"trace[2079616026] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"843.157297ms","start":"2026-02-25T17:04:31.736188Z","end":"2026-02-25T17:04:32.579345Z","steps":["trace[2079616026] 'process raft request' (duration: 842.927849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:32.579551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:31.736164Z","time spent":"843.306201ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:32.579604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.288052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:04:32.579602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.110238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:32.579659Z","caller":"traceutil/trace.go:171","msg":"trace[881321395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"277.396156ms","start":"2026-02-25T17:04:32.302251Z","end":"2026-02-25T17:04:32.579647Z","steps":["trace[881321395] 'agreement among raft nodes before linearized reading' (duration: 277.290952ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:32.579758Z","caller":"traceutil/trace.go:171","msg":"trace[118710517] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:556; }","duration":"474.341425ms","start":"2026-02-25T17:04:32.105354Z","end":"2026-02-25T17:04:32.579695Z","steps":["trace[118710517] 'agreement among raft nodes before linearized reading' (duration: 474.047476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:32.579867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:32.105334Z","time spent":"474.515491ms","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T17:04:33.671038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.156092ms","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-25T17:04:33.671091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.231388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:33.67115Z","caller":"traceutil/trace.go:171","msg":"trace[923960241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"367.342522ms","start":"2026-02-25T17:04:33.303794Z","end":"2026-02-25T17:04:33.671137Z","steps":["trace[923960241] 'range keys from in-memory index tree' (duration: 367.124014ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:33.671145Z","caller":"traceutil/trace.go:171","msg":"trace[1139665844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"664.301377ms","start":"2026-02-25T17:04:33.006816Z","end":"2026-02-25T17:04:33.671117Z","steps":["trace[1139665844] 'range keys from in-memory index tree' (duration: 663.981016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:33.67118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.303775Z","time spent":"367.398944ms","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:33.671197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.006798Z","time spent":"664.38798ms","remote":"127.0.0.1:33874","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-25T17:04:33.671205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.304087ms","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-25T17:04:33.671347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.076657ms","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-25T17:04:33.671322Z","caller":"traceutil/trace.go:171","msg":"trace[374385399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"664.477662ms","start":"2026-02-25T17:04:33.006817Z","end":"2026-02-25T17:04:33.671295Z","steps":["trace[374385399] 'range keys from in-memory index tree' (duration: 664.067189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:33.671448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.517219ms","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-25T17:04:33.671467Z","caller":"traceutil/trace.go:171","msg":"trace[771755387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"666.53675ms","start":"2026-02-25T17:04:33.004925Z","end":"2026-02-25T17:04:33.671462Z","steps":["trace[771755387] 'range keys from in-memory index tree' (duration: 666.390545ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:33.67146Z","caller":"traceutil/trace.go:171","msg":"trace[1948117593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"663.240222ms","start":"2026-02-25T17:04:33.008199Z","end":"2026-02-25T17:04:33.671439Z","steps":["trace[1948117593] 'range keys from in-memory index tree' (duration: 662.84747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:33.671486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.004875Z","time spent":"666.602921ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T17:04:33.671473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.006805Z","time spent":"664.652418ms","remote":"127.0.0.1:33874","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-25T17:04:33.671517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.008179Z","time spent":"663.323175ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:04:34.945862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609446396940222934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:04:37.301784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999882089s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T17:04:37.30189Z","caller":"traceutil/trace.go:171","msg":"trace[318431909] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000051454s","start":"2026-02-25T17:04:35.301817Z","end":"2026-02-25T17:04:37.301869Z","steps":["trace[318431909] 'agreement among raft nodes before linearized reading' (duration: 1.99990854s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:37.301936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:35.301801Z","time spent":"2.000124327s","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 17:04:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T17:04:38.092444Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096461495s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T17:04:38.53213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.621687897s","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-25T17:04:38.532284Z","caller":"traceutil/trace.go:171","msg":"trace[124198466] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:557; }","duration":"3.621838012s","start":"2026-02-25T17:04:34.910416Z","end":"2026-02-25T17:04:38.532254Z","steps":["trace[124198466] 'range keys from in-memory index tree' (duration: 3.621671507s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.533061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.44851057s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:38.5333Z","caller":"traceutil/trace.go:171","msg":"trace[391032755] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"4.856604393s","start":"2026-02-25T17:04:33.676678Z","end":"2026-02-25T17:04:38.533282Z","steps":["trace[391032755] 'process raft request' (duration: 4.856477379s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.533352Z","caller":"traceutil/trace.go:171","msg":"trace[620268937] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"4.85648503s","start":"2026-02-25T17:04:33.676844Z","end":"2026-02-25T17:04:38.533329Z","steps":["trace[620268937] 'process raft request' (duration: 4.856394757s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.533401Z","caller":"traceutil/trace.go:171","msg":"trace[1840003548] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"4.750369405s","start":"2026-02-25T17:04:33.78301Z","end":"2026-02-25T17:04:38.53338Z","steps":["trace[1840003548] 'process raft request' (duration: 4.750268661s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.533445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.676563Z","time spent":"4.85680756s","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:38.533453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.676818Z","time spent":"4.856581802s","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:38.533491Z","caller":"traceutil/trace.go:171","msg":"trace[499777165] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:577; }","duration":"4.088554668s","start":"2026-02-25T17:04:34.44491Z","end":"2026-02-25T17:04:38.533464Z","steps":["trace[499777165] 'read index received' (duration: 540.823176ms)","trace[499777165] 'applied index is now lower than readState.Index' (duration: 3.547730552s)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:38.533298Z","caller":"traceutil/trace.go:171","msg":"trace[899840314] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"4.856647875s","start":"2026-02-25T17:04:33.676602Z","end":"2026-02-25T17:04:38.53325Z","steps":["trace[899840314] 'process raft request' (duration: 407.718421ms)","trace[899840314] 'compare' (duration: 4.447961473s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:38.533564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.782976Z","time spent":"4.75050355s","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:38.533625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:33.676572Z","time spent":"4.857012127s","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:38.533693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231744173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:04:38.533717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.230206566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:38.533739Z","caller":"traceutil/trace.go:171","msg":"trace[464586954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"1.231836576s","start":"2026-02-25T17:04:37.30189Z","end":"2026-02-25T17:04:38.533727Z","steps":["trace[464586954] 'agreement among raft nodes before linearized reading' (duration: 1.231744443s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.533738Z","caller":"traceutil/trace.go:171","msg":"trace[1800263413] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.5379883s","start":"2026-02-25T17:04:36.995659Z","end":"2026-02-25T17:04:38.533648Z","steps":["trace[1800263413] 'process raft request' (duration: 1.537741282s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.533773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:37.301872Z","time spent":"1.231893357s","remote":"127.0.0.1:33578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:04:38.533774Z","caller":"traceutil/trace.go:171","msg":"trace[959788505] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:562; }","duration":"3.230279179s","start":"2026-02-25T17:04:35.303473Z","end":"2026-02-25T17:04:38.533752Z","steps":["trace[959788505] 'agreement among raft nodes before linearized reading' (duration: 3.230154585s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.533822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:35.303465Z","time spent":"3.230344961s","remote":"127.0.0.1:33824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" "} {"level":"warn","ts":"2026-02-25T17:04:38.533847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.088929531s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.54\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T17:04:38.533856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.846362585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T17:04:38.533875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:36.995629Z","time spent":"1.538165416s","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:38.533924Z","caller":"traceutil/trace.go:171","msg":"trace[548354624] range","detail":"{range_begin:/registry/masterleases/199.19.213.54; range_end:; response_count:1; response_revision:562; }","duration":"4.089072706s","start":"2026-02-25T17:04:34.444828Z","end":"2026-02-25T17:04:38.533901Z","steps":["trace[548354624] 'agreement among raft nodes before linearized reading' (duration: 4.088734485s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.533927Z","caller":"traceutil/trace.go:171","msg":"trace[186503598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"2.846469448s","start":"2026-02-25T17:04:35.687439Z","end":"2026-02-25T17:04:38.533908Z","steps":["trace[186503598] 'agreement among raft nodes before linearized reading' (duration: 2.846251231s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.533968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:35.687418Z","time spent":"2.84654147s","remote":"127.0.0.1:33874","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-25T17:04:38.533969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:34.4448Z","time spent":"4.089160249s","remote":"127.0.0.1:33636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.54\" "} {"level":"info","ts":"2026-02-25T17:04:38.74837Z","caller":"traceutil/trace.go:171","msg":"trace[272440298] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"209.661835ms","start":"2026-02-25T17:04:38.538683Z","end":"2026-02-25T17:04:38.748345Z","steps":["trace[272440298] 'process raft request' (duration: 209.581673ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.748369Z","caller":"traceutil/trace.go:171","msg":"trace[1242191324] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"125.123445ms","start":"2026-02-25T17:04:38.62323Z","end":"2026-02-25T17:04:38.748354Z","steps":["trace[1242191324] 'read index received' (duration: 121.347211ms)","trace[1242191324] 'applied index is now lower than readState.Index' (duration: 3.775394ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:38.748412Z","caller":"traceutil/trace.go:171","msg":"trace[895127999] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"209.717117ms","start":"2026-02-25T17:04:38.538686Z","end":"2026-02-25T17:04:38.748404Z","steps":["trace[895127999] 'process raft request' (duration: 205.874682ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:38.748547Z","caller":"traceutil/trace.go:171","msg":"trace[1908369153] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"124.564527ms","start":"2026-02-25T17:04:38.623937Z","end":"2026-02-25T17:04:38.748502Z","steps":["trace[1908369153] 'process raft request' (duration: 124.385151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.748667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.675295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-webhook\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:38.748748Z","caller":"traceutil/trace.go:171","msg":"trace[2124166630] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-webhook; range_end:; response_count:0; response_revision:565; }","duration":"122.807749ms","start":"2026-02-25T17:04:38.625922Z","end":"2026-02-25T17:04:38.74873Z","steps":["trace[2124166630] 'agreement among raft nodes before linearized reading' (duration: 122.681655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:38.748657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.530853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:38.748855Z","caller":"traceutil/trace.go:171","msg":"trace[629620035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"206.75848ms","start":"2026-02-25T17:04:38.542077Z","end":"2026-02-25T17:04:38.748835Z","steps":["trace[629620035] 'agreement among raft nodes before linearized reading' (duration: 206.309146ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:39.50491Z","caller":"traceutil/trace.go:171","msg":"trace[1283734721] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"120.0516ms","start":"2026-02-25T17:04:39.384823Z","end":"2026-02-25T17:04:39.504875Z","steps":["trace[1283734721] 'process raft request' (duration: 119.42773ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:40.808024Z","caller":"traceutil/trace.go:171","msg":"trace[1561190043] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"164.865572ms","start":"2026-02-25T17:04:40.643124Z","end":"2026-02-25T17:04:40.80799Z","steps":["trace[1561190043] 'process raft request' (duration: 164.52059ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:57.745957Z","caller":"traceutil/trace.go:171","msg":"trace[1997659001] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"159.116004ms","start":"2026-02-25T17:04:57.586814Z","end":"2026-02-25T17:04:57.74593Z","steps":["trace[1997659001] 'process raft request' (duration: 158.967179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:58.803566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609446396940223574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:04:59.265727Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.519906189s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T17:04:59.266102Z","caller":"traceutil/trace.go:171","msg":"trace[1151879366] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"1.636822086s","start":"2026-02-25T17:04:57.629261Z","end":"2026-02-25T17:04:59.266083Z","steps":["trace[1151879366] 'process raft request' (duration: 1.63663798s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.266289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:57.629229Z","time spent":"1.63694026s","remote":"127.0.0.1:33800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5076,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:59.312767Z","caller":"traceutil/trace.go:171","msg":"trace[93676158] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"1.561119235s","start":"2026-02-25T17:04:57.751629Z","end":"2026-02-25T17:04:59.312749Z","steps":["trace[93676158] 'process raft request' (duration: 1.554050464s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.312938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:57.751612Z","time spent":"1.561252039s","remote":"127.0.0.1:33798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1080,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:59.312986Z","caller":"traceutil/trace.go:171","msg":"trace[2088393498] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"1.560244736s","start":"2026-02-25T17:04:57.752722Z","end":"2026-02-25T17:04:59.312967Z","steps":["trace[2088393498] 'process raft request' (duration: 1.559976568s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.313108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:57.752681Z","time spent":"1.56036926s","remote":"127.0.0.1:33884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1601,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:59.31338Z","caller":"traceutil/trace.go:171","msg":"trace[1234823986] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:946; }","duration":"1.009910349s","start":"2026-02-25T17:04:58.303443Z","end":"2026-02-25T17:04:59.313354Z","steps":["trace[1234823986] 'read index received' (duration: 962.586334ms)","trace[1234823986] 'applied index is now lower than readState.Index' (duration: 47.322765ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:59.31347Z","caller":"traceutil/trace.go:171","msg":"trace[267894944] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.559173252s","start":"2026-02-25T17:04:57.754261Z","end":"2026-02-25T17:04:59.313435Z","steps":["trace[267894944] 'process raft request' (duration: 1.558609823s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.313594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010126617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:04:59.313642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.14718ms","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-25T17:04:59.313651Z","caller":"traceutil/trace.go:171","msg":"trace[734756794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"1.01024602s","start":"2026-02-25T17:04:58.303394Z","end":"2026-02-25T17:04:59.31364Z","steps":["trace[734756794] 'agreement among raft nodes before linearized reading' (duration: 1.010133687s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:59.313683Z","caller":"traceutil/trace.go:171","msg":"trace[1991947103] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:925; }","duration":"705.225522ms","start":"2026-02-25T17:04:58.608445Z","end":"2026-02-25T17:04:59.31367Z","steps":["trace[1991947103] 'agreement among raft nodes before linearized reading' (duration: 705.114769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.313693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:58.303373Z","time spent":"1.010311663s","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:59.31369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:57.754252Z","time spent":"1.559326526s","remote":"127.0.0.1:46950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:59.313711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:58.608421Z","time spent":"705.283794ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:04:59.31365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.607918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:04:59.313731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.767989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:04:59.313779Z","caller":"traceutil/trace.go:171","msg":"trace[146046143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:925; }","duration":"610.852613ms","start":"2026-02-25T17:04:58.702916Z","end":"2026-02-25T17:04:59.313769Z","steps":["trace[146046143] 'agreement among raft nodes before linearized reading' (duration: 610.650326ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:59.313777Z","caller":"traceutil/trace.go:171","msg":"trace[499336200] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:925; }","duration":"192.738012ms","start":"2026-02-25T17:04:59.121021Z","end":"2026-02-25T17:04:59.313759Z","steps":["trace[499336200] 'agreement among raft nodes before linearized reading' (duration: 192.574507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.313788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.791238ms","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-25T17:04:59.313825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:58.702908Z","time spent":"610.908184ms","remote":"127.0.0.1:33874","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-25T17:04:59.313844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.193576ms","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-25T17:04:59.313872Z","caller":"traceutil/trace.go:171","msg":"trace[1305880913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:925; }","duration":"650.286019ms","start":"2026-02-25T17:04:58.663578Z","end":"2026-02-25T17:04:59.313864Z","steps":["trace[1305880913] 'agreement among raft nodes before linearized reading' (duration: 650.213566ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:59.313874Z","caller":"traceutil/trace.go:171","msg":"trace[1342227019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:925; }","duration":"360.923233ms","start":"2026-02-25T17:04:58.952931Z","end":"2026-02-25T17:04:59.313854Z","steps":["trace[1342227019] 'agreement among raft nodes before linearized reading' (duration: 360.717347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.313903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:58.663557Z","time spent":"650.340911ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:04:59.313923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:58.95291Z","time spent":"361.001185ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T17:04:59.313497Z","caller":"traceutil/trace.go:171","msg":"trace[1304902212] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"102.901259ms","start":"2026-02-25T17:04:59.21056Z","end":"2026-02-25T17:04:59.313461Z","steps":["trace[1304902212] 'process raft request' (duration: 102.646471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.31403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.64295ms","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-25T17:04:59.314203Z","caller":"traceutil/trace.go:171","msg":"trace[956354445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"612.752855ms","start":"2026-02-25T17:04:58.701332Z","end":"2026-02-25T17:04:59.314085Z","steps":["trace[956354445] 'agreement among raft nodes before linearized reading' (duration: 612.344352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.314275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:58.701314Z","time spent":"612.94242ms","remote":"127.0.0.1:33874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T17:05:00.115232Z","caller":"traceutil/trace.go:171","msg":"trace[672510117] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"226.587937ms","start":"2026-02-25T17:04:59.888618Z","end":"2026-02-25T17:05:00.115206Z","steps":["trace[672510117] 'read index received' (duration: 189.154485ms)","trace[672510117] 'applied index is now lower than readState.Index' (duration: 37.432392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:00.115874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.239569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-tbm4h\" ","response":"range_response_count:1 size:5005"} {"level":"info","ts":"2026-02-25T17:05:00.115944Z","caller":"traceutil/trace.go:171","msg":"trace[136111106] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-tbm4h; range_end:; response_count:1; response_revision:934; }","duration":"227.365303ms","start":"2026-02-25T17:04:59.888561Z","end":"2026-02-25T17:05:00.115926Z","steps":["trace[136111106] 'agreement among raft nodes before linearized reading' (duration: 226.721962ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:07.506945Z","caller":"traceutil/trace.go:171","msg":"trace[1706662487] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"110.994231ms","start":"2026-02-25T17:05:07.395906Z","end":"2026-02-25T17:05:07.5069Z","steps":["trace[1706662487] 'process raft request' (duration: 44.413003ms)","trace[1706662487] 'compare' (duration: 66.412713ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:07.598816Z","caller":"traceutil/trace.go:171","msg":"trace[1539892489] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"195.485836ms","start":"2026-02-25T17:05:07.403301Z","end":"2026-02-25T17:05:07.598787Z","steps":["trace[1539892489] 'read index received' (duration: 36.949799ms)","trace[1539892489] 'applied index is now lower than readState.Index' (duration: 158.534967ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:07.598993Z","caller":"traceutil/trace.go:171","msg":"trace[1547428084] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"202.738894ms","start":"2026-02-25T17:05:07.396219Z","end":"2026-02-25T17:05:07.598958Z","steps":["trace[1547428084] 'process raft request' (duration: 202.353141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:07.59932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.774516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-25T17:05:07.59938Z","caller":"traceutil/trace.go:171","msg":"trace[879856315] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:971; }","duration":"196.140718ms","start":"2026-02-25T17:05:07.403224Z","end":"2026-02-25T17:05:07.599365Z","steps":["trace[879856315] 'agreement among raft nodes before linearized reading' (duration: 195.678543ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:13.531865Z","caller":"traceutil/trace.go:171","msg":"trace[1556702051] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"113.137512ms","start":"2026-02-25T17:05:13.418702Z","end":"2026-02-25T17:05:13.531839Z","steps":["trace[1556702051] 'read index received' (duration: 76.693639ms)","trace[1556702051] 'applied index is now lower than readState.Index' (duration: 36.442393ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:13.53195Z","caller":"traceutil/trace.go:171","msg":"trace[215341396] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"115.698005ms","start":"2026-02-25T17:05:13.416224Z","end":"2026-02-25T17:05:13.531922Z","steps":["trace[215341396] 'process raft request' (duration: 115.461028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:13.532211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.483373ms","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-25T17:05:13.532373Z","caller":"traceutil/trace.go:171","msg":"trace[881485968] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"113.688509ms","start":"2026-02-25T17:05:13.418664Z","end":"2026-02-25T17:05:13.532353Z","steps":["trace[881485968] 'agreement among raft nodes before linearized reading' (duration: 113.284926ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:22.261403Z","caller":"traceutil/trace.go:171","msg":"trace[1123877863] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"200.055971ms","start":"2026-02-25T17:06:22.061315Z","end":"2026-02-25T17:06:22.261371Z","steps":["trace[1123877863] 'process raft request' (duration: 199.843864ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:22.873247Z","caller":"traceutil/trace.go:171","msg":"trace[1313077136] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"545.925168ms","start":"2026-02-25T17:06:22.327298Z","end":"2026-02-25T17:06:22.873223Z","steps":["trace[1313077136] 'process raft request' (duration: 545.769113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:22.873444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:22.327285Z","time spent":"546.038181ms","remote":"127.0.0.1:33682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":828,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:22.876129Z","caller":"traceutil/trace.go:171","msg":"trace[1256884641] linearizableReadLoop","detail":"{readStateIndex:1722; appliedIndex:1720; }","duration":"179.740453ms","start":"2026-02-25T17:06:22.696358Z","end":"2026-02-25T17:06:22.876099Z","steps":["trace[1256884641] 'read index received' (duration: 176.726286ms)","trace[1256884641] 'applied index is now lower than readState.Index' (duration: 3.012797ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:22.876291Z","caller":"traceutil/trace.go:171","msg":"trace[246472103] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"527.522401ms","start":"2026-02-25T17:06:22.348731Z","end":"2026-02-25T17:06:22.876254Z","steps":["trace[246472103] 'process raft request' (duration: 527.210831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:22.87642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.044104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-02-25T17:06:22.876458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:22.348716Z","time spent":"527.653126ms","remote":"127.0.0.1:33804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5600,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:22.87649Z","caller":"traceutil/trace.go:171","msg":"trace[1718129148] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1650; }","duration":"180.161527ms","start":"2026-02-25T17:06:22.696311Z","end":"2026-02-25T17:06:22.876473Z","steps":["trace[1718129148] 'agreement among raft nodes before linearized reading' (duration: 179.897809ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:09:10.933851Z","caller":"traceutil/trace.go:171","msg":"trace[1733690156] linearizableReadLoop","detail":"{readStateIndex:2347; appliedIndex:2346; }","duration":"138.962023ms","start":"2026-02-25T17:09:10.794864Z","end":"2026-02-25T17:09:10.933826Z","steps":["trace[1733690156] 'read index received' (duration: 138.825069ms)","trace[1733690156] 'applied index is now lower than readState.Index' (duration: 135.934µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:09:10.933898Z","caller":"traceutil/trace.go:171","msg":"trace[359684939] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"139.052736ms","start":"2026-02-25T17:09:10.794811Z","end":"2026-02-25T17:09:10.933864Z","steps":["trace[359684939] 'process raft request' (duration: 138.87049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:10.93421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.269422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T17:09:10.93434Z","caller":"traceutil/trace.go:171","msg":"trace[1000906125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2239; }","duration":"139.478009ms","start":"2026-02-25T17:09:10.794832Z","end":"2026-02-25T17:09:10.93431Z","steps":["trace[1000906125] 'agreement among raft nodes before linearized reading' (duration: 139.093677ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:09:11.033626Z","caller":"traceutil/trace.go:171","msg":"trace[937093936] transaction","detail":"{read_only:false; response_revision:2240; number_of_response:1; }","duration":"237.377445ms","start":"2026-02-25T17:09:10.796222Z","end":"2026-02-25T17:09:11.033599Z","steps":["trace[937093936] 'process raft request' (duration: 237.155588ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:09:11.033961Z","caller":"traceutil/trace.go:171","msg":"trace[469097873] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"235.514507ms","start":"2026-02-25T17:09:10.798411Z","end":"2026-02-25T17:09:11.033926Z","steps":["trace[469097873] 'process raft request' (duration: 235.114795ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:13:51.339988Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2165} {"level":"info","ts":"2026-02-25T17:13:51.397718Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2165,"took":"56.89795ms","hash":1189198987,"current-db-size-bytes":14475264,"current-db-size":"14 MB","current-db-size-in-use-bytes":4521984,"current-db-size-in-use":"4.5 MB"} {"level":"info","ts":"2026-02-25T17:13:51.397783Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1189198987,"revision":2165,"compact-revision":-1} {"level":"info","ts":"2026-02-25T17:18:51.346939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3249} {"level":"info","ts":"2026-02-25T17:18:51.366761Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3249,"took":"19.178657ms","hash":113231873,"current-db-size-bytes":14475264,"current-db-size":"14 MB","current-db-size-in-use-bytes":4935680,"current-db-size-in-use":"4.9 MB"} {"level":"info","ts":"2026-02-25T17:18:51.36683Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":113231873,"revision":3249,"compact-revision":2165}