{"level":"warn","ts":"2026-02-25T02:12:35.560308Z","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-25T02:12:35.560404Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212: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://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-25T02:12:35.560613Z","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-25T02:12:35.560722Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-25T02:12:35.560761Z","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-25T02:12:35.5616Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-25T02:12:35.561699Z","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://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-25T02:12:35.565923Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.023267ms"} {"level":"info","ts":"2026-02-25T02:12:35.572254Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-25T02:12:35.572377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T02:12:35.572442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-25T02:12:35.572477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T02:12:35.5725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-25T02:12:35.572582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-25T02:12:35.580548Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T02:12:35.582233Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T02:12:35.583481Z","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-25T02:12:35.585731Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T02:12:35.586426Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T02:12:35.586621Z","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-25T02:12:35.586672Z","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-25T02:12:35.586681Z","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-25T02:12:35.5872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-25T02:12:35.587316Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-25T02:12:35.590437Z","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-25T02:12:35.590506Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-25T02:12:35.59053Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-25T02:12:35.590683Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T02:12:35.590728Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T02:12:36.473218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T02:12:36.473287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T02:12:36.473312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-25T02:12:36.473332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-25T02:12:36.473344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-25T02:12:36.473361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-25T02:12:36.473375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-25T02:12:36.474558Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:12:36.475795Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T02:12:36.475826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:12:36.475856Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T02:12:36.476068Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T02:12:36.476102Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T02:12:36.476673Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:12:36.476805Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:12:36.476839Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T02:12:36.478123Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T02:12:36.478347Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"warn","ts":"2026-02-25T02:12:47.116378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.376896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:12:47.116611Z","caller":"traceutil/trace.go:171","msg":"trace[1721539566] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:352; }","duration":"860.618059ms","start":"2026-02-25T02:12:46.255977Z","end":"2026-02-25T02:12:47.116595Z","steps":["trace[1721539566] 'read index received' (duration: 287.831354ms)","trace[1721539566] 'applied index is now lower than readState.Index' (duration: 572.785784ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:47.116722Z","caller":"traceutil/trace.go:171","msg":"trace[1889362260] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"885.532027ms","start":"2026-02-25T02:12:46.23118Z","end":"2026-02-25T02:12:47.116713Z","steps":["trace[1889362260] 'process raft request' (duration: 885.334763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.116805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.231156Z","time spent":"885.60306ms","remote":"127.0.0.1:37614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:47.117291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.468181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T02:12:47.117013Z","caller":"traceutil/trace.go:171","msg":"trace[1350015566] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"892.536659ms","start":"2026-02-25T02:12:46.224212Z","end":"2026-02-25T02:12:47.116748Z","steps":["trace[1350015566] 'process raft request' (duration: 319.073409ms)","trace[1350015566] 'compare' (duration: 571.203461ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:47.117374Z","caller":"traceutil/trace.go:171","msg":"trace[1840971115] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:344; }","duration":"660.85665ms","start":"2026-02-25T02:12:46.456497Z","end":"2026-02-25T02:12:47.117353Z","steps":["trace[1840971115] 'agreement among raft nodes before linearized reading' (duration: 660.449331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.117368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.954092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-02-25T02:12:47.117347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.591208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-25T02:12:47.117431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.456484Z","time spent":"660.932961ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"info","ts":"2026-02-25T02:12:47.117448Z","caller":"traceutil/trace.go:171","msg":"trace[367521111] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:344; }","duration":"409.083595ms","start":"2026-02-25T02:12:46.708338Z","end":"2026-02-25T02:12:47.117422Z","steps":["trace[367521111] 'agreement among raft nodes before linearized reading' (duration: 408.740648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.117508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.708315Z","time spent":"409.180958ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-25T02:12:47.117476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.646751ms","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-25T02:12:47.117441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.90357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-02-25T02:12:47.117604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.464248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-02-25T02:12:47.117681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.108142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T02:12:47.117757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.649602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T02:12:47.117508Z","caller":"traceutil/trace.go:171","msg":"trace[1985535372] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:344; }","duration":"811.125208ms","start":"2026-02-25T02:12:46.306351Z","end":"2026-02-25T02:12:47.117476Z","steps":["trace[1985535372] 'agreement among raft nodes before linearized reading' (duration: 810.521815ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:47.117794Z","caller":"traceutil/trace.go:171","msg":"trace[152462551] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:344; }","duration":"610.244035ms","start":"2026-02-25T02:12:46.507536Z","end":"2026-02-25T02:12:47.11778Z","steps":["trace[152462551] 'agreement among raft nodes before linearized reading' (duration: 610.058281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.117836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.30633Z","time spent":"811.489106ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-25T02:12:47.117546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.270983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T02:12:47.11771Z","caller":"traceutil/trace.go:171","msg":"trace[1769192447] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"560.600722ms","start":"2026-02-25T02:12:46.557079Z","end":"2026-02-25T02:12:47.11768Z","steps":["trace[1769192447] 'agreement among raft nodes before linearized reading' (duration: 560.377597ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:47.117947Z","caller":"traceutil/trace.go:171","msg":"trace[1680100616] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:344; }","duration":"460.715513ms","start":"2026-02-25T02:12:46.6572Z","end":"2026-02-25T02:12:47.117916Z","steps":["trace[1680100616] 'agreement among raft nodes before linearized reading' (duration: 460.148651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.117968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.50752Z","time spent":"610.368498ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-25T02:12:47.118007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.657184Z","time spent":"460.809504ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-02-25T02:12:47.118009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.557066Z","time spent":"560.907048ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-02-25T02:12:47.117759Z","caller":"traceutil/trace.go:171","msg":"trace[1817332198] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:344; }","duration":"710.210587ms","start":"2026-02-25T02:12:46.407447Z","end":"2026-02-25T02:12:47.117657Z","steps":["trace[1817332198] 'agreement among raft nodes before linearized reading' (duration: 709.842109ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:47.117589Z","caller":"traceutil/trace.go:171","msg":"trace[741245612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:344; }","duration":"257.799624ms","start":"2026-02-25T02:12:46.859763Z","end":"2026-02-25T02:12:47.117563Z","steps":["trace[741245612] 'agreement among raft nodes before linearized reading' (duration: 257.6108ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:12:47.117819Z","caller":"traceutil/trace.go:171","msg":"trace[1449416473] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:344; }","duration":"511.454369ms","start":"2026-02-25T02:12:46.606348Z","end":"2026-02-25T02:12:47.117802Z","steps":["trace[1449416473] 'agreement among raft nodes before linearized reading' (duration: 510.54006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.117539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.224195Z","time spent":"893.206963ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:47.11813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.407421Z","time spent":"710.660336ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-25T02:12:47.11758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.915823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-02-25T02:12:47.118117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.606339Z","time spent":"511.768396ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-02-25T02:12:47.118212Z","caller":"traceutil/trace.go:171","msg":"trace[1458632245] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"761.579677ms","start":"2026-02-25T02:12:46.356613Z","end":"2026-02-25T02:12:47.118193Z","steps":["trace[1458632245] 'agreement among raft nodes before linearized reading' (duration: 760.827791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.11826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.356602Z","time spent":"761.642739ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-25T02:12:47.117688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.056288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T02:12:47.118375Z","caller":"traceutil/trace.go:171","msg":"trace[1125485176] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:344; }","duration":"862.444198ms","start":"2026-02-25T02:12:46.255917Z","end":"2026-02-25T02:12:47.118361Z","steps":["trace[1125485176] 'agreement among raft nodes before linearized reading' (duration: 861.063299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.118425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.255895Z","time spent":"862.517769ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-25T02:12:47.117769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.208208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T02:12:47.118876Z","caller":"traceutil/trace.go:171","msg":"trace[1433411534] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:344; }","duration":"361.825834ms","start":"2026-02-25T02:12:46.757031Z","end":"2026-02-25T02:12:47.118857Z","steps":["trace[1433411534] 'agreement among raft nodes before linearized reading' (duration: 359.944704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:47.118932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:46.75701Z","time spent":"361.904025ms","remote":"127.0.0.1:37638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-02-25T02:12:52.513243Z","caller":"traceutil/trace.go:171","msg":"trace[790090337] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"348.386593ms","start":"2026-02-25T02:12:52.164826Z","end":"2026-02-25T02:12:52.513213Z","steps":["trace[790090337] 'read index received' (duration: 348.121397ms)","trace[790090337] 'applied index is now lower than readState.Index' (duration: 263.966µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:12:52.513424Z","caller":"traceutil/trace.go:171","msg":"trace[965013302] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"438.300608ms","start":"2026-02-25T02:12:52.07511Z","end":"2026-02-25T02:12:52.513411Z","steps":["trace[965013302] 'process raft request' (duration: 437.91649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:52.513592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:52.075076Z","time spent":"438.397109ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:12:52.513572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.968001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:12:52.513683Z","caller":"traceutil/trace.go:171","msg":"trace[1297797099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"260.114234ms","start":"2026-02-25T02:12:52.253538Z","end":"2026-02-25T02:12:52.513653Z","steps":["trace[1297797099] 'agreement among raft nodes before linearized reading' (duration: 259.968421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:52.514002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.178549ms","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-25T02:12:52.51399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.137718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4025"} {"level":"info","ts":"2026-02-25T02:12:52.514034Z","caller":"traceutil/trace.go:171","msg":"trace[1436751353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"349.252492ms","start":"2026-02-25T02:12:52.164772Z","end":"2026-02-25T02:12:52.514024Z","steps":["trace[1436751353] 'agreement among raft nodes before linearized reading' (duration: 349.188331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:12:52.514066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:12:52.16475Z","time spent":"349.308223ms","remote":"127.0.0.1:37708","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-25T02:12:52.514075Z","caller":"traceutil/trace.go:171","msg":"trace[1737516843] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:412; }","duration":"243.269401ms","start":"2026-02-25T02:12:52.270784Z","end":"2026-02-25T02:12:52.514054Z","steps":["trace[1737516843] 'agreement among raft nodes before linearized reading' (duration: 243.103607ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:02.935754Z","caller":"traceutil/trace.go:171","msg":"trace[1184294421] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"333.039062ms","start":"2026-02-25T02:13:02.602665Z","end":"2026-02-25T02:13:02.935704Z","steps":["trace[1184294421] 'read index received' (duration: 332.877758ms)","trace[1184294421] 'applied index is now lower than readState.Index' (duration: 160.064µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:02.935814Z","caller":"traceutil/trace.go:171","msg":"trace[254626483] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"376.603193ms","start":"2026-02-25T02:13:02.559178Z","end":"2026-02-25T02:13:02.935781Z","steps":["trace[254626483] 'process raft request' (duration: 376.371508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:02.935887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.209805ms","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-25T02:13:02.935922Z","caller":"traceutil/trace.go:171","msg":"trace[1240156814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"333.299047ms","start":"2026-02-25T02:13:02.602612Z","end":"2026-02-25T02:13:02.935911Z","steps":["trace[1240156814] 'agreement among raft nodes before linearized reading' (duration: 333.218795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:02.935964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:02.602591Z","time spent":"333.365128ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:13:02.935966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:02.55915Z","time spent":"376.737155ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:03.261081Z","caller":"traceutil/trace.go:171","msg":"trace[443104483] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"321.485162ms","start":"2026-02-25T02:13:02.939549Z","end":"2026-02-25T02:13:03.261034Z","steps":["trace[443104483] 'process raft request' (duration: 298.235829ms)","trace[443104483] 'compare' (duration: 23.107219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:03.26145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:02.939523Z","time spent":"321.785458ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:07.210773Z","caller":"traceutil/trace.go:171","msg":"trace[1743596249] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"258.232715ms","start":"2026-02-25T02:13:06.952521Z","end":"2026-02-25T02:13:07.210754Z","steps":["trace[1743596249] 'process raft request' (duration: 258.116643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:07.331311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.163374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4025"} {"level":"info","ts":"2026-02-25T02:13:07.331442Z","caller":"traceutil/trace.go:171","msg":"trace[155414058] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:508; }","duration":"105.314408ms","start":"2026-02-25T02:13:07.226104Z","end":"2026-02-25T02:13:07.331419Z","steps":["trace[155414058] 'range keys from in-memory index tree' (duration: 105.085702ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:13.408801Z","caller":"traceutil/trace.go:171","msg":"trace[1239350999] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"176.668549ms","start":"2026-02-25T02:13:13.232107Z","end":"2026-02-25T02:13:13.408775Z","steps":["trace[1239350999] 'process raft request' (duration: 176.527486ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:13.75818Z","caller":"traceutil/trace.go:171","msg":"trace[419842204] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:565; }","duration":"505.623342ms","start":"2026-02-25T02:13:13.252537Z","end":"2026-02-25T02:13:13.758161Z","steps":["trace[419842204] 'read index received' (duration: 156.047146ms)","trace[419842204] 'applied index is now lower than readState.Index' (duration: 349.575596ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:13.758428Z","caller":"traceutil/trace.go:171","msg":"trace[1057802076] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"507.77799ms","start":"2026-02-25T02:13:13.250643Z","end":"2026-02-25T02:13:13.758421Z","steps":["trace[1057802076] 'process raft request' (duration: 507.412142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:13.758499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:13.250609Z","time spent":"507.84115ms","remote":"127.0.0.1:37614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:13.75864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.102102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:13:13.758663Z","caller":"traceutil/trace.go:171","msg":"trace[1872915594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"506.148313ms","start":"2026-02-25T02:13:13.252509Z","end":"2026-02-25T02:13:13.758657Z","steps":["trace[1872915594] 'agreement among raft nodes before linearized reading' (duration: 506.113962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:13.758677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:13.2525Z","time spent":"506.174863ms","remote":"127.0.0.1:37440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:13:13.758791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.066842ms","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-25T02:13:13.758809Z","caller":"traceutil/trace.go:171","msg":"trace[614329342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"402.114103ms","start":"2026-02-25T02:13:13.356691Z","end":"2026-02-25T02:13:13.758805Z","steps":["trace[614329342] 'agreement among raft nodes before linearized reading' (duration: 402.051042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:13.758822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:13.356669Z","time spent":"402.149923ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T02:13:13.759057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.429841ms","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-25T02:13:13.759094Z","caller":"traceutil/trace.go:171","msg":"trace[124463205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"388.502313ms","start":"2026-02-25T02:13:13.370583Z","end":"2026-02-25T02:13:13.759085Z","steps":["trace[124463205] 'agreement among raft nodes before linearized reading' (duration: 388.439012ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:13.759117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:13.370563Z","time spent":"388.550044ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T02:13:13.75923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.486149ms","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-25T02:13:13.759249Z","caller":"traceutil/trace.go:171","msg":"trace[315881644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"391.54115ms","start":"2026-02-25T02:13:13.367704Z","end":"2026-02-25T02:13:13.759245Z","steps":["trace[315881644] 'agreement among raft nodes before linearized reading' (duration: 391.497389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:13.759261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:13.367689Z","time spent":"391.569791ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T02:13:14.269384Z","caller":"traceutil/trace.go:171","msg":"trace[2076056542] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"506.582561ms","start":"2026-02-25T02:13:13.762779Z","end":"2026-02-25T02:13:14.269361Z","steps":["trace[2076056542] 'process raft request' (duration: 506.449218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:14.269528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:13.762767Z","time spent":"506.686773ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:14.275304Z","caller":"traceutil/trace.go:171","msg":"trace[336720202] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"510.332123ms","start":"2026-02-25T02:13:13.764941Z","end":"2026-02-25T02:13:14.275273Z","steps":["trace[336720202] 'process raft request' (duration: 510.241691ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:14.275455Z","caller":"traceutil/trace.go:171","msg":"trace[791140944] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"510.597319ms","start":"2026-02-25T02:13:13.764849Z","end":"2026-02-25T02:13:14.275447Z","steps":["trace[791140944] 'process raft request' (duration: 510.245631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:14.27551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:13.764825Z","time spent":"510.657201ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:14.275519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:13.764934Z","time spent":"510.458026ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:15.630725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.540329ms","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-25T02:13:15.630813Z","caller":"traceutil/trace.go:171","msg":"trace[282327037] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:553; }","duration":"829.640701ms","start":"2026-02-25T02:13:14.801161Z","end":"2026-02-25T02:13:15.630802Z","steps":["trace[282327037] 'range keys from in-memory index tree' (duration: 829.526829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:15.637862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.341453ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:13:15.637985Z","caller":"traceutil/trace.go:171","msg":"trace[683752535] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"710.162081ms","start":"2026-02-25T02:13:14.927804Z","end":"2026-02-25T02:13:15.637966Z","steps":["trace[683752535] 'read index received' (duration: 51.681µs)","trace[683752535] 'applied index is now lower than readState.Index' (duration: 710.10875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:15.63811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.034604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:13:15.638114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.278103ms","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-25T02:13:15.638148Z","caller":"traceutil/trace.go:171","msg":"trace[1048621635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"385.099685ms","start":"2026-02-25T02:13:15.253036Z","end":"2026-02-25T02:13:15.638136Z","steps":["trace[1048621635] 'agreement among raft nodes before linearized reading' (duration: 385.031084ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:15.638156Z","caller":"traceutil/trace.go:171","msg":"trace[1949182127] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:553; }","duration":"710.343614ms","start":"2026-02-25T02:13:14.927799Z","end":"2026-02-25T02:13:15.638142Z","steps":["trace[1949182127] 'agreement among raft nodes before linearized reading' (duration: 710.251962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:15.638182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:15.253022Z","time spent":"385.152856ms","remote":"127.0.0.1:37436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T02:13:15.638233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:14.927744Z","time spent":"710.481918ms","remote":"127.0.0.1:37518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-02-25T02:13:15.638284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.937182ms","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-25T02:13:15.638394Z","caller":"traceutil/trace.go:171","msg":"trace[1220099237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"225.089354ms","start":"2026-02-25T02:13:15.413277Z","end":"2026-02-25T02:13:15.638367Z","steps":["trace[1220099237] 'agreement among raft nodes before linearized reading' (duration: 224.818918ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:17.690059Z","caller":"traceutil/trace.go:171","msg":"trace[190755433] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"301.473636ms","start":"2026-02-25T02:13:17.388572Z","end":"2026-02-25T02:13:17.690046Z","steps":["trace[190755433] 'process raft request' (duration: 301.17538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:17.690153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:17.388549Z","time spent":"301.563998ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2944,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T02:13:34.559586Z","caller":"traceutil/trace.go:171","msg":"trace[413942203] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"125.491734ms","start":"2026-02-25T02:13:34.434065Z","end":"2026-02-25T02:13:34.559557Z","steps":["trace[413942203] 'process raft request' (duration: 31.696519ms)","trace[413942203] 'compare' (duration: 93.663732ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:13:34.780107Z","caller":"traceutil/trace.go:171","msg":"trace[480152718] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"343.978954ms","start":"2026-02-25T02:13:34.436104Z","end":"2026-02-25T02:13:34.780083Z","steps":["trace[480152718] 'process raft request' (duration: 343.845541ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:34.780261Z","caller":"traceutil/trace.go:171","msg":"trace[976850413] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"344.134627ms","start":"2026-02-25T02:13:34.436095Z","end":"2026-02-25T02:13:34.780229Z","steps":["trace[976850413] 'process raft request' (duration: 325.091887ms)","trace[976850413] 'compare' (duration: 18.462097ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:34.78029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:34.43609Z","time spent":"344.098566ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:34.781037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:34.436083Z","time spent":"344.7571ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:34.780458Z","caller":"traceutil/trace.go:171","msg":"trace[1395114020] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"344.154967ms","start":"2026-02-25T02:13:34.436284Z","end":"2026-02-25T02:13:34.780439Z","steps":["trace[1395114020] 'process raft request' (duration: 343.756819ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:13:34.780473Z","caller":"traceutil/trace.go:171","msg":"trace[1920155726] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"332.115833ms","start":"2026-02-25T02:13:34.448338Z","end":"2026-02-25T02:13:34.780454Z","steps":["trace[1920155726] 'process raft request' (duration: 332.060641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:34.781482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:34.448322Z","time spent":"333.072413ms","remote":"127.0.0.1:37924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2547,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:34.780476Z","caller":"traceutil/trace.go:171","msg":"trace[2035609900] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"333.012192ms","start":"2026-02-25T02:13:34.44745Z","end":"2026-02-25T02:13:34.780462Z","steps":["trace[2035609900] 'process raft request' (duration: 332.878889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:34.781925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:34.447435Z","time spent":"334.182477ms","remote":"127.0.0.1:37918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2600,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:34.781953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:34.436273Z","time spent":"345.045046ms","remote":"127.0.0.1:37910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:13:35.821221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.412624ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:13:35.821437Z","caller":"traceutil/trace.go:171","msg":"trace[1441629461] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.033669735s","start":"2026-02-25T02:13:34.787755Z","end":"2026-02-25T02:13:35.821424Z","steps":["trace[1441629461] 'process raft request' (duration: 1.033555302s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.821514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:34.78773Z","time spent":"1.033732587s","remote":"127.0.0.1:37886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3853,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T02:13:35.821512Z","caller":"traceutil/trace.go:171","msg":"trace[852917185] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"1.034012574s","start":"2026-02-25T02:13:34.787469Z","end":"2026-02-25T02:13:35.821482Z","steps":["trace[852917185] 'read index received' (duration: 157.329133ms)","trace[852917185] 'applied index is now lower than readState.Index' (duration: 876.680361ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:13:35.821777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.939782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T02:13:35.821846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034157616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/cert-manager-webhook\" ","response":"range_response_count:1 size:2562"} {"level":"info","ts":"2026-02-25T02:13:35.821855Z","caller":"traceutil/trace.go:171","msg":"trace[823069547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"567.100456ms","start":"2026-02-25T02:13:35.254741Z","end":"2026-02-25T02:13:35.821841Z","steps":["trace[823069547] 'agreement among raft nodes before linearized reading' (duration: 566.988433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.821896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:35.25472Z","time spent":"567.169937ms","remote":"127.0.0.1:37440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:13:35.821902Z","caller":"traceutil/trace.go:171","msg":"trace[430210949] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/cert-manager-webhook; range_end:; response_count:1; response_revision:927; }","duration":"1.034241849s","start":"2026-02-25T02:13:34.787632Z","end":"2026-02-25T02:13:35.821874Z","steps":["trace[430210949] 'agreement among raft nodes before linearized reading' (duration: 1.034019914s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.821971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.034496645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/cert-manager-webhook\" ","response":"range_response_count:1 size:2615"} {"level":"info","ts":"2026-02-25T02:13:35.822002Z","caller":"traceutil/trace.go:171","msg":"trace[1292531826] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/cert-manager-webhook; range_end:; response_count:1; response_revision:927; }","duration":"1.034552486s","start":"2026-02-25T02:13:34.787439Z","end":"2026-02-25T02:13:35.821992Z","steps":["trace[1292531826] 'agreement among raft nodes before linearized reading' (duration: 1.034168307s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:35.822004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:34.787615Z","time spent":"1.034377342s","remote":"127.0.0.1:37924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":2586,"request content":"key:\"/registry/mutatingwebhookconfigurations/cert-manager-webhook\" "} {"level":"warn","ts":"2026-02-25T02:13:35.82203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:13:34.787426Z","time spent":"1.034597996s","remote":"127.0.0.1:37918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":2639,"request content":"key:\"/registry/validatingwebhookconfigurations/cert-manager-webhook\" "} {"level":"info","ts":"2026-02-25T02:13:54.292109Z","caller":"traceutil/trace.go:171","msg":"trace[1789217067] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"148.252934ms","start":"2026-02-25T02:13:54.143819Z","end":"2026-02-25T02:13:54.292071Z","steps":["trace[1789217067] 'process raft request' (duration: 148.029669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:13:56.939599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.21741ms","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-25T02:13:56.939749Z","caller":"traceutil/trace.go:171","msg":"trace[1500068720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"256.405985ms","start":"2026-02-25T02:13:56.683323Z","end":"2026-02-25T02:13:56.939729Z","steps":["trace[1500068720] 'range keys from in-memory index tree' (duration: 256.049286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:29.65939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.121002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:14:29.659571Z","caller":"traceutil/trace.go:171","msg":"trace[239212652] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"110.351326ms","start":"2026-02-25T02:14:29.549198Z","end":"2026-02-25T02:14:29.659549Z","steps":["trace[239212652] 'compare' (duration: 99.981039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:35.366155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.039834ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:14:35.366259Z","caller":"traceutil/trace.go:171","msg":"trace[50396289] linearizableReadLoop","detail":"{readStateIndex:1603; appliedIndex:1602; }","duration":"142.898513ms","start":"2026-02-25T02:14:35.223343Z","end":"2026-02-25T02:14:35.366242Z","steps":["trace[50396289] 'read index received' (duration: 40.451µs)","trace[50396289] 'applied index is now lower than readState.Index' (duration: 142.856482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:14:35.366427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.044675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T02:14:35.366469Z","caller":"traceutil/trace.go:171","msg":"trace[863698724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1541; }","duration":"143.115827ms","start":"2026-02-25T02:14:35.223341Z","end":"2026-02-25T02:14:35.366457Z","steps":["trace[863698724] 'agreement among raft nodes before linearized reading' (duration: 142.994315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:35.366743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.307181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T02:14:35.366857Z","caller":"traceutil/trace.go:171","msg":"trace[197240185] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1541; }","duration":"143.508565ms","start":"2026-02-25T02:14:35.223324Z","end":"2026-02-25T02:14:35.366833Z","steps":["trace[197240185] 'agreement among raft nodes before linearized reading' (duration: 143.070776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:14:35.366701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.346522ms","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-25T02:14:35.367096Z","caller":"traceutil/trace.go:171","msg":"trace[1634718569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1541; }","duration":"143.779582ms","start":"2026-02-25T02:14:35.223298Z","end":"2026-02-25T02:14:35.367077Z","steps":["trace[1634718569] 'agreement among raft nodes before linearized reading' (duration: 143.008605ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:35.55675Z","caller":"traceutil/trace.go:171","msg":"trace[1448724108] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"186.416448ms","start":"2026-02-25T02:14:35.370316Z","end":"2026-02-25T02:14:35.556733Z","steps":["trace[1448724108] 'process raft request' (duration: 186.256165ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:35.572278Z","caller":"traceutil/trace.go:171","msg":"trace[1314229879] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"200.97928ms","start":"2026-02-25T02:14:35.371267Z","end":"2026-02-25T02:14:35.572246Z","steps":["trace[1314229879] 'process raft request' (duration: 199.585539ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:14:35.572499Z","caller":"traceutil/trace.go:171","msg":"trace[314086742] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"201.158144ms","start":"2026-02-25T02:14:35.371329Z","end":"2026-02-25T02:14:35.572487Z","steps":["trace[314086742] 'process raft request' (duration: 200.871868ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:18:33.101783Z","caller":"traceutil/trace.go:171","msg":"trace[813364669] transaction","detail":"{read_only:false; response_revision:2341; number_of_response:1; }","duration":"125.485576ms","start":"2026-02-25T02:18:32.976254Z","end":"2026-02-25T02:18:33.10174Z","steps":["trace[813364669] 'process raft request' (duration: 100.648674ms)","trace[813364669] 'compare' (duration: 24.518645ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:22:36.499671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2166} {"level":"info","ts":"2026-02-25T02:22:36.565111Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2166,"took":"64.670641ms","hash":2450184580,"current-db-size-bytes":14401536,"current-db-size":"14 MB","current-db-size-in-use-bytes":4304896,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T02:22:36.565183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2450184580,"revision":2166,"compact-revision":-1} {"level":"info","ts":"2026-02-25T02:24:06.926484Z","caller":"traceutil/trace.go:171","msg":"trace[1121410632] linearizableReadLoop","detail":"{readStateIndex:3549; appliedIndex:3548; }","duration":"130.20926ms","start":"2026-02-25T02:24:06.796249Z","end":"2026-02-25T02:24:06.926458Z","steps":["trace[1121410632] 'read index received' (duration: 130.040917ms)","trace[1121410632] 'applied index is now lower than readState.Index' (duration: 167.453µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:24:06.926851Z","caller":"traceutil/trace.go:171","msg":"trace[994201910] transaction","detail":"{read_only:false; response_revision:3369; number_of_response:1; }","duration":"189.426473ms","start":"2026-02-25T02:24:06.737406Z","end":"2026-02-25T02:24:06.926832Z","steps":["trace[994201910] 'process raft request' (duration: 188.943973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:24:06.92696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.199069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-02-25T02:24:06.92702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.753142ms","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-25T02:24:06.927043Z","caller":"traceutil/trace.go:171","msg":"trace[349629387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3369; }","duration":"130.341382ms","start":"2026-02-25T02:24:06.796683Z","end":"2026-02-25T02:24:06.927024Z","steps":["trace[349629387] 'agreement among raft nodes before linearized reading' (duration: 130.061936ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:24:06.927088Z","caller":"traceutil/trace.go:171","msg":"trace[1727495284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3369; }","duration":"130.873715ms","start":"2026-02-25T02:24:06.796197Z","end":"2026-02-25T02:24:06.92707Z","steps":["trace[1727495284] 'agreement among raft nodes before linearized reading' (duration: 130.629669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:25:32.630231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.050625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:25:32.630362Z","caller":"traceutil/trace.go:171","msg":"trace[1053601806] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:3643; }","duration":"105.264209ms","start":"2026-02-25T02:25:32.525073Z","end":"2026-02-25T02:25:32.630337Z","steps":["trace[1053601806] 'count revisions from in-memory index tree' (duration: 104.84064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:25:41.876886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.078968ms","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-25T02:25:41.877092Z","caller":"traceutil/trace.go:171","msg":"trace[441482757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3671; }","duration":"125.365914ms","start":"2026-02-25T02:25:41.751693Z","end":"2026-02-25T02:25:41.877059Z","steps":["trace[441482757] 'range keys from in-memory index tree' (duration: 124.840682ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:25:47.792836Z","caller":"traceutil/trace.go:171","msg":"trace[697536189] transaction","detail":"{read_only:false; response_revision:3688; number_of_response:1; }","duration":"104.021153ms","start":"2026-02-25T02:25:47.688773Z","end":"2026-02-25T02:25:47.792794Z","steps":["trace[697536189] 'process raft request' (duration: 103.846719ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:25:50.051537Z","caller":"traceutil/trace.go:171","msg":"trace[1322848394] transaction","detail":"{read_only:false; response_revision:3697; number_of_response:1; }","duration":"101.070878ms","start":"2026-02-25T02:25:49.950424Z","end":"2026-02-25T02:25:50.051495Z","steps":["trace[1322848394] 'process raft request' (duration: 100.730921ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:26:02.41429Z","caller":"traceutil/trace.go:171","msg":"trace[830422770] transaction","detail":"{read_only:false; response_revision:3734; number_of_response:1; }","duration":"143.391631ms","start":"2026-02-25T02:26:02.270852Z","end":"2026-02-25T02:26:02.414244Z","steps":["trace[830422770] 'process raft request' (duration: 120.042156ms)","trace[830422770] 'compare' (duration: 22.969787ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:26:04.57689Z","caller":"traceutil/trace.go:171","msg":"trace[1138255348] linearizableReadLoop","detail":"{readStateIndex:3944; appliedIndex:3943; }","duration":"148.957663ms","start":"2026-02-25T02:26:04.427896Z","end":"2026-02-25T02:26:04.576854Z","steps":["trace[1138255348] 'read index received' (duration: 148.577685ms)","trace[1138255348] 'applied index is now lower than readState.Index' (duration: 378.538µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:26:04.576975Z","caller":"traceutil/trace.go:171","msg":"trace[404177019] transaction","detail":"{read_only:false; response_revision:3740; number_of_response:1; }","duration":"152.579833ms","start":"2026-02-25T02:26:04.424362Z","end":"2026-02-25T02:26:04.576942Z","steps":["trace[404177019] 'process raft request' (duration: 152.227475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:26:04.577208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.292421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T02:26:04.577294Z","caller":"traceutil/trace.go:171","msg":"trace[482488964] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:3740; }","duration":"149.430604ms","start":"2026-02-25T02:26:04.427841Z","end":"2026-02-25T02:26:04.577272Z","steps":["trace[482488964] 'agreement among raft nodes before linearized reading' (duration: 149.171488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:26:04.862911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.043339ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T02:26:05.452855Z","caller":"traceutil/trace.go:171","msg":"trace[1417301638] transaction","detail":"{read_only:false; response_revision:3743; number_of_response:1; }","duration":"179.008186ms","start":"2026-02-25T02:26:05.273813Z","end":"2026-02-25T02:26:05.452822Z","steps":["trace[1417301638] 'process raft request' (duration: 178.840582ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:26:08.178439Z","caller":"traceutil/trace.go:171","msg":"trace[685518409] transaction","detail":"{read_only:false; response_revision:3749; number_of_response:1; }","duration":"100.389173ms","start":"2026-02-25T02:26:08.078017Z","end":"2026-02-25T02:26:08.178406Z","steps":["trace[685518409] 'process raft request' (duration: 100.151707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:26:59.697762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.603699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T02:26:59.697907Z","caller":"traceutil/trace.go:171","msg":"trace[2142243277] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:3906; }","duration":"184.823064ms","start":"2026-02-25T02:26:59.513055Z","end":"2026-02-25T02:26:59.697878Z","steps":["trace[2142243277] 'range keys from in-memory index tree' (duration: 184.320183ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:27:03.01326Z","caller":"traceutil/trace.go:171","msg":"trace[1580563814] transaction","detail":"{read_only:false; response_revision:3916; number_of_response:1; }","duration":"138.924542ms","start":"2026-02-25T02:27:02.874307Z","end":"2026-02-25T02:27:03.013231Z","steps":["trace[1580563814] 'process raft request' (duration: 89.922772ms)","trace[1580563814] 'compare' (duration: 48.718574ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:27:09.689445Z","caller":"traceutil/trace.go:171","msg":"trace[1886242325] transaction","detail":"{read_only:false; response_revision:3938; number_of_response:1; }","duration":"168.167786ms","start":"2026-02-25T02:27:09.521243Z","end":"2026-02-25T02:27:09.689411Z","steps":["trace[1886242325] 'process raft request' (duration: 141.409577ms)","trace[1886242325] 'compare' (duration: 26.388051ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:27:09.709851Z","caller":"traceutil/trace.go:171","msg":"trace[1390382159] transaction","detail":"{read_only:false; response_revision:3939; number_of_response:1; }","duration":"150.834555ms","start":"2026-02-25T02:27:09.558993Z","end":"2026-02-25T02:27:09.709827Z","steps":["trace[1390382159] 'process raft request' (duration: 150.652201ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:27:19.316406Z","caller":"traceutil/trace.go:171","msg":"trace[273887762] transaction","detail":"{read_only:false; response_revision:3966; number_of_response:1; }","duration":"100.462184ms","start":"2026-02-25T02:27:19.215894Z","end":"2026-02-25T02:27:19.316357Z","steps":["trace[273887762] 'process raft request' (duration: 68.872028ms)","trace[273887762] 'compare' (duration: 31.273639ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:27:19.527526Z","caller":"traceutil/trace.go:171","msg":"trace[992271993] transaction","detail":"{read_only:false; response_revision:3967; number_of_response:1; }","duration":"137.993051ms","start":"2026-02-25T02:27:19.389485Z","end":"2026-02-25T02:27:19.527478Z","steps":["trace[992271993] 'process raft request' (duration: 116.944017ms)","trace[992271993] 'compare' (duration: 20.766757ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:27:31.700145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.691213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:27:31.700282Z","caller":"traceutil/trace.go:171","msg":"trace[1499963257] linearizableReadLoop","detail":"{readStateIndex:4225; appliedIndex:4224; }","duration":"100.261459ms","start":"2026-02-25T02:27:31.600002Z","end":"2026-02-25T02:27:31.700263Z","steps":["trace[1499963257] 'read index received' (duration: 75.671µs)","trace[1499963257] 'applied index is now lower than readState.Index' (duration: 100.184208ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:27:31.700334Z","caller":"traceutil/trace.go:171","msg":"trace[176891735] transaction","detail":"{read_only:false; response_revision:4003; number_of_response:1; }","duration":"171.719585ms","start":"2026-02-25T02:27:31.528583Z","end":"2026-02-25T02:27:31.700303Z","steps":["trace[176891735] 'process raft request' (duration: 28.762045ms)","trace[176891735] 'compare' (duration: 142.53877ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:27:31.700435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.435563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-25T02:27:31.700473Z","caller":"traceutil/trace.go:171","msg":"trace[906808770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4003; }","duration":"100.520095ms","start":"2026-02-25T02:27:31.599941Z","end":"2026-02-25T02:27:31.700462Z","steps":["trace[906808770] 'agreement among raft nodes before linearized reading' (duration: 100.370151ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:27:31.892797Z","caller":"traceutil/trace.go:171","msg":"trace[1735469966] transaction","detail":"{read_only:false; response_revision:4005; number_of_response:1; }","duration":"114.127624ms","start":"2026-02-25T02:27:31.778636Z","end":"2026-02-25T02:27:31.892764Z","steps":["trace[1735469966] 'process raft request' (duration: 81.470595ms)","trace[1735469966] 'compare' (duration: 32.494596ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:27:36.534137Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3095} {"level":"info","ts":"2026-02-25T02:27:36.633968Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3095,"took":"99.089614ms","hash":1799313009,"current-db-size-bytes":14401536,"current-db-size":"14 MB","current-db-size-in-use-bytes":4706304,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T02:27:36.634123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1799313009,"revision":3095,"compact-revision":2166} {"level":"info","ts":"2026-02-25T02:28:01.860318Z","caller":"traceutil/trace.go:171","msg":"trace[1366260854] transaction","detail":"{read_only:false; response_revision:4095; number_of_response:1; }","duration":"164.69402ms","start":"2026-02-25T02:28:01.695599Z","end":"2026-02-25T02:28:01.860293Z","steps":["trace[1366260854] 'process raft request' (duration: 164.526586ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:28:04.258497Z","caller":"traceutil/trace.go:171","msg":"trace[1925711211] transaction","detail":"{read_only:false; response_revision:4105; number_of_response:1; }","duration":"138.130804ms","start":"2026-02-25T02:28:04.120331Z","end":"2026-02-25T02:28:04.258462Z","steps":["trace[1925711211] 'process raft request' (duration: 137.96468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:28:04.938822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.580262ms","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-25T02:28:04.938975Z","caller":"traceutil/trace.go:171","msg":"trace[1981456732] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4105; }","duration":"137.717785ms","start":"2026-02-25T02:28:04.801213Z","end":"2026-02-25T02:28:04.938931Z","steps":["trace[1981456732] 'range keys from in-memory index tree' (duration: 137.559052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:28:04.939008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.515876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:28:04.939123Z","caller":"traceutil/trace.go:171","msg":"trace[1899620830] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4105; }","duration":"463.749641ms","start":"2026-02-25T02:28:04.475347Z","end":"2026-02-25T02:28:04.939096Z","steps":["trace[1899620830] 'count revisions from in-memory index tree' (duration: 463.384383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:28:04.939189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:28:04.475322Z","time spent":"463.853822ms","remote":"127.0.0.1:40556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true "} {"level":"warn","ts":"2026-02-25T02:28:04.939512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.515254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T02:28:04.939623Z","caller":"traceutil/trace.go:171","msg":"trace[2022211429] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4105; }","duration":"281.675747ms","start":"2026-02-25T02:28:04.657916Z","end":"2026-02-25T02:28:04.939592Z","steps":["trace[2022211429] 'range keys from in-memory index tree' (duration: 281.031884ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:28:05.701939Z","caller":"traceutil/trace.go:171","msg":"trace[1676529834] linearizableReadLoop","detail":"{readStateIndex:4336; appliedIndex:4335; }","duration":"157.679545ms","start":"2026-02-25T02:28:05.544233Z","end":"2026-02-25T02:28:05.701913Z","steps":["trace[1676529834] 'read index received' (duration: 157.44258ms)","trace[1676529834] 'applied index is now lower than readState.Index' (duration: 236.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:28:05.702069Z","caller":"traceutil/trace.go:171","msg":"trace[51895020] transaction","detail":"{read_only:false; response_revision:4106; number_of_response:1; }","duration":"418.81526ms","start":"2026-02-25T02:28:05.283174Z","end":"2026-02-25T02:28:05.70199Z","steps":["trace[51895020] 'process raft request' (duration: 418.553184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:28:05.702197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.944761ms","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-25T02:28:05.702263Z","caller":"traceutil/trace.go:171","msg":"trace[1920980143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4106; }","duration":"158.074534ms","start":"2026-02-25T02:28:05.544176Z","end":"2026-02-25T02:28:05.702251Z","steps":["trace[1920980143] 'agreement among raft nodes before linearized reading' (duration: 157.873569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:28:05.702266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:28:05.283138Z","time spent":"419.029124ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:28:10.018715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.028971ms","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-25T02:28:10.018815Z","caller":"traceutil/trace.go:171","msg":"trace[610701162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4121; }","duration":"118.235285ms","start":"2026-02-25T02:28:09.900559Z","end":"2026-02-25T02:28:10.018794Z","steps":["trace[610701162] 'range keys from in-memory index tree' (duration: 117.745374ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:28:10.127849Z","caller":"traceutil/trace.go:171","msg":"trace[1967417103] linearizableReadLoop","detail":"{readStateIndex:4353; appliedIndex:4352; }","duration":"102.002938ms","start":"2026-02-25T02:28:10.025813Z","end":"2026-02-25T02:28:10.127816Z","steps":["trace[1967417103] 'read index received' (duration: 101.722182ms)","trace[1967417103] 'applied index is now lower than readState.Index' (duration: 279.616µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:28:10.127938Z","caller":"traceutil/trace.go:171","msg":"trace[1393492823] transaction","detail":"{read_only:false; response_revision:4122; number_of_response:1; }","duration":"105.011964ms","start":"2026-02-25T02:28:10.022882Z","end":"2026-02-25T02:28:10.127894Z","steps":["trace[1393492823] 'process raft request' (duration: 104.727737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:28:10.128174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.347315ms","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-25T02:28:10.128242Z","caller":"traceutil/trace.go:171","msg":"trace[451176996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4122; }","duration":"102.468248ms","start":"2026-02-25T02:28:10.025758Z","end":"2026-02-25T02:28:10.128226Z","steps":["trace[451176996] 'agreement among raft nodes before linearized reading' (duration: 102.198562ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:28:11.980048Z","caller":"traceutil/trace.go:171","msg":"trace[1715568133] linearizableReadLoop","detail":"{readStateIndex:4356; appliedIndex:4355; }","duration":"111.369244ms","start":"2026-02-25T02:28:11.868646Z","end":"2026-02-25T02:28:11.980015Z","steps":["trace[1715568133] 'read index received' (duration: 111.105119ms)","trace[1715568133] 'applied index is now lower than readState.Index' (duration: 262.825µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:28:11.980139Z","caller":"traceutil/trace.go:171","msg":"trace[398582371] transaction","detail":"{read_only:false; response_revision:4125; number_of_response:1; }","duration":"123.173055ms","start":"2026-02-25T02:28:11.85694Z","end":"2026-02-25T02:28:11.980113Z","steps":["trace[398582371] 'process raft request' (duration: 122.886088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:28:11.980224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.549658ms","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-25T02:28:11.980286Z","caller":"traceutil/trace.go:171","msg":"trace[326038049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4125; }","duration":"111.662621ms","start":"2026-02-25T02:28:11.868607Z","end":"2026-02-25T02:28:11.98027Z","steps":["trace[326038049] 'agreement among raft nodes before linearized reading' (duration: 111.541598ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:29:00.606269Z","caller":"traceutil/trace.go:171","msg":"trace[735609915] transaction","detail":"{read_only:false; response_revision:4275; number_of_response:1; }","duration":"110.716129ms","start":"2026-02-25T02:29:00.495518Z","end":"2026-02-25T02:29:00.606234Z","steps":["trace[735609915] 'process raft request' (duration: 80.600675ms)","trace[735609915] 'compare' (duration: 29.92824ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:29:17.661588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.787256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T02:29:17.66168Z","caller":"traceutil/trace.go:171","msg":"trace[1837873405] linearizableReadLoop","detail":"{readStateIndex:4570; appliedIndex:4569; }","duration":"408.346241ms","start":"2026-02-25T02:29:17.253319Z","end":"2026-02-25T02:29:17.661665Z","steps":["trace[1837873405] 'read index received' (duration: 290.423591ms)","trace[1837873405] 'applied index is now lower than readState.Index' (duration: 117.92181ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:29:17.661727Z","caller":"traceutil/trace.go:171","msg":"trace[389108986] transaction","detail":"{read_only:false; response_revision:4325; number_of_response:1; }","duration":"493.487032ms","start":"2026-02-25T02:29:17.168234Z","end":"2026-02-25T02:29:17.661721Z","steps":["trace[389108986] 'process raft request' (duration: 375.503251ms)","trace[389108986] 'compare' (duration: 117.686444ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T02:29:17.661876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:29:17.168221Z","time spent":"493.521412ms","remote":"127.0.0.1:37504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":884,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T02:29:17.661952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.605997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:29:17.662074Z","caller":"traceutil/trace.go:171","msg":"trace[224000971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4325; }","duration":"408.780141ms","start":"2026-02-25T02:29:17.253269Z","end":"2026-02-25T02:29:17.662049Z","steps":["trace[224000971] 'agreement among raft nodes before linearized reading' (duration: 408.546265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:17.662138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T02:29:17.253251Z","time spent":"408.875783ms","remote":"127.0.0.1:37440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T02:29:17.715759Z","caller":"traceutil/trace.go:171","msg":"trace[1127997675] transaction","detail":"{read_only:false; response_revision:4326; number_of_response:1; }","duration":"277.446383ms","start":"2026-02-25T02:29:17.438264Z","end":"2026-02-25T02:29:17.71571Z","steps":["trace[1127997675] 'process raft request' (duration: 277.206368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:17.715789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.526586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T02:29:17.715866Z","caller":"traceutil/trace.go:171","msg":"trace[1950780348] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4326; }","duration":"205.67064ms","start":"2026-02-25T02:29:17.510176Z","end":"2026-02-25T02:29:17.715847Z","steps":["trace[1950780348] 'agreement among raft nodes before linearized reading' (duration: 205.527037ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:29:19.401881Z","caller":"traceutil/trace.go:171","msg":"trace[1076849889] transaction","detail":"{read_only:false; response_revision:4333; number_of_response:1; }","duration":"101.313292ms","start":"2026-02-25T02:29:19.300538Z","end":"2026-02-25T02:29:19.401851Z","steps":["trace[1076849889] 'process raft request' (duration: 101.180149ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:29:23.925816Z","caller":"traceutil/trace.go:171","msg":"trace[119398027] transaction","detail":"{read_only:false; response_revision:4347; number_of_response:1; }","duration":"192.133548ms","start":"2026-02-25T02:29:23.733648Z","end":"2026-02-25T02:29:23.925782Z","steps":["trace[119398027] 'process raft request' (duration: 191.944113ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:29:32.739338Z","caller":"traceutil/trace.go:171","msg":"trace[610461190] transaction","detail":"{read_only:false; response_revision:4373; number_of_response:1; }","duration":"103.812206ms","start":"2026-02-25T02:29:32.635509Z","end":"2026-02-25T02:29:32.739321Z","steps":["trace[610461190] 'process raft request' (duration: 103.559111ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:29:33.087883Z","caller":"traceutil/trace.go:171","msg":"trace[301836158] linearizableReadLoop","detail":"{readStateIndex:4626; appliedIndex:4625; }","duration":"102.780173ms","start":"2026-02-25T02:29:32.985067Z","end":"2026-02-25T02:29:33.087847Z","steps":["trace[301836158] 'read index received' (duration: 102.692541ms)","trace[301836158] 'applied index is now lower than readState.Index' (duration: 86.512µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:29:33.088068Z","caller":"traceutil/trace.go:171","msg":"trace[1549777406] transaction","detail":"{read_only:false; response_revision:4378; number_of_response:1; }","duration":"112.953529ms","start":"2026-02-25T02:29:32.97508Z","end":"2026-02-25T02:29:33.088033Z","steps":["trace[1549777406] 'process raft request' (duration: 112.596202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:33.088192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.097281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/local-path-provisioner\" ","response":"range_response_count:1 size:1081"} {"level":"info","ts":"2026-02-25T02:29:33.088243Z","caller":"traceutil/trace.go:171","msg":"trace[776106973] range","detail":"{range_begin:/registry/clusterrolebindings/local-path-provisioner; range_end:; response_count:1; response_revision:4378; }","duration":"103.196393ms","start":"2026-02-25T02:29:32.985031Z","end":"2026-02-25T02:29:33.088228Z","steps":["trace[776106973] 'agreement among raft nodes before linearized reading' (duration: 103.008099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:33.08828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.1986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:1 size:724"} {"level":"info","ts":"2026-02-25T02:29:33.088374Z","caller":"traceutil/trace.go:171","msg":"trace[865164025] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:1; response_revision:4378; }","duration":"102.331013ms","start":"2026-02-25T02:29:32.986014Z","end":"2026-02-25T02:29:33.088345Z","steps":["trace[865164025] 'agreement among raft nodes before linearized reading' (duration: 102.148619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:34.501704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.262248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:958"} {"level":"info","ts":"2026-02-25T02:29:34.501785Z","caller":"traceutil/trace.go:171","msg":"trace[669484945] range","detail":"{range_begin:/registry/clusterroles/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:4380; }","duration":"132.383811ms","start":"2026-02-25T02:29:34.369383Z","end":"2026-02-25T02:29:34.501767Z","steps":["trace[669484945] 'range keys from in-memory index tree' (duration: 132.103674ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:29:57.053295Z","caller":"traceutil/trace.go:171","msg":"trace[1537302174] linearizableReadLoop","detail":"{readStateIndex:4702; appliedIndex:4701; }","duration":"166.16542ms","start":"2026-02-25T02:29:56.887096Z","end":"2026-02-25T02:29:57.053261Z","steps":["trace[1537302174] 'read index received' (duration: 156.837064ms)","trace[1537302174] 'applied index is now lower than readState.Index' (duration: 9.326846ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:29:57.053354Z","caller":"traceutil/trace.go:171","msg":"trace[1208033230] transaction","detail":"{read_only:false; response_revision:4449; number_of_response:1; }","duration":"167.554812ms","start":"2026-02-25T02:29:56.885767Z","end":"2026-02-25T02:29:57.053321Z","steps":["trace[1208033230] 'process raft request' (duration: 158.119123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:57.053645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.789566ms","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-25T02:29:57.053654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.585777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T02:29:57.053679Z","caller":"traceutil/trace.go:171","msg":"trace[1870809312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4449; }","duration":"162.860777ms","start":"2026-02-25T02:29:56.890809Z","end":"2026-02-25T02:29:57.05367Z","steps":["trace[1870809312] 'agreement among raft nodes before linearized reading' (duration: 162.709334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:29:57.05371Z","caller":"traceutil/trace.go:171","msg":"trace[532403908] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:4449; }","duration":"128.669788ms","start":"2026-02-25T02:29:56.925025Z","end":"2026-02-25T02:29:57.053695Z","steps":["trace[532403908] 'agreement among raft nodes before linearized reading' (duration: 128.523395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:57.053777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.47704ms","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-25T02:29:57.053798Z","caller":"traceutil/trace.go:171","msg":"trace[504733714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4449; }","duration":"158.519971ms","start":"2026-02-25T02:29:56.895273Z","end":"2026-02-25T02:29:57.053793Z","steps":["trace[504733714] 'agreement among raft nodes before linearized reading' (duration: 158.46658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:57.05387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.751904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:86 size:63422"} {"level":"info","ts":"2026-02-25T02:29:57.05391Z","caller":"traceutil/trace.go:171","msg":"trace[616339060] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:86; response_revision:4449; }","duration":"166.838095ms","start":"2026-02-25T02:29:56.887061Z","end":"2026-02-25T02:29:57.053899Z","steps":["trace[616339060] 'agreement among raft nodes before linearized reading' (duration: 166.388306ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T02:29:57.173024Z","caller":"traceutil/trace.go:171","msg":"trace[462379529] linearizableReadLoop","detail":"{readStateIndex:4704; appliedIndex:4702; }","duration":"113.045362ms","start":"2026-02-25T02:29:57.059947Z","end":"2026-02-25T02:29:57.172993Z","steps":["trace[462379529] 'read index received' (duration: 84.046677ms)","trace[462379529] 'applied index is now lower than readState.Index' (duration: 28.997735ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T02:29:57.173163Z","caller":"traceutil/trace.go:171","msg":"trace[1873919553] transaction","detail":"{read_only:false; response_revision:4451; number_of_response:1; }","duration":"114.37466ms","start":"2026-02-25T02:29:57.058753Z","end":"2026-02-25T02:29:57.173127Z","steps":["trace[1873919553] 'process raft request' (duration: 111.681051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:57.17326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.76818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T02:29:57.173349Z","caller":"traceutil/trace.go:171","msg":"trace[1757427173] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:4451; }","duration":"105.915563ms","start":"2026-02-25T02:29:57.067411Z","end":"2026-02-25T02:29:57.173326Z","steps":["trace[1757427173] 'agreement among raft nodes before linearized reading' (duration: 105.78825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:57.173456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.498001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329928"} {"level":"info","ts":"2026-02-25T02:29:57.173497Z","caller":"traceutil/trace.go:171","msg":"trace[1733392076] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:4451; }","duration":"113.567913ms","start":"2026-02-25T02:29:57.059918Z","end":"2026-02-25T02:29:57.173486Z","steps":["trace[1733392076] 'agreement among raft nodes before linearized reading' (duration: 113.231766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T02:29:57.418232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.843965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/kube-root-ca.crt\" ","response":"range_response_count:1 size:1760"} {"level":"info","ts":"2026-02-25T02:29:57.418337Z","caller":"traceutil/trace.go:171","msg":"trace[1577883677] range","detail":"{range_begin:/registry/configmaps/local-path-storage/kube-root-ca.crt; range_end:; response_count:1; response_revision:4452; }","duration":"117.004519ms","start":"2026-02-25T02:29:57.301312Z","end":"2026-02-25T02:29:57.418317Z","steps":["trace[1577883677] 'range keys from in-memory index tree' (duration: 116.658861ms)"],"step_count":1}