{"level":"warn","ts":"2026-02-25T01:40:56.389705Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:40:56.389801Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57: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.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T01:40:56.389879Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:40:56.389894Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-25T01:40:56.389917Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T01:40:56.390593Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-02-25T01:40:56.390934Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T01:40:56.396773Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.361429ms"} {"level":"info","ts":"2026-02-25T01:40:56.404006Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-02-25T01:40:56.405259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:40:56.405317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-02-25T01:40:56.405332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:40:56.405347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-02-25T01:40:56.405403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-02-25T01:40:56.419675Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:40:56.424064Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:40:56.428671Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T01:40:56.430998Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:40:56.431992Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:40:56.432008Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:40:56.432147Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:40:56.432174Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T01:40:56.432768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-02-25T01:40:56.432922Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-02-25T01:40:56.435809Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T01:40:56.436183Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:40:56.436229Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:40:56.436279Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-25T01:40:56.436338Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-02-25T01:40:56.606442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:40:56.606515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:40:56.606545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-02-25T01:40:56.606563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:40:56.606572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-25T01:40:56.606585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-02-25T01:40:56.606598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-02-25T01:40:56.619786Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:40:56.620014Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:40:56.620217Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:40:56.620221Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:56.623672Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-02-25T01:40:56.623773Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:40:56.623816Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:40:56.62402Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T01:40:56.643359Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:56.643521Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:40:56.643593Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-25T01:41:07.004098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101325577967664,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:41:07.251228Z","caller":"traceutil/trace.go:171","msg":"trace[590803574] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"747.983159ms","start":"2026-02-25T01:41:06.503212Z","end":"2026-02-25T01:41:07.251195Z","steps":["trace[590803574] 'read index received' (duration: 747.822285ms)","trace[590803574] 'applied index is now lower than readState.Index' (duration: 159.534µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:07.251274Z","caller":"traceutil/trace.go:171","msg":"trace[1248561432] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"788.285272ms","start":"2026-02-25T01:41:06.462966Z","end":"2026-02-25T01:41:07.251251Z","steps":["trace[1248561432] 'process raft request' (duration: 788.079108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.251424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.187843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T01:41:07.251546Z","caller":"traceutil/trace.go:171","msg":"trace[1681624954] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:355; }","duration":"748.340776ms","start":"2026-02-25T01:41:06.503185Z","end":"2026-02-25T01:41:07.251526Z","steps":["trace[1681624954] 'agreement among raft nodes before linearized reading' (duration: 748.170693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.251595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.503172Z","time spent":"748.408298ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:07.251681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.014159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T01:41:07.251762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.733688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T01:41:07.251785Z","caller":"traceutil/trace.go:171","msg":"trace[1166049913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:355; }","duration":"534.155963ms","start":"2026-02-25T01:41:06.717612Z","end":"2026-02-25T01:41:07.251768Z","steps":["trace[1166049913] 'agreement among raft nodes before linearized reading' (duration: 533.998729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.251856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.717591Z","time spent":"534.250885ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T01:41:07.251854Z","caller":"traceutil/trace.go:171","msg":"trace[947250632] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:355; }","duration":"447.869341ms","start":"2026-02-25T01:41:06.803963Z","end":"2026-02-25T01:41:07.251823Z","steps":["trace[947250632] 'agreement among raft nodes before linearized reading' (duration: 447.723018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.251919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.803933Z","time spent":"447.973384ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:07.251947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.630383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-02-25T01:41:07.251751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.507875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-02-25T01:41:07.251953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.718918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-02-25T01:41:07.251984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.462939Z","time spent":"788.395924ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4004,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:07.25201Z","caller":"traceutil/trace.go:171","msg":"trace[596745664] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:355; }","duration":"697.700845ms","start":"2026-02-25T01:41:06.554284Z","end":"2026-02-25T01:41:07.251985Z","steps":["trace[596745664] 'agreement among raft nodes before linearized reading' (duration: 697.587293ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:07.252031Z","caller":"traceutil/trace.go:171","msg":"trace[1693183004] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:355; }","duration":"597.851613ms","start":"2026-02-25T01:41:06.654161Z","end":"2026-02-25T01:41:07.252013Z","steps":["trace[1693183004] 'agreement among raft nodes before linearized reading' (duration: 597.464184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.251954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.780021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-25T01:41:07.252072Z","caller":"traceutil/trace.go:171","msg":"trace[1978197814] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:355; }","duration":"498.877491ms","start":"2026-02-25T01:41:06.753151Z","end":"2026-02-25T01:41:07.252028Z","steps":["trace[1978197814] 'agreement among raft nodes before linearized reading' (duration: 498.616605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.252101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.65414Z","time spent":"597.942874ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:07.252119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.753117Z","time spent":"498.993183ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:07.252118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.716168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T01:41:07.252198Z","caller":"traceutil/trace.go:171","msg":"trace[968787969] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:355; }","duration":"189.068758ms","start":"2026-02-25T01:41:07.063028Z","end":"2026-02-25T01:41:07.252097Z","steps":["trace[968787969] 'agreement among raft nodes before linearized reading' (duration: 188.665599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.252059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.55427Z","time spent":"697.780237ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-25T01:41:07.252406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.364597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-25T01:41:07.252435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.572822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-25T01:41:07.252495Z","caller":"traceutil/trace.go:171","msg":"trace[2135659406] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:355; }","duration":"549.075592ms","start":"2026-02-25T01:41:06.703407Z","end":"2026-02-25T01:41:07.252482Z","steps":["trace[2135659406] 'agreement among raft nodes before linearized reading' (duration: 548.399017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.252529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.70338Z","time spent":"549.138984ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-02-25T01:41:07.252225Z","caller":"traceutil/trace.go:171","msg":"trace[987961766] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:355; }","duration":"647.85551ms","start":"2026-02-25T01:41:06.604317Z","end":"2026-02-25T01:41:07.252172Z","steps":["trace[987961766] 'agreement among raft nodes before linearized reading' (duration: 647.535583ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:07.252521Z","caller":"traceutil/trace.go:171","msg":"trace[8869924] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:355; }","duration":"734.715715ms","start":"2026-02-25T01:41:06.51779Z","end":"2026-02-25T01:41:07.252506Z","steps":["trace[8869924] 'agreement among raft nodes before linearized reading' (duration: 733.718623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:07.252991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.517766Z","time spent":"735.212705ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-02-25T01:41:07.252963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:06.604287Z","time spent":"648.654548ms","remote":"127.0.0.1:45502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-02-25T01:41:12.905479Z","caller":"traceutil/trace.go:171","msg":"trace[2000227647] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"552.850665ms","start":"2026-02-25T01:41:12.352605Z","end":"2026-02-25T01:41:12.905455Z","steps":["trace[2000227647] 'process raft request' (duration: 552.697461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:12.905661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:12.352575Z","time spent":"552.985548ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:12.906325Z","caller":"traceutil/trace.go:171","msg":"trace[358349188] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"261.405509ms","start":"2026-02-25T01:41:12.644896Z","end":"2026-02-25T01:41:12.906302Z","steps":["trace[358349188] 'process raft request' (duration: 261.317177ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:14.990198Z","caller":"traceutil/trace.go:171","msg":"trace[903946276] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"138.117834ms","start":"2026-02-25T01:41:14.852045Z","end":"2026-02-25T01:41:14.990163Z","steps":["trace[903946276] 'process raft request' (duration: 138.029122ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:14.990269Z","caller":"traceutil/trace.go:171","msg":"trace[2056216695] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"141.931688ms","start":"2026-02-25T01:41:14.848288Z","end":"2026-02-25T01:41:14.99022Z","steps":["trace[2056216695] 'process raft request' (duration: 66.69981ms)","trace[2056216695] 'compare' (duration: 74.909611ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:14.990562Z","caller":"traceutil/trace.go:171","msg":"trace[1661330191] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"134.956064ms","start":"2026-02-25T01:41:14.855584Z","end":"2026-02-25T01:41:14.990541Z","steps":["trace[1661330191] 'process raft request' (duration: 134.915313ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:14.990806Z","caller":"traceutil/trace.go:171","msg":"trace[169726638] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"138.524994ms","start":"2026-02-25T01:41:14.852264Z","end":"2026-02-25T01:41:14.990789Z","steps":["trace[169726638] 'process raft request' (duration: 137.859928ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:14.990875Z","caller":"traceutil/trace.go:171","msg":"trace[1170304479] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"136.657822ms","start":"2026-02-25T01:41:14.854193Z","end":"2026-02-25T01:41:14.990851Z","steps":["trace[1170304479] 'process raft request' (duration: 136.184871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:23.916981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101325577967868,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:41:24.015616Z","caller":"traceutil/trace.go:171","msg":"trace[1343205662] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"599.124582ms","start":"2026-02-25T01:41:23.41647Z","end":"2026-02-25T01:41:24.015594Z","steps":["trace[1343205662] 'read index received' (duration: 598.940278ms)","trace[1343205662] 'applied index is now lower than readState.Index' (duration: 183.424µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:24.015716Z","caller":"traceutil/trace.go:171","msg":"trace[1162235305] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"635.203903ms","start":"2026-02-25T01:41:23.380485Z","end":"2026-02-25T01:41:24.015689Z","steps":["trace[1162235305] 'process raft request' (duration: 634.988768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:24.015765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.283316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:41:24.015802Z","caller":"traceutil/trace.go:171","msg":"trace[1274414449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:466; }","duration":"599.358647ms","start":"2026-02-25T01:41:23.416433Z","end":"2026-02-25T01:41:24.015792Z","steps":["trace[1274414449] 'agreement among raft nodes before linearized reading' (duration: 599.278656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:24.015835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:23.41642Z","time spent":"599.405648ms","remote":"127.0.0.1:45574","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-02-25T01:41:24.015832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:23.380472Z","time spent":"635.291505ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:24.01584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.271598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T01:41:24.015852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.784306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:24.015892Z","caller":"traceutil/trace.go:171","msg":"trace[959320682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"303.36782ms","start":"2026-02-25T01:41:23.712509Z","end":"2026-02-25T01:41:24.015877Z","steps":["trace[959320682] 'agreement among raft nodes before linearized reading' (duration: 303.283918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:24.015933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:23.71249Z","time spent":"303.436241ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:41:24.015912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.995529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:41:24.015998Z","caller":"traceutil/trace.go:171","msg":"trace[1655659202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"224.141992ms","start":"2026-02-25T01:41:23.791836Z","end":"2026-02-25T01:41:24.015978Z","steps":["trace[1655659202] 'agreement among raft nodes before linearized reading' (duration: 223.954828ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:24.015937Z","caller":"traceutil/trace.go:171","msg":"trace[1731232805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"461.941719ms","start":"2026-02-25T01:41:23.553977Z","end":"2026-02-25T01:41:24.015919Z","steps":["trace[1731232805] 'agreement among raft nodes before linearized reading' (duration: 461.764125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:24.016104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:23.55395Z","time spent":"462.144923ms","remote":"127.0.0.1:45306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:41:24.335666Z","caller":"traceutil/trace.go:171","msg":"trace[391693729] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"315.562851ms","start":"2026-02-25T01:41:24.020073Z","end":"2026-02-25T01:41:24.335636Z","steps":["trace[391693729] 'process raft request' (duration: 227.997908ms)","trace[391693729] 'compare' (duration: 86.98924ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:24.335819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:24.020055Z","time spent":"315.685414ms","remote":"127.0.0.1:45574","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-02-25T01:41:24.342085Z","caller":"traceutil/trace.go:171","msg":"trace[2067517484] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"320.593084ms","start":"2026-02-25T01:41:24.021465Z","end":"2026-02-25T01:41:24.342058Z","steps":["trace[2067517484] 'process raft request' (duration: 320.547283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:24.342285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:24.021442Z","time spent":"320.722156ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:24.34227Z","caller":"traceutil/trace.go:171","msg":"trace[177908846] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"321.756469ms","start":"2026-02-25T01:41:24.020425Z","end":"2026-02-25T01:41:24.342181Z","steps":["trace[177908846] 'process raft request' (duration: 321.492804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:24.34247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:24.020404Z","time spent":"321.962634ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:26.328669Z","caller":"traceutil/trace.go:171","msg":"trace[1576026082] linearizableReadLoop","detail":"{readStateIndex:483; appliedIndex:482; }","duration":"308.454923ms","start":"2026-02-25T01:41:26.020174Z","end":"2026-02-25T01:41:26.328629Z","steps":["trace[1576026082] 'read index received' (duration: 305.575459ms)","trace[1576026082] 'applied index is now lower than readState.Index' (duration: 2.873334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:26.328932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.71746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T01:41:26.329071Z","caller":"traceutil/trace.go:171","msg":"trace[212531460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"308.927654ms","start":"2026-02-25T01:41:26.020113Z","end":"2026-02-25T01:41:26.329041Z","steps":["trace[212531460] 'agreement among raft nodes before linearized reading' (duration: 308.649708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:26.32913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:26.02009Z","time spent":"309.027626ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T01:41:30.740297Z","caller":"traceutil/trace.go:171","msg":"trace[352119136] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"384.485161ms","start":"2026-02-25T01:41:30.355773Z","end":"2026-02-25T01:41:30.740259Z","steps":["trace[352119136] 'read index received' (duration: 384.215895ms)","trace[352119136] 'applied index is now lower than readState.Index' (duration: 267.226µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:30.740389Z","caller":"traceutil/trace.go:171","msg":"trace[1357273352] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"386.884003ms","start":"2026-02-25T01:41:30.353466Z","end":"2026-02-25T01:41:30.74035Z","steps":["trace[1357273352] 'process raft request' (duration: 386.562406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:30.740689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.529468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T01:41:30.740674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.574917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:41:30.740739Z","caller":"traceutil/trace.go:171","msg":"trace[613946399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:513; }","duration":"373.65065ms","start":"2026-02-25T01:41:30.367076Z","end":"2026-02-25T01:41:30.740727Z","steps":["trace[613946399] 'agreement among raft nodes before linearized reading' (duration: 373.520897ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:30.740766Z","caller":"traceutil/trace.go:171","msg":"trace[528374731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:513; }","duration":"377.7117ms","start":"2026-02-25T01:41:30.363029Z","end":"2026-02-25T01:41:30.740741Z","steps":["trace[528374731] 'agreement among raft nodes before linearized reading' (duration: 377.542106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:30.740789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:30.367047Z","time spent":"373.732962ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:41:30.740831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:30.363018Z","time spent":"377.799092ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:41:30.740821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:30.353355Z","time spent":"387.248851ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:30.741095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.315209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:41:30.741158Z","caller":"traceutil/trace.go:171","msg":"trace[575393938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:513; }","duration":"385.3853ms","start":"2026-02-25T01:41:30.355742Z","end":"2026-02-25T01:41:30.741128Z","steps":["trace[575393938] 'agreement among raft nodes before linearized reading' (duration: 384.730986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:30.741189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:30.355728Z","time spent":"385.453382ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T01:41:37.053929Z","caller":"traceutil/trace.go:171","msg":"trace[611585273] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"250.762343ms","start":"2026-02-25T01:41:36.803141Z","end":"2026-02-25T01:41:37.053903Z","steps":["trace[611585273] 'process raft request' (duration: 250.597389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:37.622531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.538462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:41:37.62282Z","caller":"traceutil/trace.go:171","msg":"trace[1701928106] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"181.384373ms","start":"2026-02-25T01:41:37.441414Z","end":"2026-02-25T01:41:37.622798Z","steps":["trace[1701928106] 'read index received' (duration: 29.191µs)","trace[1701928106] 'applied index is now lower than readState.Index' (duration: 181.353432ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:37.62283Z","caller":"traceutil/trace.go:171","msg":"trace[1853235636] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"818.828457ms","start":"2026-02-25T01:41:36.803935Z","end":"2026-02-25T01:41:37.622763Z","steps":["trace[1853235636] 'process raft request' (duration: 327.740432ms)","trace[1853235636] 'compare' (duration: 490.371759ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:37.622939Z","caller":"traceutil/trace.go:171","msg":"trace[1848533447] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"818.793457ms","start":"2026-02-25T01:41:36.804081Z","end":"2026-02-25T01:41:37.622874Z","steps":["trace[1848533447] 'process raft request' (duration: 818.575991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:37.623109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:36.803923Z","time spent":"819.018811ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:37.623146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.697181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vgdfc\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T01:41:37.62326Z","caller":"traceutil/trace.go:171","msg":"trace[1203821599] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vgdfc; range_end:; response_count:1; response_revision:556; }","duration":"181.856735ms","start":"2026-02-25T01:41:37.441379Z","end":"2026-02-25T01:41:37.623236Z","steps":["trace[1203821599] 'agreement among raft nodes before linearized reading' (duration: 181.529838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:37.623169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:36.804046Z","time spent":"818.997161ms","remote":"127.0.0.1:45574","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-02-25T01:41:38.108048Z","caller":"traceutil/trace.go:171","msg":"trace[752051709] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"471.128672ms","start":"2026-02-25T01:41:37.636888Z","end":"2026-02-25T01:41:38.108017Z","steps":["trace[752051709] 'process raft request' (duration: 470.883467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:38.108266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:37.636865Z","time spent":"471.278735ms","remote":"127.0.0.1:45472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:39.295256Z","caller":"traceutil/trace.go:171","msg":"trace[664900203] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"236.612769ms","start":"2026-02-25T01:41:39.05861Z","end":"2026-02-25T01:41:39.295223Z","steps":["trace[664900203] 'read index received' (duration: 236.406644ms)","trace[664900203] 'applied index is now lower than readState.Index' (duration: 204.925µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:39.2954Z","caller":"traceutil/trace.go:171","msg":"trace[1076700992] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"486.688748ms","start":"2026-02-25T01:41:38.808649Z","end":"2026-02-25T01:41:39.295338Z","steps":["trace[1076700992] 'process raft request' (duration: 486.352449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:39.29551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.864606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T01:41:39.29561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:38.808602Z","time spent":"486.899462ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:39.295633Z","caller":"traceutil/trace.go:171","msg":"trace[68945642] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"237.06901ms","start":"2026-02-25T01:41:39.058537Z","end":"2026-02-25T01:41:39.295606Z","steps":["trace[68945642] 'agreement among raft nodes before linearized reading' (duration: 236.810275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.054884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101325577968068,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:41:40.333631Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.032932197s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:41:40.424972Z","caller":"traceutil/trace.go:171","msg":"trace[1642493720] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.124357906s","start":"2026-02-25T01:41:39.300563Z","end":"2026-02-25T01:41:40.424921Z","steps":["trace[1642493720] 'process raft request' (duration: 1.033659213s)","trace[1642493720] 'compare' (duration: 90.265334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:40.425373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.30053Z","time spent":"1.124536319s","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:41:40.431553Z","caller":"traceutil/trace.go:171","msg":"trace[547841901] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"877.490858ms","start":"2026-02-25T01:41:39.55403Z","end":"2026-02-25T01:41:40.431521Z","steps":["trace[547841901] 'read index received' (duration: 779.922853ms)","trace[547841901] 'applied index is now lower than readState.Index' (duration: 97.566725ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:40.431899Z","caller":"traceutil/trace.go:171","msg":"trace[1931766135] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"927.293993ms","start":"2026-02-25T01:41:39.504593Z","end":"2026-02-25T01:41:40.431887Z","steps":["trace[1931766135] 'process raft request' (duration: 926.733911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.432069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.504532Z","time spent":"927.408195ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:41:40.432143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.993969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T01:41:40.432211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.704231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:41:40.432226Z","caller":"traceutil/trace.go:171","msg":"trace[234665330] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"805.112731ms","start":"2026-02-25T01:41:39.627094Z","end":"2026-02-25T01:41:40.432207Z","steps":["trace[234665330] 'agreement among raft nodes before linearized reading' (duration: 804.900037ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:40.432241Z","caller":"traceutil/trace.go:171","msg":"trace[1941683867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"803.780103ms","start":"2026-02-25T01:41:39.628451Z","end":"2026-02-25T01:41:40.432231Z","steps":["trace[1941683867] 'agreement among raft nodes before linearized reading' (duration: 803.6413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.432268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.628426Z","time spent":"803.836023ms","remote":"127.0.0.1:45574","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-02-25T01:41:40.432189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.107052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:41:40.432271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.627016Z","time spent":"805.245354ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:41:40.432336Z","caller":"traceutil/trace.go:171","msg":"trace[1571151700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"878.331766ms","start":"2026-02-25T01:41:39.553973Z","end":"2026-02-25T01:41:40.432304Z","steps":["trace[1571151700] 'agreement among raft nodes before linearized reading' (duration: 878.02662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:41:40.432398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:41:39.553945Z","time spent":"878.439529ms","remote":"127.0.0.1:45320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:41:41.51792Z","caller":"traceutil/trace.go:171","msg":"trace[523889817] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"189.832021ms","start":"2026-02-25T01:41:41.328043Z","end":"2026-02-25T01:41:41.517875Z","steps":["trace[523889817] 'process raft request' (duration: 166.512115ms)","trace[523889817] 'compare' (duration: 23.01244ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:41:41.518006Z","caller":"traceutil/trace.go:171","msg":"trace[2011252086] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"188.461431ms","start":"2026-02-25T01:41:41.329499Z","end":"2026-02-25T01:41:41.517961Z","steps":["trace[2011252086] 'read index received' (duration: 165.081103ms)","trace[2011252086] 'applied index is now lower than readState.Index' (duration: 23.376548ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:41:41.518312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.798678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/cert-manager/\" range_end:\"/registry/limitranges/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:41:41.518375Z","caller":"traceutil/trace.go:171","msg":"trace[1067866682] range","detail":"{range_begin:/registry/limitranges/cert-manager/; range_end:/registry/limitranges/cert-manager0; response_count:0; response_revision:644; }","duration":"188.962752ms","start":"2026-02-25T01:41:41.329393Z","end":"2026-02-25T01:41:41.518356Z","steps":["trace[1067866682] 'agreement among raft nodes before linearized reading' (duration: 188.784438ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:41.518557Z","caller":"traceutil/trace.go:171","msg":"trace[2054575541] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"186.860046ms","start":"2026-02-25T01:41:41.331677Z","end":"2026-02-25T01:41:41.518537Z","steps":["trace[2054575541] 'process raft request' (duration: 186.360625ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:41.518625Z","caller":"traceutil/trace.go:171","msg":"trace[342834792] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"186.688421ms","start":"2026-02-25T01:41:41.331902Z","end":"2026-02-25T01:41:41.51859Z","steps":["trace[342834792] 'process raft request' (duration: 186.226461ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:41.518665Z","caller":"traceutil/trace.go:171","msg":"trace[1647453993] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"188.139924ms","start":"2026-02-25T01:41:41.330501Z","end":"2026-02-25T01:41:41.518641Z","steps":["trace[1647453993] 'process raft request' (duration: 187.469159ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:41.518956Z","caller":"traceutil/trace.go:171","msg":"trace[208731116] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"188.493922ms","start":"2026-02-25T01:41:41.330368Z","end":"2026-02-25T01:41:41.518862Z","steps":["trace[208731116] 'process raft request' (duration: 187.530581ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:41.519281Z","caller":"traceutil/trace.go:171","msg":"trace[1597228067] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"181.23211ms","start":"2026-02-25T01:41:41.338021Z","end":"2026-02-25T01:41:41.519253Z","steps":["trace[1597228067] 'process raft request' (duration: 180.269259ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:41.519844Z","caller":"traceutil/trace.go:171","msg":"trace[199444767] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"176.160529ms","start":"2026-02-25T01:41:41.343649Z","end":"2026-02-25T01:41:41.51981Z","steps":["trace[199444767] 'process raft request' (duration: 175.171586ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:41:42.940159Z","caller":"traceutil/trace.go:171","msg":"trace[2001704756] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"239.442973ms","start":"2026-02-25T01:41:42.700691Z","end":"2026-02-25T01:41:42.940134Z","steps":["trace[2001704756] 'process raft request' (duration: 239.30959ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:13.67604Z","caller":"traceutil/trace.go:171","msg":"trace[1723433156] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"122.999319ms","start":"2026-02-25T01:42:13.553018Z","end":"2026-02-25T01:42:13.676017Z","steps":["trace[1723433156] 'process raft request' (duration: 122.355945ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:35.558023Z","caller":"traceutil/trace.go:171","msg":"trace[39445533] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"129.297722ms","start":"2026-02-25T01:42:35.428678Z","end":"2026-02-25T01:42:35.557976Z","steps":["trace[39445533] 'process raft request' (duration: 74.273867ms)","trace[39445533] 'compare' (duration: 54.707618ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:35.558351Z","caller":"traceutil/trace.go:171","msg":"trace[128607064] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"129.583539ms","start":"2026-02-25T01:42:35.428734Z","end":"2026-02-25T01:42:35.558318Z","steps":["trace[128607064] 'process raft request' (duration: 129.143669ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:35.558538Z","caller":"traceutil/trace.go:171","msg":"trace[1778431065] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"128.973665ms","start":"2026-02-25T01:42:35.429531Z","end":"2026-02-25T01:42:35.558505Z","steps":["trace[1778431065] 'process raft request' (duration: 128.526626ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:35.796733Z","caller":"traceutil/trace.go:171","msg":"trace[1087527977] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"143.69683ms","start":"2026-02-25T01:42:35.653009Z","end":"2026-02-25T01:42:35.796706Z","steps":["trace[1087527977] 'process raft request' (duration: 92.254191ms)","trace[1087527977] 'compare' (duration: 51.112723ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:35.79719Z","caller":"traceutil/trace.go:171","msg":"trace[253922865] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"143.331882ms","start":"2026-02-25T01:42:35.653837Z","end":"2026-02-25T01:42:35.797169Z","steps":["trace[253922865] 'process raft request' (duration: 143.199019ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:39.172087Z","caller":"traceutil/trace.go:171","msg":"trace[634409397] linearizableReadLoop","detail":"{readStateIndex:1274; appliedIndex:1273; }","duration":"282.597907ms","start":"2026-02-25T01:42:38.889452Z","end":"2026-02-25T01:42:39.17205Z","steps":["trace[634409397] 'read index received' (duration: 282.347282ms)","trace[634409397] 'applied index is now lower than readState.Index' (duration: 248.885µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:39.172127Z","caller":"traceutil/trace.go:171","msg":"trace[949884470] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"286.254006ms","start":"2026-02-25T01:42:38.885836Z","end":"2026-02-25T01:42:39.17209Z","steps":["trace[949884470] 'process raft request' (duration: 285.96044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:39.17251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.949454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T01:42:39.172518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.17928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:42:39.172595Z","caller":"traceutil/trace.go:171","msg":"trace[1062793158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"283.117418ms","start":"2026-02-25T01:42:38.889463Z","end":"2026-02-25T01:42:39.172581Z","steps":["trace[1062793158] 'agreement among raft nodes before linearized reading' (duration: 282.854173ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:39.172606Z","caller":"traceutil/trace.go:171","msg":"trace[530595787] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"276.306223ms","start":"2026-02-25T01:42:38.896279Z","end":"2026-02-25T01:42:39.172586Z","steps":["trace[530595787] 'agreement among raft nodes before linearized reading' (duration: 276.068348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:39.172477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.983635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:42:39.172801Z","caller":"traceutil/trace.go:171","msg":"trace[1272793524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"283.319732ms","start":"2026-02-25T01:42:38.889426Z","end":"2026-02-25T01:42:39.172746Z","steps":["trace[1272793524] 'agreement among raft nodes before linearized reading' (duration: 282.822601ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:40.999432Z","caller":"traceutil/trace.go:171","msg":"trace[746410019] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"101.393627ms","start":"2026-02-25T01:42:40.89801Z","end":"2026-02-25T01:42:40.999404Z","steps":["trace[746410019] 'process raft request' (duration: 98.681849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:41.346973Z","caller":"traceutil/trace.go:171","msg":"trace[599572530] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"109.260865ms","start":"2026-02-25T01:42:41.237681Z","end":"2026-02-25T01:42:41.346942Z","steps":["trace[599572530] 'process raft request' (duration: 109.02335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:15.692742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.083029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:43:15.692889Z","caller":"traceutil/trace.go:171","msg":"trace[1946011433] linearizableReadLoop","detail":"{readStateIndex:1674; appliedIndex:1673; }","duration":"320.360824ms","start":"2026-02-25T01:43:15.37251Z","end":"2026-02-25T01:43:15.692871Z","steps":["trace[1946011433] 'read index received' (duration: 211.953928ms)","trace[1946011433] 'applied index is now lower than readState.Index' (duration: 108.405126ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:15.69299Z","caller":"traceutil/trace.go:171","msg":"trace[1100387155] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"543.613554ms","start":"2026-02-25T01:43:15.149364Z","end":"2026-02-25T01:43:15.692978Z","steps":["trace[1100387155] 'process raft request' (duration: 435.179548ms)","trace[1100387155] 'compare' (duration: 107.738241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:43:15.693054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:15.149335Z","time spent":"543.683855ms","remote":"127.0.0.1:45574","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-02-25T01:43:15.693347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.821594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T01:43:15.69339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.124642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:43:15.693497Z","caller":"traceutil/trace.go:171","msg":"trace[1446222909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1608; }","duration":"320.034278ms","start":"2026-02-25T01:43:15.373442Z","end":"2026-02-25T01:43:15.693476Z","steps":["trace[1446222909] 'agreement among raft nodes before linearized reading' (duration: 319.722901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:15.693491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.229855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:43:15.69352Z","caller":"traceutil/trace.go:171","msg":"trace[848535480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1608; }","duration":"138.331366ms","start":"2026-02-25T01:43:15.555159Z","end":"2026-02-25T01:43:15.693491Z","steps":["trace[848535480] 'agreement among raft nodes before linearized reading' (duration: 138.139322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:15.693494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.969788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T01:43:15.693563Z","caller":"traceutil/trace.go:171","msg":"trace[1707601419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1608; }","duration":"293.355108ms","start":"2026-02-25T01:43:15.400192Z","end":"2026-02-25T01:43:15.693547Z","steps":["trace[1707601419] 'agreement among raft nodes before linearized reading' (duration: 293.076502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:15.693522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.371843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"warn","ts":"2026-02-25T01:43:15.693555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:15.373416Z","time spent":"320.12779ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:43:15.693605Z","caller":"traceutil/trace.go:171","msg":"trace[1078976872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1608; }","duration":"321.090721ms","start":"2026-02-25T01:43:15.372493Z","end":"2026-02-25T01:43:15.693584Z","steps":["trace[1078976872] 'agreement among raft nodes before linearized reading' (duration: 320.781964ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:15.693645Z","caller":"traceutil/trace.go:171","msg":"trace[2128800947] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1608; }","duration":"319.534496ms","start":"2026-02-25T01:43:15.374075Z","end":"2026-02-25T01:43:15.693609Z","steps":["trace[2128800947] 'agreement among raft nodes before linearized reading' (duration: 319.198029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:15.693717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:15.37406Z","time spent":"319.646149ms","remote":"127.0.0.1:42836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3898,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-25T01:43:15.693657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:43:15.372411Z","time spent":"321.236373ms","remote":"127.0.0.1:45574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:43:15.693933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.032845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-25T01:43:15.693986Z","caller":"traceutil/trace.go:171","msg":"trace[986120529] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1608; }","duration":"289.133787ms","start":"2026-02-25T01:43:15.40484Z","end":"2026-02-25T01:43:15.693974Z","steps":["trace[986120529] 'agreement among raft nodes before linearized reading' (duration: 288.381821ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:43:20.813223Z","caller":"traceutil/trace.go:171","msg":"trace[1069662048] linearizableReadLoop","detail":"{readStateIndex:1701; appliedIndex:1700; }","duration":"111.357775ms","start":"2026-02-25T01:43:20.701838Z","end":"2026-02-25T01:43:20.813196Z","steps":["trace[1069662048] 'read index received' (duration: 110.985077ms)","trace[1069662048] 'applied index is now lower than readState.Index' (duration: 336.648µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:43:20.813255Z","caller":"traceutil/trace.go:171","msg":"trace[611946485] transaction","detail":"{read_only:false; response_revision:1634; number_of_response:1; }","duration":"130.685099ms","start":"2026-02-25T01:43:20.682532Z","end":"2026-02-25T01:43:20.813217Z","steps":["trace[611946485] 'process raft request' (duration: 130.406583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:43:20.813551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.694642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T01:43:20.813646Z","caller":"traceutil/trace.go:171","msg":"trace[1020911710] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:1634; }","duration":"111.839596ms","start":"2026-02-25T01:43:20.701783Z","end":"2026-02-25T01:43:20.813623Z","steps":["trace[1020911710] 'agreement among raft nodes before linearized reading' (duration: 111.621681ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:19.257322Z","caller":"traceutil/trace.go:171","msg":"trace[2097718229] transaction","detail":"{read_only:false; response_revision:2528; number_of_response:1; }","duration":"148.330126ms","start":"2026-02-25T01:47:19.108966Z","end":"2026-02-25T01:47:19.257296Z","steps":["trace[2097718229] 'process raft request' (duration: 148.287395ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:19.257734Z","caller":"traceutil/trace.go:171","msg":"trace[1553036245] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"150.285908ms","start":"2026-02-25T01:47:19.107439Z","end":"2026-02-25T01:47:19.257725Z","steps":["trace[1553036245] 'process raft request' (duration: 149.706355ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:47:19.348454Z","caller":"traceutil/trace.go:171","msg":"trace[2009322138] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"152.398206ms","start":"2026-02-25T01:47:19.19602Z","end":"2026-02-25T01:47:19.348418Z","steps":["trace[2009322138] 'process raft request' (duration: 80.091789ms)","trace[2009322138] 'compare' (duration: 72.053802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:47:50.873242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.747706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T01:47:50.873522Z","caller":"traceutil/trace.go:171","msg":"trace[702361546] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2639; }","duration":"114.098954ms","start":"2026-02-25T01:47:50.759392Z","end":"2026-02-25T01:47:50.873491Z","steps":["trace[702361546] 'range keys from in-memory index tree' (duration: 113.499061ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:48:09.819282Z","caller":"traceutil/trace.go:171","msg":"trace[1611054562] transaction","detail":"{read_only:false; response_revision:2710; number_of_response:1; }","duration":"127.974268ms","start":"2026-02-25T01:48:09.691253Z","end":"2026-02-25T01:48:09.819227Z","steps":["trace[1611054562] 'process raft request' (duration: 102.141622ms)","trace[1611054562] 'compare' (duration: 25.620612ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:50:57.488667Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2234} {"level":"info","ts":"2026-02-25T01:50:57.559153Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2234,"took":"69.617378ms","hash":366433958,"current-db-size-bytes":14512128,"current-db-size":"14 MB","current-db-size-in-use-bytes":4481024,"current-db-size-in-use":"4.5 MB"} {"level":"info","ts":"2026-02-25T01:50:57.55926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":366433958,"revision":2234,"compact-revision":-1} {"level":"info","ts":"2026-02-25T01:55:57.497244Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3310} {"level":"info","ts":"2026-02-25T01:55:57.517797Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3310,"took":"19.98244ms","hash":1509263536,"current-db-size-bytes":14512128,"current-db-size":"14 MB","current-db-size-in-use-bytes":4919296,"current-db-size-in-use":"4.9 MB"} {"level":"info","ts":"2026-02-25T01:55:57.517885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1509263536,"revision":3310,"compact-revision":2234} {"level":"warn","ts":"2026-02-25T01:58:37.27951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.554926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-gx6tk\" ","response":"range_response_count:1 size:3883"} {"level":"info","ts":"2026-02-25T01:58:37.279618Z","caller":"traceutil/trace.go:171","msg":"trace[1079441416] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-gx6tk; range_end:; response_count:1; response_revision:4950; }","duration":"101.706868ms","start":"2026-02-25T01:58:37.177892Z","end":"2026-02-25T01:58:37.279599Z","steps":["trace[1079441416] 'range keys from in-memory index tree' (duration: 101.394132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:58:37.279515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.303206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/pxc-operator\" ","response":"range_response_count:1 size:1806"} {"level":"info","ts":"2026-02-25T01:58:37.279744Z","caller":"traceutil/trace.go:171","msg":"trace[1016937295] range","detail":"{range_begin:/registry/roles/openstack/pxc-operator; range_end:; response_count:1; response_revision:4950; }","duration":"104.577202ms","start":"2026-02-25T01:58:37.175135Z","end":"2026-02-25T01:58:37.279712Z","steps":["trace[1016937295] 'range keys from in-memory index tree' (duration: 104.163003ms)"],"step_count":1}