{"level":"warn","ts":"2026-05-27T00:44:50.394246Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T00:44:50.394332Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-27T00:44:50.3944Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T00:44:50.394411Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-27T00:44:50.394427Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T00:44:50.395248Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-05-27T00:44:50.395344Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-27T00:44:50.402152Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.498275ms"} {"level":"info","ts":"2026-05-27T00:44:50.41595Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-05-27T00:44:50.416037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T00:44:50.416061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-05-27T00:44:50.416074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T00:44:50.416083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-05-27T00:44:50.416123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-05-27T00:44:50.424392Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T00:44:50.425758Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T00:44:50.426852Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T00:44:50.430015Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T00:44:50.430186Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T00:44:50.430323Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T00:44:50.431523Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T00:44:50.430339Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T00:44:50.432556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-05-27T00:44:50.432736Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-27T00:44:50.434927Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T00:44:50.435088Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-27T00:44:50.435201Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-27T00:44:50.435368Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T00:44:50.435425Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T00:44:51.016869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T00:44:51.016944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T00:44:51.016993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-05-27T00:44:51.017014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-05-27T00:44:51.017025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-27T00:44:51.017041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-05-27T00:44:51.017055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-27T00:44:51.018391Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T00:44:51.018395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T00:44:51.018381Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T00:44:51.018457Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T00:44:51.018856Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T00:44:51.018881Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T00:44:51.019393Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T00:44:51.01957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T00:44:51.019613Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T00:44:51.025067Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-05-27T00:44:51.025549Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T00:44:52.460667Z","caller":"traceutil/trace.go:171","msg":"trace[1260314969] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"131.466092ms","start":"2026-05-27T00:44:52.329158Z","end":"2026-05-27T00:44:52.460624Z","steps":["trace[1260314969] 'process raft request' (duration: 131.286928ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:52.460744Z","caller":"traceutil/trace.go:171","msg":"trace[1783280524] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"131.39677ms","start":"2026-05-27T00:44:52.329323Z","end":"2026-05-27T00:44:52.46072Z","steps":["trace[1783280524] 'process raft request' (duration: 131.278968ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:52.460891Z","caller":"traceutil/trace.go:171","msg":"trace[1228049714] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"131.508173ms","start":"2026-05-27T00:44:52.329364Z","end":"2026-05-27T00:44:52.460872Z","steps":["trace[1228049714] 'process raft request' (duration: 131.307668ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:52.461038Z","caller":"traceutil/trace.go:171","msg":"trace[1236633011] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"131.759689ms","start":"2026-05-27T00:44:52.329265Z","end":"2026-05-27T00:44:52.461025Z","steps":["trace[1236633011] 'process raft request' (duration: 131.283217ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:52.461052Z","caller":"traceutil/trace.go:171","msg":"trace[307648703] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"131.691698ms","start":"2026-05-27T00:44:52.329338Z","end":"2026-05-27T00:44:52.461029Z","steps":["trace[307648703] 'process raft request' (duration: 131.300628ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:52.461076Z","caller":"traceutil/trace.go:171","msg":"trace[1490463289] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"122.217541ms","start":"2026-05-27T00:44:52.338829Z","end":"2026-05-27T00:44:52.461046Z","steps":["trace[1490463289] 'process raft request' (duration: 122.003816ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:52.461214Z","caller":"traceutil/trace.go:171","msg":"trace[2064648501] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"124.69172ms","start":"2026-05-27T00:44:52.336437Z","end":"2026-05-27T00:44:52.461129Z","steps":["trace[2064648501] 'process raft request' (duration: 124.26992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:44:52.461344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.22908ms","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-05-27T00:44:52.461455Z","caller":"traceutil/trace.go:171","msg":"trace[2058765716] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"132.365763ms","start":"2026-05-27T00:44:52.329061Z","end":"2026-05-27T00:44:52.461427Z","steps":["trace[2058765716] 'agreement among raft nodes before linearized reading' (duration: 132.017515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:44:53.971139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.062704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-27T00:44:53.971407Z","caller":"traceutil/trace.go:171","msg":"trace[1291348864] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"220.678454ms","start":"2026-05-27T00:44:53.750708Z","end":"2026-05-27T00:44:53.971386Z","steps":["trace[1291348864] 'process raft request' (duration: 220.575121ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:53.971412Z","caller":"traceutil/trace.go:171","msg":"trace[1796983090] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"221.414551ms","start":"2026-05-27T00:44:53.749965Z","end":"2026-05-27T00:44:53.97138Z","steps":["trace[1796983090] 'process raft request' (duration: 104.392334ms)","trace[1796983090] 'compare' (duration: 115.8882ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:44:54.290409Z","caller":"traceutil/trace.go:171","msg":"trace[1605602633] transaction","detail":"{read_only:false; response_revision:107; number_of_response:1; }","duration":"108.146314ms","start":"2026-05-27T00:44:54.182231Z","end":"2026-05-27T00:44:54.290377Z","steps":["trace[1605602633] 'process raft request' (duration: 45.434586ms)","trace[1605602633] 'compare' (duration: 62.519313ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:44:54.469449Z","caller":"traceutil/trace.go:171","msg":"trace[2025912784] linearizableReadLoop","detail":"{readStateIndex:118; appliedIndex:116; }","duration":"119.176657ms","start":"2026-05-27T00:44:54.350197Z","end":"2026-05-27T00:44:54.469373Z","steps":["trace[2025912784] 'read index received' (duration: 1.877705ms)","trace[2025912784] 'applied index is now lower than readState.Index' (duration: 117.297362ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:44:54.469478Z","caller":"traceutil/trace.go:171","msg":"trace[1798726373] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"119.31655ms","start":"2026-05-27T00:44:54.350107Z","end":"2026-05-27T00:44:54.469424Z","steps":["trace[1798726373] 'process raft request' (duration: 113.712517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:44:54.469854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.595937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b3457f01a88a2b\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-05-27T00:44:54.469963Z","caller":"traceutil/trace.go:171","msg":"trace[1100247624] range","detail":"{range_begin:/registry/events/default/instance.18b3457f01a88a2b; range_end:; response_count:1; response_revision:113; }","duration":"119.757981ms","start":"2026-05-27T00:44:54.350153Z","end":"2026-05-27T00:44:54.469911Z","steps":["trace[1100247624] 'agreement among raft nodes before linearized reading' (duration: 119.459004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:44:54.470109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.751189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-27T00:44:54.470176Z","caller":"traceutil/trace.go:171","msg":"trace[1624857578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:113; }","duration":"111.997675ms","start":"2026-05-27T00:44:54.358158Z","end":"2026-05-27T00:44:54.470156Z","steps":["trace[1624857578] 'agreement among raft nodes before linearized reading' (duration: 111.683447ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:55.066818Z","caller":"traceutil/trace.go:171","msg":"trace[2102886218] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"123.697965ms","start":"2026-05-27T00:44:54.943098Z","end":"2026-05-27T00:44:55.066796Z","steps":["trace[2102886218] 'process raft request' (duration: 122.900816ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:44:55.067301Z","caller":"traceutil/trace.go:171","msg":"trace[1771714913] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"122.141987ms","start":"2026-05-27T00:44:54.945137Z","end":"2026-05-27T00:44:55.067279Z","steps":["trace[1771714913] 'process raft request' (duration: 122.063845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:02.217096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393624439255828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T00:45:02.517189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.845765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:45:02.517404Z","caller":"traceutil/trace.go:171","msg":"trace[63103438] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:350; }","duration":"800.848053ms","start":"2026-05-27T00:45:01.716534Z","end":"2026-05-27T00:45:02.517382Z","steps":["trace[63103438] 'read index received' (duration: 536.544428ms)","trace[63103438] 'applied index is now lower than readState.Index' (duration: 264.302375ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:45:02.517532Z","caller":"traceutil/trace.go:171","msg":"trace[1350142660] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"861.436011ms","start":"2026-05-27T00:45:01.656077Z","end":"2026-05-27T00:45:02.517513Z","steps":["trace[1350142660] 'process raft request' (duration: 597.030933ms)","trace[1350142660] 'compare' (duration: 263.450935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:45:02.517626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:01.65606Z","time spent":"861.517933ms","remote":"127.0.0.1:60228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:45:02.517847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.027559ms","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-05-27T00:45:02.517842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.301814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T00:45:02.517902Z","caller":"traceutil/trace.go:171","msg":"trace[259334335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:341; }","duration":"308.123261ms","start":"2026-05-27T00:45:02.209762Z","end":"2026-05-27T00:45:02.517885Z","steps":["trace[259334335] 'agreement among raft nodes before linearized reading' (duration: 308.031089ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:02.517908Z","caller":"traceutil/trace.go:171","msg":"trace[257782264] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:341; }","duration":"801.409007ms","start":"2026-05-27T00:45:01.716483Z","end":"2026-05-27T00:45:02.517892Z","steps":["trace[257782264] 'agreement among raft nodes before linearized reading' (duration: 801.265974ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:02.517904Z","caller":"traceutil/trace.go:171","msg":"trace[1854765223] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"823.370042ms","start":"2026-05-27T00:45:01.694509Z","end":"2026-05-27T00:45:02.517879Z","steps":["trace[1854765223] 'process raft request' (duration: 822.807558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:02.517944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:02.209738Z","time spent":"308.196083ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T00:45:02.517948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:01.716466Z","time spent":"801.470868ms","remote":"127.0.0.1:60290","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-05-27T00:45:02.518012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:01.694484Z","time spent":"823.465743ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":194,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T00:45:03.274582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.18079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:45:03.274787Z","caller":"traceutil/trace.go:171","msg":"trace[1421256940] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:352; }","duration":"751.550514ms","start":"2026-05-27T00:45:02.523211Z","end":"2026-05-27T00:45:03.274761Z","steps":["trace[1421256940] 'read index received' (duration: 232.401172ms)","trace[1421256940] 'applied index is now lower than readState.Index' (duration: 519.147922ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:45:03.274889Z","caller":"traceutil/trace.go:171","msg":"trace[1375878199] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"753.089032ms","start":"2026-05-27T00:45:02.521784Z","end":"2026-05-27T00:45:03.274873Z","steps":["trace[1375878199] 'process raft request' (duration: 234.484912ms)","trace[1375878199] 'compare' (duration: 518.037937ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:45:03.275129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:02.521763Z","time spent":"753.310117ms","remote":"127.0.0.1:60290","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-05-27T00:45:03.274997Z","caller":"traceutil/trace.go:171","msg":"trace[1284109836] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"752.434985ms","start":"2026-05-27T00:45:02.522488Z","end":"2026-05-27T00:45:03.274923Z","steps":["trace[1284109836] 'process raft request' (duration: 752.20792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:03.275321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:02.522473Z","time spent":"752.770243ms","remote":"127.0.0.1:60290","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-05-27T00:45:03.275317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.046186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-27T00:45:03.275441Z","caller":"traceutil/trace.go:171","msg":"trace[1441391409] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:343; }","duration":"752.224841ms","start":"2026-05-27T00:45:02.52319Z","end":"2026-05-27T00:45:03.275415Z","steps":["trace[1441391409] 'agreement among raft nodes before linearized reading' (duration: 751.953364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:03.275545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:02.523183Z","time spent":"752.352234ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-27T00:45:03.277908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.747881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T00:45:03.277977Z","caller":"traceutil/trace.go:171","msg":"trace[1735794476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:344; }","duration":"695.850454ms","start":"2026-05-27T00:45:02.582109Z","end":"2026-05-27T00:45:03.277959Z","steps":["trace[1735794476] 'agreement among raft nodes before linearized reading' (duration: 695.723561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:03.278022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:02.582087Z","time spent":"695.926546ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T00:45:03.278106Z","caller":"traceutil/trace.go:171","msg":"trace[164037847] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"386.578036ms","start":"2026-05-27T00:45:02.891485Z","end":"2026-05-27T00:45:03.278063Z","steps":["trace[164037847] 'process raft request' (duration: 386.264589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:03.278476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:02.891422Z","time spent":"386.806021ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:45:11.519256Z","caller":"traceutil/trace.go:171","msg":"trace[1025861611] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"201.068774ms","start":"2026-05-27T00:45:11.318161Z","end":"2026-05-27T00:45:11.519229Z","steps":["trace[1025861611] 'process raft request' (duration: 201.009133ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:11.519313Z","caller":"traceutil/trace.go:171","msg":"trace[1633318184] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"201.824022ms","start":"2026-05-27T00:45:11.317442Z","end":"2026-05-27T00:45:11.519266Z","steps":["trace[1633318184] 'process raft request' (duration: 157.263177ms)","trace[1633318184] 'compare' (duration: 44.315759ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:45:11.974092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.78093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T00:45:11.974221Z","caller":"traceutil/trace.go:171","msg":"trace[1290438103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"291.983636ms","start":"2026-05-27T00:45:11.682209Z","end":"2026-05-27T00:45:11.974193Z","steps":["trace[1290438103] 'range keys from in-memory index tree' (duration: 291.642347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:32.10134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.96835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-05-27T00:45:32.101462Z","caller":"traceutil/trace.go:171","msg":"trace[2142263799] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:560; }","duration":"346.137684ms","start":"2026-05-27T00:45:31.755297Z","end":"2026-05-27T00:45:32.101435Z","steps":["trace[2142263799] 'range keys from in-memory index tree' (duration: 345.889258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:32.101452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.090452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"warn","ts":"2026-05-27T00:45:32.101512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:31.755283Z","time spent":"346.219345ms","remote":"127.0.0.1:60534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-27T00:45:32.101557Z","caller":"traceutil/trace.go:171","msg":"trace[1937371357] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:560; }","duration":"346.189364ms","start":"2026-05-27T00:45:31.755315Z","end":"2026-05-27T00:45:32.101504Z","steps":["trace[1937371357] 'range keys from in-memory index tree' (duration: 345.994121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:32.101638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:31.755267Z","time spent":"346.357108ms","remote":"127.0.0.1:60534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1036,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha3.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-05-27T00:45:32.10173Z","caller":"traceutil/trace.go:171","msg":"trace[885533964] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"346.286517ms","start":"2026-05-27T00:45:31.755422Z","end":"2026-05-27T00:45:32.101709Z","steps":["trace[885533964] 'process raft request' (duration: 339.600391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:32.10189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:31.755399Z","time spent":"346.41442ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:45:32.104824Z","caller":"traceutil/trace.go:171","msg":"trace[1421566632] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"346.586605ms","start":"2026-05-27T00:45:31.755694Z","end":"2026-05-27T00:45:32.10228Z","steps":["trace[1421566632] 'process raft request' (duration: 345.95314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:32.105118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:31.755682Z","time spent":"349.195975ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:45:32.108234Z","caller":"traceutil/trace.go:171","msg":"trace[30215965] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"349.78563ms","start":"2026-05-27T00:45:31.75843Z","end":"2026-05-27T00:45:32.108215Z","steps":["trace[30215965] 'process raft request' (duration: 348.262794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:32.108336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:31.75842Z","time spent":"349.864701ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:45:32.108256Z","caller":"traceutil/trace.go:171","msg":"trace[696274669] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"351.166742ms","start":"2026-05-27T00:45:31.756884Z","end":"2026-05-27T00:45:32.108051Z","steps":["trace[696274669] 'process raft request' (duration: 345.348786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:32.108521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:31.756876Z","time spent":"351.563151ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:45:32.110456Z","caller":"traceutil/trace.go:171","msg":"trace[903126687] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"269.281306ms","start":"2026-05-27T00:45:31.841156Z","end":"2026-05-27T00:45:32.110437Z","steps":["trace[903126687] 'process raft request' (duration: 269.083041ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:32.110646Z","caller":"traceutil/trace.go:171","msg":"trace[282971311] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"338.806993ms","start":"2026-05-27T00:45:31.771828Z","end":"2026-05-27T00:45:32.110635Z","steps":["trace[282971311] 'process raft request' (duration: 335.830482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:32.110724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:31.771813Z","time spent":"338.863644ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:45:32.110871Z","caller":"traceutil/trace.go:171","msg":"trace[1061682596] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"282.187359ms","start":"2026-05-27T00:45:31.828647Z","end":"2026-05-27T00:45:32.110834Z","steps":["trace[1061682596] 'process raft request' (duration: 281.012571ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:38.446764Z","caller":"traceutil/trace.go:171","msg":"trace[1747175922] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"125.34368ms","start":"2026-05-27T00:45:38.321383Z","end":"2026-05-27T00:45:38.446727Z","steps":["trace[1747175922] 'process raft request' (duration: 123.985719ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:38.920294Z","caller":"traceutil/trace.go:171","msg":"trace[425327663] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"162.364617ms","start":"2026-05-27T00:45:38.757903Z","end":"2026-05-27T00:45:38.920268Z","steps":["trace[425327663] 'process raft request' (duration: 162.192253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:40.331535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393624439256482,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T00:45:40.627607Z","caller":"traceutil/trace.go:171","msg":"trace[420353850] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"796.65994ms","start":"2026-05-27T00:45:39.830922Z","end":"2026-05-27T00:45:40.627582Z","steps":["trace[420353850] 'read index received' (duration: 796.592468ms)","trace[420353850] 'applied index is now lower than readState.Index' (duration: 66.562µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:45:40.62767Z","caller":"traceutil/trace.go:171","msg":"trace[531442193] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"843.467533ms","start":"2026-05-27T00:45:39.784176Z","end":"2026-05-27T00:45:40.627644Z","steps":["trace[531442193] 'process raft request' (duration: 843.216518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:40.627819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:39.784152Z","time spent":"843.574576ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:45:40.627889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.7677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T00:45:40.627827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.883405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T00:45:40.627953Z","caller":"traceutil/trace.go:171","msg":"trace[783755331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"547.868901ms","start":"2026-05-27T00:45:40.080066Z","end":"2026-05-27T00:45:40.627935Z","steps":["trace[783755331] 'agreement among raft nodes before linearized reading' (duration: 547.782959ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:40.628079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:40.080041Z","time spent":"548.027095ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T00:45:40.628088Z","caller":"traceutil/trace.go:171","msg":"trace[1586278530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"797.1067ms","start":"2026-05-27T00:45:39.830886Z","end":"2026-05-27T00:45:40.627993Z","steps":["trace[1586278530] 'agreement among raft nodes before linearized reading' (duration: 796.824574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:40.629044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:39.83087Z","time spent":"798.155404ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T00:45:40.628293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.867237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T00:45:40.629431Z","caller":"traceutil/trace.go:171","msg":"trace[805187353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:656; }","duration":"406.025544ms","start":"2026-05-27T00:45:40.22337Z","end":"2026-05-27T00:45:40.629395Z","steps":["trace[805187353] 'agreement among raft nodes before linearized reading' (duration: 404.819956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:40.62979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:40.223339Z","time spent":"406.392683ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T00:45:40.917533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.163898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:45:40.917857Z","caller":"traceutil/trace.go:171","msg":"trace[851024265] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"285.064835ms","start":"2026-05-27T00:45:40.632696Z","end":"2026-05-27T00:45:40.917761Z","steps":["trace[851024265] 'process raft request' (duration: 70.11437ms)","trace[851024265] 'compare' (duration: 214.064775ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:45:40.918411Z","caller":"traceutil/trace.go:171","msg":"trace[1957478866] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"284.775529ms","start":"2026-05-27T00:45:40.633601Z","end":"2026-05-27T00:45:40.918376Z","steps":["trace[1957478866] 'process raft request' (duration: 284.053232ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:41.483679Z","caller":"traceutil/trace.go:171","msg":"trace[571458720] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"402.760648ms","start":"2026-05-27T00:45:41.080891Z","end":"2026-05-27T00:45:41.483652Z","steps":["trace[571458720] 'read index received' (duration: 349.527553ms)","trace[571458720] 'applied index is now lower than readState.Index' (duration: 53.231485ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:45:41.483994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.080686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:45:41.484088Z","caller":"traceutil/trace.go:171","msg":"trace[961873489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"403.23263ms","start":"2026-05-27T00:45:41.080834Z","end":"2026-05-27T00:45:41.484066Z","steps":["trace[961873489] 'agreement among raft nodes before linearized reading' (duration: 403.056905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:41.484141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:41.080802Z","time spent":"403.330972ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T00:45:43.167711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.788258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T00:45:43.167785Z","caller":"traceutil/trace.go:171","msg":"trace[108713408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"242.89847ms","start":"2026-05-27T00:45:42.924869Z","end":"2026-05-27T00:45:43.167768Z","steps":["trace[108713408] 'range keys from in-memory index tree' (duration: 242.637174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:43.16806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.641212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T00:45:43.168158Z","caller":"traceutil/trace.go:171","msg":"trace[1222768748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"241.774014ms","start":"2026-05-27T00:45:42.926356Z","end":"2026-05-27T00:45:43.16813Z","steps":["trace[1222768748] 'range keys from in-memory index tree' (duration: 241.461487ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:43.171954Z","caller":"traceutil/trace.go:171","msg":"trace[670112401] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"186.160933ms","start":"2026-05-27T00:45:42.985775Z","end":"2026-05-27T00:45:43.171936Z","steps":["trace[670112401] 'process raft request' (duration: 186.06472ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:52.990876Z","caller":"traceutil/trace.go:171","msg":"trace[1164533399] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"116.945565ms","start":"2026-05-27T00:45:52.873906Z","end":"2026-05-27T00:45:52.990852Z","steps":["trace[1164533399] 'process raft request' (duration: 116.776001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:53.486386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.680058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:45:53.486538Z","caller":"traceutil/trace.go:171","msg":"trace[389428944] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"195.196715ms","start":"2026-05-27T00:45:53.291316Z","end":"2026-05-27T00:45:53.486513Z","steps":["trace[389428944] 'process raft request' (duration: 93.258472ms)","trace[389428944] 'compare' (duration: 101.550924ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:45:57.627561Z","caller":"traceutil/trace.go:171","msg":"trace[1679015034] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"127.278225ms","start":"2026-05-27T00:45:57.50026Z","end":"2026-05-27T00:45:57.627538Z","steps":["trace[1679015034] 'read index received' (duration: 127.06119ms)","trace[1679015034] 'applied index is now lower than readState.Index' (duration: 216.155µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:45:57.627635Z","caller":"traceutil/trace.go:171","msg":"trace[455583110] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"332.030634ms","start":"2026-05-27T00:45:57.295579Z","end":"2026-05-27T00:45:57.627609Z","steps":["trace[455583110] 'process raft request' (duration: 331.789188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:57.627763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:57.295555Z","time spent":"332.131256ms","remote":"127.0.0.1:60290","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-05-27T00:45:57.62783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.556582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T00:45:57.627882Z","caller":"traceutil/trace.go:171","msg":"trace[1571299941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:814; }","duration":"127.653354ms","start":"2026-05-27T00:45:57.500213Z","end":"2026-05-27T00:45:57.627867Z","steps":["trace[1571299941] 'agreement among raft nodes before linearized reading' (duration: 127.47602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:58.153519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393624439256804,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T00:45:58.176324Z","caller":"traceutil/trace.go:171","msg":"trace[1690554217] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"544.44459ms","start":"2026-05-27T00:45:57.63185Z","end":"2026-05-27T00:45:58.176295Z","steps":["trace[1690554217] 'process raft request' (duration: 544.299807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:58.176526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:57.63182Z","time spent":"544.597654ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:45:58.177426Z","caller":"traceutil/trace.go:171","msg":"trace[907732399] linearizableReadLoop","detail":"{readStateIndex:849; appliedIndex:847; }","duration":"525.064769ms","start":"2026-05-27T00:45:57.65233Z","end":"2026-05-27T00:45:58.177395Z","steps":["trace[907732399] 'read index received' (duration: 523.916941ms)","trace[907732399] 'applied index is now lower than readState.Index' (duration: 1.146008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:45:58.177704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.268817ms","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":"warn","ts":"2026-05-27T00:45:58.177733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.376846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T00:45:58.17773Z","caller":"traceutil/trace.go:171","msg":"trace[574635492] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"308.72821ms","start":"2026-05-27T00:45:57.86897Z","end":"2026-05-27T00:45:58.177698Z","steps":["trace[574635492] 'process raft request' (duration: 308.234057ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:58.177767Z","caller":"traceutil/trace.go:171","msg":"trace[58803845] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:816; }","duration":"343.37457ms","start":"2026-05-27T00:45:57.834378Z","end":"2026-05-27T00:45:58.177753Z","steps":["trace[58803845] 'agreement among raft nodes before linearized reading' (duration: 343.232747ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:45:58.177788Z","caller":"traceutil/trace.go:171","msg":"trace[1179186676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:816; }","duration":"525.493118ms","start":"2026-05-27T00:45:57.652279Z","end":"2026-05-27T00:45:58.177772Z","steps":["trace[1179186676] 'agreement among raft nodes before linearized reading' (duration: 525.283794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:58.177783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.046432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T00:45:58.177817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:57.83436Z","time spent":"343.446221ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-27T00:45:58.177853Z","caller":"traceutil/trace.go:171","msg":"trace[1695998292] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:816; }","duration":"337.140654ms","start":"2026-05-27T00:45:57.840695Z","end":"2026-05-27T00:45:58.177836Z","steps":["trace[1695998292] 'agreement among raft nodes before linearized reading' (duration: 337.029821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:45:58.177874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:57.868941Z","time spent":"308.864933ms","remote":"127.0.0.1:60266","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-05-27T00:45:58.177914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:57.840685Z","time spent":"337.215046ms","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-27T00:45:58.177852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:45:57.652262Z","time spent":"525.57441ms","remote":"127.0.0.1:60290","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-05-27T00:46:34.29289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40715924s","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-05-27T00:46:34.292952Z","caller":"traceutil/trace.go:171","msg":"trace[802839547] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1028; }","duration":"1.407260122s","start":"2026-05-27T00:46:32.885679Z","end":"2026-05-27T00:46:34.292939Z","steps":["trace[802839547] 'range keys from in-memory index tree' (duration: 1.407030577s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:34.292961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396548613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T00:46:34.292979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:32.885662Z","time spent":"1.407310843s","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T00:46:34.293021Z","caller":"traceutil/trace.go:171","msg":"trace[1108276217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1028; }","duration":"1.396636416s","start":"2026-05-27T00:46:32.89637Z","end":"2026-05-27T00:46:34.293007Z","steps":["trace[1108276217] 'range keys from in-memory index tree' (duration: 1.396433261s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:34.29305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:32.896359Z","time spent":"1.396684747s","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T00:46:34.374543Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393624439257260,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T00:46:34.825985Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.753195075s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T00:46:34.826326Z","caller":"traceutil/trace.go:171","msg":"trace[814160880] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"1.753597525s","start":"2026-05-27T00:46:33.072703Z","end":"2026-05-27T00:46:34.8263Z","steps":["trace[814160880] 'process raft request' (duration: 1.753481212s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:34.826451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:33.072678Z","time spent":"1.753709238s","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:46:35.153896Z","caller":"traceutil/trace.go:171","msg":"trace[1141085123] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"856.821404ms","start":"2026-05-27T00:46:34.297056Z","end":"2026-05-27T00:46:35.153877Z","steps":["trace[1141085123] 'process raft request' (duration: 856.65725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:35.154026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:34.29704Z","time spent":"856.922577ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:46:35.154095Z","caller":"traceutil/trace.go:171","msg":"trace[1543891070] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1069; }","duration":"1.280282836s","start":"2026-05-27T00:46:33.873788Z","end":"2026-05-27T00:46:35.154071Z","steps":["trace[1543891070] 'read index received' (duration: 952.468303ms)","trace[1543891070] 'applied index is now lower than readState.Index' (duration: 327.812053ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:46:35.1542Z","caller":"traceutil/trace.go:171","msg":"trace[2076350244] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"856.113248ms","start":"2026-05-27T00:46:34.298032Z","end":"2026-05-27T00:46:35.154145Z","steps":["trace[2076350244] 'process raft request' (duration: 855.813771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:35.154314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280514451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-27T00:46:35.15438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:34.298024Z","time spent":"856.263031ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:46:35.154388Z","caller":"traceutil/trace.go:171","msg":"trace[1720205452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1031; }","duration":"1.280637304s","start":"2026-05-27T00:46:33.873735Z","end":"2026-05-27T00:46:35.154372Z","steps":["trace[1720205452] 'agreement among raft nodes before linearized reading' (duration: 1.280430029s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:35.154428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:33.873714Z","time spent":"1.280705916s","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T00:46:35.163614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082902957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T00:46:35.163781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.495231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T00:46:35.163731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.334002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T00:46:35.163818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.929137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T00:46:35.163841Z","caller":"traceutil/trace.go:171","msg":"trace[472902977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1032; }","duration":"751.587053ms","start":"2026-05-27T00:46:34.412241Z","end":"2026-05-27T00:46:35.163828Z","steps":["trace[472902977] 'agreement among raft nodes before linearized reading' (duration: 751.396259ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:35.163874Z","caller":"traceutil/trace.go:171","msg":"trace[1286769848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1032; }","duration":"665.503996ms","start":"2026-05-27T00:46:34.498354Z","end":"2026-05-27T00:46:35.163858Z","steps":["trace[1286769848] 'agreement among raft nodes before linearized reading' (duration: 665.284091ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:35.163894Z","caller":"traceutil/trace.go:171","msg":"trace[1053463586] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1032; }","duration":"945.05963ms","start":"2026-05-27T00:46:34.218815Z","end":"2026-05-27T00:46:35.163875Z","steps":["trace[1053463586] 'agreement among raft nodes before linearized reading' (duration: 944.871086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:35.163907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:34.412224Z","time spent":"751.660014ms","remote":"127.0.0.1:60290","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-05-27T00:46:35.163947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:34.498334Z","time spent":"665.599889ms","remote":"127.0.0.1:60290","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":"warn","ts":"2026-05-27T00:46:35.163965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:34.218795Z","time spent":"945.146114ms","remote":"127.0.0.1:42230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-05-27T00:46:35.164046Z","caller":"traceutil/trace.go:171","msg":"trace[1217366085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"1.083128953s","start":"2026-05-27T00:46:34.080642Z","end":"2026-05-27T00:46:35.163771Z","steps":["trace[1217366085] 'agreement among raft nodes before linearized reading' (duration: 1.082881017s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:35.164322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:34.080621Z","time spent":"1.083507963s","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T00:46:53.507436Z","caller":"traceutil/trace.go:171","msg":"trace[1404803550] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"220.573836ms","start":"2026-05-27T00:46:53.286841Z","end":"2026-05-27T00:46:53.507415Z","steps":["trace[1404803550] 'read index received' (duration: 220.387241ms)","trace[1404803550] 'applied index is now lower than readState.Index' (duration: 185.695µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:46:53.507573Z","caller":"traceutil/trace.go:171","msg":"trace[123787334] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"240.53213ms","start":"2026-05-27T00:46:53.266992Z","end":"2026-05-27T00:46:53.507524Z","steps":["trace[123787334] 'process raft request' (duration: 240.285983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:53.507673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.817572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T00:46:53.50771Z","caller":"traceutil/trace.go:171","msg":"trace[717401474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"220.905924ms","start":"2026-05-27T00:46:53.286792Z","end":"2026-05-27T00:46:53.507698Z","steps":["trace[717401474] 'agreement among raft nodes before linearized reading' (duration: 220.76741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:53.507716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.788674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:46:53.507783Z","caller":"traceutil/trace.go:171","msg":"trace[1045948608] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1090; }","duration":"102.908637ms","start":"2026-05-27T00:46:53.404855Z","end":"2026-05-27T00:46:53.507764Z","steps":["trace[1045948608] 'agreement among raft nodes before linearized reading' (duration: 102.790604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:53.507776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.861542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T00:46:53.507775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.600552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T00:46:53.507832Z","caller":"traceutil/trace.go:171","msg":"trace[706185691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"135.978174ms","start":"2026-05-27T00:46:53.371837Z","end":"2026-05-27T00:46:53.507815Z","steps":["trace[706185691] 'agreement among raft nodes before linearized reading' (duration: 135.814211ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:53.507845Z","caller":"traceutil/trace.go:171","msg":"trace[1199606170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"184.724805ms","start":"2026-05-27T00:46:53.323104Z","end":"2026-05-27T00:46:53.507829Z","steps":["trace[1199606170] 'agreement among raft nodes before linearized reading' (duration: 184.5224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:53.507874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.949664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T00:46:53.507949Z","caller":"traceutil/trace.go:171","msg":"trace[1480243903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"136.076607ms","start":"2026-05-27T00:46:53.371856Z","end":"2026-05-27T00:46:53.507933Z","steps":["trace[1480243903] 'agreement among raft nodes before linearized reading' (duration: 135.846002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:54.074995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.761081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:46:54.075094Z","caller":"traceutil/trace.go:171","msg":"trace[1889562691] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"562.986449ms","start":"2026-05-27T00:46:53.512092Z","end":"2026-05-27T00:46:54.075079Z","steps":["trace[1889562691] 'process raft request' (duration: 439.072405ms)","trace[1889562691] 'compare' (duration: 123.443513ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:46:54.07515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:53.51207Z","time spent":"563.049869ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:46:54.094865Z","caller":"traceutil/trace.go:171","msg":"trace[175321805] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1135; }","duration":"387.977899ms","start":"2026-05-27T00:46:53.70686Z","end":"2026-05-27T00:46:54.094838Z","steps":["trace[175321805] 'read index received' (duration: 244.322287ms)","trace[175321805] 'applied index is now lower than readState.Index' (duration: 143.654302ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:46:54.094904Z","caller":"traceutil/trace.go:171","msg":"trace[1781264986] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"582.375608ms","start":"2026-05-27T00:46:53.512508Z","end":"2026-05-27T00:46:54.094884Z","steps":["trace[1781264986] 'process raft request' (duration: 582.284586ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:54.094877Z","caller":"traceutil/trace.go:171","msg":"trace[1312229518] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"582.709796ms","start":"2026-05-27T00:46:53.512125Z","end":"2026-05-27T00:46:54.094835Z","steps":["trace[1312229518] 'process raft request' (duration: 582.45534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:54.095044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:53.512485Z","time spent":"582.481831ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:46:54.09491Z","caller":"traceutil/trace.go:171","msg":"trace[1483569029] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"582.41179ms","start":"2026-05-27T00:46:53.512456Z","end":"2026-05-27T00:46:54.094868Z","steps":["trace[1483569029] 'process raft request' (duration: 582.286177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:54.095105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:53.512102Z","time spent":"582.936131ms","remote":"127.0.0.1:60290","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-05-27T00:46:54.095173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:53.512441Z","time spent":"582.690736ms","remote":"127.0.0.1:60290","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-05-27T00:46:54.095184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.300966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T00:46:54.095203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.560914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:46:54.095256Z","caller":"traceutil/trace.go:171","msg":"trace[2051145337] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1094; }","duration":"388.417249ms","start":"2026-05-27T00:46:53.706808Z","end":"2026-05-27T00:46:54.095225Z","steps":["trace[2051145337] 'agreement among raft nodes before linearized reading' (duration: 388.300666ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:54.095279Z","caller":"traceutil/trace.go:171","msg":"trace[852619036] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1094; }","duration":"132.688607ms","start":"2026-05-27T00:46:53.962571Z","end":"2026-05-27T00:46:54.095259Z","steps":["trace[852619036] 'agreement among raft nodes before linearized reading' (duration: 132.563845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:54.09531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:53.706787Z","time spent":"388.508121ms","remote":"127.0.0.1:42212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-05-27T00:46:55.647583Z","caller":"traceutil/trace.go:171","msg":"trace[1891862871] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"132.068784ms","start":"2026-05-27T00:46:55.515492Z","end":"2026-05-27T00:46:55.647561Z","steps":["trace[1891862871] 'process raft request' (duration: 131.902579ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:56.070726Z","caller":"traceutil/trace.go:171","msg":"trace[1967715774] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"382.864211ms","start":"2026-05-27T00:46:55.687832Z","end":"2026-05-27T00:46:56.070696Z","steps":["trace[1967715774] 'read index received' (duration: 343.669392ms)","trace[1967715774] 'applied index is now lower than readState.Index' (duration: 39.193479ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:46:56.071089Z","caller":"traceutil/trace.go:171","msg":"trace[642074725] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"524.883305ms","start":"2026-05-27T00:46:55.546186Z","end":"2026-05-27T00:46:56.071069Z","steps":["trace[642074725] 'process raft request' (duration: 485.44368ms)","trace[642074725] 'compare' (duration: 38.935774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:46:56.071221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:55.546165Z","time spent":"524.975367ms","remote":"127.0.0.1:60290","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-05-27T00:46:56.07126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.706052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:46:56.071386Z","caller":"traceutil/trace.go:171","msg":"trace[1614932172] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1097; }","duration":"268.903107ms","start":"2026-05-27T00:46:55.802458Z","end":"2026-05-27T00:46:56.071361Z","steps":["trace[1614932172] 'agreement among raft nodes before linearized reading' (duration: 268.692572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:56.071401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.550856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-27T00:46:56.071495Z","caller":"traceutil/trace.go:171","msg":"trace[1022867164] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1097; }","duration":"383.69775ms","start":"2026-05-27T00:46:55.687781Z","end":"2026-05-27T00:46:56.071478Z","steps":["trace[1022867164] 'agreement among raft nodes before linearized reading' (duration: 383.443744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:56.071545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:55.687763Z","time spent":"383.768572ms","remote":"127.0.0.1:42164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"info","ts":"2026-05-27T00:46:56.265097Z","caller":"traceutil/trace.go:171","msg":"trace[357174796] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"137.09624ms","start":"2026-05-27T00:46:56.127967Z","end":"2026-05-27T00:46:56.265063Z","steps":["trace[357174796] 'process raft request' (duration: 136.770682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:56.505666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.443975ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:46:56.5058Z","caller":"traceutil/trace.go:171","msg":"trace[110224412] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"377.789123ms","start":"2026-05-27T00:46:56.127989Z","end":"2026-05-27T00:46:56.505779Z","steps":["trace[110224412] 'process raft request' (duration: 238.176085ms)","trace[110224412] 'compare' (duration: 139.364193ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:46:56.505873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:56.127976Z","time spent":"377.861425ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:46:56.505971Z","caller":"traceutil/trace.go:171","msg":"trace[410956088] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1146; }","duration":"139.65709ms","start":"2026-05-27T00:46:56.366289Z","end":"2026-05-27T00:46:56.505946Z","steps":["trace[410956088] 'read index received' (duration: 49.142µs)","trace[410956088] 'applied index is now lower than readState.Index' (duration: 139.606868ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:46:56.506167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.861984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:46:56.506209Z","caller":"traceutil/trace.go:171","msg":"trace[565215908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1102; }","duration":"139.960347ms","start":"2026-05-27T00:46:56.366238Z","end":"2026-05-27T00:46:56.506199Z","steps":["trace[565215908] 'agreement among raft nodes before linearized reading' (duration: 139.800633ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:56.506228Z","caller":"traceutil/trace.go:171","msg":"trace[1338406865] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"378.057489ms","start":"2026-05-27T00:46:56.128154Z","end":"2026-05-27T00:46:56.506212Z","steps":["trace[1338406865] 'process raft request' (duration: 377.586338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:56.506328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:56.128146Z","time spent":"378.134281ms","remote":"127.0.0.1:60290","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-05-27T00:46:57.162577Z","caller":"traceutil/trace.go:171","msg":"trace[1159704584] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"142.530026ms","start":"2026-05-27T00:46:57.020016Z","end":"2026-05-27T00:46:57.162546Z","steps":["trace[1159704584] 'process raft request' (duration: 142.322351ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:57.994813Z","caller":"traceutil/trace.go:171","msg":"trace[2012817848] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"339.662768ms","start":"2026-05-27T00:46:57.655124Z","end":"2026-05-27T00:46:57.994787Z","steps":["trace[2012817848] 'process raft request' (duration: 339.526555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:57.994964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:57.655107Z","time spent":"339.78001ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:46:58.523109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.035703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-27T00:46:58.523125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.578736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:46:58.523178Z","caller":"traceutil/trace.go:171","msg":"trace[1425702505] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"254.139565ms","start":"2026-05-27T00:46:58.269023Z","end":"2026-05-27T00:46:58.523163Z","steps":["trace[1425702505] 'range keys from in-memory index tree' (duration: 253.942961ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:58.5232Z","caller":"traceutil/trace.go:171","msg":"trace[18720615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"439.74009ms","start":"2026-05-27T00:46:58.083439Z","end":"2026-05-27T00:46:58.523179Z","steps":["trace[18720615] 'range keys from in-memory index tree' (duration: 439.464944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:58.523241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:58.083413Z","time spent":"439.819391ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T00:46:58.523932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.957661ms","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-05-27T00:46:58.523987Z","caller":"traceutil/trace.go:171","msg":"trace[2024074137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"395.047023ms","start":"2026-05-27T00:46:58.128926Z","end":"2026-05-27T00:46:58.523973Z","steps":["trace[2024074137] 'range keys from in-memory index tree' (duration: 394.784987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:46:58.524034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:46:58.128907Z","time spent":"395.119284ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T00:46:58.689623Z","caller":"traceutil/trace.go:171","msg":"trace[733369460] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"159.912349ms","start":"2026-05-27T00:46:58.529682Z","end":"2026-05-27T00:46:58.689595Z","steps":["trace[733369460] 'process raft request' (duration: 159.764985ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:58.740207Z","caller":"traceutil/trace.go:171","msg":"trace[891189522] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"209.242253ms","start":"2026-05-27T00:46:58.530938Z","end":"2026-05-27T00:46:58.74018Z","steps":["trace[891189522] 'process raft request' (duration: 209.13143ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:58.740256Z","caller":"traceutil/trace.go:171","msg":"trace[1249791534] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"209.516309ms","start":"2026-05-27T00:46:58.530717Z","end":"2026-05-27T00:46:58.740233Z","steps":["trace[1249791534] 'process raft request' (duration: 208.774522ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:46:58.740346Z","caller":"traceutil/trace.go:171","msg":"trace[841569485] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"207.662087ms","start":"2026-05-27T00:46:58.532663Z","end":"2026-05-27T00:46:58.740325Z","steps":["trace[841569485] 'process raft request' (duration: 207.471522ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:10.830283Z","caller":"traceutil/trace.go:171","msg":"trace[61438041] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"145.576127ms","start":"2026-05-27T00:47:10.684668Z","end":"2026-05-27T00:47:10.830244Z","steps":["trace[61438041] 'process raft request' (duration: 145.034884ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:11.056481Z","caller":"traceutil/trace.go:171","msg":"trace[840557297] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"111.075057ms","start":"2026-05-27T00:47:10.945374Z","end":"2026-05-27T00:47:11.056449Z","steps":["trace[840557297] 'process raft request' (duration: 110.990985ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:11.056493Z","caller":"traceutil/trace.go:171","msg":"trace[787934688] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"111.147968ms","start":"2026-05-27T00:47:10.945303Z","end":"2026-05-27T00:47:11.056451Z","steps":["trace[787934688] 'process raft request' (duration: 107.366381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:14.699676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393624439257684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T00:47:15.200109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393624439257684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T00:47:15.310813Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.225386423s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T00:47:15.311212Z","caller":"traceutil/trace.go:171","msg":"trace[65838263] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"1.111989093s","start":"2026-05-27T00:47:14.199199Z","end":"2026-05-27T00:47:15.311188Z","steps":["trace[65838263] 'read index received' (duration: 1.111901791s)","trace[65838263] 'applied index is now lower than readState.Index' (duration: 86.412µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:47:15.311268Z","caller":"traceutil/trace.go:171","msg":"trace[114686081] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"1.225927235s","start":"2026-05-27T00:47:14.085316Z","end":"2026-05-27T00:47:15.311243Z","steps":["trace[114686081] 'process raft request' (duration: 1.225727021s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:15.311365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112139676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T00:47:15.311386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:14.085297Z","time spent":"1.226028168s","remote":"127.0.0.1:60290","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-05-27T00:47:15.31141Z","caller":"traceutil/trace.go:171","msg":"trace[2053896539] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1208; }","duration":"1.112235598s","start":"2026-05-27T00:47:14.199162Z","end":"2026-05-27T00:47:15.311398Z","steps":["trace[2053896539] 'agreement among raft nodes before linearized reading' (duration: 1.112140266s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:15.311486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:14.199146Z","time spent":"1.11229484s","remote":"127.0.0.1:41394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-27T00:47:15.311913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.470902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T00:47:15.311953Z","caller":"traceutil/trace.go:171","msg":"trace[1363327642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"373.559854ms","start":"2026-05-27T00:47:14.938377Z","end":"2026-05-27T00:47:15.311937Z","steps":["trace[1363327642] 'agreement among raft nodes before linearized reading' (duration: 373.403561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:15.311917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.008448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T00:47:15.311983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:14.938353Z","time spent":"373.624385ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T00:47:15.312007Z","caller":"traceutil/trace.go:171","msg":"trace[108042959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1209; }","duration":"228.146311ms","start":"2026-05-27T00:47:15.083839Z","end":"2026-05-27T00:47:15.311985Z","steps":["trace[108042959] 'agreement among raft nodes before linearized reading' (duration: 227.989258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:15.312073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.639787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T00:47:15.312087Z","caller":"traceutil/trace.go:171","msg":"trace[314965555] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"367.680358ms","start":"2026-05-27T00:47:14.944402Z","end":"2026-05-27T00:47:15.312082Z","steps":["trace[314965555] 'agreement among raft nodes before linearized reading' (duration: 367.640927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:15.312099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:14.944387Z","time spent":"367.708789ms","remote":"127.0.0.1:60290","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":"warn","ts":"2026-05-27T00:47:15.312165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.64717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T00:47:15.312154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.935073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T00:47:15.312178Z","caller":"traceutil/trace.go:171","msg":"trace[496347272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1209; }","duration":"371.688372ms","start":"2026-05-27T00:47:14.940486Z","end":"2026-05-27T00:47:15.312174Z","steps":["trace[496347272] 'agreement among raft nodes before linearized reading' (duration: 371.650961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:15.31219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:14.940472Z","time spent":"371.715202ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T00:47:15.31219Z","caller":"traceutil/trace.go:171","msg":"trace[1192386962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"371.008905ms","start":"2026-05-27T00:47:14.941171Z","end":"2026-05-27T00:47:15.31218Z","steps":["trace[1192386962] 'agreement among raft nodes before linearized reading' (duration: 370.894702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:15.312218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:14.941142Z","time spent":"371.069787ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T00:47:17.54668Z","caller":"traceutil/trace.go:171","msg":"trace[1406585309] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"173.592266ms","start":"2026-05-27T00:47:17.373061Z","end":"2026-05-27T00:47:17.546653Z","steps":["trace[1406585309] 'process raft request' (duration: 154.878912ms)","trace[1406585309] 'compare' (duration: 18.214063ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:47:17.546974Z","caller":"traceutil/trace.go:171","msg":"trace[594226707] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"173.76323ms","start":"2026-05-27T00:47:17.373199Z","end":"2026-05-27T00:47:17.546962Z","steps":["trace[594226707] 'process raft request' (duration: 173.353461ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:17.546701Z","caller":"traceutil/trace.go:171","msg":"trace[34940343] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"173.432623ms","start":"2026-05-27T00:47:17.373246Z","end":"2026-05-27T00:47:17.546679Z","steps":["trace[34940343] 'process raft request' (duration: 173.389392ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:30.073489Z","caller":"traceutil/trace.go:171","msg":"trace[298530410] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"103.249464ms","start":"2026-05-27T00:47:29.97022Z","end":"2026-05-27T00:47:30.07347Z","steps":["trace[298530410] 'process raft request' (duration: 103.138431ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:30.70643Z","caller":"traceutil/trace.go:171","msg":"trace[282284071] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"276.981704ms","start":"2026-05-27T00:47:30.429421Z","end":"2026-05-27T00:47:30.706403Z","steps":["trace[282284071] 'read index received' (duration: 276.696858ms)","trace[282284071] 'applied index is now lower than readState.Index' (duration: 283.666µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:47:30.706505Z","caller":"traceutil/trace.go:171","msg":"trace[267706848] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"326.588285ms","start":"2026-05-27T00:47:30.379885Z","end":"2026-05-27T00:47:30.706474Z","steps":["trace[267706848] 'process raft request' (duration: 326.276368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:30.706567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.121117ms","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-05-27T00:47:30.706606Z","caller":"traceutil/trace.go:171","msg":"trace[1734948373] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1270; }","duration":"277.187858ms","start":"2026-05-27T00:47:30.429407Z","end":"2026-05-27T00:47:30.706595Z","steps":["trace[1734948373] 'agreement among raft nodes before linearized reading' (duration: 277.098356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:30.706664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:30.379854Z","time spent":"326.722108ms","remote":"127.0.0.1:60290","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-05-27T00:47:32.037773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.767131ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T00:47:32.038204Z","caller":"traceutil/trace.go:171","msg":"trace[739032695] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"634.499648ms","start":"2026-05-27T00:47:31.403686Z","end":"2026-05-27T00:47:32.038185Z","steps":["trace[739032695] 'process raft request' (duration: 634.234362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:32.038301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:31.40366Z","time spent":"634.58796ms","remote":"127.0.0.1:60290","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-05-27T00:47:32.038273Z","caller":"traceutil/trace.go:171","msg":"trace[586797889] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1324; }","duration":"604.043111ms","start":"2026-05-27T00:47:31.43421Z","end":"2026-05-27T00:47:32.038253Z","steps":["trace[586797889] 'read index received' (duration: 154.879122ms)","trace[586797889] 'applied index is now lower than readState.Index' (duration: 448.965855ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:32.038412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.197625ms","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-05-27T00:47:32.038459Z","caller":"traceutil/trace.go:171","msg":"trace[1040991166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"604.286387ms","start":"2026-05-27T00:47:31.43416Z","end":"2026-05-27T00:47:32.038446Z","steps":["trace[1040991166] 'agreement among raft nodes before linearized reading' (duration: 604.122293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:32.038497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:31.434144Z","time spent":"604.341839ms","remote":"127.0.0.1:60290","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":"warn","ts":"2026-05-27T00:47:32.038522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.584929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T00:47:32.038552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.144174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T00:47:32.038573Z","caller":"traceutil/trace.go:171","msg":"trace[1806949638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"338.178164ms","start":"2026-05-27T00:47:31.70039Z","end":"2026-05-27T00:47:32.038568Z","steps":["trace[1806949638] 'agreement among raft nodes before linearized reading' (duration: 338.083342ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:32.038568Z","caller":"traceutil/trace.go:171","msg":"trace[1366523958] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1271; }","duration":"352.670511ms","start":"2026-05-27T00:47:31.685885Z","end":"2026-05-27T00:47:32.038555Z","steps":["trace[1366523958] 'agreement among raft nodes before linearized reading' (duration: 352.598789ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:32.038593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:31.70038Z","time spent":"338.210165ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T00:47:32.038581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.261122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T00:47:32.038609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:31.685867Z","time spent":"352.732322ms","remote":"127.0.0.1:41238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-27T00:47:32.038641Z","caller":"traceutil/trace.go:171","msg":"trace[1937940226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"390.353044ms","start":"2026-05-27T00:47:31.648273Z","end":"2026-05-27T00:47:32.038626Z","steps":["trace[1937940226] 'agreement among raft nodes before linearized reading' (duration: 390.229481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:32.038687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:31.648252Z","time spent":"390.423956ms","remote":"127.0.0.1:60290","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-05-27T00:47:32.03894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.353442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T00:47:32.039089Z","caller":"traceutil/trace.go:171","msg":"trace[1847457471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"339.559706ms","start":"2026-05-27T00:47:31.699515Z","end":"2026-05-27T00:47:32.039074Z","steps":["trace[1847457471] 'agreement among raft nodes before linearized reading' (duration: 338.921851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:32.038479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.787098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T00:47:32.039124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:31.699502Z","time spent":"339.612817ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T00:47:32.039171Z","caller":"traceutil/trace.go:171","msg":"trace[546082669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"558.505295ms","start":"2026-05-27T00:47:31.480649Z","end":"2026-05-27T00:47:32.039154Z","steps":["trace[546082669] 'agreement among raft nodes before linearized reading' (duration: 557.746697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:32.039221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:31.480629Z","time spent":"558.574826ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T00:47:32.287763Z","caller":"traceutil/trace.go:171","msg":"trace[1302126743] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"244.625274ms","start":"2026-05-27T00:47:32.043117Z","end":"2026-05-27T00:47:32.287742Z","steps":["trace[1302126743] 'process raft request' (duration: 244.250015ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:32.287772Z","caller":"traceutil/trace.go:171","msg":"trace[271937621] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"244.497351ms","start":"2026-05-27T00:47:32.043258Z","end":"2026-05-27T00:47:32.287755Z","steps":["trace[271937621] 'process raft request' (duration: 244.402679ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:32.288001Z","caller":"traceutil/trace.go:171","msg":"trace[101025167] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"244.638684ms","start":"2026-05-27T00:47:32.043343Z","end":"2026-05-27T00:47:32.287981Z","steps":["trace[101025167] 'process raft request' (duration: 244.378729ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:32.288089Z","caller":"traceutil/trace.go:171","msg":"trace[1728619775] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1326; }","duration":"195.020024ms","start":"2026-05-27T00:47:32.093062Z","end":"2026-05-27T00:47:32.288082Z","steps":["trace[1728619775] 'read index received' (duration: 37.583142ms)","trace[1728619775] 'applied index is now lower than readState.Index' (duration: 157.436402ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:32.288152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.075565ms","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-05-27T00:47:32.288172Z","caller":"traceutil/trace.go:171","msg":"trace[1880633409] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1276; }","duration":"195.135127ms","start":"2026-05-27T00:47:32.093032Z","end":"2026-05-27T00:47:32.288167Z","steps":["trace[1880633409] 'agreement among raft nodes before linearized reading' (duration: 195.077955ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:32.288426Z","caller":"traceutil/trace.go:171","msg":"trace[348412219] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"243.12192ms","start":"2026-05-27T00:47:32.044994Z","end":"2026-05-27T00:47:32.288116Z","steps":["trace[348412219] 'process raft request' (duration: 242.943635ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:33.298277Z","caller":"traceutil/trace.go:171","msg":"trace[1879707437] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"218.378375ms","start":"2026-05-27T00:47:33.079883Z","end":"2026-05-27T00:47:33.298261Z","steps":["trace[1879707437] 'read index received' (duration: 218.248772ms)","trace[1879707437] 'applied index is now lower than readState.Index' (duration: 129.073µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:47:33.29833Z","caller":"traceutil/trace.go:171","msg":"trace[6341616] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"317.479774ms","start":"2026-05-27T00:47:32.980818Z","end":"2026-05-27T00:47:33.298298Z","steps":["trace[6341616] 'process raft request' (duration: 317.352101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:33.298399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.514338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:47:33.298419Z","caller":"traceutil/trace.go:171","msg":"trace[1505274633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"218.59285ms","start":"2026-05-27T00:47:33.079819Z","end":"2026-05-27T00:47:33.298412Z","steps":["trace[1505274633] 'agreement among raft nodes before linearized reading' (duration: 218.504938ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:33.298488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:32.980787Z","time spent":"317.615538ms","remote":"127.0.0.1:42206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T00:47:34.296845Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393624439257842,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T00:47:34.78218Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.479481422s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T00:47:34.782549Z","caller":"traceutil/trace.go:171","msg":"trace[243551529] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"1.479939512s","start":"2026-05-27T00:47:33.30259Z","end":"2026-05-27T00:47:34.782529Z","steps":["trace[243551529] 'process raft request' (duration: 1.479818619s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.78264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:33.302538Z","time spent":"1.480051484s","remote":"127.0.0.1:42206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T00:47:34.867851Z","caller":"traceutil/trace.go:171","msg":"trace[336394993] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1333; }","duration":"1.071800182s","start":"2026-05-27T00:47:33.796028Z","end":"2026-05-27T00:47:34.867829Z","steps":["trace[336394993] 'read index received' (duration: 986.363004ms)","trace[336394993] 'applied index is now lower than readState.Index' (duration: 85.436228ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:47:34.867884Z","caller":"traceutil/trace.go:171","msg":"trace[1521928617] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"823.737178ms","start":"2026-05-27T00:47:34.044118Z","end":"2026-05-27T00:47:34.867855Z","steps":["trace[1521928617] 'process raft request' (duration: 823.544373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.868042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.044093Z","time spent":"823.85351ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:47:34.868063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.685031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:47:34.868108Z","caller":"traceutil/trace.go:171","msg":"trace[140318472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"787.796183ms","start":"2026-05-27T00:47:34.080298Z","end":"2026-05-27T00:47:34.868095Z","steps":["trace[140318472] 'agreement among raft nodes before linearized reading' (duration: 787.6651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.868118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072095009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"warn","ts":"2026-05-27T00:47:34.868148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.080198Z","time spent":"787.936807ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T00:47:34.868173Z","caller":"traceutil/trace.go:171","msg":"trace[2017345796] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1280; }","duration":"1.072194541s","start":"2026-05-27T00:47:33.795967Z","end":"2026-05-27T00:47:34.868162Z","steps":["trace[2017345796] 'agreement among raft nodes before linearized reading' (duration: 1.071957525s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.868212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:33.795907Z","time spent":"1.072297093s","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-27T00:47:34.868302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.618462ms","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-05-27T00:47:34.868344Z","caller":"traceutil/trace.go:171","msg":"trace[103576231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"575.653402ms","start":"2026-05-27T00:47:34.292665Z","end":"2026-05-27T00:47:34.868318Z","steps":["trace[103576231] 'agreement among raft nodes before linearized reading' (duration: 575.595231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.868359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.292658Z","time spent":"575.697573ms","remote":"127.0.0.1:60290","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":"warn","ts":"2026-05-27T00:47:34.868382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.734005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-27T00:47:34.868418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.693571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:47:34.868419Z","caller":"traceutil/trace.go:171","msg":"trace[1877096376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"575.805507ms","start":"2026-05-27T00:47:34.292605Z","end":"2026-05-27T00:47:34.86841Z","steps":["trace[1877096376] 'agreement among raft nodes before linearized reading' (duration: 575.687714ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:34.868433Z","caller":"traceutil/trace.go:171","msg":"trace[121833707] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1280; }","duration":"480.764773ms","start":"2026-05-27T00:47:34.387663Z","end":"2026-05-27T00:47:34.868427Z","steps":["trace[121833707] 'agreement among raft nodes before linearized reading' (duration: 480.740332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.868447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.387634Z","time spent":"480.809743ms","remote":"127.0.0.1:41216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-05-27T00:47:34.868448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.292563Z","time spent":"575.878718ms","remote":"127.0.0.1:60290","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":"warn","ts":"2026-05-27T00:47:34.868458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"734.336134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T00:47:34.868486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.344116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T00:47:34.868519Z","caller":"traceutil/trace.go:171","msg":"trace[1284032313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"575.412227ms","start":"2026-05-27T00:47:34.293099Z","end":"2026-05-27T00:47:34.868511Z","steps":["trace[1284032313] 'agreement among raft nodes before linearized reading' (duration: 575.337195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.868538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.293073Z","time spent":"575.461208ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T00:47:34.868527Z","caller":"traceutil/trace.go:171","msg":"trace[1905543221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"734.446477ms","start":"2026-05-27T00:47:34.134062Z","end":"2026-05-27T00:47:34.868509Z","steps":["trace[1905543221] 'agreement among raft nodes before linearized reading' (duration: 734.275353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.868567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.893358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T00:47:34.868584Z","caller":"traceutil/trace.go:171","msg":"trace[1836271127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"575.944179ms","start":"2026-05-27T00:47:34.292635Z","end":"2026-05-27T00:47:34.868579Z","steps":["trace[1836271127] 'agreement among raft nodes before linearized reading' (duration: 575.901048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:34.868576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.134036Z","time spent":"734.526938ms","remote":"127.0.0.1:60290","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-05-27T00:47:34.868597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.292614Z","time spent":"575.97926ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T00:47:35.248513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.883603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:47:35.24858Z","caller":"traceutil/trace.go:171","msg":"trace[146652363] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"374.337428ms","start":"2026-05-27T00:47:34.874231Z","end":"2026-05-27T00:47:35.248568Z","steps":["trace[146652363] 'process raft request' (duration: 71.351643ms)","trace[146652363] 'compare' (duration: 302.553915ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:35.248611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.874213Z","time spent":"374.384229ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:47:35.673265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.51191ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:47:35.673372Z","caller":"traceutil/trace.go:171","msg":"trace[158507845] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1281; }","duration":"247.640823ms","start":"2026-05-27T00:47:35.425717Z","end":"2026-05-27T00:47:35.673357Z","steps":["trace[158507845] 'range keys from in-memory index tree' (duration: 247.49266ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:35.673432Z","caller":"traceutil/trace.go:171","msg":"trace[1784114795] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"799.01422ms","start":"2026-05-27T00:47:34.87439Z","end":"2026-05-27T00:47:35.673405Z","steps":["trace[1784114795] 'process raft request' (duration: 735.04378ms)","trace[1784114795] 'compare' (duration: 63.820677ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:35.673576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.874376Z","time spent":"799.129114ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:47:35.673695Z","caller":"traceutil/trace.go:171","msg":"trace[520400205] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"799.237027ms","start":"2026-05-27T00:47:34.874445Z","end":"2026-05-27T00:47:35.673682Z","steps":["trace[520400205] 'process raft request' (duration: 798.920188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:35.673768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.874441Z","time spent":"799.286388ms","remote":"127.0.0.1:60290","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-05-27T00:47:35.673883Z","caller":"traceutil/trace.go:171","msg":"trace[463005] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"799.39609ms","start":"2026-05-27T00:47:34.874478Z","end":"2026-05-27T00:47:35.673874Z","steps":["trace[463005] 'process raft request' (duration: 799.126384ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:35.673853Z","caller":"traceutil/trace.go:171","msg":"trace[1016021386] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"799.35298ms","start":"2026-05-27T00:47:34.874468Z","end":"2026-05-27T00:47:35.673821Z","steps":["trace[1016021386] 'process raft request' (duration: 799.209507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:35.673974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.874474Z","time spent":"799.440001ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:47:35.673998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.874457Z","time spent":"799.468232ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:47:35.674094Z","caller":"traceutil/trace.go:171","msg":"trace[326463596] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"796.78427ms","start":"2026-05-27T00:47:34.877302Z","end":"2026-05-27T00:47:35.674086Z","steps":["trace[326463596] 'process raft request' (duration: 796.417032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:35.674127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.87729Z","time spent":"796.81833ms","remote":"127.0.0.1:60220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:47:35.674242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.2224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T00:47:35.674298Z","caller":"traceutil/trace.go:171","msg":"trace[1002914512] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1286; }","duration":"775.340772ms","start":"2026-05-27T00:47:34.898944Z","end":"2026-05-27T00:47:35.674285Z","steps":["trace[1002914512] 'agreement among raft nodes before linearized reading' (duration: 775.21703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:35.674333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:34.898921Z","time spent":"775.402224ms","remote":"127.0.0.1:60240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-27T00:47:35.673785Z","caller":"traceutil/trace.go:171","msg":"trace[1425631418] linearizableReadLoop","detail":"{readStateIndex:1341; appliedIndex:1335; }","duration":"774.757899ms","start":"2026-05-27T00:47:34.899004Z","end":"2026-05-27T00:47:35.673762Z","steps":["trace[1425631418] 'read index received' (duration: 46.589829ms)","trace[1425631418] 'applied index is now lower than readState.Index' (duration: 728.16707ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:35.674706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.035438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:47:35.67476Z","caller":"traceutil/trace.go:171","msg":"trace[1087903356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"592.11462ms","start":"2026-05-27T00:47:35.082631Z","end":"2026-05-27T00:47:35.674746Z","steps":["trace[1087903356] 'agreement among raft nodes before linearized reading' (duration: 592.039268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:35.674799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:35.082613Z","time spent":"592.175671ms","remote":"127.0.0.1:42108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T00:47:35.675004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.03602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T00:47:35.675038Z","caller":"traceutil/trace.go:171","msg":"trace[1077017973] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1286; }","duration":"155.09118ms","start":"2026-05-27T00:47:35.519935Z","end":"2026-05-27T00:47:35.675026Z","steps":["trace[1077017973] 'agreement among raft nodes before linearized reading' (duration: 155.037949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:35.978088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.104483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:47:35.978178Z","caller":"traceutil/trace.go:171","msg":"trace[691905772] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1341; }","duration":"289.622656ms","start":"2026-05-27T00:47:35.688541Z","end":"2026-05-27T00:47:35.978164Z","steps":["trace[691905772] 'read index received' (duration: 121.259318ms)","trace[691905772] 'applied index is now lower than readState.Index' (duration: 168.362118ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:47:35.978316Z","caller":"traceutil/trace.go:171","msg":"trace[1802386154] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"297.502639ms","start":"2026-05-27T00:47:35.680778Z","end":"2026-05-27T00:47:35.978281Z","steps":["trace[1802386154] 'process raft request' (duration: 129.13179ms)","trace[1802386154] 'compare' (duration: 167.929409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:35.97842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.880702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-27T00:47:35.978445Z","caller":"traceutil/trace.go:171","msg":"trace[2110566335] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:1287; }","duration":"289.930783ms","start":"2026-05-27T00:47:35.688507Z","end":"2026-05-27T00:47:35.978438Z","steps":["trace[2110566335] 'agreement among raft nodes before linearized reading' (duration: 289.697377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:36.270875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.210664ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-27T00:47:36.270987Z","caller":"traceutil/trace.go:171","msg":"trace[190427086] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"188.102765ms","start":"2026-05-27T00:47:36.082862Z","end":"2026-05-27T00:47:36.270965Z","steps":["trace[190427086] 'read index received' (duration: 70.696027ms)","trace[190427086] 'applied index is now lower than readState.Index' (duration: 117.404858ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:36.271072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.199887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:47:36.271109Z","caller":"traceutil/trace.go:171","msg":"trace[419182738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"188.279239ms","start":"2026-05-27T00:47:36.08282Z","end":"2026-05-27T00:47:36.271099Z","steps":["trace[419182738] 'agreement among raft nodes before linearized reading' (duration: 188.202268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:36.908641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.164056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T00:47:36.908721Z","caller":"traceutil/trace.go:171","msg":"trace[137502489] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"588.925166ms","start":"2026-05-27T00:47:36.319783Z","end":"2026-05-27T00:47:36.908708Z","steps":["trace[137502489] 'read index received' (duration: 267.615707ms)","trace[137502489] 'applied index is now lower than readState.Index' (duration: 321.308039ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:47:36.908771Z","caller":"traceutil/trace.go:171","msg":"trace[231692149] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"636.590869ms","start":"2026-05-27T00:47:36.272171Z","end":"2026-05-27T00:47:36.908762Z","steps":["trace[231692149] 'process raft request' (duration: 315.245609ms)","trace[231692149] 'compare' (duration: 320.90884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:36.908811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:36.272143Z","time spent":"636.645021ms","remote":"127.0.0.1:42164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:47:36.909002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.220374ms","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-05-27T00:47:36.909044Z","caller":"traceutil/trace.go:171","msg":"trace[2006717853] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1288; }","duration":"589.301015ms","start":"2026-05-27T00:47:36.319729Z","end":"2026-05-27T00:47:36.90903Z","steps":["trace[2006717853] 'agreement among raft nodes before linearized reading' (duration: 589.166211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:36.909081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:36.319708Z","time spent":"589.359527ms","remote":"127.0.0.1:60368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-27T00:47:37.121502Z","caller":"traceutil/trace.go:171","msg":"trace[1364006953] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"246.994169ms","start":"2026-05-27T00:47:36.87448Z","end":"2026-05-27T00:47:37.121474Z","steps":["trace[1364006953] 'process raft request' (duration: 246.813005ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:37.131606Z","caller":"traceutil/trace.go:171","msg":"trace[1534130567] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1344; }","duration":"222.84073ms","start":"2026-05-27T00:47:36.90874Z","end":"2026-05-27T00:47:37.13158Z","steps":["trace[1534130567] 'read index received' (duration: 212.533381ms)","trace[1534130567] 'applied index is now lower than readState.Index' (duration: 10.306119ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T00:47:37.131662Z","caller":"traceutil/trace.go:171","msg":"trace[515396256] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"231.682385ms","start":"2026-05-27T00:47:36.899962Z","end":"2026-05-27T00:47:37.131644Z","steps":["trace[515396256] 'process raft request' (duration: 231.509581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:37.131924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.813883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-27T00:47:37.132613Z","caller":"traceutil/trace.go:171","msg":"trace[1606126828] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1290; }","duration":"221.53905ms","start":"2026-05-27T00:47:36.911062Z","end":"2026-05-27T00:47:37.132601Z","steps":["trace[1606126828] 'agreement among raft nodes before linearized reading' (duration: 220.811413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:37.131921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.813308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T00:47:37.132822Z","caller":"traceutil/trace.go:171","msg":"trace[214856832] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1290; }","duration":"391.683588ms","start":"2026-05-27T00:47:36.741031Z","end":"2026-05-27T00:47:37.132715Z","steps":["trace[214856832] 'agreement among raft nodes before linearized reading' (duration: 390.658355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:37.132873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:36.74101Z","time spent":"391.848482ms","remote":"127.0.0.1:60380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-27T00:47:37.13199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.777324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T00:47:37.132929Z","caller":"traceutil/trace.go:171","msg":"trace[1827697951] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1290; }","duration":"120.744606ms","start":"2026-05-27T00:47:37.012169Z","end":"2026-05-27T00:47:37.132914Z","steps":["trace[1827697951] 'agreement among raft nodes before linearized reading' (duration: 119.784904ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:45.834012Z","caller":"traceutil/trace.go:171","msg":"trace[1938489983] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"119.732931ms","start":"2026-05-27T00:47:45.714258Z","end":"2026-05-27T00:47:45.833991Z","steps":["trace[1938489983] 'read index received' (duration: 119.579738ms)","trace[1938489983] 'applied index is now lower than readState.Index' (duration: 151.783µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:45.834264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.435856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-27T00:47:45.8343Z","caller":"traceutil/trace.go:171","msg":"trace[1515702833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1365; }","duration":"113.485337ms","start":"2026-05-27T00:47:45.720806Z","end":"2026-05-27T00:47:45.834291Z","steps":["trace[1515702833] 'agreement among raft nodes before linearized reading' (duration: 113.395505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:45.834259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.987998ms","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-05-27T00:47:45.834595Z","caller":"traceutil/trace.go:171","msg":"trace[1529271885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1365; }","duration":"120.374987ms","start":"2026-05-27T00:47:45.714205Z","end":"2026-05-27T00:47:45.83458Z","steps":["trace[1529271885] 'agreement among raft nodes before linearized reading' (duration: 119.925445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:45.834371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.574117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T00:47:45.834788Z","caller":"traceutil/trace.go:171","msg":"trace[198197550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1365; }","duration":"115.998496ms","start":"2026-05-27T00:47:45.718767Z","end":"2026-05-27T00:47:45.834766Z","steps":["trace[198197550] 'agreement among raft nodes before linearized reading' (duration: 115.492514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:45.834352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.73059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T00:47:45.834921Z","caller":"traceutil/trace.go:171","msg":"trace[1231975796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1365; }","duration":"116.340263ms","start":"2026-05-27T00:47:45.718576Z","end":"2026-05-27T00:47:45.834916Z","steps":["trace[1231975796] 'agreement among raft nodes before linearized reading' (duration: 115.597306ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:46.305038Z","caller":"traceutil/trace.go:171","msg":"trace[2019140654] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"469.83373ms","start":"2026-05-27T00:47:45.835178Z","end":"2026-05-27T00:47:46.305012Z","steps":["trace[2019140654] 'process raft request' (duration: 461.899376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:46.305194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:45.835153Z","time spent":"469.966882ms","remote":"127.0.0.1:42164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:47:46.442588Z","caller":"traceutil/trace.go:171","msg":"trace[872933850] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"604.21829ms","start":"2026-05-27T00:47:45.838345Z","end":"2026-05-27T00:47:46.442563Z","steps":["trace[872933850] 'process raft request' (duration: 604.019606ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:46.442833Z","caller":"traceutil/trace.go:171","msg":"trace[1984370332] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"604.076847ms","start":"2026-05-27T00:47:45.838734Z","end":"2026-05-27T00:47:46.442811Z","steps":["trace[1984370332] 'process raft request' (duration: 603.78743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:46.443004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:45.838714Z","time spent":"604.17734ms","remote":"127.0.0.1:60290","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-05-27T00:47:46.443159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:45.838317Z","time spent":"604.351414ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:47:46.443324Z","caller":"traceutil/trace.go:171","msg":"trace[1520511276] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1427; }","duration":"135.788664ms","start":"2026-05-27T00:47:46.307513Z","end":"2026-05-27T00:47:46.443301Z","steps":["trace[1520511276] 'read index received' (duration: 134.782361ms)","trace[1520511276] 'applied index is now lower than readState.Index' (duration: 1.005353ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T00:47:46.444223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.690505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-05-27T00:47:46.444227Z","caller":"traceutil/trace.go:171","msg":"trace[1991023971] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"605.379787ms","start":"2026-05-27T00:47:45.838815Z","end":"2026-05-27T00:47:46.444195Z","steps":["trace[1991023971] 'process raft request' (duration: 603.920653ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:47:46.44433Z","caller":"traceutil/trace.go:171","msg":"trace[1606528904] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1370; }","duration":"136.864449ms","start":"2026-05-27T00:47:46.307424Z","end":"2026-05-27T00:47:46.444289Z","steps":["trace[1606528904] 'agreement among raft nodes before linearized reading' (duration: 135.958658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:46.444449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:45.838808Z","time spent":"605.525121ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T00:47:46.444637Z","caller":"traceutil/trace.go:171","msg":"trace[1158060294] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"604.904406ms","start":"2026-05-27T00:47:45.839174Z","end":"2026-05-27T00:47:46.444078Z","steps":["trace[1158060294] 'process raft request' (duration: 604.016786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T00:47:46.44478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T00:47:45.839137Z","time spent":"605.563601ms","remote":"127.0.0.1:60290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T00:47:46.445319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.571483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-27T00:47:46.4454Z","caller":"traceutil/trace.go:171","msg":"trace[1918912183] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1370; }","duration":"133.691736ms","start":"2026-05-27T00:47:46.31169Z","end":"2026-05-27T00:47:46.445381Z","steps":["trace[1918912183] 'agreement among raft nodes before linearized reading' (duration: 133.518912ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T00:51:33.919043Z","caller":"traceutil/trace.go:171","msg":"trace[752153572] transaction","detail":"{read_only:false; response_revision:2440; number_of_response:1; }","duration":"104.206695ms","start":"2026-05-27T00:51:33.814813Z","end":"2026-05-27T00:51:33.91902Z","steps":["trace[752153572] 'process raft request' (duration: 104.071152ms)"],"step_count":1}