{"level":"warn","ts":"2026-05-27T19:44:28.431306Z","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-27T19:44:28.4314Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.194:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.194:2380","--initial-cluster=instance=https://199.19.213.194:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.194:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.194: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-27T19:44:28.431471Z","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-27T19:44:28.431481Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-05-27T19:44:28.431506Z","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-27T19:44:28.432077Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"]} {"level":"info","ts":"2026-05-27T19:44:28.432147Z","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.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.194: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-27T19:44:28.436616Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.249365ms"} {"level":"info","ts":"2026-05-27T19:44:28.448128Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1444e0f5dfca45ef","cluster-id":"46f0df2abd5471c9"} {"level":"info","ts":"2026-05-27T19:44:28.448198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:44:28.448217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 0"} {"level":"info","ts":"2026-05-27T19:44:28.448236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1444e0f5dfca45ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T19:44:28.448249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 1"} {"level":"info","ts":"2026-05-27T19:44:28.448283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"warn","ts":"2026-05-27T19:44:28.457261Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:44:28.458552Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:44:28.46103Z","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-27T19:44:28.465689Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1444e0f5dfca45ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T19:44:28.466206Z","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-27T19:44:28.466302Z","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-27T19:44:28.466317Z","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-27T19:44:28.466341Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1444e0f5dfca45ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T19:44:28.469919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"info","ts":"2026-05-27T19:44:28.470113Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","added-peer-id":"1444e0f5dfca45ef","added-peer-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-05-27T19:44:28.471655Z","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-27T19:44:28.471862Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-05-27T19:44:28.471913Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-05-27T19:44:28.471916Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1444e0f5dfca45ef","initial-advertise-peer-urls":["https://199.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T19:44:28.47198Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:44:28.949335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:44:28.949397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:44:28.949424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgPreVoteResp from 1444e0f5dfca45ef at term 1"} {"level":"info","ts":"2026-05-27T19:44:28.949434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:44:28.94944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgVoteResp from 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-05-27T19:44:28.949449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became leader at term 2"} {"level":"info","ts":"2026-05-27T19:44:28.949473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1444e0f5dfca45ef elected leader 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-05-27T19:44:28.950872Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:44:28.951656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:44:28.951661Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1444e0f5dfca45ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.194:2379]}","request-path":"/0/members/1444e0f5dfca45ef/attributes","cluster-id":"46f0df2abd5471c9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T19:44:28.951728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:44:28.952416Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:44:28.952472Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:44:28.952636Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:44:28.952767Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:44:28.952819Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:44:28.955462Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:44:28.956107Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.194:2379"} {"level":"warn","ts":"2026-05-27T19:44:47.59811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.54723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-27T19:44:47.598236Z","caller":"traceutil/trace.go:171","msg":"trace[1636984093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:417; }","duration":"366.731565ms","start":"2026-05-27T19:44:47.231483Z","end":"2026-05-27T19:44:47.598214Z","steps":["trace[1636984093] 'range keys from in-memory index tree' (duration: 366.448557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:47.598299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:47.231458Z","time spent":"366.831357ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T19:44:47.753528Z","caller":"traceutil/trace.go:171","msg":"trace[240029538] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:432; }","duration":"129.300758ms","start":"2026-05-27T19:44:47.624207Z","end":"2026-05-27T19:44:47.753508Z","steps":["trace[240029538] 'read index received' (duration: 48.917461ms)","trace[240029538] 'applied index is now lower than readState.Index' (duration: 80.382487ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:47.75364Z","caller":"traceutil/trace.go:171","msg":"trace[1118940671] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"151.519202ms","start":"2026-05-27T19:44:47.60211Z","end":"2026-05-27T19:44:47.75363Z","steps":["trace[1118940671] 'process raft request' (duration: 151.262895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:47.75379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.560654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:47.753855Z","caller":"traceutil/trace.go:171","msg":"trace[1526983046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"129.678697ms","start":"2026-05-27T19:44:47.624162Z","end":"2026-05-27T19:44:47.753841Z","steps":["trace[1526983046] 'agreement among raft nodes before linearized reading' (duration: 129.548094ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:06.570735Z","caller":"traceutil/trace.go:171","msg":"trace[40570899] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"115.303088ms","start":"2026-05-27T19:45:06.455405Z","end":"2026-05-27T19:45:06.570708Z","steps":["trace[40570899] 'process raft request' (duration: 115.142104ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:06.576199Z","caller":"traceutil/trace.go:171","msg":"trace[159495027] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"118.461558ms","start":"2026-05-27T19:45:06.457688Z","end":"2026-05-27T19:45:06.576149Z","steps":["trace[159495027] 'process raft request' (duration: 118.244543ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:06.576608Z","caller":"traceutil/trace.go:171","msg":"trace[1422589513] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"104.93262ms","start":"2026-05-27T19:45:06.471634Z","end":"2026-05-27T19:45:06.576566Z","steps":["trace[1422589513] 'process raft request' (duration: 104.624533ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:06.576774Z","caller":"traceutil/trace.go:171","msg":"trace[746943531] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"107.274497ms","start":"2026-05-27T19:45:06.469485Z","end":"2026-05-27T19:45:06.576759Z","steps":["trace[746943531] 'process raft request' (duration: 106.640641ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:06.576985Z","caller":"traceutil/trace.go:171","msg":"trace[1309229558] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"107.475602ms","start":"2026-05-27T19:45:06.469493Z","end":"2026-05-27T19:45:06.576968Z","steps":["trace[1309229558] 'process raft request' (duration: 106.723754ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:06.577013Z","caller":"traceutil/trace.go:171","msg":"trace[1465448274] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"118.815866ms","start":"2026-05-27T19:45:06.458176Z","end":"2026-05-27T19:45:06.576992Z","steps":["trace[1465448274] 'process raft request' (duration: 117.844562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:12.451005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.631838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.194\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T19:45:12.451148Z","caller":"traceutil/trace.go:171","msg":"trace[727531344] range","detail":"{range_begin:/registry/masterleases/199.19.213.194; range_end:; response_count:1; response_revision:632; }","duration":"107.814742ms","start":"2026-05-27T19:45:12.343295Z","end":"2026-05-27T19:45:12.45111Z","steps":["trace[727531344] 'range keys from in-memory index tree' (duration: 107.529125ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:12.906518Z","caller":"traceutil/trace.go:171","msg":"trace[1136618086] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"282.04396ms","start":"2026-05-27T19:45:12.624426Z","end":"2026-05-27T19:45:12.90647Z","steps":["trace[1136618086] 'read index received' (duration: 275.935227ms)","trace[1136618086] 'applied index is now lower than readState.Index' (duration: 6.108042ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:12.906653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.209284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:12.906682Z","caller":"traceutil/trace.go:171","msg":"trace[190076472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"282.283587ms","start":"2026-05-27T19:45:12.624389Z","end":"2026-05-27T19:45:12.906673Z","steps":["trace[190076472] 'agreement among raft nodes before linearized reading' (duration: 282.211405ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:12.906907Z","caller":"traceutil/trace.go:171","msg":"trace[473141582] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"365.660578ms","start":"2026-05-27T19:45:12.541184Z","end":"2026-05-27T19:45:12.906844Z","steps":["trace[473141582] 'process raft request' (duration: 359.481343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:12.907762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:12.541154Z","time spent":"365.910633ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:13.578272Z","caller":"traceutil/trace.go:171","msg":"trace[2069469443] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"466.029893ms","start":"2026-05-27T19:45:13.112202Z","end":"2026-05-27T19:45:13.578232Z","steps":["trace[2069469443] 'read index received' (duration: 465.731475ms)","trace[2069469443] 'applied index is now lower than readState.Index' (duration: 297.248µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:13.57831Z","caller":"traceutil/trace.go:171","msg":"trace[824349172] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"615.524894ms","start":"2026-05-27T19:45:12.962745Z","end":"2026-05-27T19:45:13.57827Z","steps":["trace[824349172] 'process raft request' (duration: 615.285618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:13.578482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:12.962718Z","time spent":"615.677647ms","remote":"127.0.0.1:53586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:45:13.578497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.287379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rcvmh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-27T19:45:13.578536Z","caller":"traceutil/trace.go:171","msg":"trace[899483314] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rcvmh; range_end:; response_count:1; response_revision:635; }","duration":"466.375531ms","start":"2026-05-27T19:45:13.112148Z","end":"2026-05-27T19:45:13.578523Z","steps":["trace[899483314] 'agreement among raft nodes before linearized reading' (duration: 466.200527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:13.578529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.08446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T19:45:13.578561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:13.112127Z","time spent":"466.428602ms","remote":"127.0.0.1:53262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-rcvmh\" "} {"level":"info","ts":"2026-05-27T19:45:13.578572Z","caller":"traceutil/trace.go:171","msg":"trace[1247107950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"318.164512ms","start":"2026-05-27T19:45:13.260394Z","end":"2026-05-27T19:45:13.578559Z","steps":["trace[1247107950] 'agreement among raft nodes before linearized reading' (duration: 318.041139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:13.578612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:13.260375Z","time spent":"318.226043ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T19:45:14.386022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039420690307687819,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:45:14.766507Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.168302852s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-27T19:45:14.961191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.262388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:45:14.961624Z","caller":"traceutil/trace.go:171","msg":"trace[364907937] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.364305964s","start":"2026-05-27T19:45:13.597292Z","end":"2026-05-27T19:45:14.961598Z","steps":["trace[364907937] 'process raft request' (duration: 1.169473072s)","trace[364907937] 'compare' (duration: 194.165536ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:14.961753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:13.597273Z","time spent":"1.364414508s","remote":"127.0.0.1:53262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:14.961922Z","caller":"traceutil/trace.go:171","msg":"trace[154985137] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"1.076440539s","start":"2026-05-27T19:45:13.885465Z","end":"2026-05-27T19:45:14.961906Z","steps":["trace[154985137] 'read index received' (duration: 881.382671ms)","trace[154985137] 'applied index is now lower than readState.Index' (duration: 195.056938ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:14.961952Z","caller":"traceutil/trace.go:171","msg":"trace[1114814349] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"979.578231ms","start":"2026-05-27T19:45:13.982355Z","end":"2026-05-27T19:45:14.961934Z","steps":["trace[1114814349] 'process raft request' (duration: 979.061288ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:14.961999Z","caller":"traceutil/trace.go:171","msg":"trace[1445476031] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"300.50034ms","start":"2026-05-27T19:45:14.661491Z","end":"2026-05-27T19:45:14.961992Z","steps":["trace[1445476031] 'process raft request' (duration: 300.361817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:14.96205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:14.661466Z","time spent":"300.554502ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:45:14.962063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:13.982331Z","time spent":"979.684903ms","remote":"127.0.0.1:53192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:45:14.962163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075195248s","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-27T19:45:14.962204Z","caller":"traceutil/trace.go:171","msg":"trace[1464694942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"1.075287501s","start":"2026-05-27T19:45:13.886903Z","end":"2026-05-27T19:45:14.96219Z","steps":["trace[1464694942] 'agreement among raft nodes before linearized reading' (duration: 1.075149448s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:14.962238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:13.886895Z","time spent":"1.075336082s","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:45:14.962361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.444942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:14.96239Z","caller":"traceutil/trace.go:171","msg":"trace[898725695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"337.508584ms","start":"2026-05-27T19:45:14.624875Z","end":"2026-05-27T19:45:14.962384Z","steps":["trace[898725695] 'agreement among raft nodes before linearized reading' (duration: 337.466583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:14.962416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:14.624857Z","time spent":"337.554555ms","remote":"127.0.0.1:53132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:45:14.962422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"988.606166ms","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-27T19:45:14.962456Z","caller":"traceutil/trace.go:171","msg":"trace[1223896980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"988.640037ms","start":"2026-05-27T19:45:13.973806Z","end":"2026-05-27T19:45:14.962446Z","steps":["trace[1223896980] 'agreement among raft nodes before linearized reading' (duration: 988.538745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:14.962475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077003373s","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-27T19:45:14.962523Z","caller":"traceutil/trace.go:171","msg":"trace[1486591211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"1.077086935s","start":"2026-05-27T19:45:13.885424Z","end":"2026-05-27T19:45:14.962511Z","steps":["trace[1486591211] 'agreement among raft nodes before linearized reading' (duration: 1.076955152s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:14.962561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:13.885405Z","time spent":"1.077148386s","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:45:14.962486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:13.973757Z","time spent":"988.724139ms","remote":"127.0.0.1:53322","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-27T19:45:16.706261Z","caller":"traceutil/trace.go:171","msg":"trace[1126787000] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"370.461697ms","start":"2026-05-27T19:45:16.33577Z","end":"2026-05-27T19:45:16.706232Z","steps":["trace[1126787000] 'process raft request' (duration: 370.321923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:16.706429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:16.335746Z","time spent":"370.618701ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:45:16.715054Z","caller":"traceutil/trace.go:171","msg":"trace[231940913] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"178.350352ms","start":"2026-05-27T19:45:16.536679Z","end":"2026-05-27T19:45:16.715029Z","steps":["trace[231940913] 'process raft request' (duration: 177.939042ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:03.552708Z","caller":"traceutil/trace.go:171","msg":"trace[886906773] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"308.605168ms","start":"2026-05-27T19:46:03.244072Z","end":"2026-05-27T19:46:03.552677Z","steps":["trace[886906773] 'process raft request' (duration: 308.398161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:03.552922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:03.244055Z","time spent":"308.747512ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:04.123617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039420690307688596,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:46:04.282767Z","caller":"traceutil/trace.go:171","msg":"trace[2053036260] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.037891521s","start":"2026-05-27T19:46:03.244844Z","end":"2026-05-27T19:46:04.282735Z","steps":["trace[2053036260] 'process raft request' (duration: 1.037634954s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:04.282758Z","caller":"traceutil/trace.go:171","msg":"trace[1026402076] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.036504106s","start":"2026-05-27T19:46:03.246196Z","end":"2026-05-27T19:46:04.2827Z","steps":["trace[1026402076] 'process raft request' (duration: 1.036437254s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:04.282905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:03.244831Z","time spent":"1.038001483s","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:04.282934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:03.246188Z","time spent":"1.03665755s","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:04.283036Z","caller":"traceutil/trace.go:171","msg":"trace[346280843] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1054; }","duration":"659.692184ms","start":"2026-05-27T19:46:03.623317Z","end":"2026-05-27T19:46:04.28301Z","steps":["trace[346280843] 'read index received' (duration: 659.091229ms)","trace[346280843] 'applied index is now lower than readState.Index' (duration: 599.755µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:04.283161Z","caller":"traceutil/trace.go:171","msg":"trace[551529188] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"915.636983ms","start":"2026-05-27T19:46:03.367513Z","end":"2026-05-27T19:46:04.28315Z","steps":["trace[551529188] 'process raft request' (duration: 915.152991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:04.283293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.700433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:46:04.283315Z","caller":"traceutil/trace.go:171","msg":"trace[566044944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1016; }","duration":"480.722603ms","start":"2026-05-27T19:46:03.802586Z","end":"2026-05-27T19:46:04.283309Z","steps":["trace[566044944] 'agreement among raft nodes before linearized reading' (duration: 480.613471ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:04.283306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:03.367487Z","time spent":"915.699834ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:04.283351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.044993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:04.283382Z","caller":"traceutil/trace.go:171","msg":"trace[1914146157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"660.116195ms","start":"2026-05-27T19:46:03.623261Z","end":"2026-05-27T19:46:04.283378Z","steps":["trace[1914146157] 'agreement among raft nodes before linearized reading' (duration: 660.077624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:04.283392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:03.623237Z","time spent":"660.152305ms","remote":"127.0.0.1:53132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:46:04.28341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.244696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:46:04.283461Z","caller":"traceutil/trace.go:171","msg":"trace[1791316857] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1016; }","duration":"420.319478ms","start":"2026-05-27T19:46:03.86313Z","end":"2026-05-27T19:46:04.283449Z","steps":["trace[1791316857] 'agreement among raft nodes before linearized reading' (duration: 420.235626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:04.283494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:03.86312Z","time spent":"420.366009ms","remote":"127.0.0.1:53460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:46:04.28333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:03.802538Z","time spent":"480.789335ms","remote":"127.0.0.1:53322","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":"info","ts":"2026-05-27T19:46:26.245708Z","caller":"traceutil/trace.go:171","msg":"trace[1980218378] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"587.607062ms","start":"2026-05-27T19:46:25.658035Z","end":"2026-05-27T19:46:26.245642Z","steps":["trace[1980218378] 'process raft request' (duration: 587.042908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:26.246064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:25.658022Z","time spent":"587.88477ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:26.246449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.928936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:26.24655Z","caller":"traceutil/trace.go:171","msg":"trace[1325342077] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1085; }","duration":"348.08554ms","start":"2026-05-27T19:46:25.898432Z","end":"2026-05-27T19:46:26.246518Z","steps":["trace[1325342077] 'agreement among raft nodes before linearized reading' (duration: 347.080925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:26.246634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:25.898408Z","time spent":"348.209453ms","remote":"127.0.0.1:53254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-05-27T19:46:26.245263Z","caller":"traceutil/trace.go:171","msg":"trace[2058207074] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1129; }","duration":"346.737867ms","start":"2026-05-27T19:46:25.8985Z","end":"2026-05-27T19:46:26.245238Z","steps":["trace[2058207074] 'read index received' (duration: 346.530032ms)","trace[2058207074] 'applied index is now lower than readState.Index' (duration: 206.995µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:27.837558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.444968ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-27T19:46:27.837827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.987496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:27.837898Z","caller":"traceutil/trace.go:171","msg":"trace[584286872] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"212.852843ms","start":"2026-05-27T19:46:27.624828Z","end":"2026-05-27T19:46:27.837681Z","steps":["trace[584286872] 'read index received' (duration: 91.2207ms)","trace[584286872] 'applied index is now lower than readState.Index' (duration: 121.630253ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:27.83792Z","caller":"traceutil/trace.go:171","msg":"trace[1395866946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1091; }","duration":"213.125581ms","start":"2026-05-27T19:46:27.62478Z","end":"2026-05-27T19:46:27.837906Z","steps":["trace[1395866946] 'agreement among raft nodes before linearized reading' (duration: 212.981706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:28.946167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.067103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:46:28.946277Z","caller":"traceutil/trace.go:171","msg":"trace[1118436768] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"390.942512ms","start":"2026-05-27T19:46:28.555314Z","end":"2026-05-27T19:46:28.946257Z","steps":["trace[1118436768] 'process raft request' (duration: 112.706816ms)","trace[1118436768] 'compare' (duration: 277.891927ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:28.946337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:28.555295Z","time spent":"391.013764ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:44.50115Z","caller":"traceutil/trace.go:171","msg":"trace[973734648] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"108.707364ms","start":"2026-05-27T19:46:44.392409Z","end":"2026-05-27T19:46:44.501117Z","steps":["trace[973734648] 'process raft request' (duration: 108.514399ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:45.592945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039420690307689050,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:46:45.65518Z","caller":"traceutil/trace.go:171","msg":"trace[940389398] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"563.075115ms","start":"2026-05-27T19:46:45.092083Z","end":"2026-05-27T19:46:45.655158Z","steps":["trace[940389398] 'read index received' (duration: 562.827079ms)","trace[940389398] 'applied index is now lower than readState.Index' (duration: 247.316µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:45.655337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.226549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:45.655394Z","caller":"traceutil/trace.go:171","msg":"trace[1519419730] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1201; }","duration":"563.346172ms","start":"2026-05-27T19:46:45.092039Z","end":"2026-05-27T19:46:45.655385Z","steps":["trace[1519419730] 'agreement among raft nodes before linearized reading' (duration: 563.230319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:45.655423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:45.092018Z","time spent":"563.398164ms","remote":"127.0.0.1:35422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"info","ts":"2026-05-27T19:46:45.655309Z","caller":"traceutil/trace.go:171","msg":"trace[1022942607] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"617.12611ms","start":"2026-05-27T19:46:45.038148Z","end":"2026-05-27T19:46:45.655274Z","steps":["trace[1022942607] 'process raft request' (duration: 616.816703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:45.655658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:45.038115Z","time spent":"617.50468ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:47:00.733594Z","caller":"traceutil/trace.go:171","msg":"trace[1894842846] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"109.004216ms","start":"2026-05-27T19:47:00.624565Z","end":"2026-05-27T19:47:00.733569Z","steps":["trace[1894842846] 'read index received' (duration: 50.786411ms)","trace[1894842846] 'applied index is now lower than readState.Index' (duration: 58.216865ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:00.733726Z","caller":"traceutil/trace.go:171","msg":"trace[1252467039] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"126.535302ms","start":"2026-05-27T19:47:00.607175Z","end":"2026-05-27T19:47:00.73371Z","steps":["trace[1252467039] 'process raft request' (duration: 68.260185ms)","trace[1252467039] 'compare' (duration: 58.023761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:00.734026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.445668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:00.734066Z","caller":"traceutil/trace.go:171","msg":"trace[823115204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"109.55823ms","start":"2026-05-27T19:47:00.624496Z","end":"2026-05-27T19:47:00.734054Z","steps":["trace[823115204] 'agreement among raft nodes before linearized reading' (duration: 109.495599ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:01.651405Z","caller":"traceutil/trace.go:171","msg":"trace[1254873217] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"110.475603ms","start":"2026-05-27T19:47:01.540896Z","end":"2026-05-27T19:47:01.651372Z","steps":["trace[1254873217] 'process raft request' (duration: 110.313629ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:01.662832Z","caller":"traceutil/trace.go:171","msg":"trace[689187832] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"120.678656ms","start":"2026-05-27T19:47:01.542129Z","end":"2026-05-27T19:47:01.662808Z","steps":["trace[689187832] 'process raft request' (duration: 120.389989ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:01.663025Z","caller":"traceutil/trace.go:171","msg":"trace[1184960963] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"116.278378ms","start":"2026-05-27T19:47:01.546718Z","end":"2026-05-27T19:47:01.662997Z","steps":["trace[1184960963] 'process raft request' (duration: 115.926069ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:04.923347Z","caller":"traceutil/trace.go:171","msg":"trace[1677001019] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"309.765871ms","start":"2026-05-27T19:47:04.613555Z","end":"2026-05-27T19:47:04.92332Z","steps":["trace[1677001019] 'process raft request' (duration: 309.633358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:04.923513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:04.613535Z","time spent":"309.887734ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:04.923599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.5339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:04.923666Z","caller":"traceutil/trace.go:171","msg":"trace[2020254647] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1296; }","duration":"256.656043ms","start":"2026-05-27T19:47:04.666995Z","end":"2026-05-27T19:47:04.923651Z","steps":["trace[2020254647] 'agreement among raft nodes before linearized reading' (duration: 256.51551ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:04.923468Z","caller":"traceutil/trace.go:171","msg":"trace[305989827] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1351; }","duration":"256.357065ms","start":"2026-05-27T19:47:04.667043Z","end":"2026-05-27T19:47:04.9234Z","steps":["trace[305989827] 'read index received' (duration: 256.347975ms)","trace[305989827] 'applied index is now lower than readState.Index' (duration: 7.52µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:04.924367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.884957ms","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-27T19:47:04.924419Z","caller":"traceutil/trace.go:171","msg":"trace[517008700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1296; }","duration":"175.927218ms","start":"2026-05-27T19:47:04.748468Z","end":"2026-05-27T19:47:04.924396Z","steps":["trace[517008700] 'agreement among raft nodes before linearized reading' (duration: 175.715673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:04.924798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.834191ms","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-27T19:47:04.924841Z","caller":"traceutil/trace.go:171","msg":"trace[894389306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"172.898222ms","start":"2026-05-27T19:47:04.751933Z","end":"2026-05-27T19:47:04.924831Z","steps":["trace[894389306] 'agreement among raft nodes before linearized reading' (duration: 172.785259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:04.924834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.438471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:47:04.924896Z","caller":"traceutil/trace.go:171","msg":"trace[1746475956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1296; }","duration":"172.533133ms","start":"2026-05-27T19:47:04.752349Z","end":"2026-05-27T19:47:04.924882Z","steps":["trace[1746475956] 'agreement among raft nodes before linearized reading' (duration: 172.325658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:04.924925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.048777ms","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-27T19:47:04.92498Z","caller":"traceutil/trace.go:171","msg":"trace[1179331060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"171.128289ms","start":"2026-05-27T19:47:04.753838Z","end":"2026-05-27T19:47:04.924967Z","steps":["trace[1179331060] 'agreement among raft nodes before linearized reading' (duration: 170.863723ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:05.120446Z","caller":"traceutil/trace.go:171","msg":"trace[904371330] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"191.309889ms","start":"2026-05-27T19:47:04.929115Z","end":"2026-05-27T19:47:05.120425Z","steps":["trace[904371330] 'process raft request' (duration: 172.238826ms)","trace[904371330] 'compare' (duration: 18.772006ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:05.136833Z","caller":"traceutil/trace.go:171","msg":"trace[1873880440] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"207.229285ms","start":"2026-05-27T19:47:04.929558Z","end":"2026-05-27T19:47:05.136788Z","steps":["trace[1873880440] 'process raft request' (duration: 207.184394ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:05.136983Z","caller":"traceutil/trace.go:171","msg":"trace[561186601] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"207.631645ms","start":"2026-05-27T19:47:04.929284Z","end":"2026-05-27T19:47:05.136916Z","steps":["trace[561186601] 'process raft request' (duration: 207.300767ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:05.137109Z","caller":"traceutil/trace.go:171","msg":"trace[1294987954] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"207.698147ms","start":"2026-05-27T19:47:04.9294Z","end":"2026-05-27T19:47:05.137098Z","steps":["trace[1294987954] 'process raft request' (duration: 207.305157ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:07.531608Z","caller":"traceutil/trace.go:171","msg":"trace[2117083525] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"407.515898ms","start":"2026-05-27T19:47:07.124068Z","end":"2026-05-27T19:47:07.531584Z","steps":["trace[2117083525] 'read index received' (duration: 407.236922ms)","trace[2117083525] 'applied index is now lower than readState.Index' (duration: 277.826µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:07.531899Z","caller":"traceutil/trace.go:171","msg":"trace[550536432] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"602.753116ms","start":"2026-05-27T19:47:06.929132Z","end":"2026-05-27T19:47:07.531885Z","steps":["trace[550536432] 'process raft request' (duration: 602.275884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:07.532009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:06.929105Z","time spent":"602.839177ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:07.532262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.195936ms","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-27T19:47:07.532303Z","caller":"traceutil/trace.go:171","msg":"trace[889413880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1302; }","duration":"408.272098ms","start":"2026-05-27T19:47:07.124021Z","end":"2026-05-27T19:47:07.532293Z","steps":["trace[889413880] 'agreement among raft nodes before linearized reading' (duration: 408.158035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:07.532332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.124002Z","time spent":"408.323749ms","remote":"127.0.0.1:53322","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-27T19:47:07.750217Z","caller":"traceutil/trace.go:171","msg":"trace[548932409] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1357; }","duration":"218.520266ms","start":"2026-05-27T19:47:07.53167Z","end":"2026-05-27T19:47:07.750191Z","steps":["trace[548932409] 'read index received' (duration: 182.283176ms)","trace[548932409] 'applied index is now lower than readState.Index' (duration: 36.2359ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:07.75026Z","caller":"traceutil/trace.go:171","msg":"trace[1704150474] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"320.554ms","start":"2026-05-27T19:47:07.429676Z","end":"2026-05-27T19:47:07.75023Z","steps":["trace[1704150474] 'process raft request' (duration: 320.461477ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:07.750304Z","caller":"traceutil/trace.go:171","msg":"trace[374598078] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"473.971528ms","start":"2026-05-27T19:47:07.276297Z","end":"2026-05-27T19:47:07.750268Z","steps":["trace[374598078] 'process raft request' (duration: 437.642556ms)","trace[374598078] 'compare' (duration: 36.053805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:07.750387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.42965Z","time spent":"320.670882ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:07.750396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.762151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:07.750437Z","caller":"traceutil/trace.go:171","msg":"trace[910825454] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1304; }","duration":"396.860733ms","start":"2026-05-27T19:47:07.353563Z","end":"2026-05-27T19:47:07.750424Z","steps":["trace[910825454] 'agreement among raft nodes before linearized reading' (duration: 396.749801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:07.750484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.353544Z","time spent":"396.928676ms","remote":"127.0.0.1:35362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:47:07.75048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.276277Z","time spent":"474.128942ms","remote":"127.0.0.1:53260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6320,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:07.750548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.717389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-27T19:47:07.75063Z","caller":"traceutil/trace.go:171","msg":"trace[10969886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"609.822761ms","start":"2026-05-27T19:47:07.140787Z","end":"2026-05-27T19:47:07.75061Z","steps":["trace[10969886] 'agreement among raft nodes before linearized reading' (duration: 609.627966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:07.750651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.791585ms","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-27T19:47:07.750686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.140778Z","time spent":"609.895474ms","remote":"127.0.0.1:53322","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":"info","ts":"2026-05-27T19:47:07.750692Z","caller":"traceutil/trace.go:171","msg":"trace[498311084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"610.860247ms","start":"2026-05-27T19:47:07.139816Z","end":"2026-05-27T19:47:07.750676Z","steps":["trace[498311084] 'agreement among raft nodes before linearized reading' (duration: 610.785035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:07.750725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.13979Z","time spent":"610.928969ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:47:07.750548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.37691ms","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-27T19:47:07.750827Z","caller":"traceutil/trace.go:171","msg":"trace[192664082] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1304; }","duration":"609.679198ms","start":"2026-05-27T19:47:07.14114Z","end":"2026-05-27T19:47:07.750819Z","steps":["trace[192664082] 'agreement among raft nodes before linearized reading' (duration: 609.313339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:07.750854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.141136Z","time spent":"609.711349ms","remote":"127.0.0.1:53322","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-27T19:47:07.78391Z","caller":"traceutil/trace.go:171","msg":"trace[1687018872] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"246.978042ms","start":"2026-05-27T19:47:07.536905Z","end":"2026-05-27T19:47:07.783883Z","steps":["trace[1687018872] 'process raft request' (duration: 246.794618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.069061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.246572ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-27T19:47:08.06947Z","caller":"traceutil/trace.go:171","msg":"trace[531446682] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"319.162895ms","start":"2026-05-27T19:47:07.750287Z","end":"2026-05-27T19:47:08.06945Z","steps":["trace[531446682] 'read index received' (duration: 33.568904ms)","trace[531446682] 'applied index is now lower than readState.Index' (duration: 285.591711ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:08.069724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.591554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:08.069767Z","caller":"traceutil/trace.go:171","msg":"trace[922284462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"445.680025ms","start":"2026-05-27T19:47:07.624075Z","end":"2026-05-27T19:47:08.069756Z","steps":["trace[922284462] 'agreement among raft nodes before linearized reading' (duration: 445.605393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.069797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.624055Z","time spent":"445.734597ms","remote":"127.0.0.1:53132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:47:08.06992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.450941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-27T19:47:08.07004Z","caller":"traceutil/trace.go:171","msg":"trace[1711021520] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1305; }","duration":"381.588564ms","start":"2026-05-27T19:47:07.688437Z","end":"2026-05-27T19:47:08.070026Z","steps":["trace[1711021520] 'agreement among raft nodes before linearized reading' (duration: 381.208264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.07015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.688429Z","time spent":"381.677466ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-27T19:47:08.161597Z","caller":"traceutil/trace.go:171","msg":"trace[516092034] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"405.935889ms","start":"2026-05-27T19:47:07.755636Z","end":"2026-05-27T19:47:08.161572Z","steps":["trace[516092034] 'process raft request' (duration: 405.866787ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:08.161614Z","caller":"traceutil/trace.go:171","msg":"trace[979615668] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"406.154574ms","start":"2026-05-27T19:47:07.755425Z","end":"2026-05-27T19:47:08.161579Z","steps":["trace[979615668] 'process raft request' (duration: 406.027131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.161766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.755615Z","time spent":"406.067452ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:47:08.16162Z","caller":"traceutil/trace.go:171","msg":"trace[2122022088] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"406.250206ms","start":"2026-05-27T19:47:07.755336Z","end":"2026-05-27T19:47:08.161587Z","steps":["trace[2122022088] 'process raft request' (duration: 405.865897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.16179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.755404Z","time spent":"406.304217ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:08.16176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.678081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-27T19:47:08.16189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.755304Z","time spent":"406.529924ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:47:08.161966Z","caller":"traceutil/trace.go:171","msg":"trace[675770347] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"355.915397ms","start":"2026-05-27T19:47:07.806021Z","end":"2026-05-27T19:47:08.161937Z","steps":["trace[675770347] 'agreement among raft nodes before linearized reading' (duration: 355.580589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.16209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:07.805996Z","time spent":"356.051181ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T19:47:08.393906Z","caller":"traceutil/trace.go:171","msg":"trace[460991594] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"232.336589ms","start":"2026-05-27T19:47:08.16154Z","end":"2026-05-27T19:47:08.393876Z","steps":["trace[460991594] 'read index received' (duration: 159.426249ms)","trace[460991594] 'applied index is now lower than readState.Index' (duration: 72.90886ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:08.393959Z","caller":"traceutil/trace.go:171","msg":"trace[1428926647] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"319.576545ms","start":"2026-05-27T19:47:08.074334Z","end":"2026-05-27T19:47:08.393911Z","steps":["trace[1428926647] 'process raft request' (duration: 246.402028ms)","trace[1428926647] 'compare' (duration: 72.91434ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:08.394018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.406231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:08.394047Z","caller":"traceutil/trace.go:171","msg":"trace[1794484813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"323.481912ms","start":"2026-05-27T19:47:08.070557Z","end":"2026-05-27T19:47:08.394039Z","steps":["trace[1794484813] 'agreement among raft nodes before linearized reading' (duration: 323.4093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:08.394067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:08.070538Z","time spent":"323.523663ms","remote":"127.0.0.1:53134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:47:08.395445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:08.074319Z","time spent":"319.702507ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:09.243214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.981863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:47:09.243328Z","caller":"traceutil/trace.go:171","msg":"trace[233214139] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"617.896312ms","start":"2026-05-27T19:47:08.625415Z","end":"2026-05-27T19:47:09.243311Z","steps":["trace[233214139] 'read index received' (duration: 74.512µs)","trace[233214139] 'applied index is now lower than readState.Index' (duration: 617.82032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:09.243428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.018315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:09.243409Z","caller":"traceutil/trace.go:171","msg":"trace[45054207] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"1.077314229s","start":"2026-05-27T19:47:08.166061Z","end":"2026-05-27T19:47:09.243375Z","steps":["trace[45054207] 'process raft request' (duration: 457.09219ms)","trace[45054207] 'compare' (duration: 619.720696ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:09.243462Z","caller":"traceutil/trace.go:171","msg":"trace[853722370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"618.087456ms","start":"2026-05-27T19:47:08.625364Z","end":"2026-05-27T19:47:09.243452Z","steps":["trace[853722370] 'agreement among raft nodes before linearized reading' (duration: 618.030195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:09.243496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:08.625344Z","time spent":"618.145498ms","remote":"127.0.0.1:53132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:47:09.243537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:08.166045Z","time spent":"1.077421342s","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:09.24377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.666041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T19:47:09.243809Z","caller":"traceutil/trace.go:171","msg":"trace[423839371] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1310; }","duration":"407.729443ms","start":"2026-05-27T19:47:08.83607Z","end":"2026-05-27T19:47:09.243799Z","steps":["trace[423839371] 'agreement among raft nodes before linearized reading' (duration: 407.60426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:09.243844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:08.836038Z","time spent":"407.798995ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-27T19:47:15.138375Z","caller":"traceutil/trace.go:171","msg":"trace[225944253] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"116.715477ms","start":"2026-05-27T19:47:15.021637Z","end":"2026-05-27T19:47:15.138352Z","steps":["trace[225944253] 'read index received' (duration: 116.511422ms)","trace[225944253] 'applied index is now lower than readState.Index' (duration: 203.185µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:15.138566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.905952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-27T19:47:15.138602Z","caller":"traceutil/trace.go:171","msg":"trace[1825250853] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1360; }","duration":"116.995084ms","start":"2026-05-27T19:47:15.021597Z","end":"2026-05-27T19:47:15.138592Z","steps":["trace[1825250853] 'agreement among raft nodes before linearized reading' (duration: 116.850261ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:47:15.13856Z","caller":"traceutil/trace.go:171","msg":"trace[317811694] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"389.209784ms","start":"2026-05-27T19:47:14.749214Z","end":"2026-05-27T19:47:15.138423Z","steps":["trace[317811694] 'process raft request' (duration: 388.984769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:15.138779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:14.749192Z","time spent":"389.505931ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:16.248987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.023996ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:47:16.249229Z","caller":"traceutil/trace.go:171","msg":"trace[577238850] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1361; }","duration":"676.291992ms","start":"2026-05-27T19:47:15.572903Z","end":"2026-05-27T19:47:16.249195Z","steps":["trace[577238850] 'range keys from in-memory index tree' (duration: 675.996995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:16.249245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.738757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:47:16.249356Z","caller":"traceutil/trace.go:171","msg":"trace[1595945364] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"978.783283ms","start":"2026-05-27T19:47:15.270549Z","end":"2026-05-27T19:47:16.249332Z","steps":["trace[1595945364] 'process raft request' (duration: 35.858421ms)","trace[1595945364] 'compare' (duration: 942.603684ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:47:16.249632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:15.270532Z","time spent":"978.856704ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:16.318398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039420690307689383,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:47:16.60414Z","caller":"traceutil/trace.go:171","msg":"trace[663486154] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"1.001236759s","start":"2026-05-27T19:47:15.60287Z","end":"2026-05-27T19:47:16.604107Z","steps":["trace[663486154] 'process raft request' (duration: 995.365243ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:16.604307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:15.602846Z","time spent":"1.001369402s","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:47:16.604381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.291072ms","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-27T19:47:16.604134Z","caller":"traceutil/trace.go:171","msg":"trace[1625824605] linearizableReadLoop","detail":"{readStateIndex:1420; appliedIndex:1419; }","duration":"786.029575ms","start":"2026-05-27T19:47:15.818066Z","end":"2026-05-27T19:47:16.604096Z","steps":["trace[1625824605] 'read index received' (duration: 780.076928ms)","trace[1625824605] 'applied index is now lower than readState.Index' (duration: 5.951327ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:47:16.604442Z","caller":"traceutil/trace.go:171","msg":"trace[1308595277] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1363; }","duration":"786.414935ms","start":"2026-05-27T19:47:15.818016Z","end":"2026-05-27T19:47:16.604431Z","steps":["trace[1308595277] 'agreement among raft nodes before linearized reading' (duration: 786.182949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:16.604493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:15.817996Z","time spent":"786.487078ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:47:16.604716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.810428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-27T19:47:16.604786Z","caller":"traceutil/trace.go:171","msg":"trace[2116263479] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1363; }","duration":"685.928701ms","start":"2026-05-27T19:47:15.918838Z","end":"2026-05-27T19:47:16.604767Z","steps":["trace[2116263479] 'agreement among raft nodes before linearized reading' (duration: 685.733996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:16.604835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:15.918817Z","time spent":"686.006052ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-27T19:47:16.605056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.376729ms","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-27T19:47:16.605124Z","caller":"traceutil/trace.go:171","msg":"trace[1124289334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1363; }","duration":"414.43136ms","start":"2026-05-27T19:47:16.190667Z","end":"2026-05-27T19:47:16.605098Z","steps":["trace[1124289334] 'agreement among raft nodes before linearized reading' (duration: 414.313267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:16.605157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:16.190648Z","time spent":"414.500751ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:47:16.605258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.531145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:47:16.604833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.544758ms","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-27T19:47:16.605294Z","caller":"traceutil/trace.go:171","msg":"trace[121061399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1363; }","duration":"491.565785ms","start":"2026-05-27T19:47:16.113715Z","end":"2026-05-27T19:47:16.605281Z","steps":["trace[121061399] 'agreement among raft nodes before linearized reading' (duration: 491.511014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:16.605328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:16.113665Z","time spent":"491.655917ms","remote":"127.0.0.1:53132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:47:16.605349Z","caller":"traceutil/trace.go:171","msg":"trace[1797291202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1363; }","duration":"412.078141ms","start":"2026-05-27T19:47:16.193231Z","end":"2026-05-27T19:47:16.605309Z","steps":["trace[1797291202] 'agreement among raft nodes before linearized reading' (duration: 411.440725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:16.605392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.103822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-27T19:47:16.605441Z","caller":"traceutil/trace.go:171","msg":"trace[2115580183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1363; }","duration":"412.198954ms","start":"2026-05-27T19:47:16.19323Z","end":"2026-05-27T19:47:16.605429Z","steps":["trace[2115580183] 'agreement among raft nodes before linearized reading' (duration: 412.077051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:47:16.605487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:16.19321Z","time spent":"412.264146ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T19:47:16.605484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:47:16.19321Z","time spent":"412.226905ms","remote":"127.0.0.1:53322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T19:50:13.817263Z","caller":"traceutil/trace.go:171","msg":"trace[158599359] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2269; }","duration":"181.201559ms","start":"2026-05-27T19:50:13.63604Z","end":"2026-05-27T19:50:13.817242Z","steps":["trace[158599359] 'read index received' (duration: 181.029055ms)","trace[158599359] 'applied index is now lower than readState.Index' (duration: 171.714µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:50:13.817375Z","caller":"traceutil/trace.go:171","msg":"trace[1196320071] transaction","detail":"{read_only:false; response_revision:2177; number_of_response:1; }","duration":"228.943564ms","start":"2026-05-27T19:50:13.588394Z","end":"2026-05-27T19:50:13.817338Z","steps":["trace[1196320071] 'process raft request' (duration: 228.729969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:13.817458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.991659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-27T19:50:13.817481Z","caller":"traceutil/trace.go:171","msg":"trace[2133937067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2177; }","duration":"178.090271ms","start":"2026-05-27T19:50:13.639385Z","end":"2026-05-27T19:50:13.817475Z","steps":["trace[2133937067] 'agreement among raft nodes before linearized reading' (duration: 177.986358ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:13.817558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.477866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T19:50:13.818121Z","caller":"traceutil/trace.go:171","msg":"trace[1386616707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2177; }","duration":"182.090501ms","start":"2026-05-27T19:50:13.636003Z","end":"2026-05-27T19:50:13.818093Z","steps":["trace[1386616707] 'agreement among raft nodes before linearized reading' (duration: 181.373123ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:51:00.188901Z","caller":"traceutil/trace.go:171","msg":"trace[2083770026] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"154.186642ms","start":"2026-05-27T19:51:00.034685Z","end":"2026-05-27T19:51:00.188872Z","steps":["trace[2083770026] 'process raft request' (duration: 145.671508ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:51:00.189381Z","caller":"traceutil/trace.go:171","msg":"trace[22141521] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"154.011277ms","start":"2026-05-27T19:51:00.035328Z","end":"2026-05-27T19:51:00.189339Z","steps":["trace[22141521] 'process raft request' (duration: 153.503495ms)"],"step_count":1}