{"level":"warn","ts":"2026-05-25T17:53:49.544192Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T17:53:49.544299Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109: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-05-25T17:53:49.544379Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-25T17:53:49.544388Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-25T17:53:49.544432Z","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-05-25T17:53:49.545267Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-05-25T17:53:49.545373Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109: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-05-25T17:53:50.373773Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"828.151527ms"} {"level":"info","ts":"2026-05-25T17:53:50.387889Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-05-25T17:53:50.387967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-05-25T17:53:50.38799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-05-25T17:53:50.388002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-25T17:53:50.388014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-05-25T17:53:50.388044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-05-25T17:53:50.396219Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-25T17:53:50.397299Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-25T17:53:50.398333Z","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-05-25T17:53:50.402127Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-25T17:53:50.402368Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-25T17:53:50.402465Z","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-05-25T17:53:50.402494Z","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-05-25T17:53:50.402504Z","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-05-25T17:53:50.403135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-05-25T17:53:50.403301Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-25T17:53:50.406341Z","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-05-25T17:53:50.406685Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-25T17:53:50.406743Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-25T17:53:50.406466Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-25T17:53:50.407239Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-25T17:53:50.888687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-05-25T17:53:50.888753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-05-25T17:53:50.888784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-05-25T17:53:50.888798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-05-25T17:53:50.888805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-25T17:53:50.888816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-05-25T17:53:50.888825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-25T17:53:50.889599Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-25T17:53:50.889604Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T17:53:50.88963Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-25T17:53:50.889759Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-25T17:53:50.889802Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-25T17:53:50.892181Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:53:50.893321Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:53:50.893481Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:53:50.89352Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-25T17:53:50.89598Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-25T17:53:50.897275Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"warn","ts":"2026-05-25T17:54:16.615541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.517471ms","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-05-25T17:54:16.615669Z","caller":"traceutil/trace.go:171","msg":"trace[1439263580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"481.696806ms","start":"2026-05-25T17:54:16.133921Z","end":"2026-05-25T17:54:16.615617Z","steps":["trace[1439263580] 'range keys from in-memory index tree' (duration: 481.359477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:16.615729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:16.133893Z","time spent":"481.824668ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T17:54:16.95977Z","caller":"traceutil/trace.go:171","msg":"trace[1253255253] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"341.36714ms","start":"2026-05-25T17:54:16.618376Z","end":"2026-05-25T17:54:16.959743Z","steps":["trace[1253255253] 'process raft request' (duration: 341.212945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:16.960593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:16.618359Z","time spent":"341.497183ms","remote":"127.0.0.1:36144","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-05-25T17:54:16.981563Z","caller":"traceutil/trace.go:171","msg":"trace[693684052] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"362.90265ms","start":"2026-05-25T17:54:16.618629Z","end":"2026-05-25T17:54:16.981531Z","steps":["trace[693684052] 'process raft request' (duration: 362.683654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:16.981925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:16.618618Z","time spent":"363.225628ms","remote":"127.0.0.1:36144","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-05-25T17:54:17.745069Z","caller":"traceutil/trace.go:171","msg":"trace[1017737677] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"123.024576ms","start":"2026-05-25T17:54:17.622027Z","end":"2026-05-25T17:54:17.745051Z","steps":["trace[1017737677] 'read index received' (duration: 122.870952ms)","trace[1017737677] 'applied index is now lower than readState.Index' (duration: 152.974µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:17.74527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.237742ms","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-05-25T17:54:17.74535Z","caller":"traceutil/trace.go:171","msg":"trace[1084811032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"123.370745ms","start":"2026-05-25T17:54:17.621969Z","end":"2026-05-25T17:54:17.745339Z","steps":["trace[1084811032] 'agreement among raft nodes before linearized reading' (duration: 123.242412ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:17.745267Z","caller":"traceutil/trace.go:171","msg":"trace[1882431943] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"155.323676ms","start":"2026-05-25T17:54:17.589923Z","end":"2026-05-25T17:54:17.745247Z","steps":["trace[1882431943] 'process raft request' (duration: 155.028748ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.231763Z","caller":"traceutil/trace.go:171","msg":"trace[1257668112] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"198.622129ms","start":"2026-05-25T17:54:28.033121Z","end":"2026-05-25T17:54:28.231743Z","steps":["trace[1257668112] 'process raft request' (duration: 198.532137ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.238744Z","caller":"traceutil/trace.go:171","msg":"trace[2005642590] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"204.834241ms","start":"2026-05-25T17:54:28.033875Z","end":"2026-05-25T17:54:28.238709Z","steps":["trace[2005642590] 'process raft request' (duration: 204.472562ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.239094Z","caller":"traceutil/trace.go:171","msg":"trace[498220874] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"192.853151ms","start":"2026-05-25T17:54:28.046214Z","end":"2026-05-25T17:54:28.239067Z","steps":["trace[498220874] 'process raft request' (duration: 192.416579ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.239201Z","caller":"traceutil/trace.go:171","msg":"trace[1998421163] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"192.199344ms","start":"2026-05-25T17:54:28.046955Z","end":"2026-05-25T17:54:28.239154Z","steps":["trace[1998421163] 'process raft request' (duration: 191.807374ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.239331Z","caller":"traceutil/trace.go:171","msg":"trace[41402523] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"192.179742ms","start":"2026-05-25T17:54:28.047141Z","end":"2026-05-25T17:54:28.239321Z","steps":["trace[41402523] 'process raft request' (duration: 191.693559ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.239369Z","caller":"traceutil/trace.go:171","msg":"trace[104567725] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"202.570383ms","start":"2026-05-25T17:54:28.036766Z","end":"2026-05-25T17:54:28.239337Z","steps":["trace[104567725] 'process raft request' (duration: 201.67456ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.239397Z","caller":"traceutil/trace.go:171","msg":"trace[629462292] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"194.466483ms","start":"2026-05-25T17:54:28.044913Z","end":"2026-05-25T17:54:28.239379Z","steps":["trace[629462292] 'process raft request' (duration: 193.58365ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.239479Z","caller":"traceutil/trace.go:171","msg":"trace[988805222] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"191.418743ms","start":"2026-05-25T17:54:28.04805Z","end":"2026-05-25T17:54:28.239469Z","steps":["trace[988805222] 'process raft request' (duration: 190.861339ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.240136Z","caller":"traceutil/trace.go:171","msg":"trace[81379673] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"184.273127ms","start":"2026-05-25T17:54:28.055837Z","end":"2026-05-25T17:54:28.24011Z","steps":["trace[81379673] 'process raft request' (duration: 183.130878ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.240199Z","caller":"traceutil/trace.go:171","msg":"trace[1073572226] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"141.868875ms","start":"2026-05-25T17:54:28.098309Z","end":"2026-05-25T17:54:28.240178Z","steps":["trace[1073572226] 'process raft request' (duration: 141.806674ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:28.240338Z","caller":"traceutil/trace.go:171","msg":"trace[2095518541] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"173.041716ms","start":"2026-05-25T17:54:28.067285Z","end":"2026-05-25T17:54:28.240326Z","steps":["trace[2095518541] 'process raft request' (duration: 172.753288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:29.520228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.995044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:54:29.520389Z","caller":"traceutil/trace.go:171","msg":"trace[2038370] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"161.189598ms","start":"2026-05-25T17:54:29.35915Z","end":"2026-05-25T17:54:29.52034Z","steps":["trace[2038370] 'read index received' (duration: 59.537967ms)","trace[2038370] 'applied index is now lower than readState.Index' (duration: 101.650051ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:54:29.520401Z","caller":"traceutil/trace.go:171","msg":"trace[904958374] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"161.454205ms","start":"2026-05-25T17:54:29.358928Z","end":"2026-05-25T17:54:29.520382Z","steps":["trace[904958374] 'process raft request' (duration: 59.963928ms)","trace[904958374] 'compare' (duration: 100.812249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:29.520542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.387523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-05-25T17:54:29.520575Z","caller":"traceutil/trace.go:171","msg":"trace[554041155] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:559; }","duration":"161.475774ms","start":"2026-05-25T17:54:29.35909Z","end":"2026-05-25T17:54:29.520565Z","steps":["trace[554041155] 'agreement among raft nodes before linearized reading' (duration: 161.336251ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:29.52109Z","caller":"traceutil/trace.go:171","msg":"trace[59693326] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"100.794849ms","start":"2026-05-25T17:54:29.420286Z","end":"2026-05-25T17:54:29.521081Z","steps":["trace[59693326] 'process raft request' (duration: 100.540893ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:34.351193Z","caller":"traceutil/trace.go:171","msg":"trace[1907333282] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"173.906434ms","start":"2026-05-25T17:54:34.177262Z","end":"2026-05-25T17:54:34.351169Z","steps":["trace[1907333282] 'process raft request' (duration: 173.38691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:35.020186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.771694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:54:35.020962Z","caller":"traceutil/trace.go:171","msg":"trace[171834748] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"616.007533ms","start":"2026-05-25T17:54:34.404679Z","end":"2026-05-25T17:54:35.020687Z","steps":["trace[171834748] 'process raft request' (duration: 44.595582ms)","trace[171834748] 'compare' (duration: 570.494136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:35.021135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:34.404649Z","time spent":"616.412883ms","remote":"127.0.0.1:36232","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:54:35.021452Z","caller":"traceutil/trace.go:171","msg":"trace[567903448] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"193.411814ms","start":"2026-05-25T17:54:34.827961Z","end":"2026-05-25T17:54:35.021372Z","steps":["trace[567903448] 'read index received' (duration: 41.741µs)","trace[567903448] 'applied index is now lower than readState.Index' (duration: 192.656543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:35.021798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.27514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:54:35.021874Z","caller":"traceutil/trace.go:171","msg":"trace[150758165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"109.353931ms","start":"2026-05-25T17:54:34.912487Z","end":"2026-05-25T17:54:35.021841Z","steps":["trace[150758165] 'agreement among raft nodes before linearized reading' (duration: 109.221378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:35.021776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.768992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dxxw4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-25T17:54:35.022026Z","caller":"traceutil/trace.go:171","msg":"trace[1871440387] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dxxw4; range_end:; response_count:1; response_revision:591; }","duration":"194.09635ms","start":"2026-05-25T17:54:34.827905Z","end":"2026-05-25T17:54:35.022002Z","steps":["trace[1871440387] 'agreement among raft nodes before linearized reading' (duration: 193.432103ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:35.388795Z","caller":"traceutil/trace.go:171","msg":"trace[1361525749] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"304.690554ms","start":"2026-05-25T17:54:35.084072Z","end":"2026-05-25T17:54:35.388763Z","steps":["trace[1361525749] 'read index received' (duration: 304.317135ms)","trace[1361525749] 'applied index is now lower than readState.Index' (duration: 372.529µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:54:35.388936Z","caller":"traceutil/trace.go:171","msg":"trace[365188705] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"353.101418ms","start":"2026-05-25T17:54:35.035825Z","end":"2026-05-25T17:54:35.388926Z","steps":["trace[365188705] 'process raft request' (duration: 352.690657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:35.38913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:35.035815Z","time spent":"353.146999ms","remote":"127.0.0.1:36056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:54:35.389369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.743908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T17:54:35.389456Z","caller":"traceutil/trace.go:171","msg":"trace[264799947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"301.858761ms","start":"2026-05-25T17:54:35.087572Z","end":"2026-05-25T17:54:35.389431Z","steps":["trace[264799947] 'agreement among raft nodes before linearized reading' (duration: 301.632655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:35.389507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:35.087561Z","time spent":"301.936993ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-25T17:54:35.389552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.462465ms","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-05-25T17:54:35.389612Z","caller":"traceutil/trace.go:171","msg":"trace[138423931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"305.562277ms","start":"2026-05-25T17:54:35.084035Z","end":"2026-05-25T17:54:35.389598Z","steps":["trace[138423931] 'agreement among raft nodes before linearized reading' (duration: 305.379962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:35.389645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:35.08402Z","time spent":"305.6184ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-25T17:54:36.334777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367917563787582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T17:54:36.834391Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.385783916s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T17:54:36.85746Z","caller":"traceutil/trace.go:171","msg":"trace[1079147323] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.429086225s","start":"2026-05-25T17:54:35.428354Z","end":"2026-05-25T17:54:36.857441Z","steps":["trace[1079147323] 'process raft request' (duration: 1.408078687s)","trace[1079147323] 'compare' (duration: 20.33142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:36.857633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:35.42832Z","time spent":"1.429255459s","remote":"127.0.0.1:35954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:54:37.099992Z","caller":"traceutil/trace.go:171","msg":"trace[1056977924] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"869.035661ms","start":"2026-05-25T17:54:36.230922Z","end":"2026-05-25T17:54:37.099958Z","steps":["trace[1056977924] 'process raft request' (duration: 772.432082ms)","trace[1056977924] 'compare' (duration: 96.070845ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:37.100212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265807427s","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-05-25T17:54:37.100261Z","caller":"traceutil/trace.go:171","msg":"trace[422662220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"1.265868049s","start":"2026-05-25T17:54:35.834381Z","end":"2026-05-25T17:54:37.100249Z","steps":["trace[422662220] 'agreement among raft nodes before linearized reading' (duration: 1.265675754s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:37.100221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:36.230902Z","time spent":"869.245587ms","remote":"127.0.0.1:36144","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-05-25T17:54:37.100294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:35.834324Z","time spent":"1.265961891s","remote":"127.0.0.1:36144","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-05-25T17:54:37.099996Z","caller":"traceutil/trace.go:171","msg":"trace[1428170675] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"1.265580132s","start":"2026-05-25T17:54:35.834388Z","end":"2026-05-25T17:54:37.099968Z","steps":["trace[1428170675] 'read index received' (duration: 1.000389376s)","trace[1428170675] 'applied index is now lower than readState.Index' (duration: 265.188846ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:37.100603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235535297s","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-05-25T17:54:37.100653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183521241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:54:37.100693Z","caller":"traceutil/trace.go:171","msg":"trace[1320145238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"1.183560033s","start":"2026-05-25T17:54:35.917122Z","end":"2026-05-25T17:54:37.100682Z","steps":["trace[1320145238] 'agreement among raft nodes before linearized reading' (duration: 1.183504711s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:37.100735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:35.917057Z","time spent":"1.183671756s","remote":"127.0.0.1:35888","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-05-25T17:54:37.100796Z","caller":"traceutil/trace.go:171","msg":"trace[231024262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"1.235653561s","start":"2026-05-25T17:54:35.865016Z","end":"2026-05-25T17:54:37.10067Z","steps":["trace[231024262] 'agreement among raft nodes before linearized reading' (duration: 1.235506676s)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:37.100777Z","caller":"traceutil/trace.go:171","msg":"trace[1274750736] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"169.752676ms","start":"2026-05-25T17:54:36.930177Z","end":"2026-05-25T17:54:37.09993Z","steps":["trace[1274750736] 'process raft request' (duration: 169.482059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:37.100898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:35.864996Z","time spent":"1.235859146s","remote":"127.0.0.1:36144","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-05-25T17:54:37.629649Z","caller":"traceutil/trace.go:171","msg":"trace[539273027] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"175.305441ms","start":"2026-05-25T17:54:37.454316Z","end":"2026-05-25T17:54:37.629622Z","steps":["trace[539273027] 'process raft request' (duration: 152.215019ms)","trace[539273027] 'compare' (duration: 22.950669ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:54:38.210484Z","caller":"traceutil/trace.go:171","msg":"trace[167694534] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"370.000089ms","start":"2026-05-25T17:54:37.840429Z","end":"2026-05-25T17:54:38.210429Z","steps":["trace[167694534] 'process raft request' (duration: 289.820177ms)","trace[167694534] 'compare' (duration: 79.948555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:38.210668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:37.840409Z","time spent":"370.184573ms","remote":"127.0.0.1:35936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:54:38.369021Z","caller":"traceutil/trace.go:171","msg":"trace[1723000462] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"504.29686ms","start":"2026-05-25T17:54:37.864694Z","end":"2026-05-25T17:54:38.368991Z","steps":["trace[1723000462] 'process raft request' (duration: 498.997182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:38.369188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:37.864646Z","time spent":"504.455844ms","remote":"127.0.0.1:36056","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:54:40.357175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.133243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:54:40.357282Z","caller":"traceutil/trace.go:171","msg":"trace[1965307687] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"293.954616ms","start":"2026-05-25T17:54:40.063312Z","end":"2026-05-25T17:54:40.357267Z","steps":["trace[1965307687] 'process raft request' (duration: 168.663758ms)","trace[1965307687] 'compare' (duration: 124.97634ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:54:40.360571Z","caller":"traceutil/trace.go:171","msg":"trace[1910640900] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"251.877949ms","start":"2026-05-25T17:54:40.108672Z","end":"2026-05-25T17:54:40.36055Z","steps":["trace[1910640900] 'process raft request' (duration: 251.774376ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:40.408508Z","caller":"traceutil/trace.go:171","msg":"trace[1768577114] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"100.241034ms","start":"2026-05-25T17:54:40.308233Z","end":"2026-05-25T17:54:40.408474Z","steps":["trace[1768577114] 'process raft request' (duration: 100.07943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:40.607684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.047903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:54:40.60779Z","caller":"traceutil/trace.go:171","msg":"trace[195930876] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"159.041947ms","start":"2026-05-25T17:54:40.448736Z","end":"2026-05-25T17:54:40.607778Z","steps":["trace[195930876] 'compare' (duration: 149.892039ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:40.610244Z","caller":"traceutil/trace.go:171","msg":"trace[1232288738] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"104.845225ms","start":"2026-05-25T17:54:40.505391Z","end":"2026-05-25T17:54:40.610236Z","steps":["trace[1232288738] 'read index received' (duration: 104.778872ms)","trace[1232288738] 'applied index is now lower than readState.Index' (duration: 65.783µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:40.61035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.963147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:54:40.61037Z","caller":"traceutil/trace.go:171","msg":"trace[1602819662] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:656; }","duration":"104.984598ms","start":"2026-05-25T17:54:40.505378Z","end":"2026-05-25T17:54:40.610363Z","steps":["trace[1602819662] 'agreement among raft nodes before linearized reading' (duration: 104.889115ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:40.610361Z","caller":"traceutil/trace.go:171","msg":"trace[57108025] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"156.519332ms","start":"2026-05-25T17:54:40.453816Z","end":"2026-05-25T17:54:40.610335Z","steps":["trace[57108025] 'process raft request' (duration: 156.327006ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:43.74238Z","caller":"traceutil/trace.go:171","msg":"trace[1943281631] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"432.408135ms","start":"2026-05-25T17:54:43.309946Z","end":"2026-05-25T17:54:43.742354Z","steps":["trace[1943281631] 'process raft request' (duration: 432.239021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:43.742567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:43.309927Z","time spent":"432.546699ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:54:43.742896Z","caller":"traceutil/trace.go:171","msg":"trace[250636831] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:712; }","duration":"293.336579ms","start":"2026-05-25T17:54:43.449535Z","end":"2026-05-25T17:54:43.742872Z","steps":["trace[250636831] 'read index received' (duration: 293.329409ms)","trace[250636831] 'applied index is now lower than readState.Index' (duration: 5.67µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:54:43.743119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.581506ms","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-05-25T17:54:43.743158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.108812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-25T17:54:43.743174Z","caller":"traceutil/trace.go:171","msg":"trace[225283377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"293.667738ms","start":"2026-05-25T17:54:43.449493Z","end":"2026-05-25T17:54:43.743161Z","steps":["trace[225283377] 'agreement among raft nodes before linearized reading' (duration: 293.479422ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:43.743189Z","caller":"traceutil/trace.go:171","msg":"trace[1952597086] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:687; }","duration":"237.187455ms","start":"2026-05-25T17:54:43.505991Z","end":"2026-05-25T17:54:43.743179Z","steps":["trace[1952597086] 'agreement among raft nodes before linearized reading' (duration: 237.00643ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:49.019101Z","caller":"traceutil/trace.go:171","msg":"trace[1004356159] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"137.388933ms","start":"2026-05-25T17:54:48.881686Z","end":"2026-05-25T17:54:49.019075Z","steps":["trace[1004356159] 'process raft request' (duration: 137.177447ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:54.588579Z","caller":"traceutil/trace.go:171","msg":"trace[1159045027] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"483.718844ms","start":"2026-05-25T17:54:54.104831Z","end":"2026-05-25T17:54:54.58855Z","steps":["trace[1159045027] 'process raft request' (duration: 483.529949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:54:54.588855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:54:54.104814Z","time spent":"483.94895ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:54:55.145516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.756029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:54:55.145699Z","caller":"traceutil/trace.go:171","msg":"trace[926402250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"227.975605ms","start":"2026-05-25T17:54:54.917704Z","end":"2026-05-25T17:54:55.14568Z","steps":["trace[926402250] 'range keys from in-memory index tree' (duration: 227.664587ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:54:55.159677Z","caller":"traceutil/trace.go:171","msg":"trace[1191428985] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"175.355214ms","start":"2026-05-25T17:54:54.984295Z","end":"2026-05-25T17:54:55.159651Z","steps":["trace[1191428985] 'process raft request' (duration: 175.195519ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:55:09.5053Z","caller":"traceutil/trace.go:171","msg":"trace[229389238] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"140.068154ms","start":"2026-05-25T17:55:09.365184Z","end":"2026-05-25T17:55:09.505253Z","steps":["trace[229389238] 'process raft request' (duration: 109.703102ms)","trace[229389238] 'compare' (duration: 30.113715ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:10.189364Z","caller":"traceutil/trace.go:171","msg":"trace[108066523] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"145.027022ms","start":"2026-05-25T17:55:10.044311Z","end":"2026-05-25T17:55:10.189338Z","steps":["trace[108066523] 'read index received' (duration: 144.790505ms)","trace[108066523] 'applied index is now lower than readState.Index' (duration: 235.387µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:10.189437Z","caller":"traceutil/trace.go:171","msg":"trace[1353485976] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"147.520107ms","start":"2026-05-25T17:55:10.041859Z","end":"2026-05-25T17:55:10.189379Z","steps":["trace[1353485976] 'process raft request' (duration: 147.29001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:10.189738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.379471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T17:55:10.189901Z","caller":"traceutil/trace.go:171","msg":"trace[987331381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"145.577836ms","start":"2026-05-25T17:55:10.044287Z","end":"2026-05-25T17:55:10.189865Z","steps":["trace[987331381] 'agreement among raft nodes before linearized reading' (duration: 145.186456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:10.476582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.757421ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:55:10.476794Z","caller":"traceutil/trace.go:171","msg":"trace[1469060778] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"281.963972ms","start":"2026-05-25T17:55:10.194813Z","end":"2026-05-25T17:55:10.476777Z","steps":["trace[1469060778] 'process raft request' (duration: 281.920951ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:55:10.476874Z","caller":"traceutil/trace.go:171","msg":"trace[1850845252] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"287.393843ms","start":"2026-05-25T17:55:10.189442Z","end":"2026-05-25T17:55:10.476836Z","steps":["trace[1850845252] 'read index received' (duration: 39.412937ms)","trace[1850845252] 'applied index is now lower than readState.Index' (duration: 247.977826ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:10.47709Z","caller":"traceutil/trace.go:171","msg":"trace[743089243] transaction","detail":"{read_only:false; number_of_response:1; response_revision:913; }","duration":"284.304983ms","start":"2026-05-25T17:55:10.192775Z","end":"2026-05-25T17:55:10.47708Z","steps":["trace[743089243] 'process raft request' (duration: 283.896822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:10.477125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.128869ms","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-05-25T17:55:10.477175Z","caller":"traceutil/trace.go:171","msg":"trace[647910475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"432.190161ms","start":"2026-05-25T17:55:10.044968Z","end":"2026-05-25T17:55:10.477158Z","steps":["trace[647910475] 'agreement among raft nodes before linearized reading' (duration: 431.956584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:10.477217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:10.044962Z","time spent":"432.243502ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-25T17:55:10.477448Z","caller":"traceutil/trace.go:171","msg":"trace[648656332] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"432.444677ms","start":"2026-05-25T17:55:10.044909Z","end":"2026-05-25T17:55:10.477354Z","steps":["trace[648656332] 'process raft request' (duration: 183.757222ms)","trace[648656332] 'compare' (duration: 247.515134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:55:10.477782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:10.044898Z","time spent":"432.697513ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:55:18.688352Z","caller":"traceutil/trace.go:171","msg":"trace[1842529385] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"158.80073ms","start":"2026-05-25T17:55:18.529519Z","end":"2026-05-25T17:55:18.688319Z","steps":["trace[1842529385] 'read index received' (duration: 158.645287ms)","trace[1842529385] 'applied index is now lower than readState.Index' (duration: 153.973µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:18.688431Z","caller":"traceutil/trace.go:171","msg":"trace[640146718] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"172.9442ms","start":"2026-05-25T17:55:18.515463Z","end":"2026-05-25T17:55:18.688407Z","steps":["trace[640146718] 'process raft request' (duration: 172.623991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:18.688645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.117719ms","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-05-25T17:55:18.688656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.249088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T17:55:18.688681Z","caller":"traceutil/trace.go:171","msg":"trace[2145388472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"159.199121ms","start":"2026-05-25T17:55:18.529471Z","end":"2026-05-25T17:55:18.688671Z","steps":["trace[2145388472] 'agreement among raft nodes before linearized reading' (duration: 159.039037ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:55:18.688692Z","caller":"traceutil/trace.go:171","msg":"trace[831245829] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:940; }","duration":"155.291299ms","start":"2026-05-25T17:55:18.533388Z","end":"2026-05-25T17:55:18.68868Z","steps":["trace[831245829] 'agreement among raft nodes before linearized reading' (duration: 155.209287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:18.688832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.313013ms","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-05-25T17:55:18.688859Z","caller":"traceutil/trace.go:171","msg":"trace[717785231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:940; }","duration":"126.370925ms","start":"2026-05-25T17:55:18.56248Z","end":"2026-05-25T17:55:18.688851Z","steps":["trace[717785231] 'agreement among raft nodes before linearized reading' (duration: 126.280172ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:55:25.702911Z","caller":"traceutil/trace.go:171","msg":"trace[2084904692] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"106.407205ms","start":"2026-05-25T17:55:25.596479Z","end":"2026-05-25T17:55:25.702886Z","steps":["trace[2084904692] 'process raft request' (duration: 97.331358ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:55:31.80678Z","caller":"traceutil/trace.go:171","msg":"trace[1929636529] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"206.365732ms","start":"2026-05-25T17:55:31.600395Z","end":"2026-05-25T17:55:31.806761Z","steps":["trace[1929636529] 'read index received' (duration: 206.230838ms)","trace[1929636529] 'applied index is now lower than readState.Index' (duration: 134.174µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:31.806877Z","caller":"traceutil/trace.go:171","msg":"trace[1999286751] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"400.800183ms","start":"2026-05-25T17:55:31.406069Z","end":"2026-05-25T17:55:31.80687Z","steps":["trace[1999286751] 'process raft request' (duration: 400.532865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:31.806963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:31.406046Z","time spent":"400.852253ms","remote":"127.0.0.1:36144","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-05-25T17:55:31.807128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.733901ms","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-05-25T17:55:31.807151Z","caller":"traceutil/trace.go:171","msg":"trace[388448501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"206.798902ms","start":"2026-05-25T17:55:31.600346Z","end":"2026-05-25T17:55:31.807144Z","steps":["trace[388448501] 'agreement among raft nodes before linearized reading' (duration: 206.71173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:32.338483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.643808ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:55:32.33865Z","caller":"traceutil/trace.go:171","msg":"trace[1586834991] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"528.296745ms","start":"2026-05-25T17:55:31.810337Z","end":"2026-05-25T17:55:32.338633Z","steps":["trace[1586834991] 'process raft request' (duration: 528.236344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:32.338725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:31.81033Z","time spent":"528.360456ms","remote":"127.0.0.1:36144","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-05-25T17:55:32.338873Z","caller":"traceutil/trace.go:171","msg":"trace[1458484985] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"528.785849ms","start":"2026-05-25T17:55:31.810052Z","end":"2026-05-25T17:55:32.338838Z","steps":["trace[1458484985] 'process raft request' (duration: 316.704959ms)","trace[1458484985] 'compare' (duration: 211.471894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:55:32.339094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:31.810036Z","time spent":"528.966284ms","remote":"127.0.0.1:36144","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-05-25T17:55:56.19063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367917563788610,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-25T17:55:56.231081Z","caller":"traceutil/trace.go:171","msg":"trace[158917075] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"541.131382ms","start":"2026-05-25T17:55:55.689911Z","end":"2026-05-25T17:55:56.231042Z","steps":["trace[158917075] 'read index received' (duration: 540.853494ms)","trace[158917075] 'applied index is now lower than readState.Index' (duration: 276.708µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:56.231174Z","caller":"traceutil/trace.go:171","msg":"trace[1669793107] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"681.321973ms","start":"2026-05-25T17:55:55.5498Z","end":"2026-05-25T17:55:56.231122Z","steps":["trace[1669793107] 'process raft request' (duration: 681.043945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:56.23133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.389059ms","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":"warn","ts":"2026-05-25T17:55:56.231406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.302984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T17:55:56.231391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:55.549776Z","time spent":"681.508778ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:55:56.23155Z","caller":"traceutil/trace.go:171","msg":"trace[1911049689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1065; }","duration":"320.513709ms","start":"2026-05-25T17:55:55.91102Z","end":"2026-05-25T17:55:56.231534Z","steps":["trace[1911049689] 'agreement among raft nodes before linearized reading' (duration: 320.204432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:56.231428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.634383ms","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":"warn","ts":"2026-05-25T17:55:56.23161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:55.910999Z","time spent":"320.599871ms","remote":"127.0.0.1:35888","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-05-25T17:55:56.231431Z","caller":"traceutil/trace.go:171","msg":"trace[1415267391] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1065; }","duration":"541.545952ms","start":"2026-05-25T17:55:55.689867Z","end":"2026-05-25T17:55:56.231413Z","steps":["trace[1415267391] 'agreement among raft nodes before linearized reading' (duration: 541.348557ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:55:56.231695Z","caller":"traceutil/trace.go:171","msg":"trace[1037714666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"310.926261ms","start":"2026-05-25T17:55:55.920701Z","end":"2026-05-25T17:55:56.231627Z","steps":["trace[1037714666] 'agreement among raft nodes before linearized reading' (duration: 310.576772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:56.231727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:55.689848Z","time spent":"541.853331ms","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-05-25T17:55:56.231763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:55.920669Z","time spent":"311.081035ms","remote":"127.0.0.1:36144","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":"info","ts":"2026-05-25T17:55:56.712911Z","caller":"traceutil/trace.go:171","msg":"trace[66032955] linearizableReadLoop","detail":"{readStateIndex:1109; appliedIndex:1108; }","duration":"256.373334ms","start":"2026-05-25T17:55:56.456516Z","end":"2026-05-25T17:55:56.71289Z","steps":["trace[66032955] 'read index received' (duration: 236.309053ms)","trace[66032955] 'applied index is now lower than readState.Index' (duration: 20.063321ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:55:56.713028Z","caller":"traceutil/trace.go:171","msg":"trace[331111822] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"476.350943ms","start":"2026-05-25T17:55:56.236623Z","end":"2026-05-25T17:55:56.712974Z","steps":["trace[331111822] 'process raft request' (duration: 456.21709ms)","trace[331111822] 'compare' (duration: 19.713812ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:55:56.713105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.573759ms","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-05-25T17:55:56.713132Z","caller":"traceutil/trace.go:171","msg":"trace[1663559756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1066; }","duration":"256.639831ms","start":"2026-05-25T17:55:56.456485Z","end":"2026-05-25T17:55:56.713125Z","steps":["trace[1663559756] 'agreement among raft nodes before linearized reading' (duration: 256.516608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:56.713209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:55:56.236606Z","time spent":"476.513457ms","remote":"127.0.0.1:36144","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-05-25T17:55:56.713722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.008275ms","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-05-25T17:55:56.713786Z","caller":"traceutil/trace.go:171","msg":"trace[519024697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"256.076906ms","start":"2026-05-25T17:55:56.457686Z","end":"2026-05-25T17:55:56.713762Z","steps":["trace[519024697] 'agreement among raft nodes before linearized reading' (duration: 255.354628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:55:59.262919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.192182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-25T17:55:59.262964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.691778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-25T17:55:59.263023Z","caller":"traceutil/trace.go:171","msg":"trace[522277689] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1076; }","duration":"215.77276ms","start":"2026-05-25T17:55:59.047238Z","end":"2026-05-25T17:55:59.26301Z","steps":["trace[522277689] 'range keys from in-memory index tree' (duration: 215.583255ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:55:59.263002Z","caller":"traceutil/trace.go:171","msg":"trace[288023808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1076; }","duration":"228.327816ms","start":"2026-05-25T17:55:59.034654Z","end":"2026-05-25T17:55:59.262982Z","steps":["trace[288023808] 'range keys from in-memory index tree' (duration: 227.969257ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:06.972919Z","caller":"traceutil/trace.go:171","msg":"trace[97044703] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"130.191809ms","start":"2026-05-25T17:56:06.842688Z","end":"2026-05-25T17:56:06.97288Z","steps":["trace[97044703] 'process raft request' (duration: 111.253768ms)","trace[97044703] 'compare' (duration: 18.663014ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:56:16.950132Z","caller":"traceutil/trace.go:171","msg":"trace[1265108538] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"174.395976ms","start":"2026-05-25T17:56:16.775708Z","end":"2026-05-25T17:56:16.950104Z","steps":["trace[1265108538] 'process raft request' (duration: 174.14966ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:29.668141Z","caller":"traceutil/trace.go:171","msg":"trace[878267293] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"169.615951ms","start":"2026-05-25T17:56:29.498502Z","end":"2026-05-25T17:56:29.668117Z","steps":["trace[878267293] 'read index received' (duration: 169.484578ms)","trace[878267293] 'applied index is now lower than readState.Index' (duration: 130.473µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:56:29.668479Z","caller":"traceutil/trace.go:171","msg":"trace[1399192117] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"170.190527ms","start":"2026-05-25T17:56:29.498277Z","end":"2026-05-25T17:56:29.668467Z","steps":["trace[1399192117] 'process raft request' (duration: 169.693864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:29.668772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.251428ms","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-05-25T17:56:29.668812Z","caller":"traceutil/trace.go:171","msg":"trace[2666204] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"170.32027ms","start":"2026-05-25T17:56:29.498482Z","end":"2026-05-25T17:56:29.668802Z","steps":["trace[2666204] 'agreement among raft nodes before linearized reading' (duration: 170.189707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:29.668911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.044602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T17:56:29.668965Z","caller":"traceutil/trace.go:171","msg":"trace[1292692014] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1228; }","duration":"131.146715ms","start":"2026-05-25T17:56:29.537806Z","end":"2026-05-25T17:56:29.668953Z","steps":["trace[1292692014] 'agreement among raft nodes before linearized reading' (duration: 131.042342ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:32.652136Z","caller":"traceutil/trace.go:171","msg":"trace[1508921762] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"298.319823ms","start":"2026-05-25T17:56:32.353794Z","end":"2026-05-25T17:56:32.652114Z","steps":["trace[1508921762] 'process raft request' (duration: 298.108517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:33.769997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.767587ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-25T17:56:33.770097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.35632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:56:33.770137Z","caller":"traceutil/trace.go:171","msg":"trace[1409677277] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1252; }","duration":"836.920942ms","start":"2026-05-25T17:56:32.933195Z","end":"2026-05-25T17:56:33.770116Z","steps":["trace[1409677277] 'range keys from in-memory index tree' (duration: 836.749756ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:33.770216Z","caller":"traceutil/trace.go:171","msg":"trace[26444851] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"1.112506743s","start":"2026-05-25T17:56:32.65769Z","end":"2026-05-25T17:56:33.770197Z","steps":["trace[26444851] 'process raft request' (duration: 540.974329ms)","trace[26444851] 'compare' (duration: 571.257916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:56:33.77029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:32.65767Z","time spent":"1.112584496s","remote":"127.0.0.1:36034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:56:34.520339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.321466687s","expected-duration":"1s"} {"level":"info","ts":"2026-05-25T17:56:34.520753Z","caller":"traceutil/trace.go:171","msg":"trace[1767019074] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"1.862642244s","start":"2026-05-25T17:56:32.658093Z","end":"2026-05-25T17:56:34.520735Z","steps":["trace[1767019074] 'process raft request' (duration: 1.862385056s)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:34.520755Z","caller":"traceutil/trace.go:171","msg":"trace[447163391] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"1.855764505s","start":"2026-05-25T17:56:32.664972Z","end":"2026-05-25T17:56:34.520736Z","steps":["trace[447163391] 'process raft request' (duration: 1.855664562s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.520838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:32.658084Z","time spent":"1.862696175s","remote":"127.0.0.1:36152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:56:34.520841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:32.664956Z","time spent":"1.855839216s","remote":"127.0.0.1:36374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:56:34.520932Z","caller":"traceutil/trace.go:171","msg":"trace[736263446] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1304; }","duration":"1.610638961s","start":"2026-05-25T17:56:32.910286Z","end":"2026-05-25T17:56:34.520925Z","steps":["trace[736263446] 'read index received' (duration: 288.501697ms)","trace[736263446] 'applied index is now lower than readState.Index' (duration: 1.322136524s)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:56:34.521077Z","caller":"traceutil/trace.go:171","msg":"trace[24239175] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"1.438746631s","start":"2026-05-25T17:56:33.082324Z","end":"2026-05-25T17:56:34.521071Z","steps":["trace[24239175] 'process raft request' (duration: 1.438356111s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.521109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.082303Z","time spent":"1.438789771s","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:56:34.52119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517034112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:56:34.521268Z","caller":"traceutil/trace.go:171","msg":"trace[1288410691] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1256; }","duration":"1.517167146s","start":"2026-05-25T17:56:33.004083Z","end":"2026-05-25T17:56:34.52125Z","steps":["trace[1288410691] 'agreement among raft nodes before linearized reading' (duration: 1.517031342s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.521294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.434443489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-25T17:56:34.521302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111313393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T17:56:34.521318Z","caller":"traceutil/trace.go:171","msg":"trace[988158084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1256; }","duration":"1.43448223s","start":"2026-05-25T17:56:33.086831Z","end":"2026-05-25T17:56:34.521313Z","steps":["trace[988158084] 'agreement among raft nodes before linearized reading' (duration: 1.434407888s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.521334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.086823Z","time spent":"1.434507961s","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-25T17:56:34.521339Z","caller":"traceutil/trace.go:171","msg":"trace[607832193] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1256; }","duration":"1.111381435s","start":"2026-05-25T17:56:33.409945Z","end":"2026-05-25T17:56:34.521327Z","steps":["trace[607832193] 'agreement among raft nodes before linearized reading' (duration: 1.111329324s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.521326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.00405Z","time spent":"1.51726671s","remote":"127.0.0.1:36090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-25T17:56:34.521371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.409932Z","time spent":"1.111429477s","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-25T17:56:34.521411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"842.501147ms","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-05-25T17:56:34.521498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439170172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-25T17:56:34.521532Z","caller":"traceutil/trace.go:171","msg":"trace[653261318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"1.439243404s","start":"2026-05-25T17:56:33.082281Z","end":"2026-05-25T17:56:34.521524Z","steps":["trace[653261318] 'agreement among raft nodes before linearized reading' (duration: 1.43907008s)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.521569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.082224Z","time spent":"1.439338596s","remote":"127.0.0.1:36144","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-05-25T17:56:34.521581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439225753s","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-05-25T17:56:34.521492Z","caller":"traceutil/trace.go:171","msg":"trace[677948131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"842.62088ms","start":"2026-05-25T17:56:33.678853Z","end":"2026-05-25T17:56:34.521474Z","steps":["trace[677948131] 'agreement among raft nodes before linearized reading' (duration: 842.349643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.521616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.67884Z","time spent":"842.770963ms","remote":"127.0.0.1:36144","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-05-25T17:56:34.521626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.396346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-25T17:56:34.521508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.611223326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:56:34.521667Z","caller":"traceutil/trace.go:171","msg":"trace[1074870999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"1.611418532s","start":"2026-05-25T17:56:32.910243Z","end":"2026-05-25T17:56:34.521661Z","steps":["trace[1074870999] 'agreement among raft nodes before linearized reading' (duration: 1.611251918s)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:34.521659Z","caller":"traceutil/trace.go:171","msg":"trace[1209025467] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:1256; }","duration":"557.462988ms","start":"2026-05-25T17:56:33.964186Z","end":"2026-05-25T17:56:34.521649Z","steps":["trace[1209025467] 'agreement among raft nodes before linearized reading' (duration: 557.249643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.521679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:32.910222Z","time spent":"1.611454734s","remote":"127.0.0.1:35888","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-05-25T17:56:34.52169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.964137Z","time spent":"557.54603ms","remote":"127.0.0.1:35904","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.109\" "} {"level":"warn","ts":"2026-05-25T17:56:34.521702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.334344ms","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-05-25T17:56:34.521613Z","caller":"traceutil/trace.go:171","msg":"trace[1833204154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1256; }","duration":"1.439282865s","start":"2026-05-25T17:56:33.082321Z","end":"2026-05-25T17:56:34.521604Z","steps":["trace[1833204154] 'agreement among raft nodes before linearized reading' (duration: 1.438933466s)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:34.521731Z","caller":"traceutil/trace.go:171","msg":"trace[1670479580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"839.389586ms","start":"2026-05-25T17:56:33.682333Z","end":"2026-05-25T17:56:34.521723Z","steps":["trace[1670479580] 'agreement among raft nodes before linearized reading' (duration: 839.308574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:34.52178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.082302Z","time spent":"1.439473329s","remote":"127.0.0.1:36144","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-05-25T17:56:34.521795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:33.682314Z","time spent":"839.472427ms","remote":"127.0.0.1:36144","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-05-25T17:56:34.677076Z","caller":"traceutil/trace.go:171","msg":"trace[694838076] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"151.112052ms","start":"2026-05-25T17:56:34.525936Z","end":"2026-05-25T17:56:34.677048Z","steps":["trace[694838076] 'process raft request' (duration: 151.02046ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:34.677257Z","caller":"traceutil/trace.go:171","msg":"trace[2067745147] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"151.308057ms","start":"2026-05-25T17:56:34.525936Z","end":"2026-05-25T17:56:34.677244Z","steps":["trace[2067745147] 'process raft request' (duration: 151.075111ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:34.677291Z","caller":"traceutil/trace.go:171","msg":"trace[1107244909] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"151.315217ms","start":"2026-05-25T17:56:34.525936Z","end":"2026-05-25T17:56:34.677252Z","steps":["trace[1107244909] 'process raft request' (duration: 150.843756ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:34.677437Z","caller":"traceutil/trace.go:171","msg":"trace[305708708] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"150.778484ms","start":"2026-05-25T17:56:34.526651Z","end":"2026-05-25T17:56:34.677429Z","steps":["trace[305708708] 'process raft request' (duration: 150.557737ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:34.677567Z","caller":"traceutil/trace.go:171","msg":"trace[1953093559] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1309; }","duration":"145.737593ms","start":"2026-05-25T17:56:34.531812Z","end":"2026-05-25T17:56:34.677549Z","steps":["trace[1953093559] 'read index received' (duration: 55.257934ms)","trace[1953093559] 'applied index is now lower than readState.Index' (duration: 90.479039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:56:34.677665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.839615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:56:34.677688Z","caller":"traceutil/trace.go:171","msg":"trace[2023426688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"145.912327ms","start":"2026-05-25T17:56:34.531769Z","end":"2026-05-25T17:56:34.677681Z","steps":["trace[2023426688] 'agreement among raft nodes before linearized reading' (duration: 145.817915ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:34.677734Z","caller":"traceutil/trace.go:171","msg":"trace[2124013994] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"150.523837ms","start":"2026-05-25T17:56:34.527194Z","end":"2026-05-25T17:56:34.677718Z","steps":["trace[2124013994] 'process raft request' (duration: 150.214129ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:36.910108Z","caller":"traceutil/trace.go:171","msg":"trace[809333027] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"265.750917ms","start":"2026-05-25T17:56:36.644334Z","end":"2026-05-25T17:56:36.910085Z","steps":["trace[809333027] 'read index received' (duration: 265.590863ms)","trace[809333027] 'applied index is now lower than readState.Index' (duration: 159.144µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:56:36.910238Z","caller":"traceutil/trace.go:171","msg":"trace[689140010] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"380.905105ms","start":"2026-05-25T17:56:36.529322Z","end":"2026-05-25T17:56:36.910227Z","steps":["trace[689140010] 'process raft request' (duration: 380.626968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:36.910356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:36.529296Z","time spent":"380.969467ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:56:36.910459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.417599ms","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-05-25T17:56:36.910462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.138166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-25T17:56:36.91051Z","caller":"traceutil/trace.go:171","msg":"trace[800574382] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"229.24836ms","start":"2026-05-25T17:56:36.681252Z","end":"2026-05-25T17:56:36.9105Z","steps":["trace[800574382] 'agreement among raft nodes before linearized reading' (duration: 229.091166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:36.910526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.253484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-25T17:56:36.910538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.202279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T17:56:36.910545Z","caller":"traceutil/trace.go:171","msg":"trace[1965850442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1264; }","duration":"228.307186ms","start":"2026-05-25T17:56:36.682232Z","end":"2026-05-25T17:56:36.910539Z","steps":["trace[1965850442] 'agreement among raft nodes before linearized reading' (duration: 228.263775ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:36.910566Z","caller":"traceutil/trace.go:171","msg":"trace[89759901] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1264; }","duration":"266.261059ms","start":"2026-05-25T17:56:36.644297Z","end":"2026-05-25T17:56:36.910558Z","steps":["trace[89759901] 'agreement among raft nodes before linearized reading' (duration: 266.194138ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:36.91051Z","caller":"traceutil/trace.go:171","msg":"trace[843950065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"228.49103ms","start":"2026-05-25T17:56:36.682005Z","end":"2026-05-25T17:56:36.910496Z","steps":["trace[843950065] 'agreement among raft nodes before linearized reading' (duration: 228.381527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:36.91046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.429689ms","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-05-25T17:56:36.910635Z","caller":"traceutil/trace.go:171","msg":"trace[712175953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1264; }","duration":"228.630124ms","start":"2026-05-25T17:56:36.681996Z","end":"2026-05-25T17:56:36.910626Z","steps":["trace[712175953] 'agreement among raft nodes before linearized reading' (duration: 228.406648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:36.910467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.360567ms","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-05-25T17:56:36.910671Z","caller":"traceutil/trace.go:171","msg":"trace[1645252807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"228.594334ms","start":"2026-05-25T17:56:36.682073Z","end":"2026-05-25T17:56:36.910667Z","steps":["trace[1645252807] 'agreement among raft nodes before linearized reading' (duration: 228.286115ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:37.472595Z","caller":"traceutil/trace.go:171","msg":"trace[831068922] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"556.739428ms","start":"2026-05-25T17:56:36.915832Z","end":"2026-05-25T17:56:37.472572Z","steps":["trace[831068922] 'process raft request' (duration: 556.588295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:37.472739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:36.915809Z","time spent":"556.856352ms","remote":"127.0.0.1:36144","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-05-25T17:56:37.517755Z","caller":"traceutil/trace.go:171","msg":"trace[1539885999] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1318; }","duration":"534.969454ms","start":"2026-05-25T17:56:36.982764Z","end":"2026-05-25T17:56:37.517733Z","steps":["trace[1539885999] 'read index received' (duration: 490.369396ms)","trace[1539885999] 'applied index is now lower than readState.Index' (duration: 44.599178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:56:37.517909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.137618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:56:37.517944Z","caller":"traceutil/trace.go:171","msg":"trace[1024607645] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1270; }","duration":"535.246002ms","start":"2026-05-25T17:56:36.98269Z","end":"2026-05-25T17:56:37.517936Z","steps":["trace[1024607645] 'agreement among raft nodes before linearized reading' (duration: 535.129909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:37.517981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:36.982674Z","time spent":"535.298293ms","remote":"127.0.0.1:36118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-05-25T17:56:37.51801Z","caller":"traceutil/trace.go:171","msg":"trace[804542297] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"601.915742ms","start":"2026-05-25T17:56:36.916083Z","end":"2026-05-25T17:56:37.517999Z","steps":["trace[804542297] 'process raft request' (duration: 601.550493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:37.518083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:36.916065Z","time spent":"601.977954ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:56:37.518185Z","caller":"traceutil/trace.go:171","msg":"trace[418039749] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"602.220219ms","start":"2026-05-25T17:56:36.915957Z","end":"2026-05-25T17:56:37.518177Z","steps":["trace[418039749] 'process raft request' (duration: 584.835308ms)","trace[418039749] 'compare' (duration: 16.652872ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:56:37.518225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:36.915943Z","time spent":"602.26229ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-25T17:56:37.518218Z","caller":"traceutil/trace.go:171","msg":"trace[442629484] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"601.357557ms","start":"2026-05-25T17:56:36.916846Z","end":"2026-05-25T17:56:37.518203Z","steps":["trace[442629484] 'process raft request' (duration: 600.819193ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:56:37.518313Z","caller":"traceutil/trace.go:171","msg":"trace[1313183237] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"602.270091ms","start":"2026-05-25T17:56:36.916037Z","end":"2026-05-25T17:56:37.518307Z","steps":["trace[1313183237] 'process raft request' (duration: 601.546442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:37.518306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:36.916839Z","time spent":"601.418829ms","remote":"127.0.0.1:36144","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-05-25T17:56:37.518348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:36.916029Z","time spent":"602.301512ms","remote":"127.0.0.1:36144","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-05-25T17:56:37.518406Z","caller":"traceutil/trace.go:171","msg":"trace[310000824] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"139.700386ms","start":"2026-05-25T17:56:37.378699Z","end":"2026-05-25T17:56:37.518399Z","steps":["trace[310000824] 'process raft request' (duration: 138.996098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:38.412807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367917563789074,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-25T17:56:38.696329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071670123s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:56:38.696434Z","caller":"traceutil/trace.go:171","msg":"trace[52568661] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"1.115433739s","start":"2026-05-25T17:56:37.58098Z","end":"2026-05-25T17:56:38.696414Z","steps":["trace[52568661] 'process raft request' (duration: 43.615972ms)","trace[52568661] 'compare' (duration: 1.071416287s)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:56:38.696489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:37.580962Z","time spent":"1.11550566s","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:56:38.7152Z","caller":"traceutil/trace.go:171","msg":"trace[2069544173] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"802.97668ms","start":"2026-05-25T17:56:37.912203Z","end":"2026-05-25T17:56:38.71518Z","steps":["trace[2069544173] 'read index received' (duration: 802.850427ms)","trace[2069544173] 'applied index is now lower than readState.Index' (duration: 125.433µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:56:38.715472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.114943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:56:38.715518Z","caller":"traceutil/trace.go:171","msg":"trace[1414915858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"803.36959ms","start":"2026-05-25T17:56:37.912137Z","end":"2026-05-25T17:56:38.715506Z","steps":["trace[1414915858] 'agreement among raft nodes before linearized reading' (duration: 803.129403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:38.715552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:37.912117Z","time spent":"803.424382ms","remote":"127.0.0.1:35888","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-05-25T17:56:38.715227Z","caller":"traceutil/trace.go:171","msg":"trace[1916292305] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"946.240557ms","start":"2026-05-25T17:56:37.768969Z","end":"2026-05-25T17:56:38.71521Z","steps":["trace[1916292305] 'process raft request' (duration: 946.111414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:38.716194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:37.76895Z","time spent":"947.174721ms","remote":"127.0.0.1:35936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T17:56:39.237595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.343589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-25T17:56:39.23771Z","caller":"traceutil/trace.go:171","msg":"trace[995382759] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"522.437617ms","start":"2026-05-25T17:56:38.715257Z","end":"2026-05-25T17:56:39.237694Z","steps":["trace[995382759] 'read index received' (duration: 299.932445ms)","trace[995382759] 'applied index is now lower than readState.Index' (duration: 222.503222ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:56:39.237781Z","caller":"traceutil/trace.go:171","msg":"trace[1457359862] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"535.835516ms","start":"2026-05-25T17:56:38.701934Z","end":"2026-05-25T17:56:39.23777Z","steps":["trace[1457359862] 'process raft request' (duration: 313.229011ms)","trace[1457359862] 'compare' (duration: 222.179174ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:56:39.237846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:38.701902Z","time spent":"535.911488ms","remote":"127.0.0.1:35936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-25T17:56:39.23822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.892845ms","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-05-25T17:56:39.238275Z","caller":"traceutil/trace.go:171","msg":"trace[1407356534] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1274; }","duration":"538.976527ms","start":"2026-05-25T17:56:38.699285Z","end":"2026-05-25T17:56:39.238261Z","steps":["trace[1407356534] 'agreement among raft nodes before linearized reading' (duration: 538.779042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:39.238319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:38.699272Z","time spent":"539.037478ms","remote":"127.0.0.1:36144","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":"info","ts":"2026-05-25T17:56:39.250396Z","caller":"traceutil/trace.go:171","msg":"trace[490355792] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"529.480021ms","start":"2026-05-25T17:56:38.720867Z","end":"2026-05-25T17:56:39.250347Z","steps":["trace[490355792] 'process raft request' (duration: 529.178683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:39.250519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:38.720815Z","time spent":"529.650386ms","remote":"127.0.0.1:35936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-25T17:56:39.250307Z","caller":"traceutil/trace.go:171","msg":"trace[914051726] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"333.639697ms","start":"2026-05-25T17:56:38.916648Z","end":"2026-05-25T17:56:39.250288Z","steps":["trace[914051726] 'process raft request' (duration: 333.580226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:39.250666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:38.916628Z","time spent":"333.970166ms","remote":"127.0.0.1:36144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-25T17:56:39.304716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.03999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:56:39.304794Z","caller":"traceutil/trace.go:171","msg":"trace[102557976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"588.162704ms","start":"2026-05-25T17:56:38.716615Z","end":"2026-05-25T17:56:39.304778Z","steps":["trace[102557976] 'agreement among raft nodes before linearized reading' (duration: 588.03374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:39.30484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:38.716599Z","time spent":"588.230755ms","remote":"127.0.0.1:35894","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-05-25T17:56:48.237205Z","caller":"traceutil/trace.go:171","msg":"trace[279313080] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"194.57732ms","start":"2026-05-25T17:56:48.0426Z","end":"2026-05-25T17:56:48.237178Z","steps":["trace[279313080] 'process raft request' (duration: 194.379985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:49.396775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.722699ms","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:530"} {"level":"info","ts":"2026-05-25T17:56:49.396874Z","caller":"traceutil/trace.go:171","msg":"trace[467792283] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1348; }","duration":"235.871003ms","start":"2026-05-25T17:56:49.16098Z","end":"2026-05-25T17:56:49.396851Z","steps":["trace[467792283] 'range keys from in-memory index tree' (duration: 235.521844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:49.397117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.30937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T17:56:49.39719Z","caller":"traceutil/trace.go:171","msg":"trace[479474838] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1348; }","duration":"950.393172ms","start":"2026-05-25T17:56:48.446781Z","end":"2026-05-25T17:56:49.397174Z","steps":["trace[479474838] 'count revisions from in-memory index tree' (duration: 950.235368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T17:56:49.397233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-25T17:56:48.446709Z","time spent":"950.514616ms","remote":"127.0.0.1:36078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-25T17:56:49.397591Z","caller":"traceutil/trace.go:171","msg":"trace[438794237] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"118.401598ms","start":"2026-05-25T17:56:49.279175Z","end":"2026-05-25T17:56:49.397577Z","steps":["trace[438794237] 'process raft request' (duration: 60.162813ms)","trace[438794237] 'compare' (duration: 57.482736ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:56:49.545475Z","caller":"traceutil/trace.go:171","msg":"trace[1495507887] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"142.907076ms","start":"2026-05-25T17:56:49.402541Z","end":"2026-05-25T17:56:49.545448Z","steps":["trace[1495507887] 'process raft request' (duration: 102.967877ms)","trace[1495507887] 'compare' (duration: 39.729434ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T17:58:46.023727Z","caller":"traceutil/trace.go:171","msg":"trace[1116842911] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1963; }","duration":"111.816208ms","start":"2026-05-25T17:58:45.911876Z","end":"2026-05-25T17:58:46.023692Z","steps":["trace[1116842911] 'read index received' (duration: 33.72554ms)","trace[1116842911] 'applied index is now lower than readState.Index' (duration: 78.089378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-25T17:58:46.023916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.015483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T17:58:46.023966Z","caller":"traceutil/trace.go:171","msg":"trace[1989287402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1885; }","duration":"112.123665ms","start":"2026-05-25T17:58:45.911824Z","end":"2026-05-25T17:58:46.023948Z","steps":["trace[1989287402] 'agreement among raft nodes before linearized reading' (duration: 112.022783ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T17:58:46.023974Z","caller":"traceutil/trace.go:171","msg":"trace[370863779] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"123.523242ms","start":"2026-05-25T17:58:45.900414Z","end":"2026-05-25T17:58:46.023938Z","steps":["trace[370863779] 'process raft request' (duration: 45.200648ms)","trace[370863779] 'compare' (duration: 77.902794ms)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:00:26.416097Z","caller":"traceutil/trace.go:171","msg":"trace[831876809] linearizableReadLoop","detail":"{readStateIndex:2467; appliedIndex:2466; }","duration":"112.148505ms","start":"2026-05-25T18:00:26.303927Z","end":"2026-05-25T18:00:26.416076Z","steps":["trace[831876809] 'read index received' (duration: 111.98246ms)","trace[831876809] 'applied index is now lower than readState.Index' (duration: 165.185µs)"],"step_count":2} {"level":"info","ts":"2026-05-25T18:00:26.416146Z","caller":"traceutil/trace.go:171","msg":"trace[111400214] transaction","detail":"{read_only:false; response_revision:2367; number_of_response:1; }","duration":"195.875458ms","start":"2026-05-25T18:00:26.220248Z","end":"2026-05-25T18:00:26.416124Z","steps":["trace[111400214] 'process raft request' (duration: 195.684803ms)"],"step_count":1} {"level":"warn","ts":"2026-05-25T18:00:26.416224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.280038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-25T18:00:26.416256Z","caller":"traceutil/trace.go:171","msg":"trace[1883541831] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:2367; }","duration":"112.37198ms","start":"2026-05-25T18:00:26.303875Z","end":"2026-05-25T18:00:26.416247Z","steps":["trace[1883541831] 'agreement among raft nodes before linearized reading' (duration: 112.294158ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:00:32.08806Z","caller":"traceutil/trace.go:171","msg":"trace[780236503] linearizableReadLoop","detail":"{readStateIndex:2499; appliedIndex:2498; }","duration":"176.763491ms","start":"2026-05-25T18:00:31.911263Z","end":"2026-05-25T18:00:32.088027Z","steps":["trace[780236503] 'read index received' (duration: 176.460783ms)","trace[780236503] 'applied index is now lower than readState.Index' (duration: 301.318µs)"],"step_count":2} {"level":"warn","ts":"2026-05-25T18:00:32.088353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.031468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-25T18:00:32.088401Z","caller":"traceutil/trace.go:171","msg":"trace[930957324] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2398; }","duration":"177.178302ms","start":"2026-05-25T18:00:31.91121Z","end":"2026-05-25T18:00:32.088389Z","steps":["trace[930957324] 'agreement among raft nodes before linearized reading' (duration: 176.956396ms)"],"step_count":1} {"level":"info","ts":"2026-05-25T18:00:32.089021Z","caller":"traceutil/trace.go:171","msg":"trace[1561844710] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"270.191197ms","start":"2026-05-25T18:00:31.818814Z","end":"2026-05-25T18:00:32.089005Z","steps":["trace[1561844710] 'process raft request' (duration: 269.015077ms)"],"step_count":1}