{"level":"warn","ts":"2026-04-24T15:23:21.368656Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T15:23:21.368736Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.3: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.3:2380","--initial-cluster=instance=https://199.19.213.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.3: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-04-24T15:23:21.368795Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-24T15:23:21.368804Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.3:2380"]} {"level":"info","ts":"2026-04-24T15:23:21.368825Z","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-04-24T15:23:21.369367Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"]} {"level":"info","ts":"2026-04-24T15:23:21.369434Z","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.3:2380"],"listen-peer-urls":["https://199.19.213.3:2380"],"advertise-client-urls":["https://199.19.213.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.3: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-04-24T15:23:21.385377Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"15.723289ms"} {"level":"info","ts":"2026-04-24T15:23:21.464896Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cf904ec39d3e333","cluster-id":"7b1ae51097bedd0e"} {"level":"info","ts":"2026-04-24T15:23:21.465183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=()"} {"level":"info","ts":"2026-04-24T15:23:21.465226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became follower at term 0"} {"level":"info","ts":"2026-04-24T15:23:21.465366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cf904ec39d3e333 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-24T15:23:21.46554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became follower at term 1"} {"level":"info","ts":"2026-04-24T15:23:21.465676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=(4393548324105610035)"} {"level":"warn","ts":"2026-04-24T15:23:21.532484Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-24T15:23:21.545675Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-24T15:23:21.56795Z","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-04-24T15:23:21.585197Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cf904ec39d3e333","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-24T15:23:21.585529Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cf904ec39d3e333","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-24T15:23:21.585626Z","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-04-24T15:23:21.585737Z","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-04-24T15:23:21.58575Z","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-04-24T15:23:21.589594Z","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-04-24T15:23:21.589725Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.3:2380"} {"level":"info","ts":"2026-04-24T15:23:21.589786Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.3:2380"} {"level":"info","ts":"2026-04-24T15:23:21.590698Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cf904ec39d3e333","initial-advertise-peer-urls":["https://199.19.213.3:2380"],"listen-peer-urls":["https://199.19.213.3:2380"],"advertise-client-urls":["https://199.19.213.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-24T15:23:21.590759Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-24T15:23:21.599808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=(4393548324105610035)"} {"level":"info","ts":"2026-04-24T15:23:21.600077Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7b1ae51097bedd0e","local-member-id":"3cf904ec39d3e333","added-peer-id":"3cf904ec39d3e333","added-peer-peer-urls":["https://199.19.213.3:2380"]} {"level":"info","ts":"2026-04-24T15:23:22.466566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 is starting a new election at term 1"} {"level":"info","ts":"2026-04-24T15:23:22.466616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-24T15:23:22.466633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 received MsgPreVoteResp from 3cf904ec39d3e333 at term 1"} {"level":"info","ts":"2026-04-24T15:23:22.466644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became candidate at term 2"} {"level":"info","ts":"2026-04-24T15:23:22.466649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 received MsgVoteResp from 3cf904ec39d3e333 at term 2"} {"level":"info","ts":"2026-04-24T15:23:22.466657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became leader at term 2"} {"level":"info","ts":"2026-04-24T15:23:22.466664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cf904ec39d3e333 elected leader 3cf904ec39d3e333 at term 2"} {"level":"info","ts":"2026-04-24T15:23:22.514291Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:23:22.51757Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cf904ec39d3e333","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.3:2379]}","request-path":"/0/members/3cf904ec39d3e333/attributes","cluster-id":"7b1ae51097bedd0e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-24T15:23:22.517637Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T15:23:22.518234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-24T15:23:22.522522Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.3:2379"} {"level":"info","ts":"2026-04-24T15:23:22.5227Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-24T15:23:22.522738Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-24T15:23:22.524581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-24T15:23:22.549272Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7b1ae51097bedd0e","local-member-id":"3cf904ec39d3e333","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:23:22.549374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-24T15:23:22.549404Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-24T15:23:24.171326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.1608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:23:24.171617Z","caller":"traceutil/trace.go:171","msg":"trace[416957628] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"317.958863ms","start":"2026-04-24T15:23:23.85362Z","end":"2026-04-24T15:23:24.171579Z","steps":["trace[416957628] 'process raft request' (duration: 175.840937ms)","trace[416957628] 'compare' (duration: 140.98571ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:24.171921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:23.853611Z","time spent":"318.261234ms","remote":"127.0.0.1:51362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:24.17231Z","caller":"traceutil/trace.go:171","msg":"trace[142913174] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:20; }","duration":"258.985541ms","start":"2026-04-24T15:23:23.913301Z","end":"2026-04-24T15:23:24.172287Z","steps":["trace[142913174] 'read index received' (duration: 116.168952ms)","trace[142913174] 'applied index is now lower than readState.Index' (duration: 142.815569ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:24.172427Z","caller":"traceutil/trace.go:171","msg":"trace[231736106] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"312.310915ms","start":"2026-04-24T15:23:23.860106Z","end":"2026-04-24T15:23:24.172416Z","steps":["trace[231736106] 'process raft request' (duration: 312.125424ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:24.17259Z","caller":"traceutil/trace.go:171","msg":"trace[169189000] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"317.854851ms","start":"2026-04-24T15:23:23.854719Z","end":"2026-04-24T15:23:24.172574Z","steps":["trace[169189000] 'process raft request' (duration: 316.729986ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:24.172613Z","caller":"traceutil/trace.go:171","msg":"trace[1593547149] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"317.119148ms","start":"2026-04-24T15:23:23.855471Z","end":"2026-04-24T15:23:24.17259Z","steps":["trace[1593547149] 'process raft request' (duration: 316.666216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:24.172684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:23.854711Z","time spent":"317.935302ms","remote":"127.0.0.1:51362","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:24.172702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:23.855462Z","time spent":"317.194689ms","remote":"127.0.0.1:51800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:24.172747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:23.860097Z","time spent":"312.367345ms","remote":"127.0.0.1:51414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3477,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:23:24.17291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.726412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-24T15:23:24.172948Z","caller":"traceutil/trace.go:171","msg":"trace[698152182] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"307.784283ms","start":"2026-04-24T15:23:23.865153Z","end":"2026-04-24T15:23:24.172937Z","steps":["trace[698152182] 'agreement among raft nodes before linearized reading' (duration: 307.716923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:24.172977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:23.865142Z","time spent":"307.828613ms","remote":"127.0.0.1:51362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-24T15:23:24.173094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.332924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3489"} {"level":"info","ts":"2026-04-24T15:23:24.173125Z","caller":"traceutil/trace.go:171","msg":"trace[60279732] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"318.384694ms","start":"2026-04-24T15:23:23.854732Z","end":"2026-04-24T15:23:24.173117Z","steps":["trace[60279732] 'agreement among raft nodes before linearized reading' (duration: 318.340234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:24.173149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:23.854721Z","time spent":"318.423004ms","remote":"127.0.0.1:51414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3511,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-24T15:23:24.173292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.644666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-zmv42\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-24T15:23:24.173324Z","caller":"traceutil/trace.go:171","msg":"trace[820512783] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-zmv42; range_end:; response_count:1; response_revision:20; }","duration":"318.699786ms","start":"2026-04-24T15:23:23.854616Z","end":"2026-04-24T15:23:24.173316Z","steps":["trace[820512783] 'agreement among raft nodes before linearized reading' (duration: 318.649835ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:24.173352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:23.854607Z","time spent":"318.738426ms","remote":"127.0.0.1:51510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":883,"request content":"key:\"/registry/certificatesigningrequests/csr-zmv42\" "} {"level":"info","ts":"2026-04-24T15:23:24.183284Z","caller":"traceutil/trace.go:171","msg":"trace[364322558] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"265.858325ms","start":"2026-04-24T15:23:23.917407Z","end":"2026-04-24T15:23:24.183266Z","steps":["trace[364322558] 'process raft request' (duration: 265.772824ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:24.183452Z","caller":"traceutil/trace.go:171","msg":"trace[1672814101] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"266.217347ms","start":"2026-04-24T15:23:23.917224Z","end":"2026-04-24T15:23:24.183442Z","steps":["trace[1672814101] 'process raft request' (duration: 265.881045ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:24.183499Z","caller":"traceutil/trace.go:171","msg":"trace[2088347310] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"265.996276ms","start":"2026-04-24T15:23:23.917488Z","end":"2026-04-24T15:23:24.183484Z","steps":["trace[2088347310] 'process raft request' (duration: 265.723764ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:24.183652Z","caller":"traceutil/trace.go:171","msg":"trace[671096385] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"265.956315ms","start":"2026-04-24T15:23:23.917686Z","end":"2026-04-24T15:23:24.183643Z","steps":["trace[671096385] 'process raft request' (duration: 265.561004ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:24.183747Z","caller":"traceutil/trace.go:171","msg":"trace[217608977] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"266.011296ms","start":"2026-04-24T15:23:23.917726Z","end":"2026-04-24T15:23:24.183737Z","steps":["trace[217608977] 'process raft request' (duration: 265.549204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:24.183983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.698462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-04-24T15:23:24.18404Z","caller":"traceutil/trace.go:171","msg":"trace[1079144855] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:25; }","duration":"210.802742ms","start":"2026-04-24T15:23:23.973224Z","end":"2026-04-24T15:23:24.184027Z","steps":["trace[1079144855] 'agreement among raft nodes before linearized reading' (duration: 210.606481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:24.682281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.788921ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:23:24.682675Z","caller":"traceutil/trace.go:171","msg":"trace[971505758] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"159.332809ms","start":"2026-04-24T15:23:24.523317Z","end":"2026-04-24T15:23:24.682649Z","steps":["trace[971505758] 'process raft request' (duration: 159.207969ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:24.682817Z","caller":"traceutil/trace.go:171","msg":"trace[1289399560] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"164.687065ms","start":"2026-04-24T15:23:24.518082Z","end":"2026-04-24T15:23:24.682769Z","steps":["trace[1289399560] 'process raft request' (duration: 41.319991ms)","trace[1289399560] 'compare' (duration: 122.673221ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:25.029684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.069563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-24T15:23:25.029854Z","caller":"traceutil/trace.go:171","msg":"trace[400189315] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"342.667784ms","start":"2026-04-24T15:23:24.687156Z","end":"2026-04-24T15:23:25.029824Z","steps":["trace[400189315] 'process raft request' (duration: 198.31961ms)","trace[400189315] 'compare' (duration: 143.862932ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:25.029927Z","caller":"traceutil/trace.go:171","msg":"trace[2021048101] linearizableReadLoop","detail":"{readStateIndex:67; appliedIndex:65; }","duration":"335.61449ms","start":"2026-04-24T15:23:24.694279Z","end":"2026-04-24T15:23:25.029893Z","steps":["trace[2021048101] 'read index received' (duration: 6.723353ms)","trace[2021048101] 'applied index is now lower than readState.Index' (duration: 328.889087ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:25.029958Z","caller":"traceutil/trace.go:171","msg":"trace[1419941621] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"326.075443ms","start":"2026-04-24T15:23:24.70385Z","end":"2026-04-24T15:23:25.029925Z","steps":["trace[1419941621] 'process raft request' (duration: 325.996753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:25.030051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:24.703823Z","time spent":"326.195684ms","remote":"127.0.0.1:51708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":581,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:25.030077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:24.68713Z","time spent":"342.835665ms","remote":"127.0.0.1:51708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1089,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:23:25.030296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.921632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-24T15:23:25.03035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.057782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:25.030355Z","caller":"traceutil/trace.go:171","msg":"trace[1386203556] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:64; }","duration":"336.019672ms","start":"2026-04-24T15:23:24.694322Z","end":"2026-04-24T15:23:25.030342Z","steps":["trace[1386203556] 'agreement among raft nodes before linearized reading' (duration: 335.894232ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:25.030378Z","caller":"traceutil/trace.go:171","msg":"trace[2092810048] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:64; }","duration":"336.123352ms","start":"2026-04-24T15:23:24.694246Z","end":"2026-04-24T15:23:25.030369Z","steps":["trace[2092810048] 'agreement among raft nodes before linearized reading' (duration: 335.762731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:25.030428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:24.694234Z","time spent":"336.168143ms","remote":"127.0.0.1:51626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-24T15:23:25.030389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:24.694315Z","time spent":"336.065793ms","remote":"127.0.0.1:51604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-24T15:23:25.030626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.449407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:25.03068Z","caller":"traceutil/trace.go:171","msg":"trace[717638507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:64; }","duration":"312.526797ms","start":"2026-04-24T15:23:24.718132Z","end":"2026-04-24T15:23:25.030659Z","steps":["trace[717638507] 'agreement among raft nodes before linearized reading' (duration: 312.458866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:25.030716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:24.718117Z","time spent":"312.592488ms","remote":"127.0.0.1:51228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-24T15:23:25.614396Z","caller":"traceutil/trace.go:171","msg":"trace[748161615] transaction","detail":"{read_only:false; response_revision:93; number_of_response:1; }","duration":"115.689291ms","start":"2026-04-24T15:23:25.498675Z","end":"2026-04-24T15:23:25.614364Z","steps":["trace[748161615] 'process raft request' (duration: 57.469298ms)","trace[748161615] 'compare' (duration: 58.055142ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:25.801327Z","caller":"traceutil/trace.go:171","msg":"trace[744998657] transaction","detail":"{read_only:false; response_revision:98; number_of_response:1; }","duration":"113.776092ms","start":"2026-04-24T15:23:25.687506Z","end":"2026-04-24T15:23:25.801282Z","steps":["trace[744998657] 'process raft request' (duration: 37.730872ms)","trace[744998657] 'compare' (duration: 75.468836ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:26.347287Z","caller":"traceutil/trace.go:171","msg":"trace[1106581946] linearizableReadLoop","detail":"{readStateIndex:122; appliedIndex:121; }","duration":"129.816583ms","start":"2026-04-24T15:23:26.217432Z","end":"2026-04-24T15:23:26.347248Z","steps":["trace[1106581946] 'read index received' (duration: 123.453922ms)","trace[1106581946] 'applied index is now lower than readState.Index' (duration: 6.360971ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:23:26.347414Z","caller":"traceutil/trace.go:171","msg":"trace[1615220294] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"149.460919ms","start":"2026-04-24T15:23:26.197899Z","end":"2026-04-24T15:23:26.34736Z","steps":["trace[1615220294] 'process raft request' (duration: 143.067217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:26.347871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.390556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-24T15:23:26.347936Z","caller":"traceutil/trace.go:171","msg":"trace[777149403] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:118; }","duration":"130.515188ms","start":"2026-04-24T15:23:26.217403Z","end":"2026-04-24T15:23:26.347918Z","steps":["trace[777149403] 'agreement among raft nodes before linearized reading' (duration: 130.013745ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:43.931048Z","caller":"traceutil/trace.go:171","msg":"trace[908609308] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"102.016297ms","start":"2026-04-24T15:23:43.828986Z","end":"2026-04-24T15:23:43.931003Z","steps":["trace[908609308] 'process raft request' (duration: 101.843039ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:46.099283Z","caller":"traceutil/trace.go:171","msg":"trace[721806559] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"161.086719ms","start":"2026-04-24T15:23:45.938178Z","end":"2026-04-24T15:23:46.099265Z","steps":["trace[721806559] 'process raft request' (duration: 160.971024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:46.646828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.57697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:23:46.646894Z","caller":"traceutil/trace.go:171","msg":"trace[274263137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"188.693635ms","start":"2026-04-24T15:23:46.458182Z","end":"2026-04-24T15:23:46.646875Z","steps":["trace[274263137] 'range keys from in-memory index tree' (duration: 188.491487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:46.646842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.859582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-24T15:23:46.647275Z","caller":"traceutil/trace.go:171","msg":"trace[124522066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:460; }","duration":"189.329692ms","start":"2026-04-24T15:23:46.457927Z","end":"2026-04-24T15:23:46.647257Z","steps":["trace[124522066] 'range keys from in-memory index tree' (duration: 188.761258ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:50.65946Z","caller":"traceutil/trace.go:171","msg":"trace[622017192] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"540.599636ms","start":"2026-04-24T15:23:50.118833Z","end":"2026-04-24T15:23:50.659433Z","steps":["trace[622017192] 'process raft request' (duration: 540.432499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:50.659617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.118805Z","time spent":"540.737502ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:23:50.671831Z","caller":"traceutil/trace.go:171","msg":"trace[1669762597] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"473.096354ms","start":"2026-04-24T15:23:50.198707Z","end":"2026-04-24T15:23:50.671803Z","steps":["trace[1669762597] 'process raft request' (duration: 472.852764ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:50.671962Z","caller":"traceutil/trace.go:171","msg":"trace[707756285] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"463.845082ms","start":"2026-04-24T15:23:50.208087Z","end":"2026-04-24T15:23:50.671933Z","steps":["trace[707756285] 'process raft request' (duration: 463.664904ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:50.672018Z","caller":"traceutil/trace.go:171","msg":"trace[558733348] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"460.764405ms","start":"2026-04-24T15:23:50.211241Z","end":"2026-04-24T15:23:50.672005Z","steps":["trace[558733348] 'process raft request' (duration: 460.628069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:50.672004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.198687Z","time spent":"473.258122ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:50.672095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.21122Z","time spent":"460.833358ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:50.672109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.208083Z","time spent":"463.957797ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:50.67187Z","caller":"traceutil/trace.go:171","msg":"trace[232809871] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"463.860172ms","start":"2026-04-24T15:23:50.207981Z","end":"2026-04-24T15:23:50.671841Z","steps":["trace[232809871] 'process raft request' (duration: 463.700595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:50.672258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.207967Z","time spent":"464.232418ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:50.672412Z","caller":"traceutil/trace.go:171","msg":"trace[172956641] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"456.157764ms","start":"2026-04-24T15:23:50.216208Z","end":"2026-04-24T15:23:50.672366Z","steps":["trace[172956641] 'process raft request' (duration: 455.987347ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:50.67248Z","caller":"traceutil/trace.go:171","msg":"trace[201877190] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"455.867122ms","start":"2026-04-24T15:23:50.216599Z","end":"2026-04-24T15:23:50.672466Z","steps":["trace[201877190] 'process raft request' (duration: 455.746247ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:50.672549Z","caller":"traceutil/trace.go:171","msg":"trace[750586805] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"454.226364ms","start":"2026-04-24T15:23:50.218313Z","end":"2026-04-24T15:23:50.672539Z","steps":["trace[750586805] 'process raft request' (duration: 454.103599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:50.672626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.218301Z","time spent":"454.290106ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:50.672639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.216191Z","time spent":"456.348282ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:50.672555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.216593Z","time spent":"455.937025ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:50.672775Z","caller":"traceutil/trace.go:171","msg":"trace[1439256858] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"428.907658ms","start":"2026-04-24T15:23:50.24386Z","end":"2026-04-24T15:23:50.672768Z","steps":["trace[1439256858] 'process raft request' (duration: 428.613975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:50.672829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.243838Z","time spent":"428.97508ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:50.672891Z","caller":"traceutil/trace.go:171","msg":"trace[2110418832] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"424.711454ms","start":"2026-04-24T15:23:50.248167Z","end":"2026-04-24T15:23:50.672879Z","steps":["trace[2110418832] 'process raft request' (duration: 424.400771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:50.672975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.248147Z","time spent":"424.780047ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:50.673052Z","caller":"traceutil/trace.go:171","msg":"trace[687624036] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"408.92711ms","start":"2026-04-24T15:23:50.264105Z","end":"2026-04-24T15:23:50.673032Z","steps":["trace[687624036] 'process raft request' (duration: 408.691681ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:50.672833Z","caller":"traceutil/trace.go:171","msg":"trace[67640446] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"459.925361ms","start":"2026-04-24T15:23:50.212359Z","end":"2026-04-24T15:23:50.672284Z","steps":["trace[67640446] 'process raft request' (duration: 459.591407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:50.673157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.264088Z","time spent":"409.020494ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:50.673193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:50.212349Z","time spent":"460.778185ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:23:50.673326Z","caller":"traceutil/trace.go:171","msg":"trace[793197324] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"299.337379ms","start":"2026-04-24T15:23:50.373912Z","end":"2026-04-24T15:23:50.673249Z","steps":["trace[793197324] 'process raft request' (duration: 299.088829ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:56.673427Z","caller":"traceutil/trace.go:171","msg":"trace[889899422] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"205.891908ms","start":"2026-04-24T15:23:56.467509Z","end":"2026-04-24T15:23:56.673401Z","steps":["trace[889899422] 'process raft request' (duration: 204.057218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:57.959735Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371602518760750595,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-24T15:23:57.981389Z","caller":"traceutil/trace.go:171","msg":"trace[824820786] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"522.63208ms","start":"2026-04-24T15:23:57.45873Z","end":"2026-04-24T15:23:57.981362Z","steps":["trace[824820786] 'read index received' (duration: 521.839589ms)","trace[824820786] 'applied index is now lower than readState.Index' (duration: 791.591µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:57.981529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.785786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:23:57.981589Z","caller":"traceutil/trace.go:171","msg":"trace[558459452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:617; }","duration":"522.904231ms","start":"2026-04-24T15:23:57.45867Z","end":"2026-04-24T15:23:57.981575Z","steps":["trace[558459452] 'agreement among raft nodes before linearized reading' (duration: 522.793397ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:57.981492Z","caller":"traceutil/trace.go:171","msg":"trace[1706572562] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"771.833366ms","start":"2026-04-24T15:23:57.209599Z","end":"2026-04-24T15:23:57.981432Z","steps":["trace[1706572562] 'process raft request' (duration: 771.267204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:57.981631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:57.458645Z","time spent":"522.978964ms","remote":"127.0.0.1:51238","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-04-24T15:23:57.981857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.56521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bsr7r\" ","response":"range_response_count:1 size:16957"} {"level":"warn","ts":"2026-04-24T15:23:57.981889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:57.209577Z","time spent":"772.109546ms","remote":"127.0.0.1:51354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:23:57.981957Z","caller":"traceutil/trace.go:171","msg":"trace[339819755] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bsr7r; range_end:; response_count:1; response_revision:617; }","duration":"336.709226ms","start":"2026-04-24T15:23:57.645231Z","end":"2026-04-24T15:23:57.98194Z","steps":["trace[339819755] 'agreement among raft nodes before linearized reading' (duration: 336.433335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:57.982102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:57.64521Z","time spent":"336.876292ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16979,"request content":"key:\"/registry/pods/kube-system/cilium-bsr7r\" "} {"level":"info","ts":"2026-04-24T15:23:58.139943Z","caller":"traceutil/trace.go:171","msg":"trace[477408454] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"143.896677ms","start":"2026-04-24T15:23:57.996022Z","end":"2026-04-24T15:23:58.139919Z","steps":["trace[477408454] 'process raft request' (duration: 143.751921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:58.306481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.445838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:23:58.306636Z","caller":"traceutil/trace.go:171","msg":"trace[730748084] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"266.373045ms","start":"2026-04-24T15:23:58.040239Z","end":"2026-04-24T15:23:58.306612Z","steps":["trace[730748084] 'process raft request' (duration: 154.676018ms)","trace[730748084] 'compare' (duration: 111.24141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:58.958746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371602518760750603,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:23:59.221571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.138668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:23:59.221742Z","caller":"traceutil/trace.go:171","msg":"trace[879589594] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"133.530984ms","start":"2026-04-24T15:23:59.088197Z","end":"2026-04-24T15:23:59.221728Z","steps":["trace[879589594] 'process raft request' (duration: 133.456562ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:59.221916Z","caller":"traceutil/trace.go:171","msg":"trace[366625958] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"764.199308ms","start":"2026-04-24T15:23:58.457667Z","end":"2026-04-24T15:23:59.221866Z","steps":["trace[366625958] 'read index received' (duration: 609.719346ms)","trace[366625958] 'applied index is now lower than readState.Index' (duration: 154.471112ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:59.222135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.456388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:23:59.223155Z","caller":"traceutil/trace.go:171","msg":"trace[2026782046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"765.511557ms","start":"2026-04-24T15:23:58.45762Z","end":"2026-04-24T15:23:59.223132Z","steps":["trace[2026782046] 'agreement among raft nodes before linearized reading' (duration: 764.438538ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:23:59.222135Z","caller":"traceutil/trace.go:171","msg":"trace[1671299103] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"897.661411ms","start":"2026-04-24T15:23:58.324361Z","end":"2026-04-24T15:23:59.222023Z","steps":["trace[1671299103] 'process raft request' (duration: 743.017103ms)","trace[1671299103] 'compare' (duration: 153.9021ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:23:59.223287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:58.457599Z","time spent":"765.669123ms","remote":"127.0.0.1:51228","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-04-24T15:23:59.22332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:58.324339Z","time spent":"898.886396ms","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:23:59.222203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.841238ms","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-04-24T15:23:59.223595Z","caller":"traceutil/trace.go:171","msg":"trace[292438021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"517.272081ms","start":"2026-04-24T15:23:58.706302Z","end":"2026-04-24T15:23:59.223574Z","steps":["trace[292438021] 'agreement among raft nodes before linearized reading' (duration: 515.813388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:59.223668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:58.706284Z","time spent":"517.365805ms","remote":"127.0.0.1:51512","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-04-24T15:23:59.222314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.933516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:23:59.223886Z","caller":"traceutil/trace.go:171","msg":"trace[198880417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"396.542985ms","start":"2026-04-24T15:23:58.827329Z","end":"2026-04-24T15:23:59.223872Z","steps":["trace[198880417] 'agreement among raft nodes before linearized reading' (duration: 394.891544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:59.223927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:58.827305Z","time spent":"396.612178ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T15:23:59.222369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.483442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-24T15:23:59.224111Z","caller":"traceutil/trace.go:171","msg":"trace[607423479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"395.277768ms","start":"2026-04-24T15:23:58.82882Z","end":"2026-04-24T15:23:59.224098Z","steps":["trace[607423479] 'agreement among raft nodes before linearized reading' (duration: 393.309646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:59.224145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:58.828812Z","time spent":"395.32328ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T15:23:59.222377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.342377ms","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-04-24T15:23:59.224268Z","caller":"traceutil/trace.go:171","msg":"trace[1767272624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"395.293218ms","start":"2026-04-24T15:23:58.828966Z","end":"2026-04-24T15:23:59.224259Z","steps":["trace[1767272624] 'agreement among raft nodes before linearized reading' (duration: 393.239193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:23:59.224303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:58.828941Z","time spent":"395.35432ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-24T15:23:59.958104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371602518760750608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:24:00.459207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371602518760750608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:24:00.960171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371602518760750608,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:24:00.982892Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.694955898s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-24T15:24:01.458106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000307768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-24T15:24:01.458188Z","caller":"traceutil/trace.go:171","msg":"trace[721439584] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000447033s","start":"2026-04-24T15:23:59.457722Z","end":"2026-04-24T15:24:01.458169Z","steps":["trace[721439584] 'agreement among raft nodes before linearized reading' (duration: 2.000347999s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:01.45824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.457696Z","time spent":"2.000535566s","remote":"127.0.0.1:51228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-24T15:24:02.217275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.033192ms","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-04-24T15:24:02.218937Z","caller":"traceutil/trace.go:171","msg":"trace[382056522] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:622; }","duration":"903.68581ms","start":"2026-04-24T15:24:01.315206Z","end":"2026-04-24T15:24:02.218892Z","steps":["trace[382056522] 'range keys from in-memory index tree' (duration: 902.015931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:02.217369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234208231s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:24:02.219207Z","caller":"traceutil/trace.go:171","msg":"trace[129292935] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"2.991983552s","start":"2026-04-24T15:23:59.227203Z","end":"2026-04-24T15:24:02.219186Z","steps":["trace[129292935] 'process raft request' (duration: 1.755880844s)","trace[129292935] 'compare' (duration: 1.233982733s)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:24:02.21931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.227169Z","time spent":"2.992090176s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:24:02.219444Z","caller":"traceutil/trace.go:171","msg":"trace[1312148998] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"2.987894921s","start":"2026-04-24T15:23:59.231535Z","end":"2026-04-24T15:24:02.21943Z","steps":["trace[1312148998] 'process raft request' (duration: 2.98785671s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:02.219539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.231521Z","time spent":"2.987968223s","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:24:02.219562Z","caller":"traceutil/trace.go:171","msg":"trace[1647941299] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"2.9900051s","start":"2026-04-24T15:23:59.229525Z","end":"2026-04-24T15:24:02.21953Z","steps":["trace[1647941299] 'process raft request' (duration: 2.989803102s)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:02.219663Z","caller":"traceutil/trace.go:171","msg":"trace[822865174] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"2.990704854s","start":"2026-04-24T15:23:59.228934Z","end":"2026-04-24T15:24:02.219639Z","steps":["trace[822865174] 'process raft request' (duration: 2.990256558s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:02.219757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.229506Z","time spent":"2.990133824s","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:24:02.219806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.228921Z","time spent":"2.990821909s","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:24:02.219795Z","caller":"traceutil/trace.go:171","msg":"trace[822374921] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"2.989155277s","start":"2026-04-24T15:23:59.230614Z","end":"2026-04-24T15:24:02.219769Z","steps":["trace[822374921] 'process raft request' (duration: 2.988748303s)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:02.219844Z","caller":"traceutil/trace.go:171","msg":"trace[2026309282] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.990835939s","start":"2026-04-24T15:23:59.228991Z","end":"2026-04-24T15:24:02.219827Z","steps":["trace[2026309282] 'process raft request' (duration: 2.990307359s)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:02.219788Z","caller":"traceutil/trace.go:171","msg":"trace[1409458834] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"2.991245435s","start":"2026-04-24T15:23:59.228516Z","end":"2026-04-24T15:24:02.219761Z","steps":["trace[1409458834] 'process raft request' (duration: 2.990596931s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:02.219954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.230601Z","time spent":"2.98925611s","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:24:02.219983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.228961Z","time spent":"2.990936083s","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:24:02.221169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.228492Z","time spent":"2.991483042s","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:24:02.282993Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.299878207s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T15:24:02.283294Z","caller":"traceutil/trace.go:171","msg":"trace[1111524666] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:645; }","duration":"2.825496242s","start":"2026-04-24T15:23:59.457782Z","end":"2026-04-24T15:24:02.283278Z","steps":["trace[1111524666] 'read index received' (duration: 1.525314115s)","trace[1111524666] 'applied index is now lower than readState.Index' (duration: 1.300181487s)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:02.283419Z","caller":"traceutil/trace.go:171","msg":"trace[2073708114] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"2.867756637s","start":"2026-04-24T15:23:59.415637Z","end":"2026-04-24T15:24:02.283394Z","steps":["trace[2073708114] 'process raft request' (duration: 2.86755683s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:02.283548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:23:59.415607Z","time spent":"2.867881952s","remote":"127.0.0.1:51414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4537,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:24:02.439948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.298601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:24:02.440124Z","caller":"traceutil/trace.go:171","msg":"trace[800351525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"981.525679ms","start":"2026-04-24T15:24:01.458579Z","end":"2026-04-24T15:24:02.440105Z","steps":["trace[800351525] 'agreement among raft nodes before linearized reading' (duration: 981.294021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:02.44017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:01.45856Z","time spent":"981.598632ms","remote":"127.0.0.1:51238","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-04-24T15:24:02.440302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147824042s","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-04-24T15:24:02.440385Z","caller":"traceutil/trace.go:171","msg":"trace[687750121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"1.147959086s","start":"2026-04-24T15:24:01.292408Z","end":"2026-04-24T15:24:02.440367Z","steps":["trace[687750121] 'agreement among raft nodes before linearized reading' (duration: 1.147697857s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:02.440435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:01.292388Z","time spent":"1.14803863s","remote":"127.0.0.1:51512","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-04-24T15:24:02.439837Z","caller":"traceutil/trace.go:171","msg":"trace[1212749342] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"156.474131ms","start":"2026-04-24T15:24:02.28334Z","end":"2026-04-24T15:24:02.439814Z","steps":["trace[1212749342] 'read index received' (duration: 88.894267ms)","trace[1212749342] 'applied index is now lower than readState.Index' (duration: 67.578424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:24:04.469871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.566105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:24:04.469966Z","caller":"traceutil/trace.go:171","msg":"trace[410035248] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:659; }","duration":"609.692449ms","start":"2026-04-24T15:24:03.860245Z","end":"2026-04-24T15:24:04.469938Z","steps":["trace[410035248] 'range keys from in-memory index tree' (duration: 609.468671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:04.470008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:03.86023Z","time spent":"609.768422ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-04-24T15:24:04.470028Z","caller":"traceutil/trace.go:171","msg":"trace[1767900293] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"245.539836ms","start":"2026-04-24T15:24:04.224456Z","end":"2026-04-24T15:24:04.469996Z","steps":["trace[1767900293] 'read index received' (duration: 215.952603ms)","trace[1767900293] 'applied index is now lower than readState.Index' (duration: 29.585313ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:04.470138Z","caller":"traceutil/trace.go:171","msg":"trace[623675298] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"407.856749ms","start":"2026-04-24T15:24:04.06227Z","end":"2026-04-24T15:24:04.470127Z","steps":["trace[623675298] 'process raft request' (duration: 378.122961ms)","trace[623675298] 'compare' (duration: 29.200439ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:24:04.470226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:04.062244Z","time spent":"407.921761ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-24T15:24:04.47046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.992012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-24T15:24:04.470516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.043954ms","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-04-24T15:24:04.470555Z","caller":"traceutil/trace.go:171","msg":"trace[221876174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"246.117366ms","start":"2026-04-24T15:24:04.224405Z","end":"2026-04-24T15:24:04.470522Z","steps":["trace[221876174] 'agreement among raft nodes before linearized reading' (duration: 245.861477ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:04.470606Z","caller":"traceutil/trace.go:171","msg":"trace[2023179249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"246.143277ms","start":"2026-04-24T15:24:04.224445Z","end":"2026-04-24T15:24:04.470589Z","steps":["trace[2023179249] 'agreement among raft nodes before linearized reading' (duration: 245.833527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:04.514448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.883247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:24:04.514545Z","caller":"traceutil/trace.go:171","msg":"trace[1513886462] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:661; }","duration":"252.011562ms","start":"2026-04-24T15:24:04.26251Z","end":"2026-04-24T15:24:04.514521Z","steps":["trace[1513886462] 'agreement among raft nodes before linearized reading' (duration: 251.878597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:04.514579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.374756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-24T15:24:04.514647Z","caller":"traceutil/trace.go:171","msg":"trace[62196472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"287.48859ms","start":"2026-04-24T15:24:04.227142Z","end":"2026-04-24T15:24:04.51463Z","steps":["trace[62196472] 'agreement among raft nodes before linearized reading' (duration: 287.124478ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:04.591562Z","caller":"traceutil/trace.go:171","msg":"trace[159219008] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"117.885333ms","start":"2026-04-24T15:24:04.473657Z","end":"2026-04-24T15:24:04.591543Z","steps":["trace[159219008] 'process raft request' (duration: 114.821456ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:04.591958Z","caller":"traceutil/trace.go:171","msg":"trace[2141650127] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"117.458906ms","start":"2026-04-24T15:24:04.474471Z","end":"2026-04-24T15:24:04.59193Z","steps":["trace[2141650127] 'process raft request' (duration: 117.019041ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:04.592263Z","caller":"traceutil/trace.go:171","msg":"trace[1591041692] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"117.52978ms","start":"2026-04-24T15:24:04.474714Z","end":"2026-04-24T15:24:04.592244Z","steps":["trace[1591041692] 'process raft request' (duration: 117.118445ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:04.807842Z","caller":"traceutil/trace.go:171","msg":"trace[1120685973] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"131.176396ms","start":"2026-04-24T15:24:04.676637Z","end":"2026-04-24T15:24:04.807814Z","steps":["trace[1120685973] 'read index received' (duration: 130.99939ms)","trace[1120685973] 'applied index is now lower than readState.Index' (duration: 176.366µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:04.80793Z","caller":"traceutil/trace.go:171","msg":"trace[885118530] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"144.315836ms","start":"2026-04-24T15:24:04.663581Z","end":"2026-04-24T15:24:04.807897Z","steps":["trace[885118530] 'process raft request' (duration: 144.137179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:04.808033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.381744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bsr7r\" ","response":"range_response_count:1 size:17516"} {"level":"info","ts":"2026-04-24T15:24:04.808066Z","caller":"traceutil/trace.go:171","msg":"trace[1526317437] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bsr7r; range_end:; response_count:1; response_revision:669; }","duration":"131.464516ms","start":"2026-04-24T15:24:04.676591Z","end":"2026-04-24T15:24:04.808055Z","steps":["trace[1526317437] 'agreement among raft nodes before linearized reading' (duration: 131.311691ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.052243Z","caller":"traceutil/trace.go:171","msg":"trace[429384036] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"370.048499ms","start":"2026-04-24T15:24:04.682172Z","end":"2026-04-24T15:24:05.052221Z","steps":["trace[429384036] 'process raft request' (duration: 369.922515ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.052243Z","caller":"traceutil/trace.go:171","msg":"trace[765886912] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"371.216491ms","start":"2026-04-24T15:24:04.680949Z","end":"2026-04-24T15:24:05.052166Z","steps":["trace[765886912] 'process raft request' (duration: 355.690456ms)","trace[765886912] 'compare' (duration: 15.189564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:24:05.052385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:04.682153Z","time spent":"370.159823ms","remote":"127.0.0.1:51286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4318,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:24:05.05256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:04.680924Z","time spent":"371.529292ms","remote":"127.0.0.1:51322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:24:05.079885Z","caller":"traceutil/trace.go:171","msg":"trace[769134656] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"251.857575ms","start":"2026-04-24T15:24:04.828004Z","end":"2026-04-24T15:24:05.079862Z","steps":["trace[769134656] 'process raft request' (duration: 251.632587ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.080193Z","caller":"traceutil/trace.go:171","msg":"trace[649827813] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"244.099354ms","start":"2026-04-24T15:24:04.836047Z","end":"2026-04-24T15:24:05.080146Z","steps":["trace[649827813] 'process raft request' (duration: 243.738462ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.080315Z","caller":"traceutil/trace.go:171","msg":"trace[572008884] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"216.99749ms","start":"2026-04-24T15:24:04.863295Z","end":"2026-04-24T15:24:05.080292Z","steps":["trace[572008884] 'process raft request' (duration: 216.756482ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.208424Z","caller":"traceutil/trace.go:171","msg":"trace[506156602] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"118.979472ms","start":"2026-04-24T15:24:05.089417Z","end":"2026-04-24T15:24:05.208396Z","steps":["trace[506156602] 'process raft request' (duration: 118.237047ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.219193Z","caller":"traceutil/trace.go:171","msg":"trace[387381417] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"128.540842ms","start":"2026-04-24T15:24:05.090621Z","end":"2026-04-24T15:24:05.219162Z","steps":["trace[387381417] 'process raft request' (duration: 128.299234ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.448289Z","caller":"traceutil/trace.go:171","msg":"trace[1227945123] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"186.720798ms","start":"2026-04-24T15:24:05.261543Z","end":"2026-04-24T15:24:05.448263Z","steps":["trace[1227945123] 'read index received' (duration: 186.547122ms)","trace[1227945123] 'applied index is now lower than readState.Index' (duration: 172.466µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:24:05.448445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.889153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:24:05.448471Z","caller":"traceutil/trace.go:171","msg":"trace[1910928380] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"219.59346ms","start":"2026-04-24T15:24:05.228732Z","end":"2026-04-24T15:24:05.448326Z","steps":["trace[1910928380] 'process raft request' (duration: 219.396373ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.448489Z","caller":"traceutil/trace.go:171","msg":"trace[980913136] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:678; }","duration":"187.028558ms","start":"2026-04-24T15:24:05.261448Z","end":"2026-04-24T15:24:05.448477Z","steps":["trace[980913136] 'agreement among raft nodes before linearized reading' (duration: 186.904474ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:05.820271Z","caller":"traceutil/trace.go:171","msg":"trace[17169096] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"136.114943ms","start":"2026-04-24T15:24:05.684134Z","end":"2026-04-24T15:24:05.820249Z","steps":["trace[17169096] 'read index received' (duration: 97.220472ms)","trace[17169096] 'applied index is now lower than readState.Index' (duration: 38.893491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:24:05.82052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.371931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bsr7r\" ","response":"range_response_count:1 size:17782"} {"level":"info","ts":"2026-04-24T15:24:05.820504Z","caller":"traceutil/trace.go:171","msg":"trace[408804914] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"143.156776ms","start":"2026-04-24T15:24:05.677313Z","end":"2026-04-24T15:24:05.82047Z","steps":["trace[408804914] 'process raft request' (duration: 104.007436ms)","trace[408804914] 'compare' (duration: 38.842039ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:05.820554Z","caller":"traceutil/trace.go:171","msg":"trace[301398585] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bsr7r; range_end:; response_count:1; response_revision:681; }","duration":"136.455745ms","start":"2026-04-24T15:24:05.684088Z","end":"2026-04-24T15:24:05.820544Z","steps":["trace[301398585] 'agreement among raft nodes before linearized reading' (duration: 136.249938ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:08.05558Z","caller":"traceutil/trace.go:171","msg":"trace[1712556789] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"194.944896ms","start":"2026-04-24T15:24:07.860611Z","end":"2026-04-24T15:24:08.055555Z","steps":["trace[1712556789] 'read index received' (duration: 191.622056ms)","trace[1712556789] 'applied index is now lower than readState.Index' (duration: 3.32168ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:08.055735Z","caller":"traceutil/trace.go:171","msg":"trace[1373193613] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"227.284584ms","start":"2026-04-24T15:24:07.8284Z","end":"2026-04-24T15:24:08.055684Z","steps":["trace[1373193613] 'process raft request' (duration: 223.890362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:08.055788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.168013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-24T15:24:08.055822Z","caller":"traceutil/trace.go:171","msg":"trace[543729351] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:708; }","duration":"195.249185ms","start":"2026-04-24T15:24:07.860563Z","end":"2026-04-24T15:24:08.055812Z","steps":["trace[543729351] 'agreement among raft nodes before linearized reading' (duration: 195.08665ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:12.706863Z","caller":"traceutil/trace.go:171","msg":"trace[441063316] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"151.554197ms","start":"2026-04-24T15:24:12.55526Z","end":"2026-04-24T15:24:12.706815Z","steps":["trace[441063316] 'process raft request' (duration: 95.943909ms)","trace[441063316] 'compare' (duration: 55.433552ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:12.858113Z","caller":"traceutil/trace.go:171","msg":"trace[1019297827] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"145.121403ms","start":"2026-04-24T15:24:12.712938Z","end":"2026-04-24T15:24:12.858059Z","steps":["trace[1019297827] 'process raft request' (duration: 144.613987ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:12.858088Z","caller":"traceutil/trace.go:171","msg":"trace[947132512] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"144.908886ms","start":"2026-04-24T15:24:12.713163Z","end":"2026-04-24T15:24:12.858072Z","steps":["trace[947132512] 'process raft request' (duration: 144.835264ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:12.858491Z","caller":"traceutil/trace.go:171","msg":"trace[1666703670] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"145.148564ms","start":"2026-04-24T15:24:12.713315Z","end":"2026-04-24T15:24:12.858464Z","steps":["trace[1666703670] 'process raft request' (duration: 144.72435ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:12.858569Z","caller":"traceutil/trace.go:171","msg":"trace[1952028507] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"139.681659ms","start":"2026-04-24T15:24:12.718869Z","end":"2026-04-24T15:24:12.85855Z","steps":["trace[1952028507] 'process raft request' (duration: 139.38ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:13.069193Z","caller":"traceutil/trace.go:171","msg":"trace[1747246877] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:769; }","duration":"155.301002ms","start":"2026-04-24T15:24:12.913865Z","end":"2026-04-24T15:24:13.069166Z","steps":["trace[1747246877] 'read index received' (duration: 20.419308ms)","trace[1747246877] 'applied index is now lower than readState.Index' (duration: 134.880294ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:13.069299Z","caller":"traceutil/trace.go:171","msg":"trace[838622522] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"155.713586ms","start":"2026-04-24T15:24:12.913554Z","end":"2026-04-24T15:24:13.069268Z","steps":["trace[838622522] 'process raft request' (duration: 154.134555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:13.06945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.342264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4719"} {"level":"info","ts":"2026-04-24T15:24:13.069492Z","caller":"traceutil/trace.go:171","msg":"trace[1762949693] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:745; }","duration":"127.438447ms","start":"2026-04-24T15:24:12.942043Z","end":"2026-04-24T15:24:13.069482Z","steps":["trace[1762949693] 'agreement among raft nodes before linearized reading' (duration: 127.260622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:13.069509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.593962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-pqkf7\" ","response":"range_response_count:1 size:3835"} {"level":"info","ts":"2026-04-24T15:24:13.069603Z","caller":"traceutil/trace.go:171","msg":"trace[799871286] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-pqkf7; range_end:; response_count:1; response_revision:745; }","duration":"155.728936ms","start":"2026-04-24T15:24:12.913833Z","end":"2026-04-24T15:24:13.069562Z","steps":["trace[799871286] 'agreement among raft nodes before linearized reading' (duration: 155.427156ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:13.695471Z","caller":"traceutil/trace.go:171","msg":"trace[1510756398] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"301.388341ms","start":"2026-04-24T15:24:13.394055Z","end":"2026-04-24T15:24:13.695443Z","steps":["trace[1510756398] 'process raft request' (duration: 301.215595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:13.695631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:13.394036Z","time spent":"301.520914ms","remote":"127.0.0.1:37654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2258,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:24:13.730214Z","caller":"traceutil/trace.go:171","msg":"trace[56064865] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"319.462648ms","start":"2026-04-24T15:24:13.410728Z","end":"2026-04-24T15:24:13.730191Z","steps":["trace[56064865] 'process raft request' (duration: 319.355555ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:13.730367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:13.410706Z","time spent":"319.591023ms","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2173,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:24:14.074187Z","caller":"traceutil/trace.go:171","msg":"trace[1558856928] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"332.843223ms","start":"2026-04-24T15:24:13.74132Z","end":"2026-04-24T15:24:14.074163Z","steps":["trace[1558856928] 'process raft request' (duration: 332.708119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:14.074312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:13.741296Z","time spent":"332.959687ms","remote":"127.0.0.1:37654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2860,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-24T15:24:14.403072Z","caller":"traceutil/trace.go:171","msg":"trace[1971597245] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"318.68242ms","start":"2026-04-24T15:24:14.084371Z","end":"2026-04-24T15:24:14.403053Z","steps":["trace[1971597245] 'process raft request' (duration: 318.571797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:14.403185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:14.084347Z","time spent":"318.781224ms","remote":"127.0.0.1:51512","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-04-24T15:24:15.221414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.365148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:24:15.221548Z","caller":"traceutil/trace.go:171","msg":"trace[31241926] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"271.655652ms","start":"2026-04-24T15:24:14.949867Z","end":"2026-04-24T15:24:15.221523Z","steps":["trace[31241926] 'process raft request' (duration: 96.074329ms)","trace[31241926] 'compare' (duration: 175.243193ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:15.587681Z","caller":"traceutil/trace.go:171","msg":"trace[1972403009] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"125.304389ms","start":"2026-04-24T15:24:15.462348Z","end":"2026-04-24T15:24:15.587653Z","steps":["trace[1972403009] 'process raft request' (duration: 125.135323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:21.316529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371602518760750997,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:24:21.817647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371602518760750997,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:24:22.318509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16371602518760750997,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-24T15:24:22.326653Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.494355764s","expected-duration":"1s"} {"level":"info","ts":"2026-04-24T15:24:22.327076Z","caller":"traceutil/trace.go:171","msg":"trace[1350754347] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.546381595s","start":"2026-04-24T15:24:20.78067Z","end":"2026-04-24T15:24:22.327052Z","steps":["trace[1350754347] 'process raft request' (duration: 1.546145648s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:22.327368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:20.780635Z","time spent":"1.546522949s","remote":"127.0.0.1:51426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4026,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:24:22.58849Z","caller":"traceutil/trace.go:171","msg":"trace[2078305899] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:819; }","duration":"1.772132157s","start":"2026-04-24T15:24:20.816333Z","end":"2026-04-24T15:24:22.588465Z","steps":["trace[2078305899] 'read index received' (duration: 1.510548124s)","trace[2078305899] 'applied index is now lower than readState.Index' (duration: 261.582473ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:24:22.588721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.772381504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:24:22.588776Z","caller":"traceutil/trace.go:171","msg":"trace[949991820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"1.772493717s","start":"2026-04-24T15:24:20.81627Z","end":"2026-04-24T15:24:22.588763Z","steps":["trace[949991820] 'agreement among raft nodes before linearized reading' (duration: 1.772279111s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:22.588805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:20.816248Z","time spent":"1.772551228s","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-24T15:24:22.58894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.768075299s","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-04-24T15:24:22.588991Z","caller":"traceutil/trace.go:171","msg":"trace[1752353086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"1.768244883s","start":"2026-04-24T15:24:20.820732Z","end":"2026-04-24T15:24:22.588977Z","steps":["trace[1752353086] 'agreement among raft nodes before linearized reading' (duration: 1.767947425s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:22.589075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.140566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T15:24:22.58911Z","caller":"traceutil/trace.go:171","msg":"trace[954540067] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"144.206178ms","start":"2026-04-24T15:24:22.444896Z","end":"2026-04-24T15:24:22.589102Z","steps":["trace[954540067] 'agreement among raft nodes before linearized reading' (duration: 144.134056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:22.589064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:20.820688Z","time spent":"1.768364277s","remote":"127.0.0.1:51512","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-04-24T15:24:22.58923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.506508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:24:22.589257Z","caller":"traceutil/trace.go:171","msg":"trace[362156743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"130.552349ms","start":"2026-04-24T15:24:22.458696Z","end":"2026-04-24T15:24:22.589248Z","steps":["trace[362156743] 'agreement among raft nodes before linearized reading' (duration: 130.506308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:22.589492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580729049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-24T15:24:22.589489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34683471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-24T15:24:22.589517Z","caller":"traceutil/trace.go:171","msg":"trace[2806218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"1.58078995s","start":"2026-04-24T15:24:21.008721Z","end":"2026-04-24T15:24:22.589511Z","steps":["trace[2806218] 'agreement among raft nodes before linearized reading' (duration: 1.580721208s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:22.589536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:21.008674Z","time spent":"1.580857372s","remote":"127.0.0.1:51512","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-04-24T15:24:22.589535Z","caller":"traceutil/trace.go:171","msg":"trace[530663613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"1.346917683s","start":"2026-04-24T15:24:21.242603Z","end":"2026-04-24T15:24:22.58952Z","steps":["trace[530663613] 'agreement among raft nodes before linearized reading' (duration: 1.346796509s)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:22.589574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:21.24258Z","time spent":"1.346986314s","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T15:24:30.427087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.395761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-24T15:24:30.427181Z","caller":"traceutil/trace.go:171","msg":"trace[1892106368] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:873; }","duration":"153.541223ms","start":"2026-04-24T15:24:30.273621Z","end":"2026-04-24T15:24:30.427162Z","steps":["trace[1892106368] 'count revisions from in-memory index tree' (duration: 153.320378ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:30.583784Z","caller":"traceutil/trace.go:171","msg":"trace[1965538023] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"123.366767ms","start":"2026-04-24T15:24:30.460394Z","end":"2026-04-24T15:24:30.58376Z","steps":["trace[1965538023] 'read index received' (duration: 123.228634ms)","trace[1965538023] 'applied index is now lower than readState.Index' (duration: 136.743µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:24:30.584149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.733047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:24:30.58437Z","caller":"traceutil/trace.go:171","msg":"trace[1793222216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"124.029164ms","start":"2026-04-24T15:24:30.46033Z","end":"2026-04-24T15:24:30.584359Z","steps":["trace[1793222216] 'agreement among raft nodes before linearized reading' (duration: 123.540951ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:30.584738Z","caller":"traceutil/trace.go:171","msg":"trace[358260995] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"125.220966ms","start":"2026-04-24T15:24:30.459502Z","end":"2026-04-24T15:24:30.584723Z","steps":["trace[358260995] 'process raft request' (duration: 124.133368ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:32.865744Z","caller":"traceutil/trace.go:171","msg":"trace[157860236] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"210.174837ms","start":"2026-04-24T15:24:32.655541Z","end":"2026-04-24T15:24:32.865716Z","steps":["trace[157860236] 'process raft request' (duration: 157.088512ms)","trace[157860236] 'compare' (duration: 52.88811ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:32.86587Z","caller":"traceutil/trace.go:171","msg":"trace[2059799368] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"210.29553ms","start":"2026-04-24T15:24:32.655565Z","end":"2026-04-24T15:24:32.865861Z","steps":["trace[2059799368] 'process raft request' (duration: 210.099565ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:32.865955Z","caller":"traceutil/trace.go:171","msg":"trace[87934105] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"210.096695ms","start":"2026-04-24T15:24:32.655851Z","end":"2026-04-24T15:24:32.865948Z","steps":["trace[87934105] 'process raft request' (duration: 209.973142ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:32.907413Z","caller":"traceutil/trace.go:171","msg":"trace[1728851939] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"168.480773ms","start":"2026-04-24T15:24:32.738907Z","end":"2026-04-24T15:24:32.907388Z","steps":["trace[1728851939] 'process raft request' (duration: 168.341689ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:49.206726Z","caller":"traceutil/trace.go:171","msg":"trace[476814262] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"440.406579ms","start":"2026-04-24T15:24:48.766297Z","end":"2026-04-24T15:24:49.206703Z","steps":["trace[476814262] 'read index received' (duration: 440.247336ms)","trace[476814262] 'applied index is now lower than readState.Index' (duration: 158.303µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:24:49.206816Z","caller":"traceutil/trace.go:171","msg":"trace[1432794242] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"447.177847ms","start":"2026-04-24T15:24:48.759597Z","end":"2026-04-24T15:24:49.206775Z","steps":["trace[1432794242] 'process raft request' (duration: 446.986953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:49.20699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.666595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-24T15:24:49.20709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:48.759574Z","time spent":"447.33221ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:24:49.207096Z","caller":"traceutil/trace.go:171","msg":"trace[329121226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1010; }","duration":"440.830789ms","start":"2026-04-24T15:24:48.766246Z","end":"2026-04-24T15:24:49.207077Z","steps":["trace[329121226] 'agreement among raft nodes before linearized reading' (duration: 440.543942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:49.207158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:24:48.766222Z","time spent":"440.926892ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-24T15:24:49.208129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.353598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-24T15:24:49.208163Z","caller":"traceutil/trace.go:171","msg":"trace[1216264011] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"258.557639ms","start":"2026-04-24T15:24:48.949578Z","end":"2026-04-24T15:24:49.208136Z","steps":["trace[1216264011] 'process raft request' (duration: 258.355314ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:49.208177Z","caller":"traceutil/trace.go:171","msg":"trace[243539029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"271.44162ms","start":"2026-04-24T15:24:48.936724Z","end":"2026-04-24T15:24:49.208166Z","steps":["trace[243539029] 'agreement among raft nodes before linearized reading' (duration: 271.299767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:49.208388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.303598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:24:49.208404Z","caller":"traceutil/trace.go:171","msg":"trace[1307266275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"266.332418ms","start":"2026-04-24T15:24:48.942068Z","end":"2026-04-24T15:24:49.2084Z","steps":["trace[1307266275] 'agreement among raft nodes before linearized reading' (duration: 266.290417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:49.208465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.421213ms","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-04-24T15:24:49.208476Z","caller":"traceutil/trace.go:171","msg":"trace[1635832052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"268.483946ms","start":"2026-04-24T15:24:48.939988Z","end":"2026-04-24T15:24:49.208471Z","steps":["trace[1635832052] 'agreement among raft nodes before linearized reading' (duration: 268.450365ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:53.507262Z","caller":"traceutil/trace.go:171","msg":"trace[52747042] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"127.882469ms","start":"2026-04-24T15:24:53.379354Z","end":"2026-04-24T15:24:53.507236Z","steps":["trace[52747042] 'process raft request' (duration: 127.748066ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:53.508467Z","caller":"traceutil/trace.go:171","msg":"trace[1326172678] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"125.424327ms","start":"2026-04-24T15:24:53.383022Z","end":"2026-04-24T15:24:53.508446Z","steps":["trace[1326172678] 'process raft request' (duration: 124.946378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:24:58.033502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.760735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a504c218-619b-4583-9d6b-cb1a4a183fb3\" ","response":"range_response_count:1 size:3577"} {"level":"info","ts":"2026-04-24T15:24:58.033631Z","caller":"traceutil/trace.go:171","msg":"trace[578789778] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a504c218-619b-4583-9d6b-cb1a4a183fb3; range_end:; response_count:1; response_revision:1062; }","duration":"124.947808ms","start":"2026-04-24T15:24:57.908663Z","end":"2026-04-24T15:24:58.03361Z","steps":["trace[578789778] 'range keys from in-memory index tree' (duration: 124.50574ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:24:58.304215Z","caller":"traceutil/trace.go:171","msg":"trace[1053058934] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"263.296821ms","start":"2026-04-24T15:24:58.04089Z","end":"2026-04-24T15:24:58.304187Z","steps":["trace[1053058934] 'process raft request' (duration: 263.132227ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:02.943926Z","caller":"traceutil/trace.go:171","msg":"trace[993655979] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"113.705061ms","start":"2026-04-24T15:25:02.830197Z","end":"2026-04-24T15:25:02.943902Z","steps":["trace[993655979] 'process raft request' (duration: 113.544238ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:06.673977Z","caller":"traceutil/trace.go:171","msg":"trace[1415127625] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"114.530944ms","start":"2026-04-24T15:25:06.559411Z","end":"2026-04-24T15:25:06.673942Z","steps":["trace[1415127625] 'process raft request' (duration: 114.240449ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:07.960511Z","caller":"traceutil/trace.go:171","msg":"trace[1493631530] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1152; }","duration":"238.648458ms","start":"2026-04-24T15:25:07.721836Z","end":"2026-04-24T15:25:07.960484Z","steps":["trace[1493631530] 'read index received' (duration: 238.386703ms)","trace[1493631530] 'applied index is now lower than readState.Index' (duration: 260.555µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:25:07.960649Z","caller":"traceutil/trace.go:171","msg":"trace[1745745393] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"265.765667ms","start":"2026-04-24T15:25:07.694854Z","end":"2026-04-24T15:25:07.96062Z","steps":["trace[1745745393] 'process raft request' (duration: 265.467251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:07.961003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.155567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:25:07.961075Z","caller":"traceutil/trace.go:171","msg":"trace[628389930] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1113; }","duration":"239.270509ms","start":"2026-04-24T15:25:07.721787Z","end":"2026-04-24T15:25:07.961058Z","steps":["trace[628389930] 'agreement among raft nodes before linearized reading' (duration: 239.111957ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:16.661628Z","caller":"traceutil/trace.go:171","msg":"trace[553781160] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"285.129326ms","start":"2026-04-24T15:25:16.376474Z","end":"2026-04-24T15:25:16.661604Z","steps":["trace[553781160] 'process raft request' (duration: 284.940623ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:17.218411Z","caller":"traceutil/trace.go:171","msg":"trace[551565359] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"302.7669ms","start":"2026-04-24T15:25:16.915614Z","end":"2026-04-24T15:25:17.218381Z","steps":["trace[551565359] 'process raft request' (duration: 302.616647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:17.218645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:16.915581Z","time spent":"302.963194ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-24T15:25:17.218656Z","caller":"traceutil/trace.go:171","msg":"trace[927954863] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1187; }","duration":"293.36852ms","start":"2026-04-24T15:25:16.925231Z","end":"2026-04-24T15:25:17.2186Z","steps":["trace[927954863] 'read index received' (duration: 293.355009ms)","trace[927954863] 'applied index is now lower than readState.Index' (duration: 10.11µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:25:17.218962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.701755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-24T15:25:17.218998Z","caller":"traceutil/trace.go:171","msg":"trace[1560623519] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:1146; }","duration":"293.803117ms","start":"2026-04-24T15:25:16.925183Z","end":"2026-04-24T15:25:17.218986Z","steps":["trace[1560623519] 'agreement among raft nodes before linearized reading' (duration: 293.560943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:17.919011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.220594Z","time spent":"698.410414ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-24T15:25:17.952282Z","caller":"traceutil/trace.go:171","msg":"trace[160780998] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1187; }","duration":"493.1883ms","start":"2026-04-24T15:25:17.459069Z","end":"2026-04-24T15:25:17.952257Z","steps":["trace[160780998] 'read index received' (duration: 459.797403ms)","trace[160780998] 'applied index is now lower than readState.Index' (duration: 33.390007ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:25:17.952365Z","caller":"traceutil/trace.go:171","msg":"trace[1047142599] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"511.684024ms","start":"2026-04-24T15:25:17.440649Z","end":"2026-04-24T15:25:17.952333Z","steps":["trace[1047142599] 'process raft request' (duration: 511.44782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:17.952424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.345113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:25:17.952546Z","caller":"traceutil/trace.go:171","msg":"trace[1537785392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1147; }","duration":"493.469035ms","start":"2026-04-24T15:25:17.459015Z","end":"2026-04-24T15:25:17.952484Z","steps":["trace[1537785392] 'agreement among raft nodes before linearized reading' (duration: 493.331893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:17.952555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.440625Z","time spent":"511.821307ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:25:17.952594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.458993Z","time spent":"493.587197ms","remote":"127.0.0.1:51228","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-04-24T15:25:18.278403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.399902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-24T15:25:18.278508Z","caller":"traceutil/trace.go:171","msg":"trace[1853127819] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1189; }","duration":"326.135799ms","start":"2026-04-24T15:25:17.952356Z","end":"2026-04-24T15:25:18.278491Z","steps":["trace[1853127819] 'read index received' (duration: 86.644846ms)","trace[1853127819] 'applied index is now lower than readState.Index' (duration: 239.489863ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:25:18.278585Z","caller":"traceutil/trace.go:171","msg":"trace[398139151] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"358.314376ms","start":"2026-04-24T15:25:17.920259Z","end":"2026-04-24T15:25:18.278573Z","steps":["trace[398139151] 'process raft request' (duration: 118.668941ms)","trace[398139151] 'compare' (duration: 239.274289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:25:18.278646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.920236Z","time spent":"358.380027ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-24T15:25:18.278812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.205042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-24T15:25:18.27889Z","caller":"traceutil/trace.go:171","msg":"trace[27654129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1148; }","duration":"537.331244ms","start":"2026-04-24T15:25:17.741541Z","end":"2026-04-24T15:25:18.278872Z","steps":["trace[27654129] 'agreement among raft nodes before linearized reading' (duration: 537.06897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:18.278889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.151253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T15:25:18.27893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.741519Z","time spent":"537.403285ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-24T15:25:18.278956Z","caller":"traceutil/trace.go:171","msg":"trace[730686960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1148; }","duration":"325.259434ms","start":"2026-04-24T15:25:17.953683Z","end":"2026-04-24T15:25:18.278943Z","steps":["trace[730686960] 'agreement among raft nodes before linearized reading' (duration: 325.161893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:18.279001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.953666Z","time spent":"325.327846ms","remote":"127.0.0.1:51238","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-04-24T15:25:18.279049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.051215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T15:25:18.279088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.469499ms","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-04-24T15:25:18.279128Z","caller":"traceutil/trace.go:171","msg":"trace[1625931695] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"164.53919ms","start":"2026-04-24T15:25:18.114577Z","end":"2026-04-24T15:25:18.279117Z","steps":["trace[1625931695] 'agreement among raft nodes before linearized reading' (duration: 164.411288ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:18.279121Z","caller":"traceutil/trace.go:171","msg":"trace[1422442499] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1148; }","duration":"753.158117ms","start":"2026-04-24T15:25:17.525946Z","end":"2026-04-24T15:25:18.279104Z","steps":["trace[1422442499] 'agreement among raft nodes before linearized reading' (duration: 753.050875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:18.279177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.525923Z","time spent":"753.238169ms","remote":"127.0.0.1:51388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-04-24T15:25:18.279242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.239851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-24T15:25:18.279216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.614299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-24T15:25:18.279292Z","caller":"traceutil/trace.go:171","msg":"trace[711546249] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1148; }","duration":"372.317563ms","start":"2026-04-24T15:25:17.90695Z","end":"2026-04-24T15:25:18.279268Z","steps":["trace[711546249] 'agreement among raft nodes before linearized reading' (duration: 372.248071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:18.279323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.906933Z","time spent":"372.381674ms","remote":"127.0.0.1:51358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":16,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-24T15:25:18.279767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.639105ms","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-04-24T15:25:18.279833Z","caller":"traceutil/trace.go:171","msg":"trace[171048995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1148; }","duration":"459.737198ms","start":"2026-04-24T15:25:17.820078Z","end":"2026-04-24T15:25:18.279815Z","steps":["trace[171048995] 'agreement among raft nodes before linearized reading' (duration: 458.70393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:18.279879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.820059Z","time spent":"459.808219ms","remote":"127.0.0.1:51512","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-04-24T15:25:18.279317Z","caller":"traceutil/trace.go:171","msg":"trace[276930431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1148; }","duration":"477.752112ms","start":"2026-04-24T15:25:17.801542Z","end":"2026-04-24T15:25:18.279294Z","steps":["trace[276930431] 'agreement among raft nodes before linearized reading' (duration: 477.480027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:18.280319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:17.801521Z","time spent":"478.775789ms","remote":"127.0.0.1:51512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-24T15:25:18.280565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.433365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-24T15:25:18.280628Z","caller":"traceutil/trace.go:171","msg":"trace[948411954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1148; }","duration":"175.563277ms","start":"2026-04-24T15:25:18.105048Z","end":"2026-04-24T15:25:18.280611Z","steps":["trace[948411954] 'agreement among raft nodes before linearized reading' (duration: 175.372893ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:18.466871Z","caller":"traceutil/trace.go:171","msg":"trace[1402117944] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"180.496839ms","start":"2026-04-24T15:25:18.286346Z","end":"2026-04-24T15:25:18.466843Z","steps":["trace[1402117944] 'process raft request' (duration: 180.290166ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:18.467137Z","caller":"traceutil/trace.go:171","msg":"trace[1982840069] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"178.959393ms","start":"2026-04-24T15:25:18.288164Z","end":"2026-04-24T15:25:18.467123Z","steps":["trace[1982840069] 'process raft request' (duration: 178.630798ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:18.46756Z","caller":"traceutil/trace.go:171","msg":"trace[376248903] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"179.289069ms","start":"2026-04-24T15:25:18.288257Z","end":"2026-04-24T15:25:18.467546Z","steps":["trace[376248903] 'process raft request' (duration: 178.81955ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:18.467854Z","caller":"traceutil/trace.go:171","msg":"trace[831803029] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"181.264273ms","start":"2026-04-24T15:25:18.286556Z","end":"2026-04-24T15:25:18.46782Z","steps":["trace[831803029] 'read index received' (duration: 89.908074ms)","trace[831803029] 'applied index is now lower than readState.Index' (duration: 91.354879ms)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:25:18.468145Z","caller":"traceutil/trace.go:171","msg":"trace[761817787] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"179.790388ms","start":"2026-04-24T15:25:18.288345Z","end":"2026-04-24T15:25:18.468135Z","steps":["trace[761817787] 'process raft request' (duration: 179.181788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:18.468915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.3092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-24T15:25:18.469067Z","caller":"traceutil/trace.go:171","msg":"trace[1444690173] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1153; }","duration":"182.523814ms","start":"2026-04-24T15:25:18.286531Z","end":"2026-04-24T15:25:18.469055Z","steps":["trace[1444690173] 'agreement among raft nodes before linearized reading' (duration: 181.592859ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:24.665463Z","caller":"traceutil/trace.go:171","msg":"trace[1969912985] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"135.829637ms","start":"2026-04-24T15:25:24.529608Z","end":"2026-04-24T15:25:24.665438Z","steps":["trace[1969912985] 'process raft request' (duration: 131.133581ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:24.665866Z","caller":"traceutil/trace.go:171","msg":"trace[1587532161] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"135.793786ms","start":"2026-04-24T15:25:24.529998Z","end":"2026-04-24T15:25:24.665792Z","steps":["trace[1587532161] 'process raft request' (duration: 135.39684ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:24.666136Z","caller":"traceutil/trace.go:171","msg":"trace[77849073] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"136.06311ms","start":"2026-04-24T15:25:24.530049Z","end":"2026-04-24T15:25:24.666112Z","steps":["trace[77849073] 'process raft request' (duration: 135.534132ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:26.236125Z","caller":"traceutil/trace.go:171","msg":"trace[1261869785] linearizableReadLoop","detail":"{readStateIndex:1221; appliedIndex:1220; }","duration":"127.24055ms","start":"2026-04-24T15:25:26.108785Z","end":"2026-04-24T15:25:26.236026Z","steps":["trace[1261869785] 'read index received' (duration: 127.107808ms)","trace[1261869785] 'applied index is now lower than readState.Index' (duration: 131.712µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:25:26.236132Z","caller":"traceutil/trace.go:171","msg":"trace[799077015] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"147.061065ms","start":"2026-04-24T15:25:26.089012Z","end":"2026-04-24T15:25:26.236073Z","steps":["trace[799077015] 'process raft request' (duration: 146.848582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:26.236705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.9058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient\" ","response":"range_response_count:1 size:718"} {"level":"info","ts":"2026-04-24T15:25:26.236782Z","caller":"traceutil/trace.go:171","msg":"trace[845243968] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:selfnodeclient; range_end:; response_count:1; response_revision:1177; }","duration":"128.014733ms","start":"2026-04-24T15:25:26.10875Z","end":"2026-04-24T15:25:26.236765Z","steps":["trace[845243968] 'agreement among raft nodes before linearized reading' (duration: 127.425613ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:26.747063Z","caller":"traceutil/trace.go:171","msg":"trace[827889668] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"207.472573ms","start":"2026-04-24T15:25:26.539572Z","end":"2026-04-24T15:25:26.747044Z","steps":["trace[827889668] 'read index received' (duration: 207.25975ms)","trace[827889668] 'applied index is now lower than readState.Index' (duration: 211.923µs)"],"step_count":2} {"level":"info","ts":"2026-04-24T15:25:26.747108Z","caller":"traceutil/trace.go:171","msg":"trace[1451324515] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"212.823328ms","start":"2026-04-24T15:25:26.534266Z","end":"2026-04-24T15:25:26.747089Z","steps":["trace[1451324515] 'process raft request' (duration: 212.650345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:26.747289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.453366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-24T15:25:26.74733Z","caller":"traceutil/trace.go:171","msg":"trace[1996959417] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:1; response_revision:1178; }","duration":"199.538237ms","start":"2026-04-24T15:25:26.547781Z","end":"2026-04-24T15:25:26.747319Z","steps":["trace[1996959417] 'agreement among raft nodes before linearized reading' (duration: 199.460045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:26.747287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.692067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-24T15:25:26.747462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.636513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:950"} {"level":"info","ts":"2026-04-24T15:25:26.74754Z","caller":"traceutil/trace.go:171","msg":"trace[2063230670] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1178; }","duration":"193.730035ms","start":"2026-04-24T15:25:26.55379Z","end":"2026-04-24T15:25:26.74752Z","steps":["trace[2063230670] 'agreement among raft nodes before linearized reading' (duration: 193.609283ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:26.747887Z","caller":"traceutil/trace.go:171","msg":"trace[778034180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"208.311626ms","start":"2026-04-24T15:25:26.539557Z","end":"2026-04-24T15:25:26.747869Z","steps":["trace[778034180] 'agreement among raft nodes before linearized reading' (duration: 207.609995ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:27.334737Z","caller":"traceutil/trace.go:171","msg":"trace[891602379] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1226; }","duration":"266.667202ms","start":"2026-04-24T15:25:27.068046Z","end":"2026-04-24T15:25:27.334713Z","steps":["trace[891602379] 'read index received' (duration: 266.496249ms)","trace[891602379] 'applied index is now lower than readState.Index' (duration: 169.533µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:25:27.33477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-24T15:25:26.996535Z","time spent":"338.228652ms","remote":"127.0.0.1:51264","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-24T15:25:27.334917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.845705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-04-24T15:25:27.334974Z","caller":"traceutil/trace.go:171","msg":"trace[1032204225] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1182; }","duration":"266.955427ms","start":"2026-04-24T15:25:27.068003Z","end":"2026-04-24T15:25:27.334958Z","steps":["trace[1032204225] 'agreement among raft nodes before linearized reading' (duration: 266.833475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-24T15:25:27.33518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.086965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-24T15:25:27.335208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.916002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-04-24T15:25:27.335284Z","caller":"traceutil/trace.go:171","msg":"trace[174644658] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1182; }","duration":"242.231586ms","start":"2026-04-24T15:25:27.093035Z","end":"2026-04-24T15:25:27.335266Z","steps":["trace[174644658] 'agreement among raft nodes before linearized reading' (duration: 242.088144ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:27.335323Z","caller":"traceutil/trace.go:171","msg":"trace[2071524828] range","detail":"{range_begin:/registry/clusterroles/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1182; }","duration":"261.061924ms","start":"2026-04-24T15:25:27.074242Z","end":"2026-04-24T15:25:27.335304Z","steps":["trace[2071524828] 'agreement among raft nodes before linearized reading' (duration: 260.877751ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:35.04803Z","caller":"traceutil/trace.go:171","msg":"trace[380941710] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"107.714793ms","start":"2026-04-24T15:25:34.940289Z","end":"2026-04-24T15:25:35.048004Z","steps":["trace[380941710] 'process raft request' (duration: 107.50781ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:40.476413Z","caller":"traceutil/trace.go:171","msg":"trace[396681098] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"118.999149ms","start":"2026-04-24T15:25:40.357394Z","end":"2026-04-24T15:25:40.476393Z","steps":["trace[396681098] 'read index received' (duration: 118.812346ms)","trace[396681098] 'applied index is now lower than readState.Index' (duration: 185.853µs)"],"step_count":2} {"level":"warn","ts":"2026-04-24T15:25:40.476802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.391174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-system/\" range_end:\"/registry/jobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-24T15:25:40.476919Z","caller":"traceutil/trace.go:171","msg":"trace[1371414156] range","detail":"{range_begin:/registry/jobs/kube-system/; range_end:/registry/jobs/kube-system0; response_count:0; response_revision:1225; }","duration":"119.555216ms","start":"2026-04-24T15:25:40.357352Z","end":"2026-04-24T15:25:40.476907Z","steps":["trace[1371414156] 'agreement among raft nodes before linearized reading' (duration: 119.160891ms)"],"step_count":1} {"level":"info","ts":"2026-04-24T15:25:40.476534Z","caller":"traceutil/trace.go:171","msg":"trace[1543639904] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"124.879334ms","start":"2026-04-24T15:25:40.351621Z","end":"2026-04-24T15:25:40.476501Z","steps":["trace[1543639904] 'process raft request' (duration: 124.645521ms)"],"step_count":1}