{"level":"warn","ts":"2026-04-15T01:20:32.564992Z","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-15T01:20:32.565065Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.221: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://162.253.55.221:2380","--initial-cluster=instance=https://162.253.55.221:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.221:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.221: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-15T01:20:32.565261Z","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-15T01:20:32.565279Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-04-15T01:20:32.565297Z","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-15T01:20:32.565958Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"]} {"level":"info","ts":"2026-04-15T01:20:32.566078Z","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://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.221: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-15T01:20:32.621756Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"55.437771ms"} {"level":"info","ts":"2026-04-15T01:20:32.643922Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c187bdee547e8fd1","cluster-id":"ee7528b898b8b18e"} {"level":"info","ts":"2026-04-15T01:20:32.643996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-15T01:20:32.644019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 0"} {"level":"info","ts":"2026-04-15T01:20:32.644033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c187bdee547e8fd1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-15T01:20:32.644045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became follower at term 1"} {"level":"info","ts":"2026-04-15T01:20:32.644083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"warn","ts":"2026-04-15T01:20:32.657375Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-15T01:20:32.658833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-15T01:20:32.660121Z","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-15T01:20:32.662678Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c187bdee547e8fd1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-15T01:20:32.663Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c187bdee547e8fd1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-15T01:20:32.663128Z","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-15T01:20:32.663261Z","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-15T01:20:32.663275Z","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-15T01:20:32.666097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 switched to configuration voters=(13945323602493476817)"} {"level":"info","ts":"2026-04-15T01:20:32.672743Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","added-peer-id":"c187bdee547e8fd1","added-peer-peer-urls":["https://162.253.55.221:2380"]} {"level":"info","ts":"2026-04-15T01:20:32.674625Z","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-15T01:20:32.674772Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-04-15T01:20:32.67482Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.221:2380"} {"level":"info","ts":"2026-04-15T01:20:32.674955Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c187bdee547e8fd1","initial-advertise-peer-urls":["https://162.253.55.221:2380"],"listen-peer-urls":["https://162.253.55.221:2380"],"advertise-client-urls":["https://162.253.55.221:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.221:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-15T01:20:32.674998Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-15T01:20:32.745253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-15T01:20:32.745399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-15T01:20:32.745447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgPreVoteResp from c187bdee547e8fd1 at term 1"} {"level":"info","ts":"2026-04-15T01:20:32.745528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became candidate at term 2"} {"level":"info","ts":"2026-04-15T01:20:32.745571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 received MsgVoteResp from c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-04-15T01:20:32.745617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c187bdee547e8fd1 became leader at term 2"} {"level":"info","ts":"2026-04-15T01:20:32.745681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c187bdee547e8fd1 elected leader c187bdee547e8fd1 at term 2"} {"level":"info","ts":"2026-04-15T01:20:32.746629Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T01:20:32.747568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T01:20:32.747585Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-15T01:20:32.747586Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c187bdee547e8fd1","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.221:2379]}","request-path":"/0/members/c187bdee547e8fd1/attributes","cluster-id":"ee7528b898b8b18e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-15T01:20:32.747991Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-15T01:20:32.748016Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-15T01:20:32.74813Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ee7528b898b8b18e","local-member-id":"c187bdee547e8fd1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T01:20:32.748258Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T01:20:32.748324Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-15T01:20:32.750445Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-15T01:20:32.750701Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.221:2379"} {"level":"warn","ts":"2026-04-15T01:20:37.487525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.436018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"warn","ts":"2026-04-15T01:20:37.487524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.804645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:20:37.487608Z","caller":"traceutil/trace.go:171","msg":"trace[1542293057] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:286; }","duration":"146.557651ms","start":"2026-04-15T01:20:37.341035Z","end":"2026-04-15T01:20:37.487593Z","steps":["trace[1542293057] 'range keys from in-memory index tree' (duration: 146.346054ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:37.487618Z","caller":"traceutil/trace.go:171","msg":"trace[1120158593] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:286; }","duration":"120.924129ms","start":"2026-04-15T01:20:37.366676Z","end":"2026-04-15T01:20:37.4876Z","steps":["trace[1120158593] 'range keys from in-memory index tree' (duration: 120.748064ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:37.488743Z","caller":"traceutil/trace.go:171","msg":"trace[1453271919] transaction","detail":"{read_only:false; number_of_response:0; response_revision:286; }","duration":"112.609361ms","start":"2026-04-15T01:20:37.376109Z","end":"2026-04-15T01:20:37.488718Z","steps":["trace[1453271919] 'process raft request' (duration: 112.530398ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:37.491958Z","caller":"traceutil/trace.go:171","msg":"trace[2143335871] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"115.437272ms","start":"2026-04-15T01:20:37.376494Z","end":"2026-04-15T01:20:37.491931Z","steps":["trace[2143335871] 'process raft request' (duration: 115.185915ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:37.492007Z","caller":"traceutil/trace.go:171","msg":"trace[2000304356] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"100.454652ms","start":"2026-04-15T01:20:37.391488Z","end":"2026-04-15T01:20:37.491943Z","steps":["trace[2000304356] 'process raft request' (duration: 100.303797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:20:37.492134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.248735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:20:37.492236Z","caller":"traceutil/trace.go:171","msg":"trace[246344927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:292; }","duration":"100.395381ms","start":"2026-04-15T01:20:37.391829Z","end":"2026-04-15T01:20:37.492225Z","steps":["trace[246344927] 'agreement among raft nodes before linearized reading' (duration: 100.243646ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:37.492041Z","caller":"traceutil/trace.go:171","msg":"trace[631636572] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:292; }","duration":"100.151883ms","start":"2026-04-15T01:20:37.391875Z","end":"2026-04-15T01:20:37.492026Z","steps":["trace[631636572] 'read index received' (duration: 96.778665ms)","trace[631636572] 'applied index is now lower than readState.Index' (duration: 3.372268ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:20:45.575467Z","caller":"traceutil/trace.go:171","msg":"trace[91144317] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"220.520974ms","start":"2026-04-15T01:20:45.354924Z","end":"2026-04-15T01:20:45.575445Z","steps":["trace[91144317] 'process raft request' (duration: 219.995524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:20:46.271191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.588609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.221\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-15T01:20:46.271195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.776121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-15T01:20:46.27123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.510456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:20:46.271282Z","caller":"traceutil/trace.go:171","msg":"trace[1696690390] range","detail":"{range_begin:/registry/masterleases/162.253.55.221; range_end:; response_count:1; response_revision:428; }","duration":"494.733275ms","start":"2026-04-15T01:20:45.776532Z","end":"2026-04-15T01:20:46.271266Z","steps":["trace[1696690390] 'range keys from in-memory index tree' (duration: 494.468194ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:46.27129Z","caller":"traceutil/trace.go:171","msg":"trace[362102067] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:428; }","duration":"115.925678ms","start":"2026-04-15T01:20:46.155347Z","end":"2026-04-15T01:20:46.271272Z","steps":["trace[362102067] 'range keys from in-memory index tree' (duration: 115.679498ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:46.271314Z","caller":"traceutil/trace.go:171","msg":"trace[1433460438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"454.63976ms","start":"2026-04-15T01:20:45.816657Z","end":"2026-04-15T01:20:46.271297Z","steps":["trace[1433460438] 'range keys from in-memory index tree' (duration: 454.410562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:20:46.271322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:20:45.776513Z","time spent":"494.799117ms","remote":"127.0.0.1:47264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.221\" "} {"level":"warn","ts":"2026-04-15T01:20:46.271354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:20:45.816647Z","time spent":"454.700545ms","remote":"127.0.0.1:47224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T01:20:46.499269Z","caller":"traceutil/trace.go:171","msg":"trace[1216826338] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"223.923924ms","start":"2026-04-15T01:20:46.275286Z","end":"2026-04-15T01:20:46.49921Z","steps":["trace[1216826338] 'process raft request' (duration: 168.790176ms)","trace[1216826338] 'compare' (duration: 54.93006ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:20:46.545347Z","caller":"traceutil/trace.go:171","msg":"trace[1088135791] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:443; }","duration":"190.834386ms","start":"2026-04-15T01:20:46.354491Z","end":"2026-04-15T01:20:46.545325Z","steps":["trace[1088135791] 'read index received' (duration: 89.57422ms)","trace[1088135791] 'applied index is now lower than readState.Index' (duration: 101.258936ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:20:46.545471Z","caller":"traceutil/trace.go:171","msg":"trace[77920026] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"250.081874ms","start":"2026-04-15T01:20:46.29536Z","end":"2026-04-15T01:20:46.545442Z","steps":["trace[77920026] 'process raft request' (duration: 249.913697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:20:46.545635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.093276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:20:46.545753Z","caller":"traceutil/trace.go:171","msg":"trace[483121409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"191.290403ms","start":"2026-04-15T01:20:46.354441Z","end":"2026-04-15T01:20:46.545732Z","steps":["trace[483121409] 'agreement among raft nodes before linearized reading' (duration: 191.016893ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:46.546259Z","caller":"traceutil/trace.go:171","msg":"trace[1843063231] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"259.753841ms","start":"2026-04-15T01:20:46.285653Z","end":"2026-04-15T01:20:46.545407Z","steps":["trace[1843063231] 'process raft request' (duration: 259.495692ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:50.690108Z","caller":"traceutil/trace.go:171","msg":"trace[448713632] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"124.14533ms","start":"2026-04-15T01:20:50.5659Z","end":"2026-04-15T01:20:50.690045Z","steps":["trace[448713632] 'process raft request' (duration: 123.641059ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:51.971535Z","caller":"traceutil/trace.go:171","msg":"trace[1226854422] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"216.864373ms","start":"2026-04-15T01:20:51.75465Z","end":"2026-04-15T01:20:51.971515Z","steps":["trace[1226854422] 'read index received' (duration: 216.740798ms)","trace[1226854422] 'applied index is now lower than readState.Index' (duration: 122.585µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:20:51.971609Z","caller":"traceutil/trace.go:171","msg":"trace[1473824712] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"381.120195ms","start":"2026-04-15T01:20:51.590475Z","end":"2026-04-15T01:20:51.971595Z","steps":["trace[1473824712] 'process raft request' (duration: 380.889205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:20:51.971648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.974068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-15T01:20:51.971677Z","caller":"traceutil/trace.go:171","msg":"trace[388950379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:446; }","duration":"217.04153ms","start":"2026-04-15T01:20:51.754625Z","end":"2026-04-15T01:20:51.971667Z","steps":["trace[388950379] 'agreement among raft nodes before linearized reading' (duration: 216.968787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:20:51.971986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:20:51.590448Z","time spent":"381.201529ms","remote":"127.0.0.1:47382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T01:20:54.67116Z","caller":"traceutil/trace.go:171","msg":"trace[1716560818] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"113.430858ms","start":"2026-04-15T01:20:54.557704Z","end":"2026-04-15T01:20:54.671135Z","steps":["trace[1716560818] 'process raft request' (duration: 113.286151ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:20:54.816663Z","caller":"traceutil/trace.go:171","msg":"trace[1515367269] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"111.043512ms","start":"2026-04-15T01:20:54.705588Z","end":"2026-04-15T01:20:54.816632Z","steps":["trace[1515367269] 'process raft request' (duration: 62.637896ms)","trace[1515367269] 'compare' (duration: 48.255319ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:20:58.521528Z","caller":"traceutil/trace.go:171","msg":"trace[188743237] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"499.307796ms","start":"2026-04-15T01:20:58.022189Z","end":"2026-04-15T01:20:58.521497Z","steps":["trace[188743237] 'read index received' (duration: 499.103617ms)","trace[188743237] 'applied index is now lower than readState.Index' (duration: 203.059µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:20:58.521611Z","caller":"traceutil/trace.go:171","msg":"trace[1663909706] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"503.68644ms","start":"2026-04-15T01:20:58.017888Z","end":"2026-04-15T01:20:58.521575Z","steps":["trace[1663909706] 'process raft request' (duration: 503.45807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:20:58.521688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.471293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:20:58.521744Z","caller":"traceutil/trace.go:171","msg":"trace[1524328473] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:498; }","duration":"499.572268ms","start":"2026-04-15T01:20:58.022153Z","end":"2026-04-15T01:20:58.521725Z","steps":["trace[1524328473] 'agreement among raft nodes before linearized reading' (duration: 499.472503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:20:58.521794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:20:58.022136Z","time spent":"499.644681ms","remote":"127.0.0.1:47310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-15T01:20:58.521828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:20:58.017866Z","time spent":"503.856809ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T01:21:00.083174Z","caller":"traceutil/trace.go:171","msg":"trace[1048092537] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"286.122962ms","start":"2026-04-15T01:20:59.797032Z","end":"2026-04-15T01:21:00.083155Z","steps":["trace[1048092537] 'process raft request' (duration: 285.345065ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:00.094258Z","caller":"traceutil/trace.go:171","msg":"trace[1571101824] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"246.386947ms","start":"2026-04-15T01:20:59.847856Z","end":"2026-04-15T01:21:00.094243Z","steps":["trace[1571101824] 'process raft request' (duration: 246.318433ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:05.365849Z","caller":"traceutil/trace.go:171","msg":"trace[189703983] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:624; }","duration":"495.452191ms","start":"2026-04-15T01:21:04.870368Z","end":"2026-04-15T01:21:05.36582Z","steps":["trace[189703983] 'read index received' (duration: 495.401949ms)","trace[189703983] 'applied index is now lower than readState.Index' (duration: 48.882µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:21:05.365969Z","caller":"traceutil/trace.go:171","msg":"trace[139323478] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"609.191954ms","start":"2026-04-15T01:21:04.756741Z","end":"2026-04-15T01:21:05.365933Z","steps":["trace[139323478] 'process raft request' (duration: 608.933281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:05.366121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:04.756718Z","time spent":"609.326751ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T01:21:05.366136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.746487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-15T01:21:05.366203Z","caller":"traceutil/trace.go:171","msg":"trace[158070957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"495.858782ms","start":"2026-04-15T01:21:04.870325Z","end":"2026-04-15T01:21:05.366183Z","steps":["trace[158070957] 'agreement among raft nodes before linearized reading' (duration: 495.640881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:05.366255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:04.870302Z","time spent":"495.939616ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-15T01:21:05.716342Z","caller":"traceutil/trace.go:171","msg":"trace[1805367366] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"831.128897ms","start":"2026-04-15T01:21:04.885165Z","end":"2026-04-15T01:21:05.716294Z","steps":["trace[1805367366] 'process raft request' (duration: 829.656823ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:05.716608Z","caller":"traceutil/trace.go:171","msg":"trace[1416779524] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:625; }","duration":"350.491909ms","start":"2026-04-15T01:21:05.365927Z","end":"2026-04-15T01:21:05.716419Z","steps":["trace[1416779524] 'read index received' (duration: 348.14748ms)","trace[1416779524] 'applied index is now lower than readState.Index' (duration: 2.343019ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:21:05.717154Z","caller":"traceutil/trace.go:171","msg":"trace[2028245213] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"564.599696ms","start":"2026-04-15T01:21:05.152475Z","end":"2026-04-15T01:21:05.717075Z","steps":["trace[2028245213] 'process raft request' (duration: 563.734883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:05.717238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.155839ms","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-15T01:21:05.717299Z","caller":"traceutil/trace.go:171","msg":"trace[853543577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:605; }","duration":"832.257035ms","start":"2026-04-15T01:21:04.885024Z","end":"2026-04-15T01:21:05.717281Z","steps":["trace[853543577] 'agreement among raft nodes before linearized reading' (duration: 832.004201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:05.71736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:04.885002Z","time spent":"832.343989ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T01:21:05.717381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:05.152453Z","time spent":"564.809726ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T01:21:05.717614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:04.885142Z","time spent":"832.068165ms","remote":"127.0.0.1:47310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T01:21:05.725242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.798922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kdlf2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-15T01:21:05.725333Z","caller":"traceutil/trace.go:171","msg":"trace[329853550] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kdlf2; range_end:; response_count:1; response_revision:606; }","duration":"332.936288ms","start":"2026-04-15T01:21:05.392374Z","end":"2026-04-15T01:21:05.72531Z","steps":["trace[329853550] 'agreement among raft nodes before linearized reading' (duration: 332.732868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:05.725371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:05.392345Z","time spent":"333.018414ms","remote":"127.0.0.1:47382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-kdlf2\" "} {"level":"info","ts":"2026-04-15T01:21:05.725676Z","caller":"traceutil/trace.go:171","msg":"trace[1347206789] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"354.844427ms","start":"2026-04-15T01:21:05.370816Z","end":"2026-04-15T01:21:05.72566Z","steps":["trace[1347206789] 'process raft request' (duration: 354.134472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:05.725964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:05.370795Z","time spent":"354.907272ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T01:21:05.726186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.588244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:21:05.726279Z","caller":"traceutil/trace.go:171","msg":"trace[472162736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"186.760772ms","start":"2026-04-15T01:21:05.539476Z","end":"2026-04-15T01:21:05.726236Z","steps":["trace[472162736] 'agreement among raft nodes before linearized reading' (duration: 186.596413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:06.277274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237453883869431,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T01:21:06.356894Z","caller":"traceutil/trace.go:171","msg":"trace[1834792062] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"608.070183ms","start":"2026-04-15T01:21:05.748797Z","end":"2026-04-15T01:21:06.356867Z","steps":["trace[1834792062] 'process raft request' (duration: 607.926146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:06.357039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:05.74878Z","time spent":"608.185129ms","remote":"127.0.0.1:47382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T01:21:06.525517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.490805ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-15T01:21:06.526112Z","caller":"traceutil/trace.go:171","msg":"trace[1333699048] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"749.062647ms","start":"2026-04-15T01:21:05.777036Z","end":"2026-04-15T01:21:06.526098Z","steps":["trace[1333699048] 'read index received' (duration: 579.646644ms)","trace[1333699048] 'applied index is now lower than readState.Index' (duration: 169.414923ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T01:21:06.526222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.186013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.221\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-15T01:21:06.526268Z","caller":"traceutil/trace.go:171","msg":"trace[1018131914] range","detail":"{range_begin:/registry/masterleases/162.253.55.221; range_end:; response_count:1; response_revision:609; }","duration":"749.257526ms","start":"2026-04-15T01:21:05.777003Z","end":"2026-04-15T01:21:06.526261Z","steps":["trace[1018131914] 'agreement among raft nodes before linearized reading' (duration: 749.161831ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:06.526211Z","caller":"traceutil/trace.go:171","msg":"trace[2100856935] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"757.23717ms","start":"2026-04-15T01:21:05.768881Z","end":"2026-04-15T01:21:06.526118Z","steps":["trace[2100856935] 'process raft request' (duration: 637.999266ms)","trace[2100856935] 'compare' (duration: 118.321416ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T01:21:06.526316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:05.776993Z","time spent":"749.315159ms","remote":"127.0.0.1:47264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.221\" "} {"level":"warn","ts":"2026-04-15T01:21:06.526492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:05.768868Z","time spent":"757.521274ms","remote":"127.0.0.1:47738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-15T01:21:07.873043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237453883869443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-15T01:21:08.171942Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.237854255s","expected-duration":"1s"} {"level":"info","ts":"2026-04-15T01:21:08.173006Z","caller":"traceutil/trace.go:171","msg":"trace[1473504110] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.240504121s","start":"2026-04-15T01:21:06.932478Z","end":"2026-04-15T01:21:08.172982Z","steps":["trace[1473504110] 'process raft request' (duration: 1.23990978s)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:08.173148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:06.932447Z","time spent":"1.240627337s","remote":"127.0.0.1:47310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T01:21:08.20161Z","caller":"traceutil/trace.go:171","msg":"trace[1609923619] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"829.643708ms","start":"2026-04-15T01:21:07.371951Z","end":"2026-04-15T01:21:08.201595Z","steps":["trace[1609923619] 'read index received' (duration: 800.236063ms)","trace[1609923619] 'applied index is now lower than readState.Index' (duration: 29.406975ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:21:08.201813Z","caller":"traceutil/trace.go:171","msg":"trace[427695891] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"475.149799ms","start":"2026-04-15T01:21:07.726654Z","end":"2026-04-15T01:21:08.201804Z","steps":["trace[427695891] 'process raft request' (duration: 474.843913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:08.20199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.034579ms","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-15T01:21:08.20202Z","caller":"traceutil/trace.go:171","msg":"trace[1238876881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"830.095002ms","start":"2026-04-15T01:21:07.371917Z","end":"2026-04-15T01:21:08.202012Z","steps":["trace[1238876881] 'agreement among raft nodes before linearized reading' (duration: 829.994247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:08.20204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:07.371895Z","time spent":"830.140174ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-15T01:21:08.202166Z","caller":"traceutil/trace.go:171","msg":"trace[2081022906] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"293.063044ms","start":"2026-04-15T01:21:07.909097Z","end":"2026-04-15T01:21:08.20216Z","steps":["trace[2081022906] 'process raft request' (duration: 292.470054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:08.202427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.521668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:21:08.202456Z","caller":"traceutil/trace.go:171","msg":"trace[42704204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"662.584451ms","start":"2026-04-15T01:21:07.539864Z","end":"2026-04-15T01:21:08.202449Z","steps":["trace[42704204] 'agreement among raft nodes before linearized reading' (duration: 662.545969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:08.202473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:07.539846Z","time spent":"662.623273ms","remote":"127.0.0.1:47224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-15T01:21:08.202596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.093587ms","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-15T01:21:08.20262Z","caller":"traceutil/trace.go:171","msg":"trace[679367044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"458.170592ms","start":"2026-04-15T01:21:07.744444Z","end":"2026-04-15T01:21:08.202614Z","steps":["trace[679367044] 'agreement among raft nodes before linearized reading' (duration: 458.112718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:08.202636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:07.744422Z","time spent":"458.210824ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-15T01:21:08.20273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.724847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-15T01:21:08.202751Z","caller":"traceutil/trace.go:171","msg":"trace[721224499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"473.772869ms","start":"2026-04-15T01:21:07.728973Z","end":"2026-04-15T01:21:08.202746Z","steps":["trace[721224499] 'agreement among raft nodes before linearized reading' (duration: 473.723815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:08.202772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:07.72896Z","time spent":"473.807239ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-15T01:21:08.203642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:07.726637Z","time spent":"475.201312ms","remote":"127.0.0.1:47380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T01:21:09.730385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.310344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:21:09.730458Z","caller":"traceutil/trace.go:171","msg":"trace[1507537633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"191.423482ms","start":"2026-04-15T01:21:09.539018Z","end":"2026-04-15T01:21:09.730441Z","steps":["trace[1507537633] 'range keys from in-memory index tree' (duration: 191.239261ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:12.253988Z","caller":"traceutil/trace.go:171","msg":"trace[512542478] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"102.586517ms","start":"2026-04-15T01:21:12.151386Z","end":"2026-04-15T01:21:12.253972Z","steps":["trace[512542478] 'process raft request' (duration: 102.45352ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:12.378207Z","caller":"traceutil/trace.go:171","msg":"trace[787313923] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"115.472175ms","start":"2026-04-15T01:21:12.262712Z","end":"2026-04-15T01:21:12.378184Z","steps":["trace[787313923] 'process raft request' (duration: 115.250513ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:12.378427Z","caller":"traceutil/trace.go:171","msg":"trace[1008228067] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"110.175048ms","start":"2026-04-15T01:21:12.26823Z","end":"2026-04-15T01:21:12.378405Z","steps":["trace[1008228067] 'process raft request' (duration: 109.864312ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:12.674388Z","caller":"traceutil/trace.go:171","msg":"trace[352377458] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"128.338531ms","start":"2026-04-15T01:21:12.546021Z","end":"2026-04-15T01:21:12.67436Z","steps":["trace[352377458] 'process raft request' (duration: 128.140481ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:12.680207Z","caller":"traceutil/trace.go:171","msg":"trace[310295198] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"114.543805ms","start":"2026-04-15T01:21:12.565621Z","end":"2026-04-15T01:21:12.680164Z","steps":["trace[310295198] 'process raft request' (duration: 114.418348ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:12.977866Z","caller":"traceutil/trace.go:171","msg":"trace[1139172244] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"188.898682ms","start":"2026-04-15T01:21:12.788941Z","end":"2026-04-15T01:21:12.977839Z","steps":["trace[1139172244] 'process raft request' (duration: 188.761924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:23.725245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.003208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-chgcb\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-15T01:21:23.725388Z","caller":"traceutil/trace.go:171","msg":"trace[1262868086] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-chgcb; range_end:; response_count:1; response_revision:774; }","duration":"239.232752ms","start":"2026-04-15T01:21:23.486128Z","end":"2026-04-15T01:21:23.725361Z","steps":["trace[1262868086] 'range keys from in-memory index tree' (duration: 238.771086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:24.337223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237453883869810,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T01:21:24.583894Z","caller":"traceutil/trace.go:171","msg":"trace[359858638] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"746.733356ms","start":"2026-04-15T01:21:23.83712Z","end":"2026-04-15T01:21:24.583854Z","steps":["trace[359858638] 'read index received' (duration: 746.383515ms)","trace[359858638] 'applied index is now lower than readState.Index' (duration: 348.49µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:21:24.583948Z","caller":"traceutil/trace.go:171","msg":"trace[1513843214] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"849.903139ms","start":"2026-04-15T01:21:23.733997Z","end":"2026-04-15T01:21:24.5839Z","steps":["trace[1513843214] 'process raft request' (duration: 849.608661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:24.584132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:23.733962Z","time spent":"850.075008ms","remote":"127.0.0.1:47382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T01:21:24.584282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.123798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-15T01:21:24.584265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.115418ms","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-15T01:21:24.584343Z","caller":"traceutil/trace.go:171","msg":"trace[1265318139] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:775; }","duration":"212.257906ms","start":"2026-04-15T01:21:24.372068Z","end":"2026-04-15T01:21:24.584326Z","steps":["trace[1265318139] 'agreement among raft nodes before linearized reading' (duration: 212.010301ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:24.584384Z","caller":"traceutil/trace.go:171","msg":"trace[940928071] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:775; }","duration":"747.285319ms","start":"2026-04-15T01:21:23.837078Z","end":"2026-04-15T01:21:24.584363Z","steps":["trace[940928071] 'agreement among raft nodes before linearized reading' (duration: 746.936279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:21:24.584444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:21:23.837066Z","time spent":"747.366795ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-15T01:21:24.584706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.123502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-15T01:21:24.584772Z","caller":"traceutil/trace.go:171","msg":"trace[1330279697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"162.230298ms","start":"2026-04-15T01:21:24.422524Z","end":"2026-04-15T01:21:24.584754Z","steps":["trace[1330279697] 'agreement among raft nodes before linearized reading' (duration: 162.059837ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:56.72812Z","caller":"traceutil/trace.go:171","msg":"trace[2100312086] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"188.665022ms","start":"2026-04-15T01:21:56.539428Z","end":"2026-04-15T01:21:56.728093Z","steps":["trace[2100312086] 'read index received' (duration: 188.427236ms)","trace[2100312086] 'applied index is now lower than readState.Index' (duration: 236.446µs)"],"step_count":2} {"level":"warn","ts":"2026-04-15T01:21:56.728252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.800762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:21:56.728296Z","caller":"traceutil/trace.go:171","msg":"trace[566688139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"188.917039ms","start":"2026-04-15T01:21:56.539369Z","end":"2026-04-15T01:21:56.728286Z","steps":["trace[566688139] 'agreement among raft nodes before linearized reading' (duration: 188.827473ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:21:56.728185Z","caller":"traceutil/trace.go:171","msg":"trace[1060823359] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"261.969774ms","start":"2026-04-15T01:21:56.466176Z","end":"2026-04-15T01:21:56.728145Z","steps":["trace[1060823359] 'process raft request' (duration: 261.76159ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:00.902438Z","caller":"traceutil/trace.go:171","msg":"trace[1633567381] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"257.640541ms","start":"2026-04-15T01:22:00.644774Z","end":"2026-04-15T01:22:00.902415Z","steps":["trace[1633567381] 'process raft request' (duration: 257.115455ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:01.080669Z","caller":"traceutil/trace.go:171","msg":"trace[828511852] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"146.441813ms","start":"2026-04-15T01:22:00.934201Z","end":"2026-04-15T01:22:01.080643Z","steps":["trace[828511852] 'process raft request' (duration: 146.101829ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:01.289299Z","caller":"traceutil/trace.go:171","msg":"trace[453849491] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"203.372701ms","start":"2026-04-15T01:22:01.085883Z","end":"2026-04-15T01:22:01.289255Z","steps":["trace[453849491] 'process raft request' (duration: 203.278924ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:01.290291Z","caller":"traceutil/trace.go:171","msg":"trace[1943272241] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"204.002186ms","start":"2026-04-15T01:22:01.085273Z","end":"2026-04-15T01:22:01.289275Z","steps":["trace[1943272241] 'process raft request' (duration: 113.721507ms)","trace[1943272241] 'compare' (duration: 89.804116ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:22:09.252235Z","caller":"traceutil/trace.go:171","msg":"trace[117554855] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"114.235801ms","start":"2026-04-15T01:22:09.13798Z","end":"2026-04-15T01:22:09.252216Z","steps":["trace[117554855] 'read index received' (duration: 114.053128ms)","trace[117554855] 'applied index is now lower than readState.Index' (duration: 182.093µs)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:22:09.2523Z","caller":"traceutil/trace.go:171","msg":"trace[1005798910] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"119.310175ms","start":"2026-04-15T01:22:09.132957Z","end":"2026-04-15T01:22:09.252267Z","steps":["trace[1005798910] 'process raft request' (duration: 119.123873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:09.25251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.479399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:22:09.252696Z","caller":"traceutil/trace.go:171","msg":"trace[1225211414] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1085; }","duration":"114.722976ms","start":"2026-04-15T01:22:09.137955Z","end":"2026-04-15T01:22:09.252678Z","steps":["trace[1225211414] 'agreement among raft nodes before linearized reading' (duration: 114.419534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:15.868215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10363237453883870525,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-15T01:22:15.971581Z","caller":"traceutil/trace.go:171","msg":"trace[1874093087] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"646.451648ms","start":"2026-04-15T01:22:15.325099Z","end":"2026-04-15T01:22:15.971551Z","steps":["trace[1874093087] 'process raft request' (duration: 646.256453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:15.971781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:22:15.325054Z","time spent":"646.62163ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T01:22:15.991331Z","caller":"traceutil/trace.go:171","msg":"trace[1027130816] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"665.885937ms","start":"2026-04-15T01:22:15.325421Z","end":"2026-04-15T01:22:15.991307Z","steps":["trace[1027130816] 'process raft request' (duration: 665.700343ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:15.991509Z","caller":"traceutil/trace.go:171","msg":"trace[1666919238] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1149; }","duration":"624.280808ms","start":"2026-04-15T01:22:15.367213Z","end":"2026-04-15T01:22:15.991494Z","steps":["trace[1666919238] 'read index received' (duration: 604.189069ms)","trace[1666919238] 'applied index is now lower than readState.Index' (duration: 20.090839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-15T01:22:15.991535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:22:15.325403Z","time spent":"666.035167ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-15T01:22:15.991758Z","caller":"traceutil/trace.go:171","msg":"trace[678290828] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"376.038819ms","start":"2026-04-15T01:22:15.615666Z","end":"2026-04-15T01:22:15.991705Z","steps":["trace[678290828] 'process raft request' (duration: 375.598526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:15.991808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.549183ms","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-15T01:22:15.991848Z","caller":"traceutil/trace.go:171","msg":"trace[1659229689] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"623.640111ms","start":"2026-04-15T01:22:15.368197Z","end":"2026-04-15T01:22:15.991837Z","steps":["trace[1659229689] 'agreement among raft nodes before linearized reading' (duration: 623.497411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:15.991856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.219347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.221\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-15T01:22:15.991878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:22:15.368171Z","time spent":"623.700594ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-15T01:22:15.991943Z","caller":"traceutil/trace.go:171","msg":"trace[1676546971] range","detail":"{range_begin:/registry/masterleases/162.253.55.221; range_end:; response_count:1; response_revision:1113; }","duration":"212.342386ms","start":"2026-04-15T01:22:15.779581Z","end":"2026-04-15T01:22:15.991924Z","steps":["trace[1676546971] 'agreement among raft nodes before linearized reading' (duration: 212.150492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:15.991941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:22:15.615647Z","time spent":"376.181088ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-15T01:22:15.992021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.217866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:22:15.992048Z","caller":"traceutil/trace.go:171","msg":"trace[792331826] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1113; }","duration":"407.294702ms","start":"2026-04-15T01:22:15.584745Z","end":"2026-04-15T01:22:15.99204Z","steps":["trace[792331826] 'agreement among raft nodes before linearized reading' (duration: 407.229667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:15.992074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:22:15.584711Z","time spent":"407.355806ms","remote":"127.0.0.1:47494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-04-15T01:22:15.992067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.668132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-15T01:22:15.992134Z","caller":"traceutil/trace.go:171","msg":"trace[208658924] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"450.766848ms","start":"2026-04-15T01:22:15.541348Z","end":"2026-04-15T01:22:15.992115Z","steps":["trace[208658924] 'agreement among raft nodes before linearized reading' (duration: 450.666221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:15.99217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.964848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-15T01:22:15.992225Z","caller":"traceutil/trace.go:171","msg":"trace[1247550719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"625.042532ms","start":"2026-04-15T01:22:15.367173Z","end":"2026-04-15T01:22:15.992215Z","steps":["trace[1247550719] 'agreement among raft nodes before linearized reading' (duration: 624.893572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:15.992256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:22:15.367161Z","time spent":"625.088636ms","remote":"127.0.0.1:47462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-15T01:22:15.992184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-15T01:22:15.541328Z","time spent":"450.843023ms","remote":"127.0.0.1:47224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-15T01:22:30.288349Z","caller":"traceutil/trace.go:171","msg":"trace[28446083] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"180.719869ms","start":"2026-04-15T01:22:30.107584Z","end":"2026-04-15T01:22:30.288304Z","steps":["trace[28446083] 'process raft request' (duration: 180.475311ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:30.321748Z","caller":"traceutil/trace.go:171","msg":"trace[347375395] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"214.051244ms","start":"2026-04-15T01:22:30.107679Z","end":"2026-04-15T01:22:30.321731Z","steps":["trace[347375395] 'process raft request' (duration: 213.906772ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:30.321971Z","caller":"traceutil/trace.go:171","msg":"trace[940351007] linearizableReadLoop","detail":"{readStateIndex:1209; appliedIndex:1205; }","duration":"150.602457ms","start":"2026-04-15T01:22:30.171357Z","end":"2026-04-15T01:22:30.32196Z","steps":["trace[940351007] 'read index received' (duration: 116.69849ms)","trace[940351007] 'applied index is now lower than readState.Index' (duration: 33.903207ms)"],"step_count":2} {"level":"info","ts":"2026-04-15T01:22:30.322014Z","caller":"traceutil/trace.go:171","msg":"trace[2121274209] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"213.750489ms","start":"2026-04-15T01:22:30.108244Z","end":"2026-04-15T01:22:30.321994Z","steps":["trace[2121274209] 'process raft request' (duration: 213.436575ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:30.322077Z","caller":"traceutil/trace.go:171","msg":"trace[1019627678] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"153.94327ms","start":"2026-04-15T01:22:30.168101Z","end":"2026-04-15T01:22:30.322044Z","steps":["trace[1019627678] 'process raft request' (duration: 153.816151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-15T01:22:30.322168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.087388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-15T01:22:30.322181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.736267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:service-account-issuer-discovery\" ","response":"range_response_count:1 size:765"} {"level":"warn","ts":"2026-04-15T01:22:30.322179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.952914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:volume-scheduler\" ","response":"range_response_count:1 size:715"} {"level":"info","ts":"2026-04-15T01:22:30.322211Z","caller":"traceutil/trace.go:171","msg":"trace[1371718662] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1167; }","duration":"122.193904ms","start":"2026-04-15T01:22:30.200001Z","end":"2026-04-15T01:22:30.322195Z","steps":["trace[1371718662] 'agreement among raft nodes before linearized reading' (duration: 122.119238ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:30.322247Z","caller":"traceutil/trace.go:171","msg":"trace[194060414] range","detail":"{range_begin:/registry/clusterrolebindings/system:service-account-issuer-discovery; range_end:; response_count:1; response_revision:1167; }","duration":"150.920582ms","start":"2026-04-15T01:22:30.171309Z","end":"2026-04-15T01:22:30.32223Z","steps":["trace[194060414] 'agreement among raft nodes before linearized reading' (duration: 150.736129ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:30.322257Z","caller":"traceutil/trace.go:171","msg":"trace[1173890337] range","detail":"{range_begin:/registry/clusterrolebindings/system:volume-scheduler; range_end:; response_count:1; response_revision:1167; }","duration":"127.101816ms","start":"2026-04-15T01:22:30.195137Z","end":"2026-04-15T01:22:30.322239Z","steps":["trace[1173890337] 'agreement among raft nodes before linearized reading' (duration: 126.937523ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:31.340697Z","caller":"traceutil/trace.go:171","msg":"trace[1444007339] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"108.442173ms","start":"2026-04-15T01:22:31.232229Z","end":"2026-04-15T01:22:31.340671Z","steps":["trace[1444007339] 'process raft request' (duration: 108.239837ms)"],"step_count":1} {"level":"info","ts":"2026-04-15T01:22:36.252777Z","caller":"traceutil/trace.go:171","msg":"trace[601959007] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"117.118486ms","start":"2026-04-15T01:22:36.135628Z","end":"2026-04-15T01:22:36.252747Z","steps":["trace[601959007] 'process raft request' (duration: 116.938572ms)"],"step_count":1}