{"level":"warn","ts":"2026-04-02T16:42:29.959485Z","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-02T16:42:29.959596Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-02T16:42:29.959684Z","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-02T16:42:29.959704Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-02T16:42:29.959733Z","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-02T16:42:29.960494Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-04-02T16:42:29.960597Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-02T16:42:29.964736Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.90019ms"} {"level":"info","ts":"2026-04-02T16:42:29.974413Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-04-02T16:42:29.974503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T16:42:29.974544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-04-02T16:42:29.97457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T16:42:29.974589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-04-02T16:42:29.974633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-04-02T16:42:29.99044Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T16:42:29.99345Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T16:42:29.995194Z","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-02T16:42:29.998544Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T16:42:30.000992Z","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-02T16:42:30.001128Z","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-02T16:42:30.001198Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T16:42:30.001641Z","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-02T16:42:30.001678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-04-02T16:42:30.002435Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-04-02T16:42:30.003457Z","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-02T16:42:30.003585Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-02T16:42:30.003622Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-04-02T16:42:30.003856Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T16:42:30.003882Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T16:42:30.675166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T16:42:30.675229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T16:42:30.675252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-04-02T16:42:30.675273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-04-02T16:42:30.675284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-02T16:42:30.675301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-04-02T16:42:30.675316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-04-02T16:42:30.67669Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:42:30.677652Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:42:30.677651Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T16:42:30.677797Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:42:30.678053Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T16:42:30.6781Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T16:42:30.678638Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:42:30.678805Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:42:30.678843Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:42:30.682327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-04-02T16:42:30.683749Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-02T16:42:32.122874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.051285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:42:32.123Z","caller":"traceutil/trace.go:171","msg":"trace[711575673] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"118.221359ms","start":"2026-04-02T16:42:32.004758Z","end":"2026-04-02T16:42:32.12298Z","steps":["trace[711575673] 'range keys from in-memory index tree' (duration: 117.973503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:32.122984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.638663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T16:42:32.122856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.355621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:42:32.123101Z","caller":"traceutil/trace.go:171","msg":"trace[1447338299] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"110.818106ms","start":"2026-04-02T16:42:32.012262Z","end":"2026-04-02T16:42:32.12308Z","steps":["trace[1447338299] 'range keys from in-memory index tree' (duration: 110.55376ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.123227Z","caller":"traceutil/trace.go:171","msg":"trace[82398681] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:0; response_revision:3; }","duration":"112.767281ms","start":"2026-04-02T16:42:32.010381Z","end":"2026-04-02T16:42:32.123148Z","steps":["trace[82398681] 'range keys from in-memory index tree' (duration: 112.225359ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.123262Z","caller":"traceutil/trace.go:171","msg":"trace[145369599] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"110.869277ms","start":"2026-04-02T16:42:32.012344Z","end":"2026-04-02T16:42:32.123213Z","steps":["trace[145369599] 'process raft request' (duration: 106.460254ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.132829Z","caller":"traceutil/trace.go:171","msg":"trace[324669296] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"120.111363ms","start":"2026-04-02T16:42:32.0127Z","end":"2026-04-02T16:42:32.132811Z","steps":["trace[324669296] 'process raft request' (duration: 120.016291ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.132878Z","caller":"traceutil/trace.go:171","msg":"trace[1834745058] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"120.103003ms","start":"2026-04-02T16:42:32.012757Z","end":"2026-04-02T16:42:32.13286Z","steps":["trace[1834745058] 'process raft request' (duration: 120.001011ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.132946Z","caller":"traceutil/trace.go:171","msg":"trace[1742611386] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"120.516282ms","start":"2026-04-02T16:42:32.012411Z","end":"2026-04-02T16:42:32.132927Z","steps":["trace[1742611386] 'process raft request' (duration: 120.241016ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.133102Z","caller":"traceutil/trace.go:171","msg":"trace[142675831] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"119.497758ms","start":"2026-04-02T16:42:32.01356Z","end":"2026-04-02T16:42:32.133058Z","steps":["trace[142675831] 'process raft request' (duration: 119.214072ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.133464Z","caller":"traceutil/trace.go:171","msg":"trace[627735607] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:6; }","duration":"121.013304ms","start":"2026-04-02T16:42:32.012437Z","end":"2026-04-02T16:42:32.13345Z","steps":["trace[627735607] 'read index received' (duration: 106.381832ms)","trace[627735607] 'applied index is now lower than readState.Index' (duration: 14.630812ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:32.133556Z","caller":"traceutil/trace.go:171","msg":"trace[1825249226] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"119.94781ms","start":"2026-04-02T16:42:32.013601Z","end":"2026-04-02T16:42:32.133549Z","steps":["trace[1825249226] 'process raft request' (duration: 119.651002ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.133615Z","caller":"traceutil/trace.go:171","msg":"trace[86998749] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"110.657152ms","start":"2026-04-02T16:42:32.022952Z","end":"2026-04-02T16:42:32.13361Z","steps":["trace[86998749] 'process raft request' (duration: 110.471148ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.133746Z","caller":"traceutil/trace.go:171","msg":"trace[1596434519] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"111.686117ms","start":"2026-04-02T16:42:32.022031Z","end":"2026-04-02T16:42:32.133717Z","steps":["trace[1596434519] 'process raft request' (duration: 111.348268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:32.133905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.458105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:42:32.133978Z","caller":"traceutil/trace.go:171","msg":"trace[2066661333] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"121.553247ms","start":"2026-04-02T16:42:32.012412Z","end":"2026-04-02T16:42:32.133965Z","steps":["trace[2066661333] 'agreement among raft nodes before linearized reading' (duration: 121.317652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:32.135345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.024468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:42:32.13539Z","caller":"traceutil/trace.go:171","msg":"trace[2069556004] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:12; }","duration":"101.096689ms","start":"2026-04-02T16:42:32.034282Z","end":"2026-04-02T16:42:32.135378Z","steps":["trace[2069556004] 'agreement among raft nodes before linearized reading' (duration: 100.780242ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.319385Z","caller":"traceutil/trace.go:171","msg":"trace[1165100411] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"108.125733ms","start":"2026-04-02T16:42:32.211237Z","end":"2026-04-02T16:42:32.319363Z","steps":["trace[1165100411] 'process raft request' (duration: 108.072352ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.319492Z","caller":"traceutil/trace.go:171","msg":"trace[2143329109] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"113.601301ms","start":"2026-04-02T16:42:32.205875Z","end":"2026-04-02T16:42:32.319476Z","steps":["trace[2143329109] 'process raft request' (duration: 26.13033ms)","trace[2143329109] 'compare' (duration: 87.172904ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:32.437588Z","caller":"traceutil/trace.go:171","msg":"trace[1081694376] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"218.296194ms","start":"2026-04-02T16:42:32.219261Z","end":"2026-04-02T16:42:32.437557Z","steps":["trace[1081694376] 'process raft request' (duration: 211.955267ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.437733Z","caller":"traceutil/trace.go:171","msg":"trace[1808661040] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"217.750812ms","start":"2026-04-02T16:42:32.219944Z","end":"2026-04-02T16:42:32.437695Z","steps":["trace[1808661040] 'process raft request' (duration: 217.482495ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.437844Z","caller":"traceutil/trace.go:171","msg":"trace[1168341157] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"113.893368ms","start":"2026-04-02T16:42:32.323901Z","end":"2026-04-02T16:42:32.437795Z","steps":["trace[1168341157] 'process raft request' (duration: 113.573501ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:32.437621Z","caller":"traceutil/trace.go:171","msg":"trace[1991005526] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"112.792342ms","start":"2026-04-02T16:42:32.3248Z","end":"2026-04-02T16:42:32.437593Z","steps":["trace[1991005526] 'process raft request' (duration: 112.70565ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:38.324558Z","caller":"traceutil/trace.go:171","msg":"trace[87300884] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"232.610929ms","start":"2026-04-02T16:42:38.091922Z","end":"2026-04-02T16:42:38.324533Z","steps":["trace[87300884] 'read index received' (duration: 232.421194ms)","trace[87300884] 'applied index is now lower than readState.Index' (duration: 188.695µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:38.324616Z","caller":"traceutil/trace.go:171","msg":"trace[955175327] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"651.170506ms","start":"2026-04-02T16:42:37.673412Z","end":"2026-04-02T16:42:38.324583Z","steps":["trace[955175327] 'process raft request' (duration: 650.983921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:38.324831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.892535ms","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-02T16:42:38.324901Z","caller":"traceutil/trace.go:171","msg":"trace[308643629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:320; }","duration":"233.032159ms","start":"2026-04-02T16:42:38.091848Z","end":"2026-04-02T16:42:38.324881Z","steps":["trace[308643629] 'agreement among raft nodes before linearized reading' (duration: 232.834915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:38.326624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:37.673383Z","time spent":"651.35284ms","remote":"127.0.0.1:55748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:42:38.831275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622940901,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:42:41.037731Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.177776302s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T16:42:41.038833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.860276308s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:42:41.039189Z","caller":"traceutil/trace.go:171","msg":"trace[1571601863] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:333; }","duration":"2.708321897s","start":"2026-04-02T16:42:38.330843Z","end":"2026-04-02T16:42:41.039165Z","steps":["trace[1571601863] 'read index received' (duration: 847.209279ms)","trace[1571601863] 'applied index is now lower than readState.Index' (duration: 1.861107898s)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:41.039229Z","caller":"traceutil/trace.go:171","msg":"trace[1887702027] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"2.708393859s","start":"2026-04-02T16:42:38.330815Z","end":"2026-04-02T16:42:41.039209Z","steps":["trace[1887702027] 'process raft request' (duration: 2.708279596s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:41.039185Z","caller":"traceutil/trace.go:171","msg":"trace[757617508] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"2.709232929s","start":"2026-04-02T16:42:38.329907Z","end":"2026-04-02T16:42:41.03914Z","steps":["trace[757617508] 'process raft request' (duration: 848.129141ms)","trace[757617508] 'compare' (duration: 1.860116315s)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:41.039228Z","caller":"traceutil/trace.go:171","msg":"trace[322880096] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"2.70844402s","start":"2026-04-02T16:42:38.330748Z","end":"2026-04-02T16:42:41.039192Z","steps":["trace[322880096] 'process raft request' (duration: 2.708195423s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:41.039347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:38.330809Z","time spent":"2.70845939s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:42:41.039415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:38.329886Z","time spent":"2.709389922s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:42:41.039431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.708576463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:42:41.039487Z","caller":"traceutil/trace.go:171","msg":"trace[858303832] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-after-finished-controller; range_end:; response_count:0; response_revision:323; }","duration":"2.708656165s","start":"2026-04-02T16:42:38.330815Z","end":"2026-04-02T16:42:41.039472Z","steps":["trace[858303832] 'agreement among raft nodes before linearized reading' (duration: 2.708497051s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:41.039438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902480453s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:42:41.039487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699881636s","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-02T16:42:41.039561Z","caller":"traceutil/trace.go:171","msg":"trace[1203222205] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:323; }","duration":"1.902602866s","start":"2026-04-02T16:42:39.136936Z","end":"2026-04-02T16:42:41.039539Z","steps":["trace[1203222205] 'agreement among raft nodes before linearized reading' (duration: 1.902423481s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:41.039609Z","caller":"traceutil/trace.go:171","msg":"trace[736936288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:323; }","duration":"1.700045769s","start":"2026-04-02T16:42:39.339526Z","end":"2026-04-02T16:42:41.039571Z","steps":["trace[736936288] 'agreement among raft nodes before linearized reading' (duration: 1.699847015s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:41.039424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:38.330732Z","time spent":"2.708564183s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:42:41.039686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:39.339493Z","time spent":"1.700173162s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T16:42:41.039528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:38.330806Z","time spent":"2.708711356s","remote":"127.0.0.1:55748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-after-finished-controller\" "} {"level":"info","ts":"2026-04-02T16:42:41.269707Z","caller":"traceutil/trace.go:171","msg":"trace[1885094784] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"221.373365ms","start":"2026-04-02T16:42:41.048209Z","end":"2026-04-02T16:42:41.269582Z","steps":["trace[1885094784] 'process raft request' (duration: 203.488307ms)","trace[1885094784] 'compare' (duration: 17.552721ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:41.358637Z","caller":"traceutil/trace.go:171","msg":"trace[5731411] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"310.163628ms","start":"2026-04-02T16:42:41.048449Z","end":"2026-04-02T16:42:41.358613Z","steps":["trace[5731411] 'process raft request' (duration: 309.967374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:41.358827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:41.048419Z","time spent":"310.294311ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:42:41.358913Z","caller":"traceutil/trace.go:171","msg":"trace[1895141374] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"308.468209ms","start":"2026-04-02T16:42:41.050421Z","end":"2026-04-02T16:42:41.35889Z","steps":["trace[1895141374] 'process raft request' (duration: 308.140401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:41.359049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:41.050411Z","time spent":"308.56487ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:42:55.626706Z","caller":"traceutil/trace.go:171","msg":"trace[805281314] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"144.992964ms","start":"2026-04-02T16:42:55.48167Z","end":"2026-04-02T16:42:55.626663Z","steps":["trace[805281314] 'process raft request' (duration: 144.779389ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.101412Z","caller":"traceutil/trace.go:171","msg":"trace[807590810] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"376.608187ms","start":"2026-04-02T16:43:04.724754Z","end":"2026-04-02T16:43:05.101362Z","steps":["trace[807590810] 'process raft request' (duration: 376.29019ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.101665Z","caller":"traceutil/trace.go:171","msg":"trace[710148788] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"369.068273ms","start":"2026-04-02T16:43:04.73253Z","end":"2026-04-02T16:43:05.101598Z","steps":["trace[710148788] 'process raft request' (duration: 368.752435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:05.101922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.732504Z","time spent":"369.279527ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:05.101857Z","caller":"traceutil/trace.go:171","msg":"trace[1686620539] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"359.677783ms","start":"2026-04-02T16:43:04.742102Z","end":"2026-04-02T16:43:05.10178Z","steps":["trace[1686620539] 'process raft request' (duration: 359.306174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:05.101963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.724705Z","time spent":"376.898975ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:43:05.102206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.742076Z","time spent":"359.960699ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:05.102356Z","caller":"traceutil/trace.go:171","msg":"trace[1644703370] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"377.439357ms","start":"2026-04-02T16:43:04.724895Z","end":"2026-04-02T16:43:05.102334Z","steps":["trace[1644703370] 'process raft request' (duration: 376.324551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:05.10247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.72483Z","time spent":"377.57221ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:05.102567Z","caller":"traceutil/trace.go:171","msg":"trace[1247776559] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"366.602385ms","start":"2026-04-02T16:43:04.735097Z","end":"2026-04-02T16:43:05.101699Z","steps":["trace[1247776559] 'process raft request' (duration: 366.273947ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.102626Z","caller":"traceutil/trace.go:171","msg":"trace[1856323080] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"341.253602ms","start":"2026-04-02T16:43:04.761359Z","end":"2026-04-02T16:43:05.102613Z","steps":["trace[1856323080] 'process raft request' (duration: 340.224038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:05.102674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.735089Z","time spent":"367.546076ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:05.102631Z","caller":"traceutil/trace.go:171","msg":"trace[1826083856] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"366.930222ms","start":"2026-04-02T16:43:04.734816Z","end":"2026-04-02T16:43:05.101746Z","steps":["trace[1826083856] 'process raft request' (duration: 366.510412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:05.102716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.761326Z","time spent":"341.362015ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:43:05.102834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.734803Z","time spent":"367.937546ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:05.102809Z","caller":"traceutil/trace.go:171","msg":"trace[1137630938] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"341.337124ms","start":"2026-04-02T16:43:04.761393Z","end":"2026-04-02T16:43:05.10273Z","steps":["trace[1137630938] 'process raft request' (duration: 340.885053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:05.103055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.761383Z","time spent":"341.597051ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:05.10352Z","caller":"traceutil/trace.go:171","msg":"trace[1891954525] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"332.09212ms","start":"2026-04-02T16:43:04.771405Z","end":"2026-04-02T16:43:05.103497Z","steps":["trace[1891954525] 'process raft request' (duration: 331.277681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:05.103607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.771326Z","time spent":"332.240473ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:05.103836Z","caller":"traceutil/trace.go:171","msg":"trace[822565011] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"308.354476ms","start":"2026-04-02T16:43:04.795461Z","end":"2026-04-02T16:43:05.103815Z","steps":["trace[822565011] 'process raft request' (duration: 307.959566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:05.103939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:04.795421Z","time spent":"308.475808ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:05.114Z","caller":"traceutil/trace.go:171","msg":"trace[1580940301] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:534; }","duration":"214.352821ms","start":"2026-04-02T16:43:04.899631Z","end":"2026-04-02T16:43:05.113984Z","steps":["trace[1580940301] 'read index received' (duration: 201.571463ms)","trace[1580940301] 'applied index is now lower than readState.Index' (duration: 12.780428ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:05.1141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.461605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:43:05.114119Z","caller":"traceutil/trace.go:171","msg":"trace[1836156770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"214.496466ms","start":"2026-04-02T16:43:04.899616Z","end":"2026-04-02T16:43:05.114113Z","steps":["trace[1836156770] 'agreement among raft nodes before linearized reading' (duration: 214.429734ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.114283Z","caller":"traceutil/trace.go:171","msg":"trace[1944111638] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"295.488816ms","start":"2026-04-02T16:43:04.818788Z","end":"2026-04-02T16:43:05.114277Z","steps":["trace[1944111638] 'process raft request' (duration: 294.955733ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.114409Z","caller":"traceutil/trace.go:171","msg":"trace[97655971] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"294.78278ms","start":"2026-04-02T16:43:04.819622Z","end":"2026-04-02T16:43:05.114405Z","steps":["trace[97655971] 'process raft request' (duration: 294.211226ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.114692Z","caller":"traceutil/trace.go:171","msg":"trace[52180588] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"211.604088ms","start":"2026-04-02T16:43:04.903045Z","end":"2026-04-02T16:43:05.114649Z","steps":["trace[52180588] 'process raft request' (duration: 210.895051ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.114812Z","caller":"traceutil/trace.go:171","msg":"trace[423586077] transaction","detail":"{read_only:false; number_of_response:1; response_revision:524; }","duration":"295.092217ms","start":"2026-04-02T16:43:04.81971Z","end":"2026-04-02T16:43:05.114803Z","steps":["trace[423586077] 'process raft request' (duration: 294.189765ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.472329Z","caller":"traceutil/trace.go:171","msg":"trace[1597113288] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"248.025057ms","start":"2026-04-02T16:43:05.224269Z","end":"2026-04-02T16:43:05.472294Z","steps":["trace[1597113288] 'process raft request' (duration: 247.827253ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.487757Z","caller":"traceutil/trace.go:171","msg":"trace[1024034723] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"259.333292ms","start":"2026-04-02T16:43:05.228374Z","end":"2026-04-02T16:43:05.487707Z","steps":["trace[1024034723] 'process raft request' (duration: 258.926913ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.487793Z","caller":"traceutil/trace.go:171","msg":"trace[387084519] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"255.116973ms","start":"2026-04-02T16:43:05.232629Z","end":"2026-04-02T16:43:05.487746Z","steps":["trace[387084519] 'process raft request' (duration: 255.007851ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.487986Z","caller":"traceutil/trace.go:171","msg":"trace[307337028] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"255.657876ms","start":"2026-04-02T16:43:05.23232Z","end":"2026-04-02T16:43:05.487978Z","steps":["trace[307337028] 'process raft request' (duration: 255.232526ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.488008Z","caller":"traceutil/trace.go:171","msg":"trace[1813958010] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"212.854967ms","start":"2026-04-02T16:43:05.275143Z","end":"2026-04-02T16:43:05.487998Z","steps":["trace[1813958010] 'process raft request' (duration: 212.783285ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.48804Z","caller":"traceutil/trace.go:171","msg":"trace[606477995] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"255.354628ms","start":"2026-04-02T16:43:05.23268Z","end":"2026-04-02T16:43:05.488034Z","steps":["trace[606477995] 'process raft request' (duration: 254.989719ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.488066Z","caller":"traceutil/trace.go:171","msg":"trace[369485120] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"254.914648ms","start":"2026-04-02T16:43:05.233144Z","end":"2026-04-02T16:43:05.488059Z","steps":["trace[369485120] 'process raft request' (duration: 254.663043ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.488015Z","caller":"traceutil/trace.go:171","msg":"trace[1775941992] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"254.924718ms","start":"2026-04-02T16:43:05.233082Z","end":"2026-04-02T16:43:05.488007Z","steps":["trace[1775941992] 'process raft request' (duration: 254.617191ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:05.488132Z","caller":"traceutil/trace.go:171","msg":"trace[218852547] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"252.058302ms","start":"2026-04-02T16:43:05.236066Z","end":"2026-04-02T16:43:05.488125Z","steps":["trace[218852547] 'process raft request' (duration: 251.792366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:06.187145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622941326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:43:06.687862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622941326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:43:07.040092Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.40132514s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:43:07.040695Z","caller":"traceutil/trace.go:171","msg":"trace[1479671774] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.402091897s","start":"2026-04-02T16:43:05.63857Z","end":"2026-04-02T16:43:07.040662Z","steps":["trace[1479671774] 'process raft request' (duration: 1.401763049s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:07.040909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:05.638542Z","time spent":"1.40222943s","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:07.19448Z","caller":"traceutil/trace.go:171","msg":"trace[1338675405] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.542494983s","start":"2026-04-02T16:43:05.651938Z","end":"2026-04-02T16:43:07.194432Z","steps":["trace[1338675405] 'process raft request' (duration: 1.542065773s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:07.194552Z","caller":"traceutil/trace.go:171","msg":"trace[554421755] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:581; }","duration":"1.508022709s","start":"2026-04-02T16:43:05.686494Z","end":"2026-04-02T16:43:07.194517Z","steps":["trace[554421755] 'read index received' (duration: 1.353971094s)","trace[554421755] 'applied index is now lower than readState.Index' (duration: 154.049265ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:43:07.194607Z","caller":"traceutil/trace.go:171","msg":"trace[882225858] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.530166346s","start":"2026-04-02T16:43:05.664386Z","end":"2026-04-02T16:43:07.194552Z","steps":["trace[882225858] 'process raft request' (duration: 1.529859048s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:07.194698Z","caller":"traceutil/trace.go:171","msg":"trace[146986580] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"1.040890198s","start":"2026-04-02T16:43:06.153796Z","end":"2026-04-02T16:43:07.194687Z","steps":["trace[146986580] 'process raft request' (duration: 1.040651132s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:07.194726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:05.651907Z","time spent":"1.542681787s","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:43:07.194825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:05.664351Z","time spent":"1.530371849s","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:43:07.194974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:06.153771Z","time spent":"1.04096645s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:43:07.194984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508447018s","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-02T16:43:07.195112Z","caller":"traceutil/trace.go:171","msg":"trace[2100081788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"1.508622943s","start":"2026-04-02T16:43:05.686455Z","end":"2026-04-02T16:43:07.195077Z","steps":["trace[2100081788] 'agreement among raft nodes before linearized reading' (duration: 1.508315306s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:07.195172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:05.686441Z","time spent":"1.508720055s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T16:43:07.710379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.513036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:43:07.710537Z","caller":"traceutil/trace.go:171","msg":"trace[1944820794] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"515.875998ms","start":"2026-04-02T16:43:07.194637Z","end":"2026-04-02T16:43:07.710513Z","steps":["trace[1944820794] 'read index received' (duration: 118.287701ms)","trace[1944820794] 'applied index is now lower than readState.Index' (duration: 397.586607ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:43:07.710764Z","caller":"traceutil/trace.go:171","msg":"trace[1337495807] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"638.881398ms","start":"2026-04-02T16:43:07.071797Z","end":"2026-04-02T16:43:07.710678Z","steps":["trace[1337495807] 'process raft request' (duration: 240.948082ms)","trace[1337495807] 'compare' (duration: 397.316622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:07.710892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.81104729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:43:07.711018Z","caller":"traceutil/trace.go:171","msg":"trace[692647274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.811258445s","start":"2026-04-02T16:43:05.899733Z","end":"2026-04-02T16:43:07.710992Z","steps":["trace[692647274] 'agreement among raft nodes before linearized reading' (duration: 1.811038059s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:07.71108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.071752Z","time spent":"639.166626ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:43:07.711107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:05.899703Z","time spent":"1.811384869s","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:43:07.721816Z","caller":"traceutil/trace.go:171","msg":"trace[881337616] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"520.786963ms","start":"2026-04-02T16:43:07.200973Z","end":"2026-04-02T16:43:07.72176Z","steps":["trace[881337616] 'process raft request' (duration: 520.478196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:07.722066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.200939Z","time spent":"521.012348ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:07.722119Z","caller":"traceutil/trace.go:171","msg":"trace[246036480] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"508.026785ms","start":"2026-04-02T16:43:07.214032Z","end":"2026-04-02T16:43:07.722059Z","steps":["trace[246036480] 'process raft request' (duration: 507.612485ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:07.722236Z","caller":"traceutil/trace.go:171","msg":"trace[510857273] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"443.414838ms","start":"2026-04-02T16:43:07.278799Z","end":"2026-04-02T16:43:07.722214Z","steps":["trace[510857273] 'process raft request' (duration: 443.138341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:07.722327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.278745Z","time spent":"443.542381ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:43:07.72242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.213998Z","time spent":"508.280872ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:43:07.722558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.017685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-02T16:43:07.722645Z","caller":"traceutil/trace.go:171","msg":"trace[1863786031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"523.103937ms","start":"2026-04-02T16:43:07.199521Z","end":"2026-04-02T16:43:07.722625Z","steps":["trace[1863786031] 'agreement among raft nodes before linearized reading' (duration: 522.80385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:07.722665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.25779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T16:43:07.722714Z","caller":"traceutil/trace.go:171","msg":"trace[252589821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"226.357252ms","start":"2026-04-02T16:43:07.496345Z","end":"2026-04-02T16:43:07.722702Z","steps":["trace[252589821] 'agreement among raft nodes before linearized reading' (duration: 225.757248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:07.722695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.199319Z","time spent":"523.367183ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T16:43:07.723108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.120286ms","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-02T16:43:07.723186Z","caller":"traceutil/trace.go:171","msg":"trace[2113196178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"226.427824ms","start":"2026-04-02T16:43:07.496745Z","end":"2026-04-02T16:43:07.723173Z","steps":["trace[2113196178] 'agreement among raft nodes before linearized reading' (duration: 225.353279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:08.305345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.463545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:43:08.305712Z","caller":"traceutil/trace.go:171","msg":"trace[1124609470] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"575.599232ms","start":"2026-04-02T16:43:07.730083Z","end":"2026-04-02T16:43:08.305683Z","steps":["trace[1124609470] 'process raft request' (duration: 117.666966ms)","trace[1124609470] 'compare' (duration: 457.278731ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:08.305875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.730052Z","time spent":"575.734586ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:08.306003Z","caller":"traceutil/trace.go:171","msg":"trace[386991785] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"575.701624ms","start":"2026-04-02T16:43:07.730288Z","end":"2026-04-02T16:43:08.30599Z","steps":["trace[386991785] 'process raft request' (duration: 575.390497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:08.306058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.730276Z","time spent":"575.755126ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:08.306076Z","caller":"traceutil/trace.go:171","msg":"trace[423532059] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:589; }","duration":"574.468186ms","start":"2026-04-02T16:43:07.731585Z","end":"2026-04-02T16:43:08.306053Z","steps":["trace[423532059] 'read index received' (duration: 116.218382ms)","trace[423532059] 'applied index is now lower than readState.Index' (duration: 458.248604ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:43:08.306143Z","caller":"traceutil/trace.go:171","msg":"trace[864044752] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"575.954421ms","start":"2026-04-02T16:43:07.73018Z","end":"2026-04-02T16:43:08.306135Z","steps":["trace[864044752] 'process raft request' (duration: 575.418918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:08.306194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.626199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:43:08.306246Z","caller":"traceutil/trace.go:171","msg":"trace[1120132553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"574.711941ms","start":"2026-04-02T16:43:07.73152Z","end":"2026-04-02T16:43:08.306232Z","steps":["trace[1120132553] 'agreement among raft nodes before linearized reading' (duration: 574.604028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:08.306248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.730151Z","time spent":"576.017711ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:43:08.306292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.731507Z","time spent":"574.770362ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:43:08.306416Z","caller":"traceutil/trace.go:171","msg":"trace[307921082] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"569.393137ms","start":"2026-04-02T16:43:07.737013Z","end":"2026-04-02T16:43:08.306406Z","steps":["trace[307921082] 'process raft request' (duration: 568.741572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:08.306477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.736999Z","time spent":"569.447308ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:08.306605Z","caller":"traceutil/trace.go:171","msg":"trace[1690146433] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"560.053049ms","start":"2026-04-02T16:43:07.746521Z","end":"2026-04-02T16:43:08.306574Z","steps":["trace[1690146433] 'process raft request' (duration: 559.456365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:08.306798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.746501Z","time spent":"560.209283ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:08.307307Z","caller":"traceutil/trace.go:171","msg":"trace[122944035] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"563.281724ms","start":"2026-04-02T16:43:07.743252Z","end":"2026-04-02T16:43:08.306534Z","steps":["trace[122944035] 'process raft request' (duration: 562.630369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:08.307425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:07.743212Z","time spent":"564.151084ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65199,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:13.75118Z","caller":"traceutil/trace.go:171","msg":"trace[1195213097] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"109.196688ms","start":"2026-04-02T16:43:13.641948Z","end":"2026-04-02T16:43:13.751145Z","steps":["trace[1195213097] 'read index received' (duration: 107.927128ms)","trace[1195213097] 'applied index is now lower than readState.Index' (duration: 1.26801ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:13.751392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.419254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T16:43:13.751352Z","caller":"traceutil/trace.go:171","msg":"trace[171137429] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"112.773502ms","start":"2026-04-02T16:43:13.638524Z","end":"2026-04-02T16:43:13.751297Z","steps":["trace[171137429] 'process raft request' (duration: 111.127264ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:13.751432Z","caller":"traceutil/trace.go:171","msg":"trace[569164137] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:616; }","duration":"109.484205ms","start":"2026-04-02T16:43:13.641937Z","end":"2026-04-02T16:43:13.751421Z","steps":["trace[569164137] 'agreement among raft nodes before linearized reading' (duration: 109.376123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:15.311929Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622941552,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T16:43:15.329944Z","caller":"traceutil/trace.go:171","msg":"trace[136528734] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"518.547459ms","start":"2026-04-02T16:43:14.811365Z","end":"2026-04-02T16:43:15.329912Z","steps":["trace[136528734] 'read index received' (duration: 517.449574ms)","trace[136528734] 'applied index is now lower than readState.Index' (duration: 1.096445ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:15.330133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.730383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mf22j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-02T16:43:15.330202Z","caller":"traceutil/trace.go:171","msg":"trace[30959873] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mf22j; range_end:; response_count:1; response_revision:628; }","duration":"518.871317ms","start":"2026-04-02T16:43:14.811316Z","end":"2026-04-02T16:43:15.330187Z","steps":["trace[30959873] 'agreement among raft nodes before linearized reading' (duration: 518.683252ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:15.330081Z","caller":"traceutil/trace.go:171","msg":"trace[876359436] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"573.147773ms","start":"2026-04-02T16:43:14.756871Z","end":"2026-04-02T16:43:15.330019Z","steps":["trace[876359436] 'process raft request' (duration: 572.225132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:15.330245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:14.811287Z","time spent":"518.948738ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-mf22j\" "} {"level":"warn","ts":"2026-04-02T16:43:15.330414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:14.756834Z","time spent":"573.427339ms","remote":"127.0.0.1:55634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:16.010056Z","caller":"traceutil/trace.go:171","msg":"trace[250538330] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"658.615806ms","start":"2026-04-02T16:43:15.351418Z","end":"2026-04-02T16:43:16.010034Z","steps":["trace[250538330] 'process raft request' (duration: 658.434062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:16.010446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:15.351381Z","time spent":"658.745389ms","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:43:17.899953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000256669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T16:43:17.900078Z","caller":"traceutil/trace.go:171","msg":"trace[773232680] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000460314s","start":"2026-04-02T16:43:15.89959Z","end":"2026-04-02T16:43:17.90005Z","steps":["trace[773232680] 'agreement among raft nodes before linearized reading' (duration: 2.00029255s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:17.900146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:15.899559Z","time spent":"2.000571206s","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 16:43:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T16:43:18.044076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.84263927s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:43:18.044418Z","caller":"traceutil/trace.go:171","msg":"trace[1659174820] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"601.192161ms","start":"2026-04-02T16:43:17.443198Z","end":"2026-04-02T16:43:18.04439Z","steps":["trace[1659174820] 'process raft request' (duration: 601.038417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.044627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:17.443178Z","time spent":"601.293534ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:18.044914Z","caller":"traceutil/trace.go:171","msg":"trace[2121671350] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"2.661368184s","start":"2026-04-02T16:43:15.383531Z","end":"2026-04-02T16:43:18.044899Z","steps":["trace[2121671350] 'process raft request' (duration: 817.791972ms)","trace[2121671350] 'compare' (duration: 1.841946464s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:18.044987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:15.383501Z","time spent":"2.661453725s","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:43:18.045079Z","caller":"traceutil/trace.go:171","msg":"trace[1388623746] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"2.282533646s","start":"2026-04-02T16:43:15.762534Z","end":"2026-04-02T16:43:18.045068Z","steps":["trace[1388623746] 'read index received' (duration: 247.402581ms)","trace[1388623746] 'applied index is now lower than readState.Index' (duration: 2.035129835s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:18.045454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.02762776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mf22j\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-04-02T16:43:18.045495Z","caller":"traceutil/trace.go:171","msg":"trace[2099449462] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mf22j; range_end:; response_count:1; response_revision:631; }","duration":"2.027703812s","start":"2026-04-02T16:43:16.017781Z","end":"2026-04-02T16:43:18.045484Z","steps":["trace[2099449462] 'agreement among raft nodes before linearized reading' (duration: 2.027537518s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.045564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:16.017765Z","time spent":"2.027791284s","remote":"127.0.0.1:55730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17172,"request content":"key:\"/registry/pods/kube-system/cilium-mf22j\" "} {"level":"warn","ts":"2026-04-02T16:43:18.045846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.487285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:43:18.045886Z","caller":"traceutil/trace.go:171","msg":"trace[1461456686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"145.579247ms","start":"2026-04-02T16:43:17.900297Z","end":"2026-04-02T16:43:18.045876Z","steps":["trace[1461456686] 'agreement among raft nodes before linearized reading' (duration: 145.516856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.045972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684270813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T16:43:18.04603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684338094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T16:43:18.045474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.282919495s","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-02T16:43:18.046095Z","caller":"traceutil/trace.go:171","msg":"trace[1713687404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"1.684455727s","start":"2026-04-02T16:43:16.361617Z","end":"2026-04-02T16:43:18.046073Z","steps":["trace[1713687404] 'agreement among raft nodes before linearized reading' (duration: 1.684259692s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.046152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:16.361597Z","time spent":"1.684539779s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T16:43:18.04614Z","caller":"traceutil/trace.go:171","msg":"trace[1250651266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"2.283644052s","start":"2026-04-02T16:43:15.762478Z","end":"2026-04-02T16:43:18.046122Z","steps":["trace[1250651266] 'agreement among raft nodes before linearized reading' (duration: 2.282775772s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.046212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677768541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-02T16:43:18.046221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:15.762449Z","time spent":"2.283754255s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T16:43:18.046254Z","caller":"traceutil/trace.go:171","msg":"trace[12031185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.677846753s","start":"2026-04-02T16:43:16.368395Z","end":"2026-04-02T16:43:18.046242Z","steps":["trace[12031185] 'agreement among raft nodes before linearized reading' (duration: 1.67772291s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.04629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:16.368375Z","time spent":"1.677906634s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T16:43:18.046076Z","caller":"traceutil/trace.go:171","msg":"trace[244720907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.684371225s","start":"2026-04-02T16:43:16.361668Z","end":"2026-04-02T16:43:18.046039Z","steps":["trace[244720907] 'agreement among raft nodes before linearized reading' (duration: 1.68415067s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.046581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:16.361663Z","time spent":"1.684888137s","remote":"127.0.0.1:55812","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-02T16:43:18.282244Z","caller":"traceutil/trace.go:171","msg":"trace[1690980514] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"229.764418ms","start":"2026-04-02T16:43:18.052417Z","end":"2026-04-02T16:43:18.282182Z","steps":["trace[1690980514] 'process raft request' (duration: 213.673811ms)","trace[1690980514] 'compare' (duration: 15.600105ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:43:18.282932Z","caller":"traceutil/trace.go:171","msg":"trace[1232197856] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"230.443444ms","start":"2026-04-02T16:43:18.052474Z","end":"2026-04-02T16:43:18.282918Z","steps":["trace[1232197856] 'process raft request' (duration: 230.146097ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:18.283304Z","caller":"traceutil/trace.go:171","msg":"trace[1958449030] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"230.3231ms","start":"2026-04-02T16:43:18.052626Z","end":"2026-04-02T16:43:18.282949Z","steps":["trace[1958449030] 'process raft request' (duration: 230.195668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.28353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.931906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-02T16:43:18.283584Z","caller":"traceutil/trace.go:171","msg":"trace[877350408] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:636; }","duration":"231.035219ms","start":"2026-04-02T16:43:18.052536Z","end":"2026-04-02T16:43:18.283571Z","steps":["trace[877350408] 'agreement among raft nodes before linearized reading' (duration: 230.850444ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:18.283355Z","caller":"traceutil/trace.go:171","msg":"trace[1510503201] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"230.751782ms","start":"2026-04-02T16:43:18.052571Z","end":"2026-04-02T16:43:18.283323Z","steps":["trace[1510503201] 'read index received' (duration: 213.60804ms)","trace[1510503201] 'applied index is now lower than readState.Index' (duration: 17.142422ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:43:18.282996Z","caller":"traceutil/trace.go:171","msg":"trace[925195661] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"229.940652ms","start":"2026-04-02T16:43:18.053027Z","end":"2026-04-02T16:43:18.282967Z","steps":["trace[925195661] 'process raft request' (duration: 229.84338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:18.283964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.251653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:43:18.284001Z","caller":"traceutil/trace.go:171","msg":"trace[2038970124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"228.304524ms","start":"2026-04-02T16:43:18.055686Z","end":"2026-04-02T16:43:18.28399Z","steps":["trace[2038970124] 'agreement among raft nodes before linearized reading' (duration: 228.246463ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:18.284188Z","caller":"traceutil/trace.go:171","msg":"trace[825831301] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"229.985873ms","start":"2026-04-02T16:43:18.053601Z","end":"2026-04-02T16:43:18.283587Z","steps":["trace[825831301] 'process raft request' (duration: 229.326407ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:20.27801Z","caller":"traceutil/trace.go:171","msg":"trace[775764486] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"209.612067ms","start":"2026-04-02T16:43:20.068372Z","end":"2026-04-02T16:43:20.277984Z","steps":["trace[775764486] 'process raft request' (duration: 209.300819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:26.009843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.77714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:43:26.009988Z","caller":"traceutil/trace.go:171","msg":"trace[980798978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"110.074837ms","start":"2026-04-02T16:43:25.899881Z","end":"2026-04-02T16:43:26.009956Z","steps":["trace[980798978] 'range keys from in-memory index tree' (duration: 109.619057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:27.075885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.627834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:43:27.076031Z","caller":"traceutil/trace.go:171","msg":"trace[1797857735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"173.802218ms","start":"2026-04-02T16:43:26.902198Z","end":"2026-04-02T16:43:27.076001Z","steps":["trace[1797857735] 'range keys from in-memory index tree' (duration: 173.493861ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:29.561834Z","caller":"traceutil/trace.go:171","msg":"trace[610950830] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"132.904701ms","start":"2026-04-02T16:43:29.428895Z","end":"2026-04-02T16:43:29.5618Z","steps":["trace[610950830] 'read index received' (duration: 34.872957ms)","trace[610950830] 'applied index is now lower than readState.Index' (duration: 98.029174ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:29.562181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.265789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mf22j\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-04-02T16:43:29.562225Z","caller":"traceutil/trace.go:171","msg":"trace[1801154996] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mf22j; range_end:; response_count:1; response_revision:746; }","duration":"133.409973ms","start":"2026-04-02T16:43:29.428805Z","end":"2026-04-02T16:43:29.562215Z","steps":["trace[1801154996] 'agreement among raft nodes before linearized reading' (duration: 133.120316ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:29.942879Z","caller":"traceutil/trace.go:171","msg":"trace[1139206939] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"210.927148ms","start":"2026-04-02T16:43:29.731922Z","end":"2026-04-02T16:43:29.942849Z","steps":["trace[1139206939] 'process raft request' (duration: 210.868976ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:29.942891Z","caller":"traceutil/trace.go:171","msg":"trace[1688931945] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"211.592693ms","start":"2026-04-02T16:43:29.731273Z","end":"2026-04-02T16:43:29.942866Z","steps":["trace[1688931945] 'process raft request' (duration: 211.45702ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:29.943167Z","caller":"traceutil/trace.go:171","msg":"trace[979955902] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"211.747427ms","start":"2026-04-02T16:43:29.731063Z","end":"2026-04-02T16:43:29.94281Z","steps":["trace[979955902] 'process raft request' (duration: 211.48857ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:29.982523Z","caller":"traceutil/trace.go:171","msg":"trace[1718099626] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"240.847017ms","start":"2026-04-02T16:43:29.74164Z","end":"2026-04-02T16:43:29.982487Z","steps":["trace[1718099626] 'process raft request' (duration: 240.54826ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:32.470047Z","caller":"traceutil/trace.go:171","msg":"trace[1759638514] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"101.531317ms","start":"2026-04-02T16:43:32.368482Z","end":"2026-04-02T16:43:32.470014Z","steps":["trace[1759638514] 'process raft request' (duration: 101.091847ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:33.506728Z","caller":"traceutil/trace.go:171","msg":"trace[1080836082] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"110.754553ms","start":"2026-04-02T16:43:33.395946Z","end":"2026-04-02T16:43:33.506701Z","steps":["trace[1080836082] 'process raft request' (duration: 110.575369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:37.013556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622941898,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T16:43:37.401774Z","caller":"traceutil/trace.go:171","msg":"trace[727190512] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"916.061281ms","start":"2026-04-02T16:43:36.485686Z","end":"2026-04-02T16:43:37.401748Z","steps":["trace[727190512] 'process raft request' (duration: 915.866647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:37.402118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:36.485653Z","time spent":"916.203775ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:37.421258Z","caller":"traceutil/trace.go:171","msg":"trace[1696232714] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:834; }","duration":"908.095055ms","start":"2026-04-02T16:43:36.513138Z","end":"2026-04-02T16:43:37.421233Z","steps":["trace[1696232714] 'read index received' (duration: 888.354382ms)","trace[1696232714] 'applied index is now lower than readState.Index' (duration: 19.739012ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:43:37.421327Z","caller":"traceutil/trace.go:171","msg":"trace[668407396] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"461.233325ms","start":"2026-04-02T16:43:36.960058Z","end":"2026-04-02T16:43:37.421292Z","steps":["trace[668407396] 'process raft request' (duration: 460.981249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:37.421579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.388612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T16:43:37.421648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.360356ms","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-02T16:43:37.421664Z","caller":"traceutil/trace.go:171","msg":"trace[273485047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"908.572946ms","start":"2026-04-02T16:43:36.513072Z","end":"2026-04-02T16:43:37.421645Z","steps":["trace[273485047] 'agreement among raft nodes before linearized reading' (duration: 908.32534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:37.421724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:36.513045Z","time spent":"908.664388ms","remote":"127.0.0.1:55812","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-02T16:43:37.421697Z","caller":"traceutil/trace.go:171","msg":"trace[1660912386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"836.462858ms","start":"2026-04-02T16:43:36.58522Z","end":"2026-04-02T16:43:37.421683Z","steps":["trace[1660912386] 'agreement among raft nodes before linearized reading' (duration: 836.293714ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:37.421781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:36.585191Z","time spent":"836.581511ms","remote":"127.0.0.1:55812","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-02T16:43:37.421748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:36.96002Z","time spent":"461.487082ms","remote":"127.0.0.1:55796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:43:37.422128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.745935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:43:37.422204Z","caller":"traceutil/trace.go:171","msg":"trace[1623166845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"836.839696ms","start":"2026-04-02T16:43:36.585345Z","end":"2026-04-02T16:43:37.422185Z","steps":["trace[1623166845] 'agreement among raft nodes before linearized reading' (duration: 836.425537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:37.422605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:36.58534Z","time spent":"837.250927ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T16:43:46.462988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.742472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:43:46.463157Z","caller":"traceutil/trace.go:171","msg":"trace[1339201243] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"332.820761ms","start":"2026-04-02T16:43:46.130309Z","end":"2026-04-02T16:43:46.46313Z","steps":["trace[1339201243] 'process raft request' (duration: 42.799692ms)","trace[1339201243] 'compare' (duration: 289.577089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:46.463255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:46.130284Z","time spent":"332.934873ms","remote":"127.0.0.1:55708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:46.509594Z","caller":"traceutil/trace.go:171","msg":"trace[856442707] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"379.150174ms","start":"2026-04-02T16:43:46.130424Z","end":"2026-04-02T16:43:46.509574Z","steps":["trace[856442707] 'process raft request' (duration: 378.95328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:46.509772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:46.130385Z","time spent":"379.276237ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:46.509908Z","caller":"traceutil/trace.go:171","msg":"trace[858026373] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"378.343026ms","start":"2026-04-02T16:43:46.131503Z","end":"2026-04-02T16:43:46.509846Z","steps":["trace[858026373] 'process raft request' (duration: 377.993118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:46.510121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:43:46.131487Z","time spent":"378.52173ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1451,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:43:47.415843Z","caller":"traceutil/trace.go:171","msg":"trace[1369216620] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"170.320736ms","start":"2026-04-02T16:43:47.245503Z","end":"2026-04-02T16:43:47.415824Z","steps":["trace[1369216620] 'process raft request' (duration: 170.183373ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.510888Z","caller":"traceutil/trace.go:171","msg":"trace[914845056] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"264.945582ms","start":"2026-04-02T16:43:47.245908Z","end":"2026-04-02T16:43:47.510854Z","steps":["trace[914845056] 'process raft request' (duration: 264.740607ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.600074Z","caller":"traceutil/trace.go:171","msg":"trace[1974227182] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"176.702336ms","start":"2026-04-02T16:43:47.42334Z","end":"2026-04-02T16:43:47.600042Z","steps":["trace[1974227182] 'process raft request' (duration: 172.037386ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.600142Z","caller":"traceutil/trace.go:171","msg":"trace[1753351983] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"176.353038ms","start":"2026-04-02T16:43:47.423757Z","end":"2026-04-02T16:43:47.60011Z","steps":["trace[1753351983] 'process raft request' (duration: 176.190014ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.600206Z","caller":"traceutil/trace.go:171","msg":"trace[1360520455] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"176.226875ms","start":"2026-04-02T16:43:47.423934Z","end":"2026-04-02T16:43:47.600161Z","steps":["trace[1360520455] 'process raft request' (duration: 176.072512ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.600656Z","caller":"traceutil/trace.go:171","msg":"trace[396486063] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"175.988289ms","start":"2026-04-02T16:43:47.424655Z","end":"2026-04-02T16:43:47.600643Z","steps":["trace[396486063] 'process raft request' (duration: 175.385726ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.602462Z","caller":"traceutil/trace.go:171","msg":"trace[2001748269] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"174.31312ms","start":"2026-04-02T16:43:47.428107Z","end":"2026-04-02T16:43:47.60242Z","steps":["trace[2001748269] 'process raft request' (duration: 172.282744ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.652938Z","caller":"traceutil/trace.go:171","msg":"trace[129056497] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"130.818332ms","start":"2026-04-02T16:43:47.522093Z","end":"2026-04-02T16:43:47.652911Z","steps":["trace[129056497] 'process raft request' (duration: 130.685018ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.79849Z","caller":"traceutil/trace.go:171","msg":"trace[1289882529] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"110.70314ms","start":"2026-04-02T16:43:47.687762Z","end":"2026-04-02T16:43:47.798465Z","steps":["trace[1289882529] 'process raft request' (duration: 110.646859ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:47.798556Z","caller":"traceutil/trace.go:171","msg":"trace[1649283574] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"111.292834ms","start":"2026-04-02T16:43:47.687218Z","end":"2026-04-02T16:43:47.798511Z","steps":["trace[1649283574] 'process raft request' (duration: 111.002717ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:08.917412Z","caller":"traceutil/trace.go:171","msg":"trace[671332600] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"136.804682ms","start":"2026-04-02T16:44:08.780586Z","end":"2026-04-02T16:44:08.917391Z","steps":["trace[671332600] 'read index received' (duration: 136.589667ms)","trace[671332600] 'applied index is now lower than readState.Index' (duration: 213.755µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:44:08.917512Z","caller":"traceutil/trace.go:171","msg":"trace[586924594] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"191.033671ms","start":"2026-04-02T16:44:08.726462Z","end":"2026-04-02T16:44:08.917496Z","steps":["trace[586924594] 'process raft request' (duration: 190.765445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:08.917556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.932115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:44:08.91759Z","caller":"traceutil/trace.go:171","msg":"trace[999152812] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1005; }","duration":"137.046448ms","start":"2026-04-02T16:44:08.780534Z","end":"2026-04-02T16:44:08.91758Z","steps":["trace[999152812] 'agreement among raft nodes before linearized reading' (duration: 136.949806ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:10.227422Z","caller":"traceutil/trace.go:171","msg":"trace[2107373173] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"123.679045ms","start":"2026-04-02T16:44:10.103712Z","end":"2026-04-02T16:44:10.227391Z","steps":["trace[2107373173] 'process raft request' (duration: 121.693078ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:12.209754Z","caller":"traceutil/trace.go:171","msg":"trace[1985141130] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"163.809214ms","start":"2026-04-02T16:44:12.045903Z","end":"2026-04-02T16:44:12.209712Z","steps":["trace[1985141130] 'process raft request' (duration: 163.120128ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:13.170367Z","caller":"traceutil/trace.go:171","msg":"trace[1370129206] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"232.330529ms","start":"2026-04-02T16:44:12.937964Z","end":"2026-04-02T16:44:13.170294Z","steps":["trace[1370129206] 'process raft request' (duration: 232.108934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:13.367168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.240967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:44:13.367285Z","caller":"traceutil/trace.go:171","msg":"trace[1362666621] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"420.449121ms","start":"2026-04-02T16:44:12.946815Z","end":"2026-04-02T16:44:13.367264Z","steps":["trace[1362666621] 'process raft request' (duration: 279.0058ms)","trace[1362666621] 'compare' (duration: 141.020921ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:13.367368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:12.946782Z","time spent":"420.557424ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:44:14.10598Z","caller":"traceutil/trace.go:171","msg":"trace[1342576178] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"205.889249ms","start":"2026-04-02T16:44:13.900001Z","end":"2026-04-02T16:44:14.10589Z","steps":["trace[1342576178] 'read index received' (duration: 204.007826ms)","trace[1342576178] 'applied index is now lower than readState.Index' (duration: 1.876523ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:14.106272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:13.699581Z","time spent":"406.677179ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T16:44:14.107323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.726029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:44:14.107415Z","caller":"traceutil/trace.go:171","msg":"trace[421244804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"207.500136ms","start":"2026-04-02T16:44:13.899879Z","end":"2026-04-02T16:44:14.107379Z","steps":["trace[421244804] 'agreement among raft nodes before linearized reading' (duration: 206.80976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:14.720699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.758787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:44:14.720836Z","caller":"traceutil/trace.go:171","msg":"trace[255572654] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"609.796715ms","start":"2026-04-02T16:44:14.111018Z","end":"2026-04-02T16:44:14.720815Z","steps":["trace[255572654] 'process raft request' (duration: 341.839793ms)","trace[255572654] 'compare' (duration: 267.339708ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:14.7209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:14.110999Z","time spent":"609.870396ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:44:14.724425Z","caller":"traceutil/trace.go:171","msg":"trace[765999381] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1067; }","duration":"510.241295ms","start":"2026-04-02T16:44:14.214156Z","end":"2026-04-02T16:44:14.724397Z","steps":["trace[765999381] 'read index received' (duration: 238.721229ms)","trace[765999381] 'applied index is now lower than readState.Index' (duration: 271.519216ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:44:14.724515Z","caller":"traceutil/trace.go:171","msg":"trace[181313798] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"278.086498ms","start":"2026-04-02T16:44:14.446391Z","end":"2026-04-02T16:44:14.724477Z","steps":["trace[181313798] 'process raft request' (duration: 277.971886ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:14.724564Z","caller":"traceutil/trace.go:171","msg":"trace[761753221] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"611.781131ms","start":"2026-04-02T16:44:14.11267Z","end":"2026-04-02T16:44:14.724451Z","steps":["trace[761753221] 'process raft request' (duration: 611.541265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:14.724708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.542131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-02T16:44:14.724748Z","caller":"traceutil/trace.go:171","msg":"trace[1903844881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"510.657683ms","start":"2026-04-02T16:44:14.21408Z","end":"2026-04-02T16:44:14.724738Z","steps":["trace[1903844881] 'agreement among raft nodes before linearized reading' (duration: 510.455429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:14.724773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:14.214056Z","time spent":"510.711785ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T16:44:14.724905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.620452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T16:44:14.724934Z","caller":"traceutil/trace.go:171","msg":"trace[178969870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"501.695934ms","start":"2026-04-02T16:44:14.223228Z","end":"2026-04-02T16:44:14.724924Z","steps":["trace[178969870] 'agreement among raft nodes before linearized reading' (duration: 501.628322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:14.724968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:14.223208Z","time spent":"501.751736ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T16:44:14.724969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:14.112646Z","time spent":"612.070437ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:44:14.725153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.341291ms","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-02T16:44:14.725185Z","caller":"traceutil/trace.go:171","msg":"trace[2055445679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"482.418693ms","start":"2026-04-02T16:44:14.242758Z","end":"2026-04-02T16:44:14.725176Z","steps":["trace[2055445679] 'agreement among raft nodes before linearized reading' (duration: 482.345851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:14.72523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:14.242733Z","time spent":"482.487485ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T16:44:17.534736Z","caller":"traceutil/trace.go:171","msg":"trace[993054122] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"151.788523ms","start":"2026-04-02T16:44:17.38287Z","end":"2026-04-02T16:44:17.534659Z","steps":["trace[993054122] 'process raft request' (duration: 151.025315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:42.391279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.714451ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:44:42.391396Z","caller":"traceutil/trace.go:171","msg":"trace[827281191] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"490.50269ms","start":"2026-04-02T16:44:41.900875Z","end":"2026-04-02T16:44:42.391378Z","steps":["trace[827281191] 'read index received' (duration: 89.452µs)","trace[827281191] 'applied index is now lower than readState.Index' (duration: 490.411968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:42.391473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.627213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:44:42.391498Z","caller":"traceutil/trace.go:171","msg":"trace[2106663144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"490.704235ms","start":"2026-04-02T16:44:41.900784Z","end":"2026-04-02T16:44:42.391488Z","steps":["trace[2106663144] 'agreement among raft nodes before linearized reading' (duration: 490.641494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:42.391528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:41.900757Z","time spent":"490.764177ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:44:42.391775Z","caller":"traceutil/trace.go:171","msg":"trace[1601998699] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"744.936226ms","start":"2026-04-02T16:44:41.646797Z","end":"2026-04-02T16:44:42.391734Z","steps":["trace[1601998699] 'process raft request' (duration: 199.646681ms)","trace[1601998699] 'compare' (duration: 544.601569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:42.391955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:41.646766Z","time spent":"745.09183ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:44:43.76445Z","caller":"traceutil/trace.go:171","msg":"trace[1980501876] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1169; }","duration":"114.090168ms","start":"2026-04-02T16:44:43.65033Z","end":"2026-04-02T16:44:43.76442Z","steps":["trace[1980501876] 'read index received' (duration: 113.80966ms)","trace[1980501876] 'applied index is now lower than readState.Index' (duration: 278.787µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:44:43.764628Z","caller":"traceutil/trace.go:171","msg":"trace[1885868220] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"331.457545ms","start":"2026-04-02T16:44:43.433131Z","end":"2026-04-02T16:44:43.764589Z","steps":["trace[1885868220] 'process raft request' (duration: 331.084715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:43.764899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.550778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T16:44:43.764954Z","caller":"traceutil/trace.go:171","msg":"trace[1592168049] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1121; }","duration":"114.63278ms","start":"2026-04-02T16:44:43.650309Z","end":"2026-04-02T16:44:43.764942Z","steps":["trace[1592168049] 'agreement among raft nodes before linearized reading' (duration: 114.230841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:43.765142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:43.433113Z","time spent":"331.69183ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:44:44.880695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.526143ms","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-02T16:44:44.88087Z","caller":"traceutil/trace.go:171","msg":"trace[2063041685] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1121; }","duration":"833.712808ms","start":"2026-04-02T16:44:44.047131Z","end":"2026-04-02T16:44:44.880844Z","steps":["trace[2063041685] 'range keys from in-memory index tree' (duration: 833.498893ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:44.881376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.418855ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-02T16:44:44.88148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:43.766853Z","time spent":"1.114620987s","remote":"127.0.0.1:55560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-02T16:44:44.881721Z","caller":"traceutil/trace.go:171","msg":"trace[256543244] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"483.143754ms","start":"2026-04-02T16:44:44.398559Z","end":"2026-04-02T16:44:44.881703Z","steps":["trace[256543244] 'process raft request' (duration: 482.915458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:44.881857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:44.398505Z","time spent":"483.275097ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:44:44.881947Z","caller":"traceutil/trace.go:171","msg":"trace[1295490585] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1170; }","duration":"982.426025ms","start":"2026-04-02T16:44:43.899501Z","end":"2026-04-02T16:44:44.881927Z","steps":["trace[1295490585] 'read index received' (duration: 204.386035ms)","trace[1295490585] 'applied index is now lower than readState.Index' (duration: 778.03799ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:44.882187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.684462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:44:44.882242Z","caller":"traceutil/trace.go:171","msg":"trace[289230356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1122; }","duration":"982.769844ms","start":"2026-04-02T16:44:43.899457Z","end":"2026-04-02T16:44:44.882227Z","steps":["trace[289230356] 'agreement among raft nodes before linearized reading' (duration: 982.671161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:44.882283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:43.89944Z","time spent":"982.835445ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:44:45.345072Z","caller":"traceutil/trace.go:171","msg":"trace[576012073] linearizableReadLoop","detail":"{readStateIndex:1174; appliedIndex:1173; }","duration":"299.556298ms","start":"2026-04-02T16:44:45.045486Z","end":"2026-04-02T16:44:45.345043Z","steps":["trace[576012073] 'read index received' (duration: 299.334963ms)","trace[576012073] 'applied index is now lower than readState.Index' (duration: 219.905µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:44:45.345119Z","caller":"traceutil/trace.go:171","msg":"trace[107652561] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"302.007847ms","start":"2026-04-02T16:44:45.043082Z","end":"2026-04-02T16:44:45.34509Z","steps":["trace[107652561] 'process raft request' (duration: 301.764052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:45.345291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:45.043058Z","time spent":"302.13982ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:44:45.345393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.954122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:44:45.34549Z","caller":"traceutil/trace.go:171","msg":"trace[383726270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"281.116426ms","start":"2026-04-02T16:44:45.064363Z","end":"2026-04-02T16:44:45.345479Z","steps":["trace[383726270] 'agreement among raft nodes before linearized reading' (duration: 280.88075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:45.345547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.582383ms","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-02T16:44:45.345588Z","caller":"traceutil/trace.go:171","msg":"trace[1689589073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1124; }","duration":"280.635094ms","start":"2026-04-02T16:44:45.064937Z","end":"2026-04-02T16:44:45.345572Z","steps":["trace[1689589073] 'agreement among raft nodes before linearized reading' (duration: 280.48965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:45.345493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.968698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:44:45.345661Z","caller":"traceutil/trace.go:171","msg":"trace[934556826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1124; }","duration":"300.176763ms","start":"2026-04-02T16:44:45.045459Z","end":"2026-04-02T16:44:45.345635Z","steps":["trace[934556826] 'agreement among raft nodes before linearized reading' (duration: 299.761193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:45.345718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:45.045447Z","time spent":"300.259035ms","remote":"127.0.0.1:55812","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-02T16:44:46.118171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.384189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:44:46.118428Z","caller":"traceutil/trace.go:171","msg":"trace[346793734] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"768.800339ms","start":"2026-04-02T16:44:45.349602Z","end":"2026-04-02T16:44:46.118402Z","steps":["trace[346793734] 'process raft request' (duration: 768.760399ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:46.118421Z","caller":"traceutil/trace.go:171","msg":"trace[815586833] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"769.025545ms","start":"2026-04-02T16:44:45.349351Z","end":"2026-04-02T16:44:46.118377Z","steps":["trace[815586833] 'process raft request' (duration: 115.332498ms)","trace[815586833] 'compare' (duration: 653.131724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:46.118506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:45.349563Z","time spent":"768.907972ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:44:46.118598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:45.349327Z","time spent":"769.182658ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:44:46.118785Z","caller":"traceutil/trace.go:171","msg":"trace[1653615505] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"769.230729ms","start":"2026-04-02T16:44:45.349543Z","end":"2026-04-02T16:44:46.118774Z","steps":["trace[1653615505] 'process raft request' (duration: 768.755438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:46.118845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:45.3495Z","time spent":"769.317361ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:44:46.779339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.475873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:44:46.779489Z","caller":"traceutil/trace.go:171","msg":"trace[1253783774] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1174; }","duration":"1.008590663s","start":"2026-04-02T16:44:45.770876Z","end":"2026-04-02T16:44:46.779467Z","steps":["trace[1253783774] 'read index received' (duration: 97.248983ms)","trace[1253783774] 'applied index is now lower than readState.Index' (duration: 911.33966ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:46.779637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.056479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:44:46.779674Z","caller":"traceutil/trace.go:171","msg":"trace[368622883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1128; }","duration":"880.133231ms","start":"2026-04-02T16:44:45.899531Z","end":"2026-04-02T16:44:46.779664Z","steps":["trace[368622883] 'agreement among raft nodes before linearized reading' (duration: 880.058689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:46.77971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:45.899506Z","time spent":"880.195782ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:44:46.779936Z","caller":"traceutil/trace.go:171","msg":"trace[586047236] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"1.230529789s","start":"2026-04-02T16:44:45.548963Z","end":"2026-04-02T16:44:46.779492Z","steps":["trace[586047236] 'process raft request' (duration: 916.78717ms)","trace[586047236] 'compare' (duration: 313.140655ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:46.780247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:45.548931Z","time spent":"1.231103363s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:44:46.780658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.009155826s","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-02T16:44:46.780839Z","caller":"traceutil/trace.go:171","msg":"trace[1588842663] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1128; }","duration":"1.009970095s","start":"2026-04-02T16:44:45.770821Z","end":"2026-04-02T16:44:46.780791Z","steps":["trace[1588842663] 'agreement among raft nodes before linearized reading' (duration: 1.008723116s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:46.780923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:45.770798Z","time spent":"1.010100329s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T16:44:46.928472Z","caller":"traceutil/trace.go:171","msg":"trace[748180831] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"142.296185ms","start":"2026-04-02T16:44:46.786139Z","end":"2026-04-02T16:44:46.928436Z","steps":["trace[748180831] 'process raft request' (duration: 113.406962ms)","trace[748180831] 'compare' (duration: 28.708829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:48.422944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.698292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-02T16:44:48.422978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.354317ms","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-02T16:44:48.423028Z","caller":"traceutil/trace.go:171","msg":"trace[1541270547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"299.867756ms","start":"2026-04-02T16:44:48.123138Z","end":"2026-04-02T16:44:48.423006Z","steps":["trace[1541270547] 'range keys from in-memory index tree' (duration: 299.462476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:48.422925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.28236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-02T16:44:48.423135Z","caller":"traceutil/trace.go:171","msg":"trace[1397757442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1131; }","duration":"299.466635ms","start":"2026-04-02T16:44:48.123623Z","end":"2026-04-02T16:44:48.42309Z","steps":["trace[1397757442] 'range keys from in-memory index tree' (duration: 299.144518ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:48.423061Z","caller":"traceutil/trace.go:171","msg":"trace[1587947043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1131; }","duration":"300.494421ms","start":"2026-04-02T16:44:48.122546Z","end":"2026-04-02T16:44:48.42304Z","steps":["trace[1587947043] 'range keys from in-memory index tree' (duration: 300.163913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:48.423467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.659006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:44:48.423519Z","caller":"traceutil/trace.go:171","msg":"trace[1086483998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1131; }","duration":"523.713758ms","start":"2026-04-02T16:44:47.899794Z","end":"2026-04-02T16:44:48.423508Z","steps":["trace[1086483998] 'range keys from in-memory index tree' (duration: 522.662342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:48.423549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:47.899728Z","time spent":"523.81407ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:44:48.424183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:48.122522Z","time spent":"301.639108ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T16:44:48.707377Z","caller":"traceutil/trace.go:171","msg":"trace[1707887879] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"277.336106ms","start":"2026-04-02T16:44:48.430007Z","end":"2026-04-02T16:44:48.707343Z","steps":["trace[1707887879] 'process raft request' (duration: 277.242324ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:48.707638Z","caller":"traceutil/trace.go:171","msg":"trace[797640397] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"278.771079ms","start":"2026-04-02T16:44:48.428813Z","end":"2026-04-02T16:44:48.707584Z","steps":["trace[797640397] 'process raft request' (duration: 204.283182ms)","trace[797640397] 'compare' (duration: 73.968855ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:44:49.21867Z","caller":"traceutil/trace.go:171","msg":"trace[1503476618] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"284.696441ms","start":"2026-04-02T16:44:48.933751Z","end":"2026-04-02T16:44:49.218448Z","steps":["trace[1503476618] 'read index received' (duration: 192.070128ms)","trace[1503476618] 'applied index is now lower than readState.Index' (duration: 92.624313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:44:49.219102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.323768ms","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-02T16:44:49.219263Z","caller":"traceutil/trace.go:171","msg":"trace[408171905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1134; }","duration":"285.554543ms","start":"2026-04-02T16:44:48.933683Z","end":"2026-04-02T16:44:49.219238Z","steps":["trace[408171905] 'agreement among raft nodes before linearized reading' (duration: 285.179744ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:44:49.247729Z","caller":"traceutil/trace.go:171","msg":"trace[1353674476] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"306.418334ms","start":"2026-04-02T16:44:48.94129Z","end":"2026-04-02T16:44:49.247709Z","steps":["trace[1353674476] 'process raft request' (duration: 306.194749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:44:49.24799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:44:48.941257Z","time spent":"306.606869ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:01.058857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.365642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:45:01.059069Z","caller":"traceutil/trace.go:171","msg":"trace[993020782] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"286.049194ms","start":"2026-04-02T16:45:00.773002Z","end":"2026-04-02T16:45:01.059051Z","steps":["trace[993020782] 'read index received' (duration: 45.171µs)","trace[993020782] 'applied index is now lower than readState.Index' (duration: 286.002593ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:01.059126Z","caller":"traceutil/trace.go:171","msg":"trace[904561284] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"507.440077ms","start":"2026-04-02T16:45:00.551649Z","end":"2026-04-02T16:45:01.059089Z","steps":["trace[904561284] 'process raft request' (duration: 160.612704ms)","trace[904561284] 'compare' (duration: 346.225819ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:45:01.059269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:00.551617Z","time spent":"507.578491ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:01.059269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.60251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:45:01.059331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.332471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:45:01.059337Z","caller":"traceutil/trace.go:171","msg":"trace[257995983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"159.732534ms","start":"2026-04-02T16:45:00.89959Z","end":"2026-04-02T16:45:01.059322Z","steps":["trace[257995983] 'agreement among raft nodes before linearized reading' (duration: 159.60397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:01.059345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.177817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:45:01.059391Z","caller":"traceutil/trace.go:171","msg":"trace[2117494002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1223; }","duration":"286.401502ms","start":"2026-04-02T16:45:00.772954Z","end":"2026-04-02T16:45:01.059355Z","steps":["trace[2117494002] 'agreement among raft nodes before linearized reading' (duration: 286.198277ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:01.059432Z","caller":"traceutil/trace.go:171","msg":"trace[2142155644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1223; }","duration":"286.2821ms","start":"2026-04-02T16:45:00.77313Z","end":"2026-04-02T16:45:01.059412Z","steps":["trace[2142155644] 'agreement among raft nodes before linearized reading' (duration: 286.055334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:01.992751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622942857,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:45:02.49291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622942857,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:45:02.859358Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.509992375s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:45:02.859632Z","caller":"traceutil/trace.go:171","msg":"trace[1173908938] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"1.367219249s","start":"2026-04-02T16:45:01.492392Z","end":"2026-04-02T16:45:02.859612Z","steps":["trace[1173908938] 'read index received' (duration: 1.367065545s)","trace[1173908938] 'applied index is now lower than readState.Index' (duration: 152.834µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:02.859767Z","caller":"traceutil/trace.go:171","msg":"trace[1215845566] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"1.510528848s","start":"2026-04-02T16:45:01.34923Z","end":"2026-04-02T16:45:02.859759Z","steps":["trace[1215845566] 'process raft request' (duration: 1.510274532s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:02.859855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:01.349211Z","time spent":"1.510576688s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:02.859932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.605073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T16:45:02.859918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229380411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:45:02.859982Z","caller":"traceutil/trace.go:171","msg":"trace[790499690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"330.726627ms","start":"2026-04-02T16:45:02.529242Z","end":"2026-04-02T16:45:02.859969Z","steps":["trace[790499690] 'agreement among raft nodes before linearized reading' (duration: 330.566043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:02.860016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:02.529193Z","time spent":"330.815888ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T16:45:02.860015Z","caller":"traceutil/trace.go:171","msg":"trace[1261570547] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1227; }","duration":"1.229537185s","start":"2026-04-02T16:45:01.630455Z","end":"2026-04-02T16:45:02.859992Z","steps":["trace[1261570547] 'agreement among raft nodes before linearized reading' (duration: 1.22934252s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:02.860012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.839542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:45:02.86003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.005431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:45:02.860063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:01.630431Z","time spent":"1.229621976s","remote":"127.0.0.1:55980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-02T16:45:02.860096Z","caller":"traceutil/trace.go:171","msg":"trace[28592593] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1227; }","duration":"826.970814ms","start":"2026-04-02T16:45:02.033104Z","end":"2026-04-02T16:45:02.860075Z","steps":["trace[28592593] 'agreement among raft nodes before linearized reading' (duration: 826.79903ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:02.860122Z","caller":"traceutil/trace.go:171","msg":"trace[184025475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"961.136855ms","start":"2026-04-02T16:45:01.898964Z","end":"2026-04-02T16:45:02.860101Z","steps":["trace[184025475] 'agreement among raft nodes before linearized reading' (duration: 960.982261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:02.860137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:02.033085Z","time spent":"827.043756ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:45:02.860164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:01.898945Z","time spent":"961.211056ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:45:02.86052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36812989s","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-02T16:45:02.860552Z","caller":"traceutil/trace.go:171","msg":"trace[347380087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"1.368197142s","start":"2026-04-02T16:45:01.492345Z","end":"2026-04-02T16:45:02.860543Z","steps":["trace[347380087] 'agreement among raft nodes before linearized reading' (duration: 1.368065989s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:02.860575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:01.492327Z","time spent":"1.368242093s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T16:45:03.176815Z","caller":"traceutil/trace.go:171","msg":"trace[204931496] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"101.652459ms","start":"2026-04-02T16:45:03.075128Z","end":"2026-04-02T16:45:03.17678Z","steps":["trace[204931496] 'process raft request' (duration: 48.000532ms)","trace[204931496] 'compare' (duration: 53.501214ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:05.65817Z","caller":"traceutil/trace.go:171","msg":"trace[2017787273] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"264.658141ms","start":"2026-04-02T16:45:05.393485Z","end":"2026-04-02T16:45:05.658143Z","steps":["trace[2017787273] 'process raft request' (duration: 264.465147ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:19.266859Z","caller":"traceutil/trace.go:171","msg":"trace[1912722438] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"108.847492ms","start":"2026-04-02T16:45:19.15799Z","end":"2026-04-02T16:45:19.266837Z","steps":["trace[1912722438] 'read index received' (duration: 108.720889ms)","trace[1912722438] 'applied index is now lower than readState.Index' (duration: 125.533µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:19.266961Z","caller":"traceutil/trace.go:171","msg":"trace[403091481] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"164.955129ms","start":"2026-04-02T16:45:19.101972Z","end":"2026-04-02T16:45:19.266928Z","steps":["trace[403091481] 'process raft request' (duration: 164.726563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:19.267182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.169209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:45:19.267234Z","caller":"traceutil/trace.go:171","msg":"trace[1897015879] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1298; }","duration":"109.226971ms","start":"2026-04-02T16:45:19.157984Z","end":"2026-04-02T16:45:19.267211Z","steps":["trace[1897015879] 'agreement among raft nodes before linearized reading' (duration: 109.128108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:20.399849Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622943036,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:45:20.900365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622943036,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:45:21.400967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622943036,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:45:21.443466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770291312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:21.443544Z","caller":"traceutil/trace.go:171","msg":"trace[1198441951] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1304; }","duration":"1.770384543s","start":"2026-04-02T16:45:19.673139Z","end":"2026-04-02T16:45:21.443524Z","steps":["trace[1198441951] 'count revisions from in-memory index tree' (duration: 1.77023761s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:21.443579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:19.673128Z","time spent":"1.770440345s","remote":"127.0.0.1:35466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:45:21.443009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800292432s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:21.443839Z","caller":"traceutil/trace.go:171","msg":"trace[295618652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"1.801184533s","start":"2026-04-02T16:45:19.642646Z","end":"2026-04-02T16:45:21.443831Z","steps":["trace[295618652] 'range keys from in-memory index tree' (duration: 1.800195289s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:21.443858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:19.642621Z","time spent":"1.801230374s","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:45:21.454657Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.699239707s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:45:21.455237Z","caller":"traceutil/trace.go:171","msg":"trace[1673421162] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"1.556401s","start":"2026-04-02T16:45:19.898807Z","end":"2026-04-02T16:45:21.455208Z","steps":["trace[1673421162] 'read index received' (duration: 1.556154154s)","trace[1673421162] 'applied index is now lower than readState.Index' (duration: 245.586µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:21.455317Z","caller":"traceutil/trace.go:171","msg":"trace[624817436] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"1.700062506s","start":"2026-04-02T16:45:19.755217Z","end":"2026-04-02T16:45:21.455279Z","steps":["trace[624817436] 'process raft request' (duration: 1.699835831s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:21.45549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.195949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-02T16:45:21.455493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:19.755186Z","time spent":"1.70021662s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:21.455532Z","caller":"traceutil/trace.go:171","msg":"trace[1129191055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1305; }","duration":"424.302451ms","start":"2026-04-02T16:45:21.031219Z","end":"2026-04-02T16:45:21.455521Z","steps":["trace[1129191055] 'agreement among raft nodes before linearized reading' (duration: 424.175388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:21.455583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:21.031197Z","time spent":"424.365773ms","remote":"127.0.0.1:55812","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-02T16:45:21.455364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.556540893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:21.455706Z","caller":"traceutil/trace.go:171","msg":"trace[1090518804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"1.556903301s","start":"2026-04-02T16:45:19.898791Z","end":"2026-04-02T16:45:21.455695Z","steps":["trace[1090518804] 'agreement among raft nodes before linearized reading' (duration: 1.556515442s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:21.455744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:19.898731Z","time spent":"1.557004343s","remote":"127.0.0.1:55532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:45:21.455741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.989665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T16:45:21.455761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.099798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:45:21.455782Z","caller":"traceutil/trace.go:171","msg":"trace[1893055358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1305; }","duration":"184.088528ms","start":"2026-04-02T16:45:21.271683Z","end":"2026-04-02T16:45:21.455772Z","steps":["trace[1893055358] 'agreement among raft nodes before linearized reading' (duration: 183.890313ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:21.455797Z","caller":"traceutil/trace.go:171","msg":"trace[1404174934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1305; }","duration":"149.162189ms","start":"2026-04-02T16:45:21.306623Z","end":"2026-04-02T16:45:21.455786Z","steps":["trace[1404174934] 'agreement among raft nodes before linearized reading' (duration: 149.075947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:21.455629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.611936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-02T16:45:21.45587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.675622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-02T16:45:21.455882Z","caller":"traceutil/trace.go:171","msg":"trace[1888105017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1305; }","duration":"178.924263ms","start":"2026-04-02T16:45:21.276949Z","end":"2026-04-02T16:45:21.455873Z","steps":["trace[1888105017] 'agreement among raft nodes before linearized reading' (duration: 178.546804ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:21.455902Z","caller":"traceutil/trace.go:171","msg":"trace[1303851318] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1305; }","duration":"407.729844ms","start":"2026-04-02T16:45:21.048163Z","end":"2026-04-02T16:45:21.455893Z","steps":["trace[1303851318] 'agreement among raft nodes before linearized reading' (duration: 407.644952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:21.455929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:21.048148Z","time spent":"407.773445ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T16:45:23.629918Z","caller":"traceutil/trace.go:171","msg":"trace[1373546435] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"161.811483ms","start":"2026-04-02T16:45:23.468082Z","end":"2026-04-02T16:45:23.629893Z","steps":["trace[1373546435] 'read index received' (duration: 161.367253ms)","trace[1373546435] 'applied index is now lower than readState.Index' (duration: 443.28µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:45:23.630168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.074339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:45:23.630207Z","caller":"traceutil/trace.go:171","msg":"trace[1597938375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1312; }","duration":"162.172061ms","start":"2026-04-02T16:45:23.468022Z","end":"2026-04-02T16:45:23.630194Z","steps":["trace[1597938375] 'agreement among raft nodes before linearized reading' (duration: 162.030108ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:23.630312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.710532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T16:45:23.630394Z","caller":"traceutil/trace.go:171","msg":"trace[510440163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1312; }","duration":"159.873967ms","start":"2026-04-02T16:45:23.470504Z","end":"2026-04-02T16:45:23.630378Z","steps":["trace[510440163] 'agreement among raft nodes before linearized reading' (duration: 159.61425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:23.630522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.067021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-02T16:45:23.630559Z","caller":"traceutil/trace.go:171","msg":"trace[1467792031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1312; }","duration":"160.127843ms","start":"2026-04-02T16:45:23.470422Z","end":"2026-04-02T16:45:23.63055Z","steps":["trace[1467792031] 'agreement among raft nodes before linearized reading' (duration: 160.03742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:23.630625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.339172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:45:23.630673Z","caller":"traceutil/trace.go:171","msg":"trace[1956344172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1312; }","duration":"159.409004ms","start":"2026-04-02T16:45:23.471248Z","end":"2026-04-02T16:45:23.630657Z","steps":["trace[1956344172] 'agreement among raft nodes before linearized reading' (duration: 159.288551ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:23.630012Z","caller":"traceutil/trace.go:171","msg":"trace[1439476937] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"168.065823ms","start":"2026-04-02T16:45:23.4619Z","end":"2026-04-02T16:45:23.629965Z","steps":["trace[1439476937] 'process raft request' (duration: 167.583891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:23.631072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.316397ms","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-02T16:45:23.631133Z","caller":"traceutil/trace.go:171","msg":"trace[1074102409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1312; }","duration":"161.184778ms","start":"2026-04-02T16:45:23.469933Z","end":"2026-04-02T16:45:23.631118Z","steps":["trace[1074102409] 'agreement among raft nodes before linearized reading' (duration: 160.163893ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:24.008935Z","caller":"traceutil/trace.go:171","msg":"trace[1186590624] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"372.610191ms","start":"2026-04-02T16:45:23.636299Z","end":"2026-04-02T16:45:24.008909Z","steps":["trace[1186590624] 'process raft request' (duration: 372.550089ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:24.008952Z","caller":"traceutil/trace.go:171","msg":"trace[2050341349] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"372.612701ms","start":"2026-04-02T16:45:23.636299Z","end":"2026-04-02T16:45:24.008912Z","steps":["trace[2050341349] 'process raft request' (duration: 346.824862ms)","trace[2050341349] 'compare' (duration: 25.237486ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:45:24.009128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:23.636279Z","time spent":"372.746626ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:24.009129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:23.636279Z","time spent":"372.730265ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:24.068263Z","caller":"traceutil/trace.go:171","msg":"trace[902946704] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"431.819402ms","start":"2026-04-02T16:45:23.636414Z","end":"2026-04-02T16:45:24.068233Z","steps":["trace[902946704] 'process raft request' (duration: 431.741861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:24.068447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:23.636408Z","time spent":"431.954466ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:24.068602Z","caller":"traceutil/trace.go:171","msg":"trace[717509877] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"432.227473ms","start":"2026-04-02T16:45:23.636365Z","end":"2026-04-02T16:45:24.068592Z","steps":["trace[717509877] 'process raft request' (duration: 431.643669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:24.068657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:23.636358Z","time spent":"432.270824ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:24.068942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.539882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T16:45:24.069008Z","caller":"traceutil/trace.go:171","msg":"trace[1217094824] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1317; }","duration":"415.647953ms","start":"2026-04-02T16:45:23.653346Z","end":"2026-04-02T16:45:24.068994Z","steps":["trace[1217094824] 'agreement among raft nodes before linearized reading' (duration: 415.457589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:24.069053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:23.653332Z","time spent":"415.711696ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.242\" "} {"level":"info","ts":"2026-04-02T16:45:24.068429Z","caller":"traceutil/trace.go:171","msg":"trace[733747153] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1371; }","duration":"415.022809ms","start":"2026-04-02T16:45:23.653383Z","end":"2026-04-02T16:45:24.068405Z","steps":["trace[733747153] 'read index received' (duration: 329.714202ms)","trace[733747153] 'applied index is now lower than readState.Index' (duration: 85.307837ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:24.069407Z","caller":"traceutil/trace.go:171","msg":"trace[1307626952] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"431.556776ms","start":"2026-04-02T16:45:23.63721Z","end":"2026-04-02T16:45:24.068767Z","steps":["trace[1307626952] 'process raft request' (duration: 430.987643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:24.069555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:23.63717Z","time spent":"432.274493ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:26.220775Z","caller":"traceutil/trace.go:171","msg":"trace[1532516235] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"321.264419ms","start":"2026-04-02T16:45:25.899477Z","end":"2026-04-02T16:45:26.220741Z","steps":["trace[1532516235] 'read index received' (duration: 320.991342ms)","trace[1532516235] 'applied index is now lower than readState.Index' (duration: 271.217µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:45:26.22138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.004476ms","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-02T16:45:26.221434Z","caller":"traceutil/trace.go:171","msg":"trace[752669098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"208.101359ms","start":"2026-04-02T16:45:26.013319Z","end":"2026-04-02T16:45:26.22142Z","steps":["trace[752669098] 'agreement among raft nodes before linearized reading' (duration: 207.935125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:26.221583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.0049ms","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-02T16:45:26.221686Z","caller":"traceutil/trace.go:171","msg":"trace[2132774532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"148.260966ms","start":"2026-04-02T16:45:26.0734Z","end":"2026-04-02T16:45:26.221661Z","steps":["trace[2132774532] 'agreement among raft nodes before linearized reading' (duration: 147.923148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:26.221703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.308397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:45:26.221737Z","caller":"traceutil/trace.go:171","msg":"trace[1426450380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"148.375218ms","start":"2026-04-02T16:45:26.073352Z","end":"2026-04-02T16:45:26.221728Z","steps":["trace[1426450380] 'agreement among raft nodes before linearized reading' (duration: 148.286406ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:26.221837Z","caller":"traceutil/trace.go:171","msg":"trace[1145572285] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"585.507151ms","start":"2026-04-02T16:45:25.636319Z","end":"2026-04-02T16:45:26.221826Z","steps":["trace[1145572285] 'process raft request' (duration: 584.159029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:26.221855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.4632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:45:26.221882Z","caller":"traceutil/trace.go:171","msg":"trace[2061040700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"148.531032ms","start":"2026-04-02T16:45:26.073343Z","end":"2026-04-02T16:45:26.221874Z","steps":["trace[2061040700] 'agreement among raft nodes before linearized reading' (duration: 148.46075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:26.221923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:25.636285Z","time spent":"585.576153ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:26.222075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.646001ms","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-02T16:45:26.22206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.550909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:26.222112Z","caller":"traceutil/trace.go:171","msg":"trace[2007425861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"208.688963ms","start":"2026-04-02T16:45:26.013414Z","end":"2026-04-02T16:45:26.222103Z","steps":["trace[2007425861] 'agreement among raft nodes before linearized reading' (duration: 208.59691ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:26.222158Z","caller":"traceutil/trace.go:171","msg":"trace[243329539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"322.726614ms","start":"2026-04-02T16:45:25.899404Z","end":"2026-04-02T16:45:26.222131Z","steps":["trace[243329539] 'agreement among raft nodes before linearized reading' (duration: 322.559229ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:26.222205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:25.899385Z","time spent":"322.811916ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:45:27.035397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.690924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:45:27.035569Z","caller":"traceutil/trace.go:171","msg":"trace[11419892] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"808.267174ms","start":"2026-04-02T16:45:26.22725Z","end":"2026-04-02T16:45:27.035518Z","steps":["trace[11419892] 'process raft request' (duration: 645.376206ms)","trace[11419892] 'compare' (duration: 162.476718ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:45:27.035656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:26.227224Z","time spent":"808.393066ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:27.337589Z","caller":"traceutil/trace.go:171","msg":"trace[666840933] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1380; }","duration":"437.797399ms","start":"2026-04-02T16:45:26.899766Z","end":"2026-04-02T16:45:27.337563Z","steps":["trace[666840933] 'read index received' (duration: 437.502062ms)","trace[666840933] 'applied index is now lower than readState.Index' (duration: 293.967µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:45:27.337747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.957393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:27.337785Z","caller":"traceutil/trace.go:171","msg":"trace[1360346809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"438.064036ms","start":"2026-04-02T16:45:26.899708Z","end":"2026-04-02T16:45:27.337772Z","steps":["trace[1360346809] 'agreement among raft nodes before linearized reading' (duration: 437.960623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:27.337819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:26.899684Z","time spent":"438.126887ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:45:27.338264Z","caller":"traceutil/trace.go:171","msg":"trace[211544772] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"1.110651546s","start":"2026-04-02T16:45:26.227597Z","end":"2026-04-02T16:45:27.338248Z","steps":["trace[211544772] 'process raft request' (duration: 1.109655412s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:27.338371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:26.22758Z","time spent":"1.110715998s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:27.338457Z","caller":"traceutil/trace.go:171","msg":"trace[1130886492] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"1.109221132s","start":"2026-04-02T16:45:26.229223Z","end":"2026-04-02T16:45:27.338444Z","steps":["trace[1130886492] 'process raft request' (duration: 1.108196587s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:27.338499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:26.229215Z","time spent":"1.109260732s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:27.338582Z","caller":"traceutil/trace.go:171","msg":"trace[1268590509] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.108776421s","start":"2026-04-02T16:45:26.229797Z","end":"2026-04-02T16:45:27.338573Z","steps":["trace[1268590509] 'process raft request' (duration: 1.107669714s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:27.338627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:26.229791Z","time spent":"1.108810791s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:27.338707Z","caller":"traceutil/trace.go:171","msg":"trace[408369037] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"1.108581317s","start":"2026-04-02T16:45:26.230118Z","end":"2026-04-02T16:45:27.338699Z","steps":["trace[408369037] 'process raft request' (duration: 1.107384498s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:27.338749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:26.230112Z","time spent":"1.108612837s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:28.7014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500113415622943080,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T16:45:29.012253Z","caller":"traceutil/trace.go:171","msg":"trace[1408585847] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"816.889801ms","start":"2026-04-02T16:45:28.195333Z","end":"2026-04-02T16:45:29.012223Z","steps":["trace[1408585847] 'process raft request' (duration: 816.709917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.012434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:28.195311Z","time spent":"817.037205ms","remote":"127.0.0.1:55606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:45:29.048294Z","caller":"traceutil/trace.go:171","msg":"trace[1469268893] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"819.519124ms","start":"2026-04-02T16:45:28.228746Z","end":"2026-04-02T16:45:29.048265Z","steps":["trace[1469268893] 'process raft request' (duration: 819.242148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.0486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:28.228726Z","time spent":"819.649467ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:29.26507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.902892ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T16:45:29.265311Z","caller":"traceutil/trace.go:171","msg":"trace[151881259] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1387; }","duration":"1.064904033s","start":"2026-04-02T16:45:28.200368Z","end":"2026-04-02T16:45:29.265272Z","steps":["trace[151881259] 'read index received' (duration: 811.729746ms)","trace[151881259] 'applied index is now lower than readState.Index' (duration: 253.166446ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:29.265361Z","caller":"traceutil/trace.go:171","msg":"trace[1506949847] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"248.785981ms","start":"2026-04-02T16:45:29.016538Z","end":"2026-04-02T16:45:29.265324Z","steps":["trace[1506949847] 'process raft request' (duration: 248.657648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.265554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065142368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:29.265671Z","caller":"traceutil/trace.go:171","msg":"trace[345611535] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1330; }","duration":"1.065321283s","start":"2026-04-02T16:45:28.200331Z","end":"2026-04-02T16:45:29.265652Z","steps":["trace[345611535] 'agreement among raft nodes before linearized reading' (duration: 1.065045886s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.265738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:28.200322Z","time spent":"1.065403364s","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-02T16:45:29.26579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.212365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:29.265852Z","caller":"traceutil/trace.go:171","msg":"trace[1643437092] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1330; }","duration":"765.354228ms","start":"2026-04-02T16:45:28.500479Z","end":"2026-04-02T16:45:29.265834Z","steps":["trace[1643437092] 'agreement among raft nodes before linearized reading' (duration: 765.212445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.265896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:28.500454Z","time spent":"765.43247ms","remote":"127.0.0.1:47720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-02T16:45:29.26623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.949125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:45:29.266215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.008417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:45:29.266276Z","caller":"traceutil/trace.go:171","msg":"trace[38758346] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1330; }","duration":"684.046837ms","start":"2026-04-02T16:45:28.582218Z","end":"2026-04-02T16:45:29.266264Z","steps":["trace[38758346] 'agreement among raft nodes before linearized reading' (duration: 683.978085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.266309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:28.58219Z","time spent":"684.111189ms","remote":"127.0.0.1:47706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-02T16:45:29.266411Z","caller":"traceutil/trace.go:171","msg":"trace[1814955752] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1330; }","duration":"480.17116ms","start":"2026-04-02T16:45:28.786111Z","end":"2026-04-02T16:45:29.266282Z","steps":["trace[1814955752] 'agreement among raft nodes before linearized reading' (duration: 479.749861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.266227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.864337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T16:45:29.266557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:28.786086Z","time spent":"480.376535ms","remote":"127.0.0.1:55898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-02T16:45:29.266582Z","caller":"traceutil/trace.go:171","msg":"trace[1064615108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1330; }","duration":"226.239786ms","start":"2026-04-02T16:45:29.040296Z","end":"2026-04-02T16:45:29.266536Z","steps":["trace[1064615108] 'agreement among raft nodes before linearized reading' (duration: 225.774124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.267525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.541549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:29.26763Z","caller":"traceutil/trace.go:171","msg":"trace[1581830930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"368.659482ms","start":"2026-04-02T16:45:28.898946Z","end":"2026-04-02T16:45:29.267605Z","steps":["trace[1581830930] 'agreement among raft nodes before linearized reading' (duration: 366.898909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.267675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:28.898874Z","time spent":"368.793114ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T16:45:29.494632Z","caller":"traceutil/trace.go:171","msg":"trace[1876482663] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"154.723562ms","start":"2026-04-02T16:45:29.339879Z","end":"2026-04-02T16:45:29.494603Z","steps":["trace[1876482663] 'process raft request' (duration: 154.575789ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:29.494922Z","caller":"traceutil/trace.go:171","msg":"trace[1452929125] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1394; }","duration":"151.563176ms","start":"2026-04-02T16:45:29.3433Z","end":"2026-04-02T16:45:29.494863Z","steps":["trace[1452929125] 'read index received' (duration: 151.548795ms)","trace[1452929125] 'applied index is now lower than readState.Index' (duration: 11.13µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:45:29.495293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.959175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-02T16:45:29.495394Z","caller":"traceutil/trace.go:171","msg":"trace[710905917] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1333; }","duration":"152.113519ms","start":"2026-04-02T16:45:29.343261Z","end":"2026-04-02T16:45:29.495374Z","steps":["trace[710905917] 'agreement among raft nodes before linearized reading' (duration: 151.74087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.517851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.508898ms","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-02T16:45:29.517922Z","caller":"traceutil/trace.go:171","msg":"trace[753918591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1334; }","duration":"174.635601ms","start":"2026-04-02T16:45:29.343271Z","end":"2026-04-02T16:45:29.517907Z","steps":["trace[753918591] 'agreement among raft nodes before linearized reading' (duration: 174.458867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:29.518128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.1557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-02T16:45:29.518151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.730774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-02T16:45:29.51823Z","caller":"traceutil/trace.go:171","msg":"trace[896423517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1334; }","duration":"174.347104ms","start":"2026-04-02T16:45:29.343863Z","end":"2026-04-02T16:45:29.51821Z","steps":["trace[896423517] 'agreement among raft nodes before linearized reading' (duration: 173.965545ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:29.518255Z","caller":"traceutil/trace.go:171","msg":"trace[931283176] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"174.870517ms","start":"2026-04-02T16:45:29.343361Z","end":"2026-04-02T16:45:29.518232Z","steps":["trace[931283176] 'agreement among raft nodes before linearized reading' (duration: 174.526619ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:29.518427Z","caller":"traceutil/trace.go:171","msg":"trace[260381614] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"174.940789ms","start":"2026-04-02T16:45:29.343467Z","end":"2026-04-02T16:45:29.518408Z","steps":["trace[260381614] 'process raft request' (duration: 174.075789ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:29.627176Z","caller":"traceutil/trace.go:171","msg":"trace[542255458] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"126.531602ms","start":"2026-04-02T16:45:29.500608Z","end":"2026-04-02T16:45:29.62714Z","steps":["trace[542255458] 'process raft request' (duration: 90.562414ms)","trace[542255458] 'compare' (duration: 35.792943ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:29.670598Z","caller":"traceutil/trace.go:171","msg":"trace[330339834] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"147.619949ms","start":"2026-04-02T16:45:29.522945Z","end":"2026-04-02T16:45:29.670565Z","steps":["trace[330339834] 'process raft request' (duration: 147.344773ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:29.670737Z","caller":"traceutil/trace.go:171","msg":"trace[763289033] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"147.549168ms","start":"2026-04-02T16:45:29.523146Z","end":"2026-04-02T16:45:29.670695Z","steps":["trace[763289033] 'process raft request' (duration: 147.301701ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:29.67095Z","caller":"traceutil/trace.go:171","msg":"trace[2055185319] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"146.565926ms","start":"2026-04-02T16:45:29.524271Z","end":"2026-04-02T16:45:29.670837Z","steps":["trace[2055185319] 'process raft request' (duration: 146.228267ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:33.18525Z","caller":"traceutil/trace.go:171","msg":"trace[278468695] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"123.645142ms","start":"2026-04-02T16:45:33.061566Z","end":"2026-04-02T16:45:33.185212Z","steps":["trace[278468695] 'process raft request' (duration: 123.14369ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:34.883826Z","caller":"traceutil/trace.go:171","msg":"trace[359734549] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"148.283245ms","start":"2026-04-02T16:45:34.735515Z","end":"2026-04-02T16:45:34.883798Z","steps":["trace[359734549] 'read index received' (duration: 148.090481ms)","trace[359734549] 'applied index is now lower than readState.Index' (duration: 191.454µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:34.883894Z","caller":"traceutil/trace.go:171","msg":"trace[809656229] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"356.397746ms","start":"2026-04-02T16:45:34.527448Z","end":"2026-04-02T16:45:34.883846Z","steps":["trace[809656229] 'process raft request' (duration: 356.1692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:34.883977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.438639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:34.884015Z","caller":"traceutil/trace.go:171","msg":"trace[1875466883] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1367; }","duration":"148.551313ms","start":"2026-04-02T16:45:34.735453Z","end":"2026-04-02T16:45:34.884004Z","steps":["trace[1875466883] 'agreement among raft nodes before linearized reading' (duration: 148.45886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:34.884046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:34.527419Z","time spent":"356.55107ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:45:35.547159Z","caller":"traceutil/trace.go:171","msg":"trace[402020671] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"356.539628ms","start":"2026-04-02T16:45:35.19058Z","end":"2026-04-02T16:45:35.54712Z","steps":["trace[402020671] 'process raft request' (duration: 356.130309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:35.547413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:35.190546Z","time spent":"356.704932ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:35.884959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.133683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:45:35.885079Z","caller":"traceutil/trace.go:171","msg":"trace[934219955] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"223.91122ms","start":"2026-04-02T16:45:35.66115Z","end":"2026-04-02T16:45:35.885061Z","steps":["trace[934219955] 'read index received' (duration: 70.51666ms)","trace[934219955] 'applied index is now lower than readState.Index' (duration: 153.39339ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:45:35.885268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.125236ms","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-02T16:45:35.885227Z","caller":"traceutil/trace.go:171","msg":"trace[54252418] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"333.084804ms","start":"2026-04-02T16:45:35.552093Z","end":"2026-04-02T16:45:35.885178Z","steps":["trace[54252418] 'process raft request' (duration: 179.649353ms)","trace[54252418] 'compare' (duration: 153.00845ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:35.885299Z","caller":"traceutil/trace.go:171","msg":"trace[803704629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1369; }","duration":"224.198017ms","start":"2026-04-02T16:45:35.661092Z","end":"2026-04-02T16:45:35.88529Z","steps":["trace[803704629] 'agreement among raft nodes before linearized reading' (duration: 224.068374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:35.88546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.625923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T16:45:35.885602Z","caller":"traceutil/trace.go:171","msg":"trace[1593613799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1369; }","duration":"167.813947ms","start":"2026-04-02T16:45:35.717762Z","end":"2026-04-02T16:45:35.885576Z","steps":["trace[1593613799] 'agreement among raft nodes before linearized reading' (duration: 167.450128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:35.8855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:45:35.552069Z","time spent":"333.251647ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:45:35.885709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.783046ms","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-02T16:45:35.885775Z","caller":"traceutil/trace.go:171","msg":"trace[44584937] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1369; }","duration":"145.875208ms","start":"2026-04-02T16:45:35.739873Z","end":"2026-04-02T16:45:35.885748Z","steps":["trace[44584937] 'agreement among raft nodes before linearized reading' (duration: 145.583381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:35.885444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.391246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T16:45:35.886338Z","caller":"traceutil/trace.go:171","msg":"trace[480991138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1369; }","duration":"145.94026ms","start":"2026-04-02T16:45:35.739975Z","end":"2026-04-02T16:45:35.885916Z","steps":["trace[480991138] 'agreement among raft nodes before linearized reading' (duration: 145.276014ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:40.09428Z","caller":"traceutil/trace.go:171","msg":"trace[135061270] linearizableReadLoop","detail":"{readStateIndex:1471; appliedIndex:1469; }","duration":"173.062843ms","start":"2026-04-02T16:45:39.921205Z","end":"2026-04-02T16:45:40.094268Z","steps":["trace[135061270] 'read index received' (duration: 172.852808ms)","trace[135061270] 'applied index is now lower than readState.Index' (duration: 205.765µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:45:40.094389Z","caller":"traceutil/trace.go:171","msg":"trace[1244366994] transaction","detail":"{read_only:false; response_revision:1408; number_of_response:1; }","duration":"178.458663ms","start":"2026-04-02T16:45:39.915918Z","end":"2026-04-02T16:45:40.094377Z","steps":["trace[1244366994] 'process raft request' (duration: 178.190397ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:40.094171Z","caller":"traceutil/trace.go:171","msg":"trace[2015246210] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"178.276249ms","start":"2026-04-02T16:45:39.915864Z","end":"2026-04-02T16:45:40.09414Z","steps":["trace[2015246210] 'process raft request' (duration: 178.102625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:40.094704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.324663ms","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-02T16:45:40.094749Z","caller":"traceutil/trace.go:171","msg":"trace[1444590146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1408; }","duration":"181.405916ms","start":"2026-04-02T16:45:39.913334Z","end":"2026-04-02T16:45:40.09474Z","steps":["trace[1444590146] 'agreement among raft nodes before linearized reading' (duration: 181.252692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:45:40.138375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.029472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:45:40.138465Z","caller":"traceutil/trace.go:171","msg":"trace[1932317241] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1409; }","duration":"180.173145ms","start":"2026-04-02T16:45:39.958269Z","end":"2026-04-02T16:45:40.138442Z","steps":["trace[1932317241] 'agreement among raft nodes before linearized reading' (duration: 179.97821ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:45:40.13851Z","caller":"traceutil/trace.go:171","msg":"trace[27531312] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"213.672973ms","start":"2026-04-02T16:45:39.924786Z","end":"2026-04-02T16:45:40.138459Z","steps":["trace[27531312] 'process raft request' (duration: 189.549952ms)","trace[27531312] 'compare' (duration: 23.795774ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:46:01.30577Z","caller":"traceutil/trace.go:171","msg":"trace[182222905] linearizableReadLoop","detail":"{readStateIndex:1571; appliedIndex:1570; }","duration":"114.493311ms","start":"2026-04-02T16:46:01.191243Z","end":"2026-04-02T16:46:01.305736Z","steps":["trace[182222905] 'read index received' (duration: 114.201824ms)","trace[182222905] 'applied index is now lower than readState.Index' (duration: 289.797µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:46:01.30584Z","caller":"traceutil/trace.go:171","msg":"trace[1579420271] transaction","detail":"{read_only:false; response_revision:1504; number_of_response:1; }","duration":"161.799972ms","start":"2026-04-02T16:46:01.143979Z","end":"2026-04-02T16:46:01.305779Z","steps":["trace[1579420271] 'process raft request' (duration: 161.494615ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:46:01.306052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.767678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T16:46:01.306167Z","caller":"traceutil/trace.go:171","msg":"trace[168948275] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1504; }","duration":"114.905941ms","start":"2026-04-02T16:46:01.191232Z","end":"2026-04-02T16:46:01.306138Z","steps":["trace[168948275] 'agreement among raft nodes before linearized reading' (duration: 114.638375ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:46:03.858514Z","caller":"traceutil/trace.go:171","msg":"trace[5319603] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"169.806425ms","start":"2026-04-02T16:46:03.688678Z","end":"2026-04-02T16:46:03.858484Z","steps":["trace[5319603] 'process raft request' (duration: 155.696114ms)","trace[5319603] 'compare' (duration: 13.978068ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:46:04.589125Z","caller":"traceutil/trace.go:171","msg":"trace[1748468989] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"152.312643ms","start":"2026-04-02T16:46:04.436541Z","end":"2026-04-02T16:46:04.588854Z","steps":["trace[1748468989] 'process raft request' (duration: 112.257357ms)","trace[1748468989] 'compare' (duration: 39.947313ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:46:04.594081Z","caller":"traceutil/trace.go:171","msg":"trace[551072236] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"156.72356ms","start":"2026-04-02T16:46:04.437325Z","end":"2026-04-02T16:46:04.594049Z","steps":["trace[551072236] 'process raft request' (duration: 156.570336ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:46:28.87664Z","caller":"traceutil/trace.go:171","msg":"trace[93120005] transaction","detail":"{read_only:false; response_revision:1632; number_of_response:1; }","duration":"112.542924ms","start":"2026-04-02T16:46:28.764073Z","end":"2026-04-02T16:46:28.876616Z","steps":["trace[93120005] 'process raft request' (duration: 89.97722ms)","trace[93120005] 'compare' (duration: 22.280738ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:46:59.029695Z","caller":"traceutil/trace.go:171","msg":"trace[813978642] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"102.686106ms","start":"2026-04-02T16:46:58.92698Z","end":"2026-04-02T16:46:59.029666Z","steps":["trace[813978642] 'process raft request' (duration: 94.527058ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:12.301947Z","caller":"traceutil/trace.go:171","msg":"trace[1413965903] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"159.576846ms","start":"2026-04-02T16:47:12.142343Z","end":"2026-04-02T16:47:12.30192Z","steps":["trace[1413965903] 'process raft request' (duration: 159.39652ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:38.536552Z","caller":"traceutil/trace.go:171","msg":"trace[2014424502] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"149.794185ms","start":"2026-04-02T16:47:38.386733Z","end":"2026-04-02T16:47:38.536527Z","steps":["trace[2014424502] 'process raft request' (duration: 149.653091ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:47:41.11734Z","caller":"traceutil/trace.go:171","msg":"trace[1961635613] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"106.034867ms","start":"2026-04-02T16:47:41.011271Z","end":"2026-04-02T16:47:41.117305Z","steps":["trace[1961635613] 'process raft request' (duration: 60.993718ms)","trace[1961635613] 'compare' (duration: 44.818824ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:48:53.630787Z","caller":"traceutil/trace.go:171","msg":"trace[824747759] transaction","detail":"{read_only:false; response_revision:2324; number_of_response:1; }","duration":"102.949676ms","start":"2026-04-02T16:48:53.527805Z","end":"2026-04-02T16:48:53.630755Z","steps":["trace[824747759] 'process raft request' (duration: 59.226ms)","trace[824747759] 'compare' (duration: 43.512371ms)"],"step_count":2}