{"level":"warn","ts":"2026-03-09T15:31:07.082645Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T15:31:07.082726Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-09T15:31:07.082784Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T15:31:07.082797Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-03-09T15:31:07.082815Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-09T15:31:07.083362Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-03-09T15:31:07.083423Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-09T15:31:07.087305Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.692135ms"} {"level":"info","ts":"2026-03-09T15:31:07.095112Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-03-09T15:31:07.095233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T15:31:07.095271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-03-09T15:31:07.095291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T15:31:07.095316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-03-09T15:31:07.095368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-03-09T15:31:07.103574Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T15:31:07.1048Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T15:31:07.11028Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-09T15:31:07.114126Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T15:31:07.114273Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-09T15:31:07.114349Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-09T15:31:07.114394Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-09T15:31:07.114595Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T15:31:07.116098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-03-09T15:31:07.117211Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-03-09T15:31:07.119239Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-09T15:31:07.119327Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-03-09T15:31:07.119362Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-03-09T15:31:07.119455Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T15:31:07.119501Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T15:31:07.296266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T15:31:07.296347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T15:31:07.296398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-03-09T15:31:07.296419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-03-09T15:31:07.296437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-03-09T15:31:07.296457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-03-09T15:31:07.296476Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-03-09T15:31:07.297227Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T15:31:07.298445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T15:31:07.298429Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T15:31:07.298484Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T15:31:07.298916Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T15:31:07.298942Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T15:31:07.300101Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-03-09T15:31:07.301537Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T15:31:07.306487Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T15:31:07.306589Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T15:31:07.306633Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T15:31:09.950951Z","caller":"traceutil/trace.go:171","msg":"trace[1058831500] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"264.095788ms","start":"2026-03-09T15:31:09.686827Z","end":"2026-03-09T15:31:09.950922Z","steps":["trace[1058831500] 'read index received' (duration: 263.896183ms)","trace[1058831500] 'applied index is now lower than readState.Index' (duration: 198.495µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:31:09.951069Z","caller":"traceutil/trace.go:171","msg":"trace[1101537580] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"265.704014ms","start":"2026-03-09T15:31:09.68533Z","end":"2026-03-09T15:31:09.951034Z","steps":["trace[1101537580] 'process raft request' (duration: 265.446676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:31:09.951329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.71414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-09T15:31:09.951469Z","caller":"traceutil/trace.go:171","msg":"trace[1848069758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"164.895185ms","start":"2026-03-09T15:31:09.786553Z","end":"2026-03-09T15:31:09.951449Z","steps":["trace[1848069758] 'agreement among raft nodes before linearized reading' (duration: 164.70621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:31:09.951332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.427458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-09T15:31:09.95173Z","caller":"traceutil/trace.go:171","msg":"trace[316379108] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"264.89963ms","start":"2026-03-09T15:31:09.6868Z","end":"2026-03-09T15:31:09.951699Z","steps":["trace[316379108] 'agreement among raft nodes before linearized reading' (duration: 264.282203ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:31:10.403326Z","caller":"traceutil/trace.go:171","msg":"trace[1703508880] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"444.658857ms","start":"2026-03-09T15:31:09.958647Z","end":"2026-03-09T15:31:10.403305Z","steps":["trace[1703508880] 'process raft request' (duration: 444.325397ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:31:10.403264Z","caller":"traceutil/trace.go:171","msg":"trace[1110490290] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"444.569345ms","start":"2026-03-09T15:31:09.958662Z","end":"2026-03-09T15:31:10.403232Z","steps":["trace[1110490290] 'read index received' (duration: 102.683µs)","trace[1110490290] 'applied index is now lower than readState.Index' (duration: 444.427411ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:31:10.403745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.065619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-09T15:31:10.403888Z","caller":"traceutil/trace.go:171","msg":"trace[2082387055] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:72; }","duration":"445.244974ms","start":"2026-03-09T15:31:09.958605Z","end":"2026-03-09T15:31:10.40385Z","steps":["trace[2082387055] 'agreement among raft nodes before linearized reading' (duration: 444.75821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:31:10.403801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:31:09.958633Z","time spent":"444.74885ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-09T15:31:10.404141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:31:09.958582Z","time spent":"445.47051ms","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"info","ts":"2026-03-09T15:31:10.640893Z","caller":"traceutil/trace.go:171","msg":"trace[889365971] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"226.07805ms","start":"2026-03-09T15:31:10.414773Z","end":"2026-03-09T15:31:10.640851Z","steps":["trace[889365971] 'process raft request' (duration: 157.090214ms)","trace[889365971] 'compare' (duration: 68.894963ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:31:37.027885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.237334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T15:31:37.027954Z","caller":"traceutil/trace.go:171","msg":"trace[1849409486] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"203.270558ms","start":"2026-03-09T15:31:36.824672Z","end":"2026-03-09T15:31:37.027943Z","steps":["trace[1849409486] 'process raft request' (duration: 56.795337ms)","trace[1849409486] 'compare' (duration: 146.157922ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:31:37.81519Z","caller":"traceutil/trace.go:171","msg":"trace[940872240] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"257.635696ms","start":"2026-03-09T15:31:37.557531Z","end":"2026-03-09T15:31:37.815167Z","steps":["trace[940872240] 'process raft request' (duration: 257.497812ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:31:39.122928Z","caller":"traceutil/trace.go:171","msg":"trace[541054942] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"325.60358ms","start":"2026-03-09T15:31:38.797304Z","end":"2026-03-09T15:31:39.122907Z","steps":["trace[541054942] 'process raft request' (duration: 325.371114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:31:39.12308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:31:38.797293Z","time spent":"325.706993ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T15:31:39.243746Z","caller":"traceutil/trace.go:171","msg":"trace[890019244] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:491; }","duration":"212.193112ms","start":"2026-03-09T15:31:39.031524Z","end":"2026-03-09T15:31:39.243717Z","steps":["trace[890019244] 'read index received' (duration: 91.172626ms)","trace[890019244] 'applied index is now lower than readState.Index' (duration: 120.984935ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:31:39.24383Z","caller":"traceutil/trace.go:171","msg":"trace[406105873] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"445.928976ms","start":"2026-03-09T15:31:38.797871Z","end":"2026-03-09T15:31:39.2438Z","steps":["trace[406105873] 'process raft request' (duration: 416.171309ms)","trace[406105873] 'compare' (duration: 29.536601ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:31:39.243971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:31:38.797848Z","time spent":"446.04954ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:31:39.243995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.452619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-09T15:31:39.244052Z","caller":"traceutil/trace.go:171","msg":"trace[1041548653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"212.565002ms","start":"2026-03-09T15:31:39.031476Z","end":"2026-03-09T15:31:39.244041Z","steps":["trace[1041548653] 'agreement among raft nodes before linearized reading' (duration: 212.380987ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:31:43.637848Z","caller":"traceutil/trace.go:171","msg":"trace[555122055] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"108.953222ms","start":"2026-03-09T15:31:43.528876Z","end":"2026-03-09T15:31:43.63783Z","steps":["trace[555122055] 'process raft request' (duration: 108.789577ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:31:44.362878Z","caller":"traceutil/trace.go:171","msg":"trace[778493901] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"154.801957ms","start":"2026-03-09T15:31:44.208054Z","end":"2026-03-09T15:31:44.362856Z","steps":["trace[778493901] 'read index received' (duration: 154.54465ms)","trace[778493901] 'applied index is now lower than readState.Index' (duration: 256.347µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:31:44.362998Z","caller":"traceutil/trace.go:171","msg":"trace[1534096397] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"278.20826ms","start":"2026-03-09T15:31:44.084756Z","end":"2026-03-09T15:31:44.362965Z","steps":["trace[1534096397] 'process raft request' (duration: 277.912482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:31:44.363013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.944551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:31:44.363088Z","caller":"traceutil/trace.go:171","msg":"trace[1993277552] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"155.033325ms","start":"2026-03-09T15:31:44.208039Z","end":"2026-03-09T15:31:44.363072Z","steps":["trace[1993277552] 'agreement among raft nodes before linearized reading' (duration: 154.912001ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:31:45.494272Z","caller":"traceutil/trace.go:171","msg":"trace[2054354463] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"285.825328ms","start":"2026-03-09T15:31:45.208423Z","end":"2026-03-09T15:31:45.494248Z","steps":["trace[2054354463] 'read index received' (duration: 285.665514ms)","trace[2054354463] 'applied index is now lower than readState.Index' (duration: 158.544µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:31:45.494415Z","caller":"traceutil/trace.go:171","msg":"trace[1046551384] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"347.969888ms","start":"2026-03-09T15:31:45.146435Z","end":"2026-03-09T15:31:45.494405Z","steps":["trace[1046551384] 'process raft request' (duration: 347.667489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:31:45.494518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:31:45.14641Z","time spent":"348.034629ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:31:45.494725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.703507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-09T15:31:45.494802Z","caller":"traceutil/trace.go:171","msg":"trace[547605120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"224.78172ms","start":"2026-03-09T15:31:45.270001Z","end":"2026-03-09T15:31:45.494783Z","steps":["trace[547605120] 'agreement among raft nodes before linearized reading' (duration: 224.675297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:31:45.49522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.795256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:31:45.495293Z","caller":"traceutil/trace.go:171","msg":"trace[314484321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"286.873818ms","start":"2026-03-09T15:31:45.208408Z","end":"2026-03-09T15:31:45.495282Z","steps":["trace[314484321] 'agreement among raft nodes before linearized reading' (duration: 286.178808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:31:45.495472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.606126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-09T15:31:45.495528Z","caller":"traceutil/trace.go:171","msg":"trace[1361994836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"231.602154ms","start":"2026-03-09T15:31:45.263909Z","end":"2026-03-09T15:31:45.495511Z","steps":["trace[1361994836] 'agreement among raft nodes before linearized reading' (duration: 230.572625ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:31:47.428929Z","caller":"traceutil/trace.go:171","msg":"trace[1311036609] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"174.849819ms","start":"2026-03-09T15:31:47.254057Z","end":"2026-03-09T15:31:47.428907Z","steps":["trace[1311036609] 'process raft request' (duration: 174.731355ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:05.541847Z","caller":"traceutil/trace.go:171","msg":"trace[193046654] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"600.869588ms","start":"2026-03-09T15:32:04.940949Z","end":"2026-03-09T15:32:05.541819Z","steps":["trace[193046654] 'process raft request' (duration: 600.651772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:05.54202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:04.94093Z","time spent":"601.000101ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:32:06.088046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.117996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-09T15:32:06.088072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.982577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-09T15:32:06.088129Z","caller":"traceutil/trace.go:171","msg":"trace[1490754952] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:688; }","duration":"476.234319ms","start":"2026-03-09T15:32:05.611875Z","end":"2026-03-09T15:32:06.08811Z","steps":["trace[1490754952] 'range keys from in-memory index tree' (duration: 475.985691ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:06.088146Z","caller":"traceutil/trace.go:171","msg":"trace[219070544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:688; }","duration":"126.099351ms","start":"2026-03-09T15:32:05.962029Z","end":"2026-03-09T15:32:06.088129Z","steps":["trace[219070544] 'range keys from in-memory index tree' (duration: 125.820033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:06.08817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:05.611853Z","time spent":"476.307351ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T15:32:06.088176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.017618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T15:32:06.088227Z","caller":"traceutil/trace.go:171","msg":"trace[235687565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"473.08426ms","start":"2026-03-09T15:32:05.615122Z","end":"2026-03-09T15:32:06.088207Z","steps":["trace[235687565] 'range keys from in-memory index tree' (duration: 472.907605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:06.088063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.021029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-09T15:32:06.088277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:05.615102Z","time spent":"473.167082ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-09T15:32:06.088334Z","caller":"traceutil/trace.go:171","msg":"trace[488947731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"505.343948ms","start":"2026-03-09T15:32:05.582981Z","end":"2026-03-09T15:32:06.088325Z","steps":["trace[488947731] 'range keys from in-memory index tree' (duration: 504.848634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:06.088463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:05.582962Z","time spent":"505.489862ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-09T15:32:06.293947Z","caller":"traceutil/trace.go:171","msg":"trace[1803349506] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"200.844658ms","start":"2026-03-09T15:32:06.093082Z","end":"2026-03-09T15:32:06.293926Z","steps":["trace[1803349506] 'process raft request' (duration: 190.610597ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:06.293948Z","caller":"traceutil/trace.go:171","msg":"trace[1905049077] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"200.601982ms","start":"2026-03-09T15:32:06.09332Z","end":"2026-03-09T15:32:06.293922Z","steps":["trace[1905049077] 'process raft request' (duration: 200.482858ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:06.294282Z","caller":"traceutil/trace.go:171","msg":"trace[517788824] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"200.498209ms","start":"2026-03-09T15:32:06.093774Z","end":"2026-03-09T15:32:06.294272Z","steps":["trace[517788824] 'process raft request' (duration: 200.111488ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:30.592157Z","caller":"traceutil/trace.go:171","msg":"trace[1343003159] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"114.488602ms","start":"2026-03-09T15:32:30.477642Z","end":"2026-03-09T15:32:30.592131Z","steps":["trace[1343003159] 'process raft request' (duration: 114.344668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:31.898834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.987845ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:32:31.899022Z","caller":"traceutil/trace.go:171","msg":"trace[22369876] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:766; }","duration":"181.199801ms","start":"2026-03-09T15:32:31.717803Z","end":"2026-03-09T15:32:31.899003Z","steps":["trace[22369876] 'range keys from in-memory index tree' (duration: 180.968145ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:31.899144Z","caller":"traceutil/trace.go:171","msg":"trace[741133728] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"251.795516ms","start":"2026-03-09T15:32:31.647324Z","end":"2026-03-09T15:32:31.89912Z","steps":["trace[741133728] 'read index received' (duration: 217.980659ms)","trace[741133728] 'applied index is now lower than readState.Index' (duration: 33.813917ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:32:31.899288Z","caller":"traceutil/trace.go:171","msg":"trace[2069929074] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"356.464577ms","start":"2026-03-09T15:32:31.542788Z","end":"2026-03-09T15:32:31.899252Z","steps":["trace[2069929074] 'process raft request' (duration: 322.523867ms)","trace[2069929074] 'compare' (duration: 33.473147ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:32:31.899528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:31.542709Z","time spent":"356.705724ms","remote":"127.0.0.1:39488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:32:31.899351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.017022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-09T15:32:31.899641Z","caller":"traceutil/trace.go:171","msg":"trace[150382259] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:767; }","duration":"252.356882ms","start":"2026-03-09T15:32:31.647272Z","end":"2026-03-09T15:32:31.899629Z","steps":["trace[150382259] 'agreement among raft nodes before linearized reading' (duration: 251.93058ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:32.167178Z","caller":"traceutil/trace.go:171","msg":"trace[33609090] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"262.632408ms","start":"2026-03-09T15:32:31.904516Z","end":"2026-03-09T15:32:32.167149Z","steps":["trace[33609090] 'read index received' (duration: 195.088546ms)","trace[33609090] 'applied index is now lower than readState.Index' (duration: 67.542852ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:32:32.167397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.863814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-09T15:32:32.167476Z","caller":"traceutil/trace.go:171","msg":"trace[383268800] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:768; }","duration":"262.973807ms","start":"2026-03-09T15:32:31.904487Z","end":"2026-03-09T15:32:32.167461Z","steps":["trace[383268800] 'agreement among raft nodes before linearized reading' (duration: 262.840194ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:32.167272Z","caller":"traceutil/trace.go:171","msg":"trace[140420817] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"263.921953ms","start":"2026-03-09T15:32:31.903314Z","end":"2026-03-09T15:32:32.167236Z","steps":["trace[140420817] 'process raft request' (duration: 196.307309ms)","trace[140420817] 'compare' (duration: 67.285045ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:32:32.663051Z","caller":"traceutil/trace.go:171","msg":"trace[705839009] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"114.310118ms","start":"2026-03-09T15:32:32.54872Z","end":"2026-03-09T15:32:32.66303Z","steps":["trace[705839009] 'process raft request' (duration: 114.005409ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:34.602471Z","caller":"traceutil/trace.go:171","msg":"trace[1810878293] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"250.031489ms","start":"2026-03-09T15:32:34.352416Z","end":"2026-03-09T15:32:34.602447Z","steps":["trace[1810878293] 'read index received' (duration: 249.822083ms)","trace[1810878293] 'applied index is now lower than readState.Index' (duration: 208.496µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:32:34.602507Z","caller":"traceutil/trace.go:171","msg":"trace[1806636412] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"354.498173ms","start":"2026-03-09T15:32:34.247979Z","end":"2026-03-09T15:32:34.602477Z","steps":["trace[1806636412] 'process raft request' (duration: 354.316068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:34.602704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:34.247952Z","time spent":"354.635056ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:32:34.60275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.321116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-09T15:32:34.602818Z","caller":"traceutil/trace.go:171","msg":"trace[1487350120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"250.4328ms","start":"2026-03-09T15:32:34.352374Z","end":"2026-03-09T15:32:34.602807Z","steps":["trace[1487350120] 'agreement among raft nodes before linearized reading' (duration: 250.186503ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:34.930289Z","caller":"traceutil/trace.go:171","msg":"trace[33752841] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"322.480396ms","start":"2026-03-09T15:32:34.607785Z","end":"2026-03-09T15:32:34.930266Z","steps":["trace[33752841] 'process raft request' (duration: 322.324972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:34.930465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:34.607766Z","time spent":"322.595769ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:32:35.204162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.133035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T15:32:35.204321Z","caller":"traceutil/trace.go:171","msg":"trace[186992650] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"537.182055ms","start":"2026-03-09T15:32:34.667117Z","end":"2026-03-09T15:32:35.204299Z","steps":["trace[186992650] 'read index received' (duration: 262.861833ms)","trace[186992650] 'applied index is now lower than readState.Index' (duration: 274.318442ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:32:35.204426Z","caller":"traceutil/trace.go:171","msg":"trace[1661673564] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"596.405627ms","start":"2026-03-09T15:32:34.607999Z","end":"2026-03-09T15:32:35.204405Z","steps":["trace[1661673564] 'process raft request' (duration: 351.937072ms)","trace[1661673564] 'compare' (duration: 244.017662ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:32:35.204535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:34.607979Z","time spent":"596.503819ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:32:35.204715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.586867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T15:32:35.204861Z","caller":"traceutil/trace.go:171","msg":"trace[1648544500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:776; }","duration":"537.720751ms","start":"2026-03-09T15:32:34.667078Z","end":"2026-03-09T15:32:35.204799Z","steps":["trace[1648544500] 'agreement among raft nodes before linearized reading' (duration: 537.390692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:35.204968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:34.667063Z","time spent":"537.886745ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T15:32:35.204989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.102912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-09T15:32:35.205037Z","caller":"traceutil/trace.go:171","msg":"trace[657784460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"494.172334ms","start":"2026-03-09T15:32:34.710848Z","end":"2026-03-09T15:32:35.20502Z","steps":["trace[657784460] 'agreement among raft nodes before linearized reading' (duration: 494.04955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:35.205088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:34.710837Z","time spent":"494.240235ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-09T15:32:47.696114Z","caller":"traceutil/trace.go:171","msg":"trace[1331196046] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"437.998296ms","start":"2026-03-09T15:32:47.258084Z","end":"2026-03-09T15:32:47.696083Z","steps":["trace[1331196046] 'process raft request' (duration: 437.750119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:47.696265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.198159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-09T15:32:47.696282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:47.258072Z","time spent":"438.132721ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T15:32:47.696303Z","caller":"traceutil/trace.go:171","msg":"trace[942702187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:860; }","duration":"339.264851ms","start":"2026-03-09T15:32:47.357028Z","end":"2026-03-09T15:32:47.696293Z","steps":["trace[942702187] 'agreement among raft nodes before linearized reading' (duration: 339.112537ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:32:47.696275Z","caller":"traceutil/trace.go:171","msg":"trace[820308026] linearizableReadLoop","detail":"{readStateIndex:892; appliedIndex:891; }","duration":"338.940822ms","start":"2026-03-09T15:32:47.357053Z","end":"2026-03-09T15:32:47.695994Z","steps":["trace[820308026] 'read index received' (duration: 338.727466ms)","trace[820308026] 'applied index is now lower than readState.Index' (duration: 212.506µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:32:47.696328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:47.357015Z","time spent":"339.307812ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T15:32:47.696437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.427232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:32:47.696469Z","caller":"traceutil/trace.go:171","msg":"trace[2081237828] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:860; }","duration":"179.483884ms","start":"2026-03-09T15:32:47.516974Z","end":"2026-03-09T15:32:47.696458Z","steps":["trace[2081237828] 'agreement among raft nodes before linearized reading' (duration: 179.433342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:47.696477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.516744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T15:32:47.696511Z","caller":"traceutil/trace.go:171","msg":"trace[510614391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:860; }","duration":"328.574775ms","start":"2026-03-09T15:32:47.367925Z","end":"2026-03-09T15:32:47.696499Z","steps":["trace[510614391] 'agreement among raft nodes before linearized reading' (duration: 328.445351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:47.696548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:47.367917Z","time spent":"328.621616ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-09T15:32:48.108917Z","caller":"traceutil/trace.go:171","msg":"trace[1758908666] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"409.268621ms","start":"2026-03-09T15:32:47.699628Z","end":"2026-03-09T15:32:48.108897Z","steps":["trace[1758908666] 'process raft request' (duration: 409.126107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:48.109046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:47.699614Z","time spent":"409.365313ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T15:32:48.109655Z","caller":"traceutil/trace.go:171","msg":"trace[1563197101] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"409.804246ms","start":"2026-03-09T15:32:47.699835Z","end":"2026-03-09T15:32:48.109639Z","steps":["trace[1563197101] 'process raft request' (duration: 409.572249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:32:48.10975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:32:47.699807Z","time spent":"409.884648ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T15:33:04.429449Z","caller":"traceutil/trace.go:171","msg":"trace[437000828] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"118.139024ms","start":"2026-03-09T15:33:04.311278Z","end":"2026-03-09T15:33:04.429417Z","steps":["trace[437000828] 'process raft request' (duration: 41.329235ms)","trace[437000828] 'compare' (duration: 76.687675ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:04.678644Z","caller":"traceutil/trace.go:171","msg":"trace[1729522428] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"181.724016ms","start":"2026-03-09T15:33:04.496888Z","end":"2026-03-09T15:33:04.678612Z","steps":["trace[1729522428] 'process raft request' (duration: 137.960603ms)","trace[1729522428] 'compare' (duration: 43.618279ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:04.718268Z","caller":"traceutil/trace.go:171","msg":"trace[1922767358] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"220.995403ms","start":"2026-03-09T15:33:04.497243Z","end":"2026-03-09T15:33:04.718239Z","steps":["trace[1922767358] 'process raft request' (duration: 220.789408ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:04.718242Z","caller":"traceutil/trace.go:171","msg":"trace[1999274318] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"216.866329ms","start":"2026-03-09T15:33:04.501348Z","end":"2026-03-09T15:33:04.718215Z","steps":["trace[1999274318] 'process raft request' (duration: 216.812067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:04.949726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.764363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T15:33:04.950102Z","caller":"traceutil/trace.go:171","msg":"trace[1370933723] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"270.701581ms","start":"2026-03-09T15:33:04.679369Z","end":"2026-03-09T15:33:04.95007Z","steps":["trace[1370933723] 'process raft request' (duration: 104.483595ms)","trace[1370933723] 'compare' (duration: 165.65809ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:33:05.914654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.117493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T15:33:05.914741Z","caller":"traceutil/trace.go:171","msg":"trace[1420903736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:931; }","duration":"131.242097ms","start":"2026-03-09T15:33:05.783475Z","end":"2026-03-09T15:33:05.914717Z","steps":["trace[1420903736] 'range keys from in-memory index tree' (duration: 130.887248ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:06.0598Z","caller":"traceutil/trace.go:171","msg":"trace[2132306654] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"141.901172ms","start":"2026-03-09T15:33:05.917872Z","end":"2026-03-09T15:33:06.059773Z","steps":["trace[2132306654] 'process raft request' (duration: 141.751568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:06.358971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.237693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:33:06.359048Z","caller":"traceutil/trace.go:171","msg":"trace[1743297318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"150.345876ms","start":"2026-03-09T15:33:06.208684Z","end":"2026-03-09T15:33:06.35903Z","steps":["trace[1743297318] 'range keys from in-memory index tree' (duration: 150.162101ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:06.642291Z","caller":"traceutil/trace.go:171","msg":"trace[1249725900] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"112.855128ms","start":"2026-03-09T15:33:06.5294Z","end":"2026-03-09T15:33:06.642255Z","steps":["trace[1249725900] 'process raft request' (duration: 87.786493ms)","trace[1249725900] 'compare' (duration: 24.923461ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:06.907649Z","caller":"traceutil/trace.go:171","msg":"trace[1460559173] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"133.127059ms","start":"2026-03-09T15:33:06.774504Z","end":"2026-03-09T15:33:06.907631Z","steps":["trace[1460559173] 'process raft request' (duration: 133.002336ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:08.611137Z","caller":"traceutil/trace.go:171","msg":"trace[1458381357] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"404.319044ms","start":"2026-03-09T15:33:08.206802Z","end":"2026-03-09T15:33:08.611121Z","steps":["trace[1458381357] 'read index received' (duration: 404.293663ms)","trace[1458381357] 'applied index is now lower than readState.Index' (duration: 24.631µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:08.611195Z","caller":"traceutil/trace.go:171","msg":"trace[1401296722] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"543.114539ms","start":"2026-03-09T15:33:08.068061Z","end":"2026-03-09T15:33:08.611175Z","steps":["trace[1401296722] 'process raft request' (duration: 542.962555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:08.611326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:08.068035Z","time spent":"543.204362ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:33:08.611332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.625363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-09T15:33:08.611388Z","caller":"traceutil/trace.go:171","msg":"trace[406615498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"146.724836ms","start":"2026-03-09T15:33:08.46465Z","end":"2026-03-09T15:33:08.611375Z","steps":["trace[406615498] 'agreement among raft nodes before linearized reading' (duration: 146.585972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:08.611924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.120635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:33:08.612748Z","caller":"traceutil/trace.go:171","msg":"trace[1447603111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"405.983409ms","start":"2026-03-09T15:33:08.206749Z","end":"2026-03-09T15:33:08.612732Z","steps":["trace[1447603111] 'agreement among raft nodes before linearized reading' (duration: 405.130916ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:08.612801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:08.206728Z","time spent":"406.046782ms","remote":"127.0.0.1:39458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T15:33:09.813088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.284042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T15:33:09.813274Z","caller":"traceutil/trace.go:171","msg":"trace[1765831366] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:979; }","duration":"787.585583ms","start":"2026-03-09T15:33:09.025668Z","end":"2026-03-09T15:33:09.813254Z","steps":["trace[1765831366] 'read index received' (duration: 125.015724ms)","trace[1765831366] 'applied index is now lower than readState.Index' (duration: 662.568709ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:09.813706Z","caller":"traceutil/trace.go:171","msg":"trace[1176937742] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.199397325s","start":"2026-03-09T15:33:08.614096Z","end":"2026-03-09T15:33:09.813493Z","steps":["trace[1176937742] 'process raft request' (duration: 536.607999ms)","trace[1176937742] 'compare' (duration: 661.732507ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:33:09.813755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.810255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T15:33:09.813795Z","caller":"traceutil/trace.go:171","msg":"trace[725917191] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:945; }","duration":"117.850816ms","start":"2026-03-09T15:33:09.695929Z","end":"2026-03-09T15:33:09.81378Z","steps":["trace[725917191] 'agreement among raft nodes before linearized reading' (duration: 117.783404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:09.813792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.996804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T15:33:09.813848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:08.614082Z","time spent":"1.199683962s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:33:09.813834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.156069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-09T15:33:09.813866Z","caller":"traceutil/trace.go:171","msg":"trace[662866728] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"605.109458ms","start":"2026-03-09T15:33:09.208734Z","end":"2026-03-09T15:33:09.813843Z","steps":["trace[662866728] 'agreement among raft nodes before linearized reading' (duration: 604.789659ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:09.813907Z","caller":"traceutil/trace.go:171","msg":"trace[2025943396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"788.282103ms","start":"2026-03-09T15:33:09.025604Z","end":"2026-03-09T15:33:09.813886Z","steps":["trace[2025943396] 'agreement among raft nodes before linearized reading' (duration: 787.874381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:09.813919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:09.208716Z","time spent":"605.189059ms","remote":"127.0.0.1:39458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T15:33:09.813958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:09.02558Z","time spent":"788.369696ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-09T15:33:09.813982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.54234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-09T15:33:09.814084Z","caller":"traceutil/trace.go:171","msg":"trace[260092162] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.198789398s","start":"2026-03-09T15:33:08.615283Z","end":"2026-03-09T15:33:09.814072Z","steps":["trace[260092162] 'process raft request' (duration: 1.197916264s)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:09.814078Z","caller":"traceutil/trace.go:171","msg":"trace[366470625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:945; }","duration":"583.717774ms","start":"2026-03-09T15:33:09.230338Z","end":"2026-03-09T15:33:09.814056Z","steps":["trace[366470625] 'agreement among raft nodes before linearized reading' (duration: 583.398066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:09.814127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:09.230322Z","time spent":"583.796047ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-09T15:33:09.814188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:08.615271Z","time spent":"1.198839439s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T15:33:09.956548Z","caller":"traceutil/trace.go:171","msg":"trace[1778659114] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"129.388805ms","start":"2026-03-09T15:33:09.82713Z","end":"2026-03-09T15:33:09.956519Z","steps":["trace[1778659114] 'process raft request' (duration: 113.137355ms)","trace[1778659114] 'compare' (duration: 16.147377ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T15:33:11.872588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763919920848698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T15:33:12.373486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763919920848698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T15:33:12.873773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763919920848698,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T15:33:12.884462Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.579489688s","expected-duration":"1s"} {"level":"info","ts":"2026-03-09T15:33:12.884871Z","caller":"traceutil/trace.go:171","msg":"trace[1939911928] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.579998822s","start":"2026-03-09T15:33:11.304849Z","end":"2026-03-09T15:33:12.884848Z","steps":["trace[1939911928] 'process raft request' (duration: 1.579852388s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:12.884996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.30482Z","time spent":"1.580098145s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T15:33:13.379755Z","caller":"traceutil/trace.go:171","msg":"trace[1790178100] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"2.00756162s","start":"2026-03-09T15:33:11.37217Z","end":"2026-03-09T15:33:13.379732Z","steps":["trace[1790178100] 'read index received' (duration: 1.512551583s)","trace[1790178100] 'applied index is now lower than readState.Index' (duration: 495.009127ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:13.379867Z","caller":"traceutil/trace.go:171","msg":"trace[983821012] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.526056857s","start":"2026-03-09T15:33:11.853779Z","end":"2026-03-09T15:33:13.379836Z","steps":["trace[983821012] 'process raft request' (duration: 1.52580673s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.885770115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-09T15:33:13.380243Z","caller":"traceutil/trace.go:171","msg":"trace[432973372] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:949; }","duration":"1.885868448s","start":"2026-03-09T15:33:11.494363Z","end":"2026-03-09T15:33:13.380232Z","steps":["trace[432973372] 'agreement among raft nodes before linearized reading' (duration: 1.885748825s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495082258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-09T15:33:13.380276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.494345Z","time spent":"1.885922719s","remote":"127.0.0.1:39488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"info","ts":"2026-03-09T15:33:13.380301Z","caller":"traceutil/trace.go:171","msg":"trace[1499757640] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:949; }","duration":"1.49516473s","start":"2026-03-09T15:33:11.885123Z","end":"2026-03-09T15:33:13.380287Z","steps":["trace[1499757640] 'agreement among raft nodes before linearized reading' (duration: 1.495012016s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.885107Z","time spent":"1.495221202s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-09T15:33:13.380319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.853757Z","time spent":"1.526370055s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:33:13.380381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.77986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-09T15:33:13.380051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007862738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-09T15:33:13.380453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54819087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-03-09T15:33:13.380447Z","caller":"traceutil/trace.go:171","msg":"trace[2124329815] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:949; }","duration":"350.850841ms","start":"2026-03-09T15:33:13.02958Z","end":"2026-03-09T15:33:13.380431Z","steps":["trace[2124329815] 'agreement among raft nodes before linearized reading' (duration: 350.644446ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:13.380499Z","caller":"traceutil/trace.go:171","msg":"trace[1672818962] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:949; }","duration":"1.548283153s","start":"2026-03-09T15:33:11.8322Z","end":"2026-03-09T15:33:13.380483Z","steps":["trace[1672818962] 'agreement among raft nodes before linearized reading' (duration: 1.54819491s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741047184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-09T15:33:13.380532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:13.029504Z","time spent":"351.017117ms","remote":"127.0.0.1:40038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":111,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-09T15:33:13.380533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.832183Z","time spent":"1.548342304s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-09T15:33:13.380522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562287561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-09T15:33:13.380548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174464294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:33:13.380508Z","caller":"traceutil/trace.go:171","msg":"trace[776571175] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:949; }","duration":"2.008347672s","start":"2026-03-09T15:33:11.372122Z","end":"2026-03-09T15:33:13.38047Z","steps":["trace[776571175] 'agreement among raft nodes before linearized reading' (duration: 2.007792166s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.38035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562779195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T15:33:13.38058Z","caller":"traceutil/trace.go:171","msg":"trace[1432081945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"1.174516995s","start":"2026-03-09T15:33:12.206053Z","end":"2026-03-09T15:33:13.38057Z","steps":["trace[1432081945] 'agreement among raft nodes before linearized reading' (duration: 1.174468224s)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:13.380579Z","caller":"traceutil/trace.go:171","msg":"trace[292903282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:949; }","duration":"1.562370263s","start":"2026-03-09T15:33:11.818196Z","end":"2026-03-09T15:33:13.380567Z","steps":["trace[292903282] 'agreement among raft nodes before linearized reading' (duration: 1.562231799s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:12.206037Z","time spent":"1.174567926s","remote":"127.0.0.1:39458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-09T15:33:13.380606Z","caller":"traceutil/trace.go:171","msg":"trace[77665278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:949; }","duration":"1.563070202s","start":"2026-03-09T15:33:11.817524Z","end":"2026-03-09T15:33:13.380594Z","steps":["trace[77665278] 'agreement among raft nodes before linearized reading' (duration: 1.562666381s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.818189Z","time spent":"1.562423784s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T15:33:13.380647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.372105Z","time spent":"2.008491335s","remote":"127.0.0.1:39528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":111,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-09T15:33:13.380659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59977202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-09T15:33:13.380646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.81751Z","time spent":"1.563125374s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-09T15:33:13.380572Z","caller":"traceutil/trace.go:171","msg":"trace[852175823] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:949; }","duration":"1.741150818s","start":"2026-03-09T15:33:11.639401Z","end":"2026-03-09T15:33:13.380552Z","steps":["trace[852175823] 'agreement among raft nodes before linearized reading' (duration: 1.741010824s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.478043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T15:33:13.380708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.639392Z","time spent":"1.741306122s","remote":"127.0.0.1:39892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-03-09T15:33:13.38073Z","caller":"traceutil/trace.go:171","msg":"trace[1695732639] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:949; }","duration":"170.569236ms","start":"2026-03-09T15:33:13.210149Z","end":"2026-03-09T15:33:13.380718Z","steps":["trace[1695732639] 'agreement among raft nodes before linearized reading' (duration: 170.512305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421044897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-09T15:33:13.380689Z","caller":"traceutil/trace.go:171","msg":"trace[892154879] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:949; }","duration":"1.599843671s","start":"2026-03-09T15:33:11.780838Z","end":"2026-03-09T15:33:13.380681Z","steps":["trace[892154879] 'agreement among raft nodes before linearized reading' (duration: 1.599764059s)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:13.380816Z","caller":"traceutil/trace.go:171","msg":"trace[1338252470] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:949; }","duration":"1.421096838s","start":"2026-03-09T15:33:11.959711Z","end":"2026-03-09T15:33:13.380807Z","steps":["trace[1338252470] 'agreement among raft nodes before linearized reading' (duration: 1.421009805s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:13.380843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.959674Z","time spent":"1.42116259s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-09T15:33:13.380841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:11.780815Z","time spent":"1.600011516s","remote":"127.0.0.1:39828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-03-09T15:33:13.842247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.275557ms","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-03-09T15:33:13.842312Z","caller":"traceutil/trace.go:171","msg":"trace[460646686] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:955; }","duration":"245.359789ms","start":"2026-03-09T15:33:13.596938Z","end":"2026-03-09T15:33:13.842297Z","steps":["trace[460646686] 'count revisions from in-memory index tree' (duration: 245.177854ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:18.567202Z","caller":"traceutil/trace.go:171","msg":"trace[224321673] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"124.208371ms","start":"2026-03-09T15:33:18.44296Z","end":"2026-03-09T15:33:18.567169Z","steps":["trace[224321673] 'process raft request' (duration: 123.975045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:19.67018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.938602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T15:33:19.670246Z","caller":"traceutil/trace.go:171","msg":"trace[602348120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:982; }","duration":"758.056776ms","start":"2026-03-09T15:33:18.912174Z","end":"2026-03-09T15:33:19.67023Z","steps":["trace[602348120] 'range keys from in-memory index tree' (duration: 757.764708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:19.670276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:18.912147Z","time spent":"758.122937ms","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-09T15:33:19.67076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.554352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:33:19.670791Z","caller":"traceutil/trace.go:171","msg":"trace[1106836973] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"460.610664ms","start":"2026-03-09T15:33:19.210174Z","end":"2026-03-09T15:33:19.670784Z","steps":["trace[1106836973] 'range keys from in-memory index tree' (duration: 460.500471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:19.67081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:19.21016Z","time spent":"460.645854ms","remote":"127.0.0.1:39458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T15:33:19.671494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.305536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-09T15:33:19.671481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.900043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T15:33:19.67153Z","caller":"traceutil/trace.go:171","msg":"trace[1671572734] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:982; }","duration":"215.380558ms","start":"2026-03-09T15:33:19.456141Z","end":"2026-03-09T15:33:19.671521Z","steps":["trace[1671572734] 'range keys from in-memory index tree' (duration: 215.194273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:19.671517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.768033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-09T15:33:19.671545Z","caller":"traceutil/trace.go:171","msg":"trace[328764825] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:982; }","duration":"172.13383ms","start":"2026-03-09T15:33:19.499394Z","end":"2026-03-09T15:33:19.671528Z","steps":["trace[328764825] 'count revisions from in-memory index tree' (duration: 171.689148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:19.67162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.099824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T15:33:19.671673Z","caller":"traceutil/trace.go:171","msg":"trace[377350120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:982; }","duration":"209.883585ms","start":"2026-03-09T15:33:19.46169Z","end":"2026-03-09T15:33:19.671574Z","steps":["trace[377350120] 'range keys from in-memory index tree' (duration: 209.616889ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:19.671707Z","caller":"traceutil/trace.go:171","msg":"trace[139192488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:982; }","duration":"209.223827ms","start":"2026-03-09T15:33:19.462461Z","end":"2026-03-09T15:33:19.671685Z","steps":["trace[139192488] 'range keys from in-memory index tree' (duration: 208.870487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:19.671725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.345293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-09T15:33:19.671885Z","caller":"traceutil/trace.go:171","msg":"trace[452660371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"208.576069ms","start":"2026-03-09T15:33:19.463279Z","end":"2026-03-09T15:33:19.671855Z","steps":["trace[452660371] 'range keys from in-memory index tree' (duration: 208.057455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:26.222319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763919920848855,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T15:33:26.740791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763919920848855,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T15:33:27.241964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356763919920848855,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T15:33:27.380586Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.474454006s","expected-duration":"1s"} {"level":"info","ts":"2026-03-09T15:33:27.38126Z","caller":"traceutil/trace.go:171","msg":"trace[1177124735] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"2.475107224s","start":"2026-03-09T15:33:24.906003Z","end":"2026-03-09T15:33:27.38111Z","steps":["trace[1177124735] 'process raft request' (duration: 2.474845477s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.381542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:24.905982Z","time spent":"2.475419973s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T15:33:27.382452Z","caller":"traceutil/trace.go:171","msg":"trace[174982314] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1062; }","duration":"1.660667476s","start":"2026-03-09T15:33:25.721759Z","end":"2026-03-09T15:33:27.382427Z","steps":["trace[174982314] 'read index received' (duration: 1.659054392s)","trace[174982314] 'applied index is now lower than readState.Index' (duration: 1.612054ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:27.382548Z","caller":"traceutil/trace.go:171","msg":"trace[889704201] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"1.190862578s","start":"2026-03-09T15:33:26.191675Z","end":"2026-03-09T15:33:27.382538Z","steps":["trace[889704201] 'process raft request' (duration: 1.190619702s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.382669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:26.191658Z","time spent":"1.19091815s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T15:33:27.382902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.659199706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-09T15:33:27.382989Z","caller":"traceutil/trace.go:171","msg":"trace[1197586345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"1.65935853s","start":"2026-03-09T15:33:25.723612Z","end":"2026-03-09T15:33:27.38297Z","steps":["trace[1197586345] 'agreement among raft nodes before linearized reading' (duration: 1.659057872s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.383042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:25.723599Z","time spent":"1.659432522s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-09T15:33:27.383139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17609556s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T15:33:27.383229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192072062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-03-09T15:33:27.383191Z","caller":"traceutil/trace.go:171","msg":"trace[1386495655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"1.176168911s","start":"2026-03-09T15:33:26.207009Z","end":"2026-03-09T15:33:27.383178Z","steps":["trace[1386495655] 'agreement among raft nodes before linearized reading' (duration: 1.176090699s)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:27.383264Z","caller":"traceutil/trace.go:171","msg":"trace[1197156289] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1025; }","duration":"1.192125233s","start":"2026-03-09T15:33:26.19113Z","end":"2026-03-09T15:33:27.383256Z","steps":["trace[1197156289] 'agreement among raft nodes before linearized reading' (duration: 1.192024671s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.383299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:26.191121Z","time spent":"1.192169464s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-09T15:33:27.38337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:26.206998Z","time spent":"1.176359877s","remote":"127.0.0.1:39458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-09T15:33:27.383546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.659305059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T15:33:27.383584Z","caller":"traceutil/trace.go:171","msg":"trace[827210329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1025; }","duration":"1.659388941s","start":"2026-03-09T15:33:25.724186Z","end":"2026-03-09T15:33:27.383575Z","steps":["trace[827210329] 'agreement among raft nodes before linearized reading' (duration: 1.659289918s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.383612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:25.724163Z","time spent":"1.659442943s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T15:33:27.383622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66185767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-09T15:33:27.383695Z","caller":"traceutil/trace.go:171","msg":"trace[2119693288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"1.662006274s","start":"2026-03-09T15:33:25.721672Z","end":"2026-03-09T15:33:27.383679Z","steps":["trace[2119693288] 'agreement among raft nodes before linearized reading' (duration: 1.661885501s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.383727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:25.721639Z","time spent":"1.662081876s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T15:33:27.383754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193156242s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-03-09T15:33:27.383624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658060604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T15:33:27.38382Z","caller":"traceutil/trace.go:171","msg":"trace[341619946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1025; }","duration":"1.658323032s","start":"2026-03-09T15:33:25.725491Z","end":"2026-03-09T15:33:27.383814Z","steps":["trace[341619946] 'agreement among raft nodes before linearized reading' (duration: 1.657859169s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.383838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:25.72548Z","time spent":"1.658352942s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-09T15:33:27.383824Z","caller":"traceutil/trace.go:171","msg":"trace[821228442] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1025; }","duration":"1.193258234s","start":"2026-03-09T15:33:26.190538Z","end":"2026-03-09T15:33:27.383796Z","steps":["trace[821228442] 'agreement among raft nodes before linearized reading' (duration: 1.193135611s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.383884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:26.19051Z","time spent":"1.193362037s","remote":"127.0.0.1:39738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-09T15:33:27.383933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.451087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-09T15:33:27.384053Z","caller":"traceutil/trace.go:171","msg":"trace[1222911426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1025; }","duration":"675.618001ms","start":"2026-03-09T15:33:26.708388Z","end":"2026-03-09T15:33:27.384006Z","steps":["trace[1222911426] 'agreement among raft nodes before linearized reading' (duration: 675.22449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:27.384155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T15:33:26.708367Z","time spent":"675.721935ms","remote":"127.0.0.1:39738","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":"info","ts":"2026-03-09T15:33:43.62468Z","caller":"traceutil/trace.go:171","msg":"trace[1805037590] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"115.170117ms","start":"2026-03-09T15:33:43.509489Z","end":"2026-03-09T15:33:43.62466Z","steps":["trace[1805037590] 'read index received' (duration: 38.124959ms)","trace[1805037590] 'applied index is now lower than readState.Index' (duration: 77.044198ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:43.624744Z","caller":"traceutil/trace.go:171","msg":"trace[1332183996] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"116.458933ms","start":"2026-03-09T15:33:43.50819Z","end":"2026-03-09T15:33:43.624649Z","steps":["trace[1332183996] 'process raft request' (duration: 39.448765ms)","trace[1332183996] 'compare' (duration: 76.882934ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T15:33:43.625005Z","caller":"traceutil/trace.go:171","msg":"trace[1170339025] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"115.121636ms","start":"2026-03-09T15:33:43.509859Z","end":"2026-03-09T15:33:43.624981Z","steps":["trace[1170339025] 'process raft request' (duration: 114.761376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T15:33:43.625187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.681761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-09T15:33:43.62522Z","caller":"traceutil/trace.go:171","msg":"trace[589826070] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1096; }","duration":"115.742964ms","start":"2026-03-09T15:33:43.509465Z","end":"2026-03-09T15:33:43.625208Z","steps":["trace[589826070] 'agreement among raft nodes before linearized reading' (duration: 115.316692ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T15:33:43.625234Z","caller":"traceutil/trace.go:171","msg":"trace[2122586414] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"112.986347ms","start":"2026-03-09T15:33:43.512222Z","end":"2026-03-09T15:33:43.625208Z","steps":["trace[2122586414] 'process raft request' (duration: 112.620136ms)"],"step_count":1}