{"level":"warn","ts":"2026-05-14T13:17:52.244859Z","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-14T13:17:52.244975Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.23: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.23:2380","--initial-cluster=instance=https://199.204.45.23:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.23:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.23: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-14T13:17:52.245061Z","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-14T13:17:52.245072Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-14T13:17:52.245095Z","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-14T13:17:52.246124Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"]} {"level":"info","ts":"2026-05-14T13:17:52.246256Z","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.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.23: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-14T13:17:52.253678Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.125542ms"} {"level":"info","ts":"2026-05-14T13:17:52.264215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"51a5c416a4d98c86","cluster-id":"ade2045974c19907"} {"level":"info","ts":"2026-05-14T13:17:52.26431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=()"} {"level":"info","ts":"2026-05-14T13:17:52.264342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 0"} {"level":"info","ts":"2026-05-14T13:17:52.264361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 51a5c416a4d98c86 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-14T13:17:52.264385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became follower at term 1"} {"level":"info","ts":"2026-05-14T13:17:52.264465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"warn","ts":"2026-05-14T13:17:52.281089Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-14T13:17:52.282765Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-14T13:17:52.284924Z","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-14T13:17:52.287848Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"51a5c416a4d98c86","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-14T13:17:52.288271Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"51a5c416a4d98c86","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-14T13:17:52.288323Z","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-14T13:17:52.288488Z","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-14T13:17:52.288547Z","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-14T13:17:52.292614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 switched to configuration voters=(5883324089763466374)"} {"level":"info","ts":"2026-05-14T13:17:52.292838Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","added-peer-id":"51a5c416a4d98c86","added-peer-peer-urls":["https://199.204.45.23:2380"]} {"level":"info","ts":"2026-05-14T13:17:52.294967Z","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-14T13:17:52.295151Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-14T13:17:52.295254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.23:2380"} {"level":"info","ts":"2026-05-14T13:17:52.295282Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"51a5c416a4d98c86","initial-advertise-peer-urls":["https://199.204.45.23:2380"],"listen-peer-urls":["https://199.204.45.23:2380"],"advertise-client-urls":["https://199.204.45.23:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.23:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-14T13:17:52.295342Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-14T13:17:53.265166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 is starting a new election at term 1"} {"level":"info","ts":"2026-05-14T13:17:53.265239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-14T13:17:53.265276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgPreVoteResp from 51a5c416a4d98c86 at term 1"} {"level":"info","ts":"2026-05-14T13:17:53.2653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became candidate at term 2"} {"level":"info","ts":"2026-05-14T13:17:53.265313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 received MsgVoteResp from 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-14T13:17:53.265451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"51a5c416a4d98c86 became leader at term 2"} {"level":"info","ts":"2026-05-14T13:17:53.265561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 51a5c416a4d98c86 elected leader 51a5c416a4d98c86 at term 2"} {"level":"info","ts":"2026-05-14T13:17:53.26679Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:17:53.26717Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T13:17:53.2672Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-14T13:17:53.267164Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"51a5c416a4d98c86","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.23:2379]}","request-path":"/0/members/51a5c416a4d98c86/attributes","cluster-id":"ade2045974c19907","publish-timeout":"7s"} {"level":"info","ts":"2026-05-14T13:17:53.267506Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-14T13:17:53.267566Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-14T13:17:53.269136Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ade2045974c19907","local-member-id":"51a5c416a4d98c86","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:17:53.269305Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:17:53.269352Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-14T13:17:53.270562Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-14T13:17:53.270833Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.23:2379"} {"level":"info","ts":"2026-05-14T13:18:23.256146Z","caller":"traceutil/trace.go:171","msg":"trace[1685612404] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"132.017156ms","start":"2026-05-14T13:18:23.124102Z","end":"2026-05-14T13:18:23.256119Z","steps":["trace[1685612404] 'process raft request' (duration: 129.7056ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.256211Z","caller":"traceutil/trace.go:171","msg":"trace[183069949] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"131.491425ms","start":"2026-05-14T13:18:23.124712Z","end":"2026-05-14T13:18:23.256203Z","steps":["trace[183069949] 'process raft request' (duration: 131.288911ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.25611Z","caller":"traceutil/trace.go:171","msg":"trace[1188044009] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"131.281791ms","start":"2026-05-14T13:18:23.124804Z","end":"2026-05-14T13:18:23.256086Z","steps":["trace[1188044009] 'process raft request' (duration: 131.246041ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.260706Z","caller":"traceutil/trace.go:171","msg":"trace[1296880099] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"126.883664ms","start":"2026-05-14T13:18:23.133804Z","end":"2026-05-14T13:18:23.260687Z","steps":["trace[1296880099] 'process raft request' (duration: 126.407554ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.261144Z","caller":"traceutil/trace.go:171","msg":"trace[378348693] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"129.165929ms","start":"2026-05-14T13:18:23.131962Z","end":"2026-05-14T13:18:23.261128Z","steps":["trace[378348693] 'process raft request' (duration: 128.159459ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.262007Z","caller":"traceutil/trace.go:171","msg":"trace[2106348967] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"123.975076ms","start":"2026-05-14T13:18:23.138018Z","end":"2026-05-14T13:18:23.261993Z","steps":["trace[2106348967] 'process raft request' (duration: 122.458236ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.262258Z","caller":"traceutil/trace.go:171","msg":"trace[921089564] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"124.303052ms","start":"2026-05-14T13:18:23.137943Z","end":"2026-05-14T13:18:23.262246Z","steps":["trace[921089564] 'process raft request' (duration: 122.303991ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.263179Z","caller":"traceutil/trace.go:171","msg":"trace[1030361975] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"121.440215ms","start":"2026-05-14T13:18:23.141719Z","end":"2026-05-14T13:18:23.263159Z","steps":["trace[1030361975] 'process raft request' (duration: 118.874934ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.263282Z","caller":"traceutil/trace.go:171","msg":"trace[1857559799] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"118.751042ms","start":"2026-05-14T13:18:23.144526Z","end":"2026-05-14T13:18:23.263277Z","steps":["trace[1857559799] 'process raft request' (duration: 116.118339ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.2635Z","caller":"traceutil/trace.go:171","msg":"trace[222152923] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"125.479976ms","start":"2026-05-14T13:18:23.138012Z","end":"2026-05-14T13:18:23.263492Z","steps":["trace[222152923] 'process raft request' (duration: 122.346493ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.752929Z","caller":"traceutil/trace.go:171","msg":"trace[830482136] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"168.000605ms","start":"2026-05-14T13:18:23.584905Z","end":"2026-05-14T13:18:23.752906Z","steps":["trace[830482136] 'process raft request' (duration: 167.843312ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.758076Z","caller":"traceutil/trace.go:171","msg":"trace[1917006513] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"115.268861ms","start":"2026-05-14T13:18:23.642793Z","end":"2026-05-14T13:18:23.758062Z","steps":["trace[1917006513] 'process raft request' (duration: 115.22214ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:23.758444Z","caller":"traceutil/trace.go:171","msg":"trace[640415353] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"143.346762ms","start":"2026-05-14T13:18:23.615086Z","end":"2026-05-14T13:18:23.758433Z","steps":["trace[640415353] 'process raft request' (duration: 142.778161ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:27.071707Z","caller":"traceutil/trace.go:171","msg":"trace[2067568325] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"112.09567ms","start":"2026-05-14T13:18:26.959579Z","end":"2026-05-14T13:18:27.071675Z","steps":["trace[2067568325] 'process raft request' (duration: 111.419925ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:28.464779Z","caller":"traceutil/trace.go:171","msg":"trace[1465991106] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"810.394903ms","start":"2026-05-14T13:18:27.654356Z","end":"2026-05-14T13:18:28.464751Z","steps":["trace[1465991106] 'process raft request' (duration: 810.24146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:28.465199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:27.654333Z","time spent":"810.527076ms","remote":"127.0.0.1:35536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:18:28.467927Z","caller":"traceutil/trace.go:171","msg":"trace[2094691894] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:581; }","duration":"100.301074ms","start":"2026-05-14T13:18:28.367604Z","end":"2026-05-14T13:18:28.467905Z","steps":["trace[2094691894] 'read index received' (duration: 97.024517ms)","trace[2094691894] 'applied index is now lower than readState.Index' (duration: 3.275947ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:18:28.46812Z","caller":"traceutil/trace.go:171","msg":"trace[1983470313] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"163.432024ms","start":"2026-05-14T13:18:28.304615Z","end":"2026-05-14T13:18:28.468047Z","steps":["trace[1983470313] 'process raft request' (duration: 163.169079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:28.468177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.559858ms","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-14T13:18:28.468372Z","caller":"traceutil/trace.go:171","msg":"trace[1851551664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:563; }","duration":"100.783063ms","start":"2026-05-14T13:18:28.367553Z","end":"2026-05-14T13:18:28.468336Z","steps":["trace[1851551664] 'agreement among raft nodes before linearized reading' (duration: 100.442156ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:30.245588Z","caller":"traceutil/trace.go:171","msg":"trace[1375077555] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"414.805873ms","start":"2026-05-14T13:18:29.830743Z","end":"2026-05-14T13:18:30.245548Z","steps":["trace[1375077555] 'process raft request' (duration: 413.711922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:30.246019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:29.830719Z","time spent":"414.962087ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036269,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:18:30.369467Z","caller":"traceutil/trace.go:171","msg":"trace[306631380] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"101.672401ms","start":"2026-05-14T13:18:30.267731Z","end":"2026-05-14T13:18:30.369403Z","steps":["trace[306631380] 'process raft request' (duration: 101.501118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.165343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.371345ms","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-14T13:18:31.165425Z","caller":"traceutil/trace.go:171","msg":"trace[1039083882] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"405.496726ms","start":"2026-05-14T13:18:30.759908Z","end":"2026-05-14T13:18:31.165404Z","steps":["trace[1039083882] 'range keys from in-memory index tree' (duration: 405.190121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.165436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.765435ms","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-14T13:18:31.165465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:30.759888Z","time spent":"405.567538ms","remote":"127.0.0.1:35472","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-14T13:18:31.165513Z","caller":"traceutil/trace.go:171","msg":"trace[830388069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"688.847556ms","start":"2026-05-14T13:18:30.476623Z","end":"2026-05-14T13:18:31.165471Z","steps":["trace[830388069] 'range keys from in-memory index tree' (duration: 688.676402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.165546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:30.47659Z","time spent":"688.947628ms","remote":"127.0.0.1:35472","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-14T13:18:31.545963Z","caller":"traceutil/trace.go:171","msg":"trace[152447533] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"374.366586ms","start":"2026-05-14T13:18:31.171579Z","end":"2026-05-14T13:18:31.545945Z","steps":["trace[152447533] 'read index received' (duration: 372.712323ms)","trace[152447533] 'applied index is now lower than readState.Index' (duration: 1.652163ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:18:31.546009Z","caller":"traceutil/trace.go:171","msg":"trace[1842197911] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"372.264964ms","start":"2026-05-14T13:18:31.173716Z","end":"2026-05-14T13:18:31.545981Z","steps":["trace[1842197911] 'process raft request' (duration: 372.155172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.546192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:31.1737Z","time spent":"372.409277ms","remote":"127.0.0.1:35472","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-14T13:18:31.54641Z","caller":"traceutil/trace.go:171","msg":"trace[563349246] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"371.777343ms","start":"2026-05-14T13:18:31.174603Z","end":"2026-05-14T13:18:31.546381Z","steps":["trace[563349246] 'process raft request' (duration: 371.325614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.546684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:31.17457Z","time spent":"371.982778ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:18:31.546746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.163082ms","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-14T13:18:31.546799Z","caller":"traceutil/trace.go:171","msg":"trace[1237824830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"375.232813ms","start":"2026-05-14T13:18:31.171553Z","end":"2026-05-14T13:18:31.546786Z","steps":["trace[1237824830] 'agreement among raft nodes before linearized reading' (duration: 374.533818ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:31.547811Z","caller":"traceutil/trace.go:171","msg":"trace[2025911910] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"772.519017ms","start":"2026-05-14T13:18:30.773404Z","end":"2026-05-14T13:18:31.545923Z","steps":["trace[2025911910] 'process raft request' (duration: 771.13987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.547903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:30.773375Z","time spent":"774.480836ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:18:31.546873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:31.171525Z","time spent":"375.337775ms","remote":"127.0.0.1:35472","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-14T13:18:31.576701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.772558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:18:31.576768Z","caller":"traceutil/trace.go:171","msg":"trace[2051060030] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:581; }","duration":"258.89593ms","start":"2026-05-14T13:18:31.317856Z","end":"2026-05-14T13:18:31.576752Z","steps":["trace[2051060030] 'agreement among raft nodes before linearized reading' (duration: 258.723717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.577148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.992806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:31.577174Z","caller":"traceutil/trace.go:171","msg":"trace[168301058] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:581; }","duration":"109.035967ms","start":"2026-05-14T13:18:31.468132Z","end":"2026-05-14T13:18:31.577168Z","steps":["trace[168301058] 'agreement among raft nodes before linearized reading' (duration: 108.998926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:31.577227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.125998ms","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-14T13:18:31.577253Z","caller":"traceutil/trace.go:171","msg":"trace[2099592036] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:581; }","duration":"109.17251ms","start":"2026-05-14T13:18:31.468068Z","end":"2026-05-14T13:18:31.57724Z","steps":["trace[2099592036] 'agreement among raft nodes before linearized reading' (duration: 109.145509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.74713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.388738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.747225Z","caller":"traceutil/trace.go:171","msg":"trace[926649451] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:594; }","duration":"336.50441ms","start":"2026-05-14T13:18:32.4107Z","end":"2026-05-14T13:18:32.747205Z","steps":["trace[926649451] 'range keys from in-memory index tree' (duration: 336.332047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.747264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:32.410607Z","time spent":"336.644873ms","remote":"127.0.0.1:36200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-14T13:18:32.747428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.663213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.747467Z","caller":"traceutil/trace.go:171","msg":"trace[1105140532] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:594; }","duration":"336.705894ms","start":"2026-05-14T13:18:32.410752Z","end":"2026-05-14T13:18:32.747458Z","steps":["trace[1105140532] 'count revisions from in-memory index tree' (duration: 336.616692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.747496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:32.410717Z","time spent":"336.770095ms","remote":"127.0.0.1:36200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:18:32.747695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.383317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.747736Z","caller":"traceutil/trace.go:171","msg":"trace[163964933] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:594; }","duration":"336.440719ms","start":"2026-05-14T13:18:32.411286Z","end":"2026-05-14T13:18:32.747726Z","steps":["trace[163964933] 'range keys from in-memory index tree' (duration: 336.286375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.747761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.049602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:18:32.74782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.512666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.747823Z","caller":"traceutil/trace.go:171","msg":"trace[75611859] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:594; }","duration":"257.129065ms","start":"2026-05-14T13:18:32.490678Z","end":"2026-05-14T13:18:32.747807Z","steps":["trace[75611859] 'count revisions from in-memory index tree' (duration: 257.001272ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:32.747855Z","caller":"traceutil/trace.go:171","msg":"trace[901569810] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:594; }","duration":"168.550457ms","start":"2026-05-14T13:18:32.579288Z","end":"2026-05-14T13:18:32.747839Z","steps":["trace[901569810] 'range keys from in-memory index tree' (duration: 168.482705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.74786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.093915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:18:32.74786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.823601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:18:32.747863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.28134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.747941Z","caller":"traceutil/trace.go:171","msg":"trace[467294178] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:594; }","duration":"257.225997ms","start":"2026-05-14T13:18:32.490691Z","end":"2026-05-14T13:18:32.747917Z","steps":["trace[467294178] 'range keys from in-memory index tree' (duration: 257.013553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.747972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.589385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.747992Z","caller":"traceutil/trace.go:171","msg":"trace[515672195] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:594; }","duration":"256.620815ms","start":"2026-05-14T13:18:32.491365Z","end":"2026-05-14T13:18:32.747986Z","steps":["trace[515672195] 'range keys from in-memory index tree' (duration: 256.551794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.030296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:18:32.748027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.561592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748021Z","caller":"traceutil/trace.go:171","msg":"trace[214944916] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:594; }","duration":"169.528516ms","start":"2026-05-14T13:18:32.578458Z","end":"2026-05-14T13:18:32.747987Z","steps":["trace[214944916] 'range keys from in-memory index tree' (duration: 169.177158ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:32.748062Z","caller":"traceutil/trace.go:171","msg":"trace[1179546378] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:594; }","duration":"313.629903ms","start":"2026-05-14T13:18:32.434421Z","end":"2026-05-14T13:18:32.748051Z","steps":["trace[1179546378] 'count revisions from in-memory index tree' (duration: 313.383378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.951745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:18:32.748106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.376569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748126Z","caller":"traceutil/trace.go:171","msg":"trace[697712612] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:594; }","duration":"147.013216ms","start":"2026-05-14T13:18:32.601105Z","end":"2026-05-14T13:18:32.748118Z","steps":["trace[697712612] 'count revisions from in-memory index tree' (duration: 146.856153ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:32.748137Z","caller":"traceutil/trace.go:171","msg":"trace[1043498340] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:594; }","duration":"284.450801ms","start":"2026-05-14T13:18:32.463676Z","end":"2026-05-14T13:18:32.748127Z","steps":["trace[1043498340] 'count revisions from in-memory index tree' (duration: 284.298338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.74815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.266237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748179Z","caller":"traceutil/trace.go:171","msg":"trace[1257385489] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:594; }","duration":"284.305137ms","start":"2026-05-14T13:18:32.463865Z","end":"2026-05-14T13:18:32.74817Z","steps":["trace[1257385489] 'range keys from in-memory index tree' (duration: 284.207485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.619843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:18:32.748272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.602948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:18:32.747767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:32.411281Z","time spent":"336.47722ms","remote":"127.0.0.1:36200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"info","ts":"2026-05-14T13:18:32.748296Z","caller":"traceutil/trace.go:171","msg":"trace[380124093] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:594; }","duration":"313.661134ms","start":"2026-05-14T13:18:32.434627Z","end":"2026-05-14T13:18:32.748288Z","steps":["trace[380124093] 'range keys from in-memory index tree' (duration: 313.564592ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:32.748303Z","caller":"traceutil/trace.go:171","msg":"trace[248897168] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:594; }","duration":"146.655529ms","start":"2026-05-14T13:18:32.601639Z","end":"2026-05-14T13:18:32.748295Z","steps":["trace[248897168] 'range keys from in-memory index tree' (duration: 146.516596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:32.434622Z","time spent":"313.694034ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-14T13:18:32.748374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.929683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.747939Z","caller":"traceutil/trace.go:171","msg":"trace[1492145444] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:594; }","duration":"229.953053ms","start":"2026-05-14T13:18:32.517972Z","end":"2026-05-14T13:18:32.747925Z","steps":["trace[1492145444] 'count revisions from in-memory index tree' (duration: 229.761469ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:32.748402Z","caller":"traceutil/trace.go:171","msg":"trace[1601186267] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:594; }","duration":"169.982525ms","start":"2026-05-14T13:18:32.578411Z","end":"2026-05-14T13:18:32.748394Z","steps":["trace[1601186267] 'count revisions from in-memory index tree' (duration: 169.868102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.74843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.644378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748458Z","caller":"traceutil/trace.go:171","msg":"trace[1368276094] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:594; }","duration":"130.685369ms","start":"2026-05-14T13:18:32.617764Z","end":"2026-05-14T13:18:32.748449Z","steps":["trace[1368276094] 'count revisions from in-memory index tree' (duration: 130.594117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.343997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748507Z","caller":"traceutil/trace.go:171","msg":"trace[270646872] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:594; }","duration":"313.382609ms","start":"2026-05-14T13:18:32.435116Z","end":"2026-05-14T13:18:32.748499Z","steps":["trace[270646872] 'range keys from in-memory index tree' (duration: 313.296147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:32.435111Z","time spent":"313.417469ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-14T13:18:32.748545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.413591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748579Z","caller":"traceutil/trace.go:171","msg":"trace[2109547196] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:594; }","duration":"230.475473ms","start":"2026-05-14T13:18:32.518093Z","end":"2026-05-14T13:18:32.748569Z","steps":["trace[2109547196] 'range keys from in-memory index tree' (duration: 230.341949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.800561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748047Z","caller":"traceutil/trace.go:171","msg":"trace[1184818235] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:594; }","duration":"147.062927ms","start":"2026-05-14T13:18:32.600973Z","end":"2026-05-14T13:18:32.748036Z","steps":["trace[1184818235] 'range keys from in-memory index tree' (duration: 146.919144ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:32.748608Z","caller":"traceutil/trace.go:171","msg":"trace[1742691778] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:594; }","duration":"130.840703ms","start":"2026-05-14T13:18:32.617759Z","end":"2026-05-14T13:18:32.748599Z","steps":["trace[1742691778] 'range keys from in-memory index tree' (duration: 130.73915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.000101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748674Z","caller":"traceutil/trace.go:171","msg":"trace[1720451532] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:594; }","duration":"285.040122ms","start":"2026-05-14T13:18:32.463625Z","end":"2026-05-14T13:18:32.748665Z","steps":["trace[1720451532] 'range keys from in-memory index tree' (duration: 284.96054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.001993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748722Z","caller":"traceutil/trace.go:171","msg":"trace[1708042714] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:594; }","duration":"230.042124ms","start":"2026-05-14T13:18:32.51867Z","end":"2026-05-14T13:18:32.748712Z","steps":["trace[1708042714] 'range keys from in-memory index tree' (duration: 229.933381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.043087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748751Z","caller":"traceutil/trace.go:171","msg":"trace[671131831] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:594; }","duration":"130.086797ms","start":"2026-05-14T13:18:32.618656Z","end":"2026-05-14T13:18:32.748743Z","steps":["trace[671131831] 'range keys from in-memory index tree' (duration: 129.999886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.748857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.519937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:32.748883Z","caller":"traceutil/trace.go:171","msg":"trace[1779815642] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"106.558378ms","start":"2026-05-14T13:18:32.642316Z","end":"2026-05-14T13:18:32.748875Z","steps":["trace[1779815642] 'range keys from in-memory index tree' (duration: 106.477876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:32.74895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.509867ms","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:5"} {"level":"warn","ts":"2026-05-14T13:18:32.748096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:32.434407Z","time spent":"313.679814ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-05-14T13:18:32.748985Z","caller":"traceutil/trace.go:171","msg":"trace[23562194] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:594; }","duration":"106.572348ms","start":"2026-05-14T13:18:32.642403Z","end":"2026-05-14T13:18:32.748976Z","steps":["trace[23562194] 'count revisions from in-memory index tree' (duration: 106.423925ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:36.565305Z","caller":"traceutil/trace.go:171","msg":"trace[1952280830] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"115.108808ms","start":"2026-05-14T13:18:36.45017Z","end":"2026-05-14T13:18:36.565279Z","steps":["trace[1952280830] 'process raft request' (duration: 114.881244ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:43.93957Z","caller":"traceutil/trace.go:171","msg":"trace[343802787] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"308.413954ms","start":"2026-05-14T13:18:43.631132Z","end":"2026-05-14T13:18:43.939546Z","steps":["trace[343802787] 'read index received' (duration: 289.45433ms)","trace[343802787] 'applied index is now lower than readState.Index' (duration: 18.958714ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:18:43.939603Z","caller":"traceutil/trace.go:171","msg":"trace[999462009] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"339.434991ms","start":"2026-05-14T13:18:43.600145Z","end":"2026-05-14T13:18:43.93958Z","steps":["trace[999462009] 'process raft request' (duration: 320.340225ms)","trace[999462009] 'compare' (duration: 18.954233ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:18:43.939757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:43.60012Z","time spent":"339.540264ms","remote":"127.0.0.1:35472","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-14T13:18:43.939842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.648148ms","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-14T13:18:43.939922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.75807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T13:18:43.939996Z","caller":"traceutil/trace.go:171","msg":"trace[1599829733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:699; }","duration":"308.78675ms","start":"2026-05-14T13:18:43.631166Z","end":"2026-05-14T13:18:43.939953Z","steps":["trace[1599829733] 'agreement among raft nodes before linearized reading' (duration: 308.522095ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:43.940006Z","caller":"traceutil/trace.go:171","msg":"trace[688790657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:699; }","duration":"308.882902ms","start":"2026-05-14T13:18:43.631105Z","end":"2026-05-14T13:18:43.939988Z","steps":["trace[688790657] 'agreement among raft nodes before linearized reading' (duration: 308.570106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:43.940045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:43.631161Z","time spent":"308.870502ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:18:43.940055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:43.631094Z","time spent":"308.950984ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T13:18:53.819663Z","caller":"traceutil/trace.go:171","msg":"trace[1752803486] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"145.525192ms","start":"2026-05-14T13:18:53.674113Z","end":"2026-05-14T13:18:53.819638Z","steps":["trace[1752803486] 'process raft request' (duration: 145.391449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:54.71299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.513952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T13:18:54.713154Z","caller":"traceutil/trace.go:171","msg":"trace[1257518674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"717.625674ms","start":"2026-05-14T13:18:53.995436Z","end":"2026-05-14T13:18:54.713062Z","steps":["trace[1257518674] 'range keys from in-memory index tree' (duration: 716.330189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:54.713134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.054444ms","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-14T13:18:54.713234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:53.995425Z","time spent":"717.791099ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T13:18:54.713252Z","caller":"traceutil/trace.go:171","msg":"trace[2013650843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"719.181057ms","start":"2026-05-14T13:18:53.994044Z","end":"2026-05-14T13:18:54.713225Z","steps":["trace[2013650843] 'range keys from in-memory index tree' (duration: 718.823708ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:54.713347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:53.993989Z","time spent":"719.33997ms","remote":"127.0.0.1:35472","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-14T13:18:54.711872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.383706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:54.713562Z","caller":"traceutil/trace.go:171","msg":"trace[656006223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"463.114211ms","start":"2026-05-14T13:18:54.25041Z","end":"2026-05-14T13:18:54.713524Z","steps":["trace[656006223] 'range keys from in-memory index tree' (duration: 461.266784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:54.713679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:54.250385Z","time spent":"463.251284ms","remote":"127.0.0.1:56742","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-14T13:18:54.71398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.32107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:18:54.714071Z","caller":"traceutil/trace.go:171","msg":"trace[325149234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"719.429092ms","start":"2026-05-14T13:18:53.994606Z","end":"2026-05-14T13:18:54.714035Z","steps":["trace[325149234] 'range keys from in-memory index tree' (duration: 719.183597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:54.714123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:53.994587Z","time spent":"719.523043ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T13:18:55.393993Z","caller":"traceutil/trace.go:171","msg":"trace[206672395] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"239.050092ms","start":"2026-05-14T13:18:55.154918Z","end":"2026-05-14T13:18:55.393968Z","steps":["trace[206672395] 'read index received' (duration: 238.790297ms)","trace[206672395] 'applied index is now lower than readState.Index' (duration: 258.145µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:18:55.394131Z","caller":"traceutil/trace.go:171","msg":"trace[2139096031] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"243.033653ms","start":"2026-05-14T13:18:55.15106Z","end":"2026-05-14T13:18:55.394094Z","steps":["trace[2139096031] 'process raft request' (duration: 242.740407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:55.394213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.265106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:18:55.394279Z","caller":"traceutil/trace.go:171","msg":"trace[697099434] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:756; }","duration":"239.380058ms","start":"2026-05-14T13:18:55.154882Z","end":"2026-05-14T13:18:55.394262Z","steps":["trace[697099434] 'agreement among raft nodes before linearized reading' (duration: 239.215125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:59.295585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954700968529563,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T13:18:59.339019Z","caller":"traceutil/trace.go:171","msg":"trace[105934345] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"954.097414ms","start":"2026-05-14T13:18:58.384895Z","end":"2026-05-14T13:18:59.338993Z","steps":["trace[105934345] 'process raft request' (duration: 953.910031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:59.339181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:58.38487Z","time spent":"954.228917ms","remote":"127.0.0.1:56894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:18:59.405898Z","caller":"traceutil/trace.go:171","msg":"trace[896721459] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:800; }","duration":"611.022439ms","start":"2026-05-14T13:18:58.794844Z","end":"2026-05-14T13:18:59.405867Z","steps":["trace[896721459] 'read index received' (duration: 543.953414ms)","trace[896721459] 'applied index is now lower than readState.Index' (duration: 67.067525ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:18:59.406037Z","caller":"traceutil/trace.go:171","msg":"trace[1445978676] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"320.354815ms","start":"2026-05-14T13:18:59.085668Z","end":"2026-05-14T13:18:59.406023Z","steps":["trace[1445978676] 'process raft request' (duration: 320.052829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:59.40621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.346026ms","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-14T13:18:59.406301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:59.085638Z","time spent":"320.447108ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:18:59.406364Z","caller":"traceutil/trace.go:171","msg":"trace[372054182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:774; }","duration":"611.513299ms","start":"2026-05-14T13:18:58.794803Z","end":"2026-05-14T13:18:59.406317Z","steps":["trace[372054182] 'agreement among raft nodes before linearized reading' (duration: 611.272134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:59.406452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:58.794781Z","time spent":"611.659963ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:18:59.418333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.661236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T13:18:59.418348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.638531ms","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-14T13:18:59.418383Z","caller":"traceutil/trace.go:171","msg":"trace[1074838143] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:775; }","duration":"244.752197ms","start":"2026-05-14T13:18:59.17362Z","end":"2026-05-14T13:18:59.418373Z","steps":["trace[1074838143] 'agreement among raft nodes before linearized reading' (duration: 244.616665ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:18:59.418389Z","caller":"traceutil/trace.go:171","msg":"trace[1466892332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"607.715533ms","start":"2026-05-14T13:18:58.810663Z","end":"2026-05-14T13:18:59.418379Z","steps":["trace[1466892332] 'agreement among raft nodes before linearized reading' (duration: 607.582901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:59.418419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:58.810626Z","time spent":"607.783005ms","remote":"127.0.0.1:35472","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-14T13:18:59.41871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.625111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T13:18:59.41875Z","caller":"traceutil/trace.go:171","msg":"trace[1099773865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"609.749614ms","start":"2026-05-14T13:18:58.80899Z","end":"2026-05-14T13:18:59.41874Z","steps":["trace[1099773865] 'agreement among raft nodes before linearized reading' (duration: 609.569431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:18:59.418816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:18:58.808958Z","time spent":"609.848776ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T13:19:01.751311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.031695ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-14T13:19:01.75142Z","caller":"traceutil/trace.go:171","msg":"trace[233121717] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"320.358615ms","start":"2026-05-14T13:19:01.431042Z","end":"2026-05-14T13:19:01.7514Z","steps":["trace[233121717] 'read index received' (duration: 64.184337ms)","trace[233121717] 'applied index is now lower than readState.Index' (duration: 256.172588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:01.751685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.624431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:19:01.751768Z","caller":"traceutil/trace.go:171","msg":"trace[465397443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:782; }","duration":"320.754653ms","start":"2026-05-14T13:19:01.430992Z","end":"2026-05-14T13:19:01.751746Z","steps":["trace[465397443] 'agreement among raft nodes before linearized reading' (duration: 320.481787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:01.751816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:01.430972Z","time spent":"320.834125ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:19:01.833239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.073747ms","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-14T13:19:01.833276Z","caller":"traceutil/trace.go:171","msg":"trace[1563922585] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"398.022425ms","start":"2026-05-14T13:19:01.435231Z","end":"2026-05-14T13:19:01.833253Z","steps":["trace[1563922585] 'process raft request' (duration: 397.648947ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:01.833322Z","caller":"traceutil/trace.go:171","msg":"trace[1384317938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:783; }","duration":"400.19046ms","start":"2026-05-14T13:19:01.433112Z","end":"2026-05-14T13:19:01.833302Z","steps":["trace[1384317938] 'agreement among raft nodes before linearized reading' (duration: 399.969325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:01.833369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:01.433103Z","time spent":"400.254281ms","remote":"127.0.0.1:35472","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-14T13:19:01.833401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:01.43521Z","time spent":"398.114317ms","remote":"127.0.0.1:35458","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-14T13:19:01.833742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.512866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-14T13:19:01.833852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.235559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T13:19:01.833989Z","caller":"traceutil/trace.go:171","msg":"trace[1267408753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:783; }","duration":"396.485273ms","start":"2026-05-14T13:19:01.437477Z","end":"2026-05-14T13:19:01.833962Z","steps":["trace[1267408753] 'agreement among raft nodes before linearized reading' (duration: 395.851381ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:01.83395Z","caller":"traceutil/trace.go:171","msg":"trace[649421012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"400.639178ms","start":"2026-05-14T13:19:01.433165Z","end":"2026-05-14T13:19:01.833804Z","steps":["trace[649421012] 'agreement among raft nodes before linearized reading' (duration: 400.386783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:01.834056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:01.437452Z","time spent":"396.592296ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T13:19:01.834142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:01.433144Z","time spent":"400.981575ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-14T13:19:01.870831Z","caller":"traceutil/trace.go:171","msg":"trace[1855808906] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"114.76267ms","start":"2026-05-14T13:19:01.756046Z","end":"2026-05-14T13:19:01.870808Z","steps":["trace[1855808906] 'process raft request' (duration: 104.006072ms)","trace[1855808906] 'compare' (duration: 10.540083ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:06.015473Z","caller":"traceutil/trace.go:171","msg":"trace[1055388780] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"122.955495ms","start":"2026-05-14T13:19:05.892492Z","end":"2026-05-14T13:19:06.015447Z","steps":["trace[1055388780] 'process raft request' (duration: 122.848023ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:06.015487Z","caller":"traceutil/trace.go:171","msg":"trace[1456203652] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"123.342493ms","start":"2026-05-14T13:19:05.892109Z","end":"2026-05-14T13:19:06.015451Z","steps":["trace[1456203652] 'process raft request' (duration: 123.068848ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:06.015757Z","caller":"traceutil/trace.go:171","msg":"trace[2131731538] linearizableReadLoop","detail":"{readStateIndex:871; appliedIndex:867; }","duration":"116.359942ms","start":"2026-05-14T13:19:05.899372Z","end":"2026-05-14T13:19:06.015732Z","steps":["trace[2131731538] 'read index received' (duration: 76.304121ms)","trace[2131731538] 'applied index is now lower than readState.Index' (duration: 40.054711ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:06.015828Z","caller":"traceutil/trace.go:171","msg":"trace[1669067580] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"122.098788ms","start":"2026-05-14T13:19:05.893701Z","end":"2026-05-14T13:19:06.0158Z","steps":["trace[1669067580] 'process raft request' (duration: 121.69992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:06.015973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.582316ms","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-14T13:19:06.016013Z","caller":"traceutil/trace.go:171","msg":"trace[375183280] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"116.658228ms","start":"2026-05-14T13:19:05.899345Z","end":"2026-05-14T13:19:06.016004Z","steps":["trace[375183280] 'agreement among raft nodes before linearized reading' (duration: 116.491675ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:06.171769Z","caller":"traceutil/trace.go:171","msg":"trace[660700386] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"124.119899ms","start":"2026-05-14T13:19:06.047625Z","end":"2026-05-14T13:19:06.171745Z","steps":["trace[660700386] 'process raft request' (duration: 123.770542ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:06.459424Z","caller":"traceutil/trace.go:171","msg":"trace[1351685888] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"153.36593ms","start":"2026-05-14T13:19:06.306026Z","end":"2026-05-14T13:19:06.459392Z","steps":["trace[1351685888] 'process raft request' (duration: 127.115909ms)","trace[1351685888] 'compare' (duration: 26.112308ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:11.573828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.37862ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-14T13:19:18.224382Z","caller":"traceutil/trace.go:171","msg":"trace[1541634852] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"121.560737ms","start":"2026-05-14T13:19:18.102788Z","end":"2026-05-14T13:19:18.224349Z","steps":["trace[1541634852] 'process raft request' (duration: 98.763855ms)","trace[1541634852] 'compare' (duration: 22.649649ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:18.463579Z","caller":"traceutil/trace.go:171","msg":"trace[1550158078] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:964; }","duration":"208.012674ms","start":"2026-05-14T13:19:18.255539Z","end":"2026-05-14T13:19:18.463552Z","steps":["trace[1550158078] 'read index received' (duration: 27.993546ms)","trace[1550158078] 'applied index is now lower than readState.Index' (duration: 180.017398ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:18.463681Z","caller":"traceutil/trace.go:171","msg":"trace[1830203316] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"233.931509ms","start":"2026-05-14T13:19:18.22969Z","end":"2026-05-14T13:19:18.463621Z","steps":["trace[1830203316] 'process raft request' (duration: 196.281558ms)","trace[1830203316] 'compare' (duration: 37.424126ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:18.463716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.155247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:19:18.463793Z","caller":"traceutil/trace.go:171","msg":"trace[1976729488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"208.26247ms","start":"2026-05-14T13:19:18.255514Z","end":"2026-05-14T13:19:18.463776Z","steps":["trace[1976729488] 'agreement among raft nodes before linearized reading' (duration: 208.146858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:18.463868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.440032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-14T13:19:18.463873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.766718ms","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-14T13:19:18.463929Z","caller":"traceutil/trace.go:171","msg":"trace[160027423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"202.540525ms","start":"2026-05-14T13:19:18.261368Z","end":"2026-05-14T13:19:18.463908Z","steps":["trace[160027423] 'agreement among raft nodes before linearized reading' (duration: 202.361321ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:18.463934Z","caller":"traceutil/trace.go:171","msg":"trace[1602846688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"201.85532ms","start":"2026-05-14T13:19:18.262061Z","end":"2026-05-14T13:19:18.463916Z","steps":["trace[1602846688] 'agreement among raft nodes before linearized reading' (duration: 201.708777ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:20.770879Z","caller":"traceutil/trace.go:171","msg":"trace[1273694209] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"209.823461ms","start":"2026-05-14T13:19:20.560991Z","end":"2026-05-14T13:19:20.770815Z","steps":["trace[1273694209] 'process raft request' (duration: 196.407091ms)","trace[1273694209] 'compare' (duration: 13.109254ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:20.771111Z","caller":"traceutil/trace.go:171","msg":"trace[845278922] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"209.745349ms","start":"2026-05-14T13:19:20.561207Z","end":"2026-05-14T13:19:20.770952Z","steps":["trace[845278922] 'process raft request' (duration: 209.418103ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:22.400537Z","caller":"traceutil/trace.go:171","msg":"trace[307507739] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"102.858448ms","start":"2026-05-14T13:19:22.297655Z","end":"2026-05-14T13:19:22.400513Z","steps":["trace[307507739] 'process raft request' (duration: 102.676435ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:22.548315Z","caller":"traceutil/trace.go:171","msg":"trace[220025189] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"142.462049ms","start":"2026-05-14T13:19:22.405816Z","end":"2026-05-14T13:19:22.548278Z","steps":["trace[220025189] 'process raft request' (duration: 82.916335ms)","trace[220025189] 'compare' (duration: 59.371841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:22.864919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.178624ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:19:22.865135Z","caller":"traceutil/trace.go:171","msg":"trace[1069886809] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"230.488258ms","start":"2026-05-14T13:19:22.634626Z","end":"2026-05-14T13:19:22.865114Z","steps":["trace[1069886809] 'process raft request' (duration: 88.993478ms)","trace[1069886809] 'compare' (duration: 141.006161ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:23.360913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.563235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:19:23.361033Z","caller":"traceutil/trace.go:171","msg":"trace[2093728544] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"465.352755ms","start":"2026-05-14T13:19:22.895661Z","end":"2026-05-14T13:19:23.361014Z","steps":["trace[2093728544] 'read index received' (duration: 348.977983ms)","trace[2093728544] 'applied index is now lower than readState.Index' (duration: 116.373592ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:23.361128Z","caller":"traceutil/trace.go:171","msg":"trace[1922488922] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"491.225408ms","start":"2026-05-14T13:19:22.869889Z","end":"2026-05-14T13:19:23.361115Z","steps":["trace[1922488922] 'process raft request' (duration: 375.357017ms)","trace[1922488922] 'compare' (duration: 115.419192ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:23.361192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:22.869862Z","time spent":"491.300519ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:19:23.361207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.819309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:19:23.361316Z","caller":"traceutil/trace.go:171","msg":"trace[1356170571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:948; }","duration":"109.977354ms","start":"2026-05-14T13:19:23.251316Z","end":"2026-05-14T13:19:23.361293Z","steps":["trace[1356170571] 'agreement among raft nodes before linearized reading' (duration: 109.82144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:23.361363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.698614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:19:23.361635Z","caller":"traceutil/trace.go:171","msg":"trace[1138700000] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:948; }","duration":"466.017179ms","start":"2026-05-14T13:19:22.895601Z","end":"2026-05-14T13:19:23.361618Z","steps":["trace[1138700000] 'agreement among raft nodes before linearized reading' (duration: 465.659551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:23.361688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:22.895584Z","time spent":"466.083681ms","remote":"127.0.0.1:35484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-05-14T13:19:24.664131Z","caller":"traceutil/trace.go:171","msg":"trace[1499674643] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"118.927004ms","start":"2026-05-14T13:19:24.545176Z","end":"2026-05-14T13:19:24.664103Z","steps":["trace[1499674643] 'read index received' (duration: 118.68929ms)","trace[1499674643] 'applied index is now lower than readState.Index' (duration: 236.654µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:24.664236Z","caller":"traceutil/trace.go:171","msg":"trace[747663606] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"257.452454ms","start":"2026-05-14T13:19:24.406762Z","end":"2026-05-14T13:19:24.664214Z","steps":["trace[747663606] 'process raft request' (duration: 257.180829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:24.66433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.128388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:19:24.664729Z","caller":"traceutil/trace.go:171","msg":"trace[1652845923] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:950; }","duration":"119.590207ms","start":"2026-05-14T13:19:24.545114Z","end":"2026-05-14T13:19:24.664705Z","steps":["trace[1652845923] 'agreement among raft nodes before linearized reading' (duration: 119.140258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:24.664452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.195718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:19:24.664881Z","caller":"traceutil/trace.go:171","msg":"trace[371147698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"112.635927ms","start":"2026-05-14T13:19:24.552187Z","end":"2026-05-14T13:19:24.664823Z","steps":["trace[371147698] 'agreement among raft nodes before linearized reading' (duration: 112.103126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:24.980526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.865126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T13:19:24.980845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.701958ms","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-14T13:19:24.980893Z","caller":"traceutil/trace.go:171","msg":"trace[1809300849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"111.79572ms","start":"2026-05-14T13:19:24.869084Z","end":"2026-05-14T13:19:24.98088Z","steps":["trace[1809300849] 'range keys from in-memory index tree' (duration: 111.299579ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:24.98089Z","caller":"traceutil/trace.go:171","msg":"trace[1552312819] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:951; }","duration":"135.274563ms","start":"2026-05-14T13:19:24.845589Z","end":"2026-05-14T13:19:24.980863Z","steps":["trace[1552312819] 'count revisions from in-memory index tree' (duration: 134.787764ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:25.187049Z","caller":"traceutil/trace.go:171","msg":"trace[637902389] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"201.746677ms","start":"2026-05-14T13:19:24.985273Z","end":"2026-05-14T13:19:25.187019Z","steps":["trace[637902389] 'process raft request' (duration: 174.761362ms)","trace[637902389] 'compare' (duration: 26.841032ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:25.657665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.076193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T13:19:25.657756Z","caller":"traceutil/trace.go:171","msg":"trace[1813797585] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"289.191045ms","start":"2026-05-14T13:19:25.368546Z","end":"2026-05-14T13:19:25.657737Z","steps":["trace[1813797585] 'range keys from in-memory index tree' (duration: 288.844248ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:26.39099Z","caller":"traceutil/trace.go:171","msg":"trace[347648667] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"140.01072ms","start":"2026-05-14T13:19:26.250941Z","end":"2026-05-14T13:19:26.390951Z","steps":["trace[347648667] 'read index received' (duration: 112.396482ms)","trace[347648667] 'applied index is now lower than readState.Index' (duration: 27.612488ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:26.391231Z","caller":"traceutil/trace.go:171","msg":"trace[69949250] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"164.516846ms","start":"2026-05-14T13:19:26.226699Z","end":"2026-05-14T13:19:26.391216Z","steps":["trace[69949250] 'process raft request' (duration: 136.680283ms)","trace[69949250] 'compare' (duration: 27.308592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:26.391578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.868577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:19:26.391679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.713534ms","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-14T13:19:26.391682Z","caller":"traceutil/trace.go:171","msg":"trace[1099644291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:955; }","duration":"140.037691ms","start":"2026-05-14T13:19:26.25162Z","end":"2026-05-14T13:19:26.391658Z","steps":["trace[1099644291] 'agreement among raft nodes before linearized reading' (duration: 139.772925ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:26.391806Z","caller":"traceutil/trace.go:171","msg":"trace[702022809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"140.876377ms","start":"2026-05-14T13:19:26.250867Z","end":"2026-05-14T13:19:26.391744Z","steps":["trace[702022809] 'agreement among raft nodes before linearized reading' (duration: 140.546501ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:26.77336Z","caller":"traceutil/trace.go:171","msg":"trace[516691330] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"375.306116ms","start":"2026-05-14T13:19:26.398027Z","end":"2026-05-14T13:19:26.773334Z","steps":["trace[516691330] 'read index received' (duration: 375.221354ms)","trace[516691330] 'applied index is now lower than readState.Index' (duration: 83.112µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:26.773511Z","caller":"traceutil/trace.go:171","msg":"trace[316452578] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"376.457829ms","start":"2026-05-14T13:19:26.397011Z","end":"2026-05-14T13:19:26.773469Z","steps":["trace[316452578] 'process raft request' (duration: 376.138062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:26.773646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:26.396992Z","time spent":"376.547351ms","remote":"127.0.0.1:35472","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-14T13:19:26.773797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.734625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-14T13:19:26.773921Z","caller":"traceutil/trace.go:171","msg":"trace[705190737] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:956; }","duration":"375.897559ms","start":"2026-05-14T13:19:26.397994Z","end":"2026-05-14T13:19:26.773892Z","steps":["trace[705190737] 'agreement among raft nodes before linearized reading' (duration: 375.565652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:26.774004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:26.397984Z","time spent":"376.00381ms","remote":"127.0.0.1:35484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-05-14T13:19:26.91067Z","caller":"traceutil/trace.go:171","msg":"trace[1223197325] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"290.36764ms","start":"2026-05-14T13:19:26.62027Z","end":"2026-05-14T13:19:26.910638Z","steps":["trace[1223197325] 'process raft request' (duration: 289.438681ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:26.910829Z","caller":"traceutil/trace.go:171","msg":"trace[1389259423] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"240.848138ms","start":"2026-05-14T13:19:26.669961Z","end":"2026-05-14T13:19:26.910809Z","steps":["trace[1389259423] 'process raft request' (duration: 240.552361ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:26.910823Z","caller":"traceutil/trace.go:171","msg":"trace[1127494299] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:991; }","duration":"137.361236ms","start":"2026-05-14T13:19:26.773439Z","end":"2026-05-14T13:19:26.9108Z","steps":["trace[1127494299] 'read index received' (duration: 136.206932ms)","trace[1127494299] 'applied index is now lower than readState.Index' (duration: 1.152644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:26.911021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.763427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:19:26.911076Z","caller":"traceutil/trace.go:171","msg":"trace[923564090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:958; }","duration":"194.866628ms","start":"2026-05-14T13:19:26.716197Z","end":"2026-05-14T13:19:26.911063Z","steps":["trace[923564090] 'agreement among raft nodes before linearized reading' (duration: 194.668824ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:27.306097Z","caller":"traceutil/trace.go:171","msg":"trace[1683331081] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"109.615025ms","start":"2026-05-14T13:19:27.196447Z","end":"2026-05-14T13:19:27.306062Z","steps":["trace[1683331081] 'process raft request' (duration: 79.491605ms)","trace[1683331081] 'compare' (duration: 29.807264ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:27.943066Z","caller":"traceutil/trace.go:171","msg":"trace[2110696331] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"209.76883ms","start":"2026-05-14T13:19:27.733272Z","end":"2026-05-14T13:19:27.943041Z","steps":["trace[2110696331] 'process raft request' (duration: 209.614207ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:28.897679Z","caller":"traceutil/trace.go:171","msg":"trace[1573772622] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"116.394933ms","start":"2026-05-14T13:19:28.781257Z","end":"2026-05-14T13:19:28.897652Z","steps":["trace[1573772622] 'process raft request' (duration: 116.199489ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:29.091851Z","caller":"traceutil/trace.go:171","msg":"trace[1377192779] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"134.956278ms","start":"2026-05-14T13:19:28.95685Z","end":"2026-05-14T13:19:29.091806Z","steps":["trace[1377192779] 'read index received' (duration: 74.21838ms)","trace[1377192779] 'applied index is now lower than readState.Index' (duration: 60.735608ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:29.091878Z","caller":"traceutil/trace.go:171","msg":"trace[2025273626] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"175.63965ms","start":"2026-05-14T13:19:28.916205Z","end":"2026-05-14T13:19:29.091845Z","steps":["trace[2025273626] 'process raft request' (duration: 114.967074ms)","trace[2025273626] 'compare' (duration: 60.36992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:29.092067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.210744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:19:29.092113Z","caller":"traceutil/trace.go:171","msg":"trace[1613701121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:964; }","duration":"135.314806ms","start":"2026-05-14T13:19:28.956788Z","end":"2026-05-14T13:19:29.092103Z","steps":["trace[1613701121] 'agreement among raft nodes before linearized reading' (duration: 135.156802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:29.581629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.11504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:19:29.58174Z","caller":"traceutil/trace.go:171","msg":"trace[1942695246] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"399.020826ms","start":"2026-05-14T13:19:29.1827Z","end":"2026-05-14T13:19:29.581721Z","steps":["trace[1942695246] 'read index received' (duration: 126.63163ms)","trace[1942695246] 'applied index is now lower than readState.Index' (duration: 272.387666ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:19:29.581815Z","caller":"traceutil/trace.go:171","msg":"trace[166684460] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"486.181777ms","start":"2026-05-14T13:19:29.095599Z","end":"2026-05-14T13:19:29.581781Z","steps":["trace[166684460] 'process raft request' (duration: 213.815482ms)","trace[166684460] 'compare' (duration: 271.732532ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:29.581921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.228719ms","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-14T13:19:29.581948Z","caller":"traceutil/trace.go:171","msg":"trace[196079133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"399.302451ms","start":"2026-05-14T13:19:29.182637Z","end":"2026-05-14T13:19:29.581939Z","steps":["trace[196079133] 'agreement among raft nodes before linearized reading' (duration: 399.163888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:29.581981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:29.18261Z","time spent":"399.362762ms","remote":"127.0.0.1:35472","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-14T13:19:29.581987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:29.095579Z","time spent":"486.310779ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:19:29.582177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.417039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:19:29.582234Z","caller":"traceutil/trace.go:171","msg":"trace[1046565564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"331.525401ms","start":"2026-05-14T13:19:29.250692Z","end":"2026-05-14T13:19:29.582218Z","steps":["trace[1046565564] 'agreement among raft nodes before linearized reading' (duration: 331.43522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:29.582255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.481838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-14T13:19:29.582273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:29.250666Z","time spent":"331.597232ms","remote":"127.0.0.1:56742","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-14T13:19:29.582312Z","caller":"traceutil/trace.go:171","msg":"trace[1898948230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"271.555379ms","start":"2026-05-14T13:19:29.310737Z","end":"2026-05-14T13:19:29.582292Z","steps":["trace[1898948230] 'agreement among raft nodes before linearized reading' (duration: 271.404326ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:29.778413Z","caller":"traceutil/trace.go:171","msg":"trace[1666309798] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"191.545502ms","start":"2026-05-14T13:19:29.586846Z","end":"2026-05-14T13:19:29.778391Z","steps":["trace[1666309798] 'process raft request' (duration: 182.304355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:43.750488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954700968530077,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T13:19:43.861281Z","caller":"traceutil/trace.go:171","msg":"trace[599013615] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"899.160345ms","start":"2026-05-14T13:19:42.962098Z","end":"2026-05-14T13:19:43.861258Z","steps":["trace[599013615] 'process raft request' (duration: 899.050443ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:43.861429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:42.962077Z","time spent":"899.267607ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:19:44.745457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.265728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:19:44.745578Z","caller":"traceutil/trace.go:171","msg":"trace[318072002] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1052; }","duration":"1.49542379s","start":"2026-05-14T13:19:43.250139Z","end":"2026-05-14T13:19:44.745562Z","steps":["trace[318072002] 'read index received' (duration: 611.337782ms)","trace[318072002] 'applied index is now lower than readState.Index' (duration: 884.084008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:44.745892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101081817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:19:44.74595Z","caller":"traceutil/trace.go:171","msg":"trace[652797759] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"1.10119157s","start":"2026-05-14T13:19:43.644747Z","end":"2026-05-14T13:19:44.745938Z","steps":["trace[652797759] 'agreement among raft nodes before linearized reading' (duration: 1.101040977s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:44.745984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:43.644722Z","time spent":"1.101253531s","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:19:44.746073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.177737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:19:44.746146Z","caller":"traceutil/trace.go:171","msg":"trace[1280263201] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1014; }","duration":"211.29786ms","start":"2026-05-14T13:19:44.534828Z","end":"2026-05-14T13:19:44.746126Z","steps":["trace[1280263201] 'agreement among raft nodes before linearized reading' (duration: 211.135486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:44.746178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.559244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:19:44.746226Z","caller":"traceutil/trace.go:171","msg":"trace[1374997587] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1014; }","duration":"175.672617ms","start":"2026-05-14T13:19:44.570543Z","end":"2026-05-14T13:19:44.746215Z","steps":["trace[1374997587] 'agreement among raft nodes before linearized reading' (duration: 175.591606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:44.746291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.338243ms","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-14T13:19:44.746314Z","caller":"traceutil/trace.go:171","msg":"trace[1523807439] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.608854893s","start":"2026-05-14T13:19:43.137425Z","end":"2026-05-14T13:19:44.74628Z","steps":["trace[1523807439] 'process raft request' (duration: 1.088685745s)","trace[1523807439] 'compare' (duration: 518.910681ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:44.746195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.496042892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:19:44.74637Z","caller":"traceutil/trace.go:171","msg":"trace[1932856902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"911.457765ms","start":"2026-05-14T13:19:43.834896Z","end":"2026-05-14T13:19:44.746353Z","steps":["trace[1932856902] 'agreement among raft nodes before linearized reading' (duration: 911.20323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:44.746195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065417985s","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-14T13:19:44.746411Z","caller":"traceutil/trace.go:171","msg":"trace[1048082783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1014; }","duration":"1.065661279s","start":"2026-05-14T13:19:43.680742Z","end":"2026-05-14T13:19:44.746403Z","steps":["trace[1048082783] 'agreement among raft nodes before linearized reading' (duration: 1.065410754s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:44.746417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:43.834868Z","time spent":"911.536467ms","remote":"127.0.0.1:35472","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-14T13:19:44.746435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:43.680725Z","time spent":"1.065703941s","remote":"127.0.0.1:35472","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-14T13:19:44.746418Z","caller":"traceutil/trace.go:171","msg":"trace[554914227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"1.496306838s","start":"2026-05-14T13:19:43.25009Z","end":"2026-05-14T13:19:44.746397Z","steps":["trace[554914227] 'agreement among raft nodes before linearized reading' (duration: 1.496032702s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:44.746473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.636586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-14T13:19:44.746483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:43.250074Z","time spent":"1.496390039s","remote":"127.0.0.1:56742","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-14T13:19:44.746455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:43.137383Z","time spent":"1.608988095s","remote":"127.0.0.1:35472","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-14T13:19:44.74651Z","caller":"traceutil/trace.go:171","msg":"trace[2113187680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"646.701858ms","start":"2026-05-14T13:19:44.099795Z","end":"2026-05-14T13:19:44.746497Z","steps":["trace[2113187680] 'agreement among raft nodes before linearized reading' (duration: 646.589906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:44.747643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:44.099778Z","time spent":"647.825692ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T13:19:57.180844Z","caller":"traceutil/trace.go:171","msg":"trace[1713036132] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"302.573355ms","start":"2026-05-14T13:19:56.878237Z","end":"2026-05-14T13:19:57.180811Z","steps":["trace[1713036132] 'process raft request' (duration: 239.277929ms)","trace[1713036132] 'compare' (duration: 63.051001ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:19:57.181008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:56.878219Z","time spent":"302.694217ms","remote":"127.0.0.1:35472","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-14T13:19:57.206381Z","caller":"traceutil/trace.go:171","msg":"trace[2094422213] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"327.899789ms","start":"2026-05-14T13:19:56.87846Z","end":"2026-05-14T13:19:57.20636Z","steps":["trace[2094422213] 'process raft request' (duration: 327.751456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:57.206548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:56.878451Z","time spent":"328.020052ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:19:57.206643Z","caller":"traceutil/trace.go:171","msg":"trace[1692934077] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"326.963769ms","start":"2026-05-14T13:19:56.879664Z","end":"2026-05-14T13:19:57.206628Z","steps":["trace[1692934077] 'process raft request' (duration: 326.877168ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:19:57.206689Z","caller":"traceutil/trace.go:171","msg":"trace[857757303] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"328.083413ms","start":"2026-05-14T13:19:56.878562Z","end":"2026-05-14T13:19:57.206646Z","steps":["trace[857757303] 'process raft request' (duration: 327.761676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:19:57.206728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:56.879651Z","time spent":"327.036171ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:19:57.206856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:19:56.878536Z","time spent":"328.220517ms","remote":"127.0.0.1:35472","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-14T13:19:57.562086Z","caller":"traceutil/trace.go:171","msg":"trace[964250182] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"174.38689ms","start":"2026-05-14T13:19:57.387679Z","end":"2026-05-14T13:19:57.562066Z","steps":["trace[964250182] 'process raft request' (duration: 174.274707ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:07.52859Z","caller":"traceutil/trace.go:171","msg":"trace[1758223679] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"289.477449ms","start":"2026-05-14T13:20:07.239059Z","end":"2026-05-14T13:20:07.528537Z","steps":["trace[1758223679] 'read index received' (duration: 289.169532ms)","trace[1758223679] 'applied index is now lower than readState.Index' (duration: 306.517µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:07.528652Z","caller":"traceutil/trace.go:171","msg":"trace[157382533] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"534.067532ms","start":"2026-05-14T13:20:06.994571Z","end":"2026-05-14T13:20:07.528639Z","steps":["trace[157382533] 'process raft request' (duration: 533.759034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:07.52877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.707904ms","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-14T13:20:07.528798Z","caller":"traceutil/trace.go:171","msg":"trace[814936973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1087; }","duration":"289.786976ms","start":"2026-05-14T13:20:07.239006Z","end":"2026-05-14T13:20:07.528793Z","steps":["trace[814936973] 'agreement among raft nodes before linearized reading' (duration: 289.676724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:07.528975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.432858ms","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-14T13:20:07.528998Z","caller":"traceutil/trace.go:171","msg":"trace[1441497764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"271.4833ms","start":"2026-05-14T13:20:07.257509Z","end":"2026-05-14T13:20:07.528993Z","steps":["trace[1441497764] 'agreement among raft nodes before linearized reading' (duration: 271.426809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:07.529042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.002478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-14T13:20:07.529088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.437971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-14T13:20:07.529124Z","caller":"traceutil/trace.go:171","msg":"trace[1937044754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"270.11941ms","start":"2026-05-14T13:20:07.258986Z","end":"2026-05-14T13:20:07.529106Z","steps":["trace[1937044754] 'agreement among raft nodes before linearized reading' (duration: 269.891265ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:07.529152Z","caller":"traceutil/trace.go:171","msg":"trace[621302278] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"273.505602ms","start":"2026-05-14T13:20:07.255628Z","end":"2026-05-14T13:20:07.529133Z","steps":["trace[621302278] 'agreement among raft nodes before linearized reading' (duration: 273.363439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:07.529419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:06.994551Z","time spent":"534.744935ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:07.529534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.152001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:20:07.529587Z","caller":"traceutil/trace.go:171","msg":"trace[694362438] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"278.872065ms","start":"2026-05-14T13:20:07.250699Z","end":"2026-05-14T13:20:07.529571Z","steps":["trace[694362438] 'agreement among raft nodes before linearized reading' (duration: 278.15292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:08.32858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.637018ms","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-14T13:20:08.328659Z","caller":"traceutil/trace.go:171","msg":"trace[1031599658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1092; }","duration":"203.765071ms","start":"2026-05-14T13:20:08.124875Z","end":"2026-05-14T13:20:08.32864Z","steps":["trace[1031599658] 'range keys from in-memory index tree' (duration: 203.466074ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:11.16694Z","caller":"traceutil/trace.go:171","msg":"trace[1665950722] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"415.860826ms","start":"2026-05-14T13:20:10.751054Z","end":"2026-05-14T13:20:11.166915Z","steps":["trace[1665950722] 'process raft request' (duration: 415.662312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:11.167107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:10.751026Z","time spent":"416.009399ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:20:13.837013Z","caller":"traceutil/trace.go:171","msg":"trace[1820920976] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"146.467781ms","start":"2026-05-14T13:20:13.690504Z","end":"2026-05-14T13:20:13.836972Z","steps":["trace[1820920976] 'process raft request' (duration: 141.065307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:24.389347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.582725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:20:24.389458Z","caller":"traceutil/trace.go:171","msg":"trace[1740458646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1195; }","duration":"137.826679ms","start":"2026-05-14T13:20:24.251607Z","end":"2026-05-14T13:20:24.389434Z","steps":["trace[1740458646] 'range keys from in-memory index tree' (duration: 137.467071ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:26.113161Z","caller":"traceutil/trace.go:171","msg":"trace[1459911767] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"412.895263ms","start":"2026-05-14T13:20:25.700246Z","end":"2026-05-14T13:20:26.113141Z","steps":["trace[1459911767] 'read index received' (duration: 412.73885ms)","trace[1459911767] 'applied index is now lower than readState.Index' (duration: 155.283µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:26.113221Z","caller":"traceutil/trace.go:171","msg":"trace[1243653227] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"434.421427ms","start":"2026-05-14T13:20:25.678766Z","end":"2026-05-14T13:20:26.113188Z","steps":["trace[1243653227] 'process raft request' (duration: 434.229764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:26.113382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.090377ms","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-14T13:20:26.113419Z","caller":"traceutil/trace.go:171","msg":"trace[1597264515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1197; }","duration":"376.141998ms","start":"2026-05-14T13:20:25.737266Z","end":"2026-05-14T13:20:26.113408Z","steps":["trace[1597264515] 'agreement among raft nodes before linearized reading' (duration: 376.006945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:26.113397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:25.678749Z","time spent":"434.54673ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:26.113414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.142688ms","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-14T13:20:26.113456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:25.737233Z","time spent":"376.21391ms","remote":"127.0.0.1:35472","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-14T13:20:26.113547Z","caller":"traceutil/trace.go:171","msg":"trace[1825543813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"413.313172ms","start":"2026-05-14T13:20:25.700202Z","end":"2026-05-14T13:20:26.113515Z","steps":["trace[1825543813] 'agreement among raft nodes before linearized reading' (duration: 413.037646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:26.113568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.878853ms","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-14T13:20:26.113613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:25.700185Z","time spent":"413.413954ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-14T13:20:26.113647Z","caller":"traceutil/trace.go:171","msg":"trace[484753789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1197; }","duration":"124.012656ms","start":"2026-05-14T13:20:25.989614Z","end":"2026-05-14T13:20:26.113627Z","steps":["trace[484753789] 'agreement among raft nodes before linearized reading' (duration: 123.738371ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:26.455785Z","caller":"traceutil/trace.go:171","msg":"trace[956589074] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1246; }","duration":"237.371371ms","start":"2026-05-14T13:20:26.218391Z","end":"2026-05-14T13:20:26.455762Z","steps":["trace[956589074] 'read index received' (duration: 234.526041ms)","trace[956589074] 'applied index is now lower than readState.Index' (duration: 2.844439ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:26.455918Z","caller":"traceutil/trace.go:171","msg":"trace[1824593373] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"337.623995ms","start":"2026-05-14T13:20:26.118274Z","end":"2026-05-14T13:20:26.455898Z","steps":["trace[1824593373] 'process raft request' (duration: 337.458072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:26.456038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:26.118268Z","time spent":"337.705577ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:26.456107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.711097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-14T13:20:26.456143Z","caller":"traceutil/trace.go:171","msg":"trace[1025831805] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:1201; }","duration":"237.783628ms","start":"2026-05-14T13:20:26.218347Z","end":"2026-05-14T13:20:26.456131Z","steps":["trace[1025831805] 'agreement among raft nodes before linearized reading' (duration: 237.656055ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:26.456266Z","caller":"traceutil/trace.go:171","msg":"trace[112867345] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"338.045294ms","start":"2026-05-14T13:20:26.11821Z","end":"2026-05-14T13:20:26.456256Z","steps":["trace[112867345] 'process raft request' (duration: 337.487582ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:26.456279Z","caller":"traceutil/trace.go:171","msg":"trace[98995597] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"338.874511ms","start":"2026-05-14T13:20:26.117375Z","end":"2026-05-14T13:20:26.45625Z","steps":["trace[98995597] 'process raft request' (duration: 335.512691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:26.456301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.039889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:20:26.456326Z","caller":"traceutil/trace.go:171","msg":"trace[169274512] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"338.491944ms","start":"2026-05-14T13:20:26.11783Z","end":"2026-05-14T13:20:26.456322Z","steps":["trace[169274512] 'process raft request' (duration: 337.81341ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:26.456328Z","caller":"traceutil/trace.go:171","msg":"trace[722138520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"207.100881ms","start":"2026-05-14T13:20:26.249219Z","end":"2026-05-14T13:20:26.45632Z","steps":["trace[722138520] 'agreement among raft nodes before linearized reading' (duration: 207.05974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:26.456349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:26.117797Z","time spent":"338.539825ms","remote":"127.0.0.1:35472","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-14T13:20:26.456371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:26.117353Z","time spent":"338.957444ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:26.456306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:26.118197Z","time spent":"338.087354ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:20:30.652261Z","caller":"traceutil/trace.go:171","msg":"trace[854829179] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"146.709056ms","start":"2026-05-14T13:20:30.505532Z","end":"2026-05-14T13:20:30.652242Z","steps":["trace[854829179] 'process raft request' (duration: 146.579803ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:30.682146Z","caller":"traceutil/trace.go:171","msg":"trace[2110068014] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"175.471192ms","start":"2026-05-14T13:20:30.506659Z","end":"2026-05-14T13:20:30.68213Z","steps":["trace[2110068014] 'process raft request' (duration: 175.33369ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:30.682332Z","caller":"traceutil/trace.go:171","msg":"trace[15304914] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1262; }","duration":"148.251769ms","start":"2026-05-14T13:20:30.53405Z","end":"2026-05-14T13:20:30.682302Z","steps":["trace[15304914] 'read index received' (duration: 118.122224ms)","trace[15304914] 'applied index is now lower than readState.Index' (duration: 30.127215ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:30.682454Z","caller":"traceutil/trace.go:171","msg":"trace[491710983] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"175.716237ms","start":"2026-05-14T13:20:30.506698Z","end":"2026-05-14T13:20:30.682414Z","steps":["trace[491710983] 'process raft request' (duration: 175.394901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:30.682588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.531604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T13:20:30.68262Z","caller":"traceutil/trace.go:171","msg":"trace[77172545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"148.625297ms","start":"2026-05-14T13:20:30.533985Z","end":"2026-05-14T13:20:30.68261Z","steps":["trace[77172545] 'agreement among raft nodes before linearized reading' (duration: 148.453303ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:38.898121Z","caller":"traceutil/trace.go:171","msg":"trace[1239536524] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"140.914684ms","start":"2026-05-14T13:20:38.757183Z","end":"2026-05-14T13:20:38.898098Z","steps":["trace[1239536524] 'process raft request' (duration: 101.34949ms)","trace[1239536524] 'compare' (duration: 39.397561ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:38.898267Z","caller":"traceutil/trace.go:171","msg":"trace[991075128] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1291; }","duration":"131.013154ms","start":"2026-05-14T13:20:38.767223Z","end":"2026-05-14T13:20:38.898236Z","steps":["trace[991075128] 'read index received' (duration: 39.544974ms)","trace[991075128] 'applied index is now lower than readState.Index' (duration: 91.46571ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:38.898304Z","caller":"traceutil/trace.go:171","msg":"trace[1195709404] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"139.294689ms","start":"2026-05-14T13:20:38.758976Z","end":"2026-05-14T13:20:38.89827Z","steps":["trace[1195709404] 'process raft request' (duration: 139.074564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:38.898449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.208948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T13:20:38.898487Z","caller":"traceutil/trace.go:171","msg":"trace[1036081967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1242; }","duration":"131.29793ms","start":"2026-05-14T13:20:38.767178Z","end":"2026-05-14T13:20:38.898476Z","steps":["trace[1036081967] 'agreement among raft nodes before linearized reading' (duration: 131.164827ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:42.033838Z","caller":"traceutil/trace.go:171","msg":"trace[970348934] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"282.685296ms","start":"2026-05-14T13:20:41.751128Z","end":"2026-05-14T13:20:42.033813Z","steps":["trace[970348934] 'process raft request' (duration: 282.511392ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:42.086585Z","caller":"traceutil/trace.go:171","msg":"trace[773310636] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"331.746541ms","start":"2026-05-14T13:20:41.754773Z","end":"2026-05-14T13:20:42.08652Z","steps":["trace[773310636] 'process raft request' (duration: 331.502996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:42.086775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:41.754737Z","time spent":"331.934204ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:20:42.087114Z","caller":"traceutil/trace.go:171","msg":"trace[909105975] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1318; }","duration":"332.044237ms","start":"2026-05-14T13:20:41.755053Z","end":"2026-05-14T13:20:42.087098Z","steps":["trace[909105975] 'read index received' (duration: 278.512118ms)","trace[909105975] 'applied index is now lower than readState.Index' (duration: 53.527029ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:20:42.087179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.131019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:20:42.087214Z","caller":"traceutil/trace.go:171","msg":"trace[953238920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"332.19021ms","start":"2026-05-14T13:20:41.755012Z","end":"2026-05-14T13:20:42.087202Z","steps":["trace[953238920] 'agreement among raft nodes before linearized reading' (duration: 332.137129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:42.087235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:41.755003Z","time spent":"332.228401ms","remote":"127.0.0.1:56742","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-14T13:20:42.239143Z","caller":"traceutil/trace.go:171","msg":"trace[1504303267] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"192.369659ms","start":"2026-05-14T13:20:42.046749Z","end":"2026-05-14T13:20:42.239119Z","steps":["trace[1504303267] 'process raft request' (duration: 192.321848ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:42.239185Z","caller":"traceutil/trace.go:171","msg":"trace[149731618] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"196.199811ms","start":"2026-05-14T13:20:42.042951Z","end":"2026-05-14T13:20:42.23915Z","steps":["trace[149731618] 'process raft request' (duration: 196.070738ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:42.239149Z","caller":"traceutil/trace.go:171","msg":"trace[296720994] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"198.397716ms","start":"2026-05-14T13:20:42.040716Z","end":"2026-05-14T13:20:42.239114Z","steps":["trace[296720994] 'process raft request' (duration: 198.166501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.245087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.985639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:20:43.245172Z","caller":"traceutil/trace.go:171","msg":"trace[1188962563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1271; }","duration":"679.123912ms","start":"2026-05-14T13:20:42.56603Z","end":"2026-05-14T13:20:43.245153Z","steps":["trace[1188962563] 'range keys from in-memory index tree' (duration: 678.565911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.245215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:42.56601Z","time spent":"679.196183ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:20:43.245403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.683129ms","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-14T13:20:43.245529Z","caller":"traceutil/trace.go:171","msg":"trace[1215275242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1271; }","duration":"330.837012ms","start":"2026-05-14T13:20:42.914646Z","end":"2026-05-14T13:20:43.245483Z","steps":["trace[1215275242] 'range keys from in-memory index tree' (duration: 330.455954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.245596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:42.914625Z","time spent":"330.956614ms","remote":"127.0.0.1:35472","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-14T13:20:43.245801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.920417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T13:20:43.245855Z","caller":"traceutil/trace.go:171","msg":"trace[448204986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"316.036219ms","start":"2026-05-14T13:20:42.929804Z","end":"2026-05-14T13:20:43.24584Z","steps":["trace[448204986] 'range keys from in-memory index tree' (duration: 315.779713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.245893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:42.929785Z","time spent":"316.10011ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T13:20:43.245905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.588343ms","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-14T13:20:43.245982Z","caller":"traceutil/trace.go:171","msg":"trace[2055335428] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1271; }","duration":"427.668345ms","start":"2026-05-14T13:20:42.818304Z","end":"2026-05-14T13:20:43.245972Z","steps":["trace[2055335428] 'range keys from in-memory index tree' (duration: 427.577583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.246377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.033456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:20:43.246429Z","caller":"traceutil/trace.go:171","msg":"trace[406424048] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1271; }","duration":"366.120657ms","start":"2026-05-14T13:20:42.880298Z","end":"2026-05-14T13:20:43.246418Z","steps":["trace[406424048] 'count revisions from in-memory index tree' (duration: 365.981315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.246466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:42.880282Z","time spent":"366.175698ms","remote":"127.0.0.1:35582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:20:43.246389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.961712ms","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-14T13:20:43.246548Z","caller":"traceutil/trace.go:171","msg":"trace[2132068844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"428.153695ms","start":"2026-05-14T13:20:42.818376Z","end":"2026-05-14T13:20:43.24653Z","steps":["trace[2132068844] 'range keys from in-memory index tree' (duration: 427.810369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.246612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:42.818355Z","time spent":"428.239777ms","remote":"127.0.0.1:35472","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-14T13:20:43.246493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.158154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T13:20:43.246732Z","caller":"traceutil/trace.go:171","msg":"trace[967945026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"336.417719ms","start":"2026-05-14T13:20:42.910305Z","end":"2026-05-14T13:20:43.246723Z","steps":["trace[967945026] 'range keys from in-memory index tree' (duration: 336.067632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.246773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:42.910294Z","time spent":"336.46847ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T13:20:43.59358Z","caller":"traceutil/trace.go:171","msg":"trace[717565201] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"339.277611ms","start":"2026-05-14T13:20:43.254276Z","end":"2026-05-14T13:20:43.593554Z","steps":["trace[717565201] 'process raft request' (duration: 339.115798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.593721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:43.254253Z","time spent":"339.395973ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:20:43.83134Z","caller":"traceutil/trace.go:171","msg":"trace[1403474426] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"575.031346ms","start":"2026-05-14T13:20:43.256285Z","end":"2026-05-14T13:20:43.831316Z","steps":["trace[1403474426] 'process raft request' (duration: 574.991234ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:43.831366Z","caller":"traceutil/trace.go:171","msg":"trace[1293149973] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"576.841413ms","start":"2026-05-14T13:20:43.254495Z","end":"2026-05-14T13:20:43.831336Z","steps":["trace[1293149973] 'process raft request' (duration: 576.575607ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:43.831441Z","caller":"traceutil/trace.go:171","msg":"trace[1652627422] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"576.803614ms","start":"2026-05-14T13:20:43.254589Z","end":"2026-05-14T13:20:43.831392Z","steps":["trace[1652627422] 'process raft request' (duration: 576.620329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.831489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:43.256272Z","time spent":"575.140838ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:43.831507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:43.254479Z","time spent":"576.961937ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:43.831598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:43.254578Z","time spent":"576.934706ms","remote":"127.0.0.1:35472","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-14T13:20:43.831619Z","caller":"traceutil/trace.go:171","msg":"trace[1413129307] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"575.888525ms","start":"2026-05-14T13:20:43.255719Z","end":"2026-05-14T13:20:43.831608Z","steps":["trace[1413129307] 'process raft request' (duration: 575.525587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:43.831665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:43.25566Z","time spent":"575.979367ms","remote":"127.0.0.1:35472","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-14T13:20:45.620759Z","caller":"traceutil/trace.go:171","msg":"trace[1979923153] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"360.790394ms","start":"2026-05-14T13:20:45.259944Z","end":"2026-05-14T13:20:45.620734Z","steps":["trace[1979923153] 'process raft request' (duration: 359.757642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:45.62091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:45.25988Z","time spent":"360.951437ms","remote":"127.0.0.1:56810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-14T13:20:46.133057Z","caller":"traceutil/trace.go:171","msg":"trace[306062345] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"345.305826ms","start":"2026-05-14T13:20:45.787725Z","end":"2026-05-14T13:20:46.133031Z","steps":["trace[306062345] 'read index received' (duration: 304.007735ms)","trace[306062345] 'applied index is now lower than readState.Index' (duration: 41.296681ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:46.133201Z","caller":"traceutil/trace.go:171","msg":"trace[817624155] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"507.879938ms","start":"2026-05-14T13:20:45.625283Z","end":"2026-05-14T13:20:46.133163Z","steps":["trace[817624155] 'process raft request' (duration: 466.512024ms)","trace[817624155] 'compare' (duration: 40.77296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:20:46.133285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.540672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:20:46.133321Z","caller":"traceutil/trace.go:171","msg":"trace[1557601128] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1279; }","duration":"345.621564ms","start":"2026-05-14T13:20:45.787687Z","end":"2026-05-14T13:20:46.133309Z","steps":["trace[1557601128] 'agreement among raft nodes before linearized reading' (duration: 345.479641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.133357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:45.787671Z","time spent":"345.675895ms","remote":"127.0.0.1:35464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:20:46.133459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:45.625269Z","time spent":"507.99799ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:46.134368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.558678ms","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-14T13:20:46.134453Z","caller":"traceutil/trace.go:171","msg":"trace[199649005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1279; }","duration":"296.659081ms","start":"2026-05-14T13:20:45.837772Z","end":"2026-05-14T13:20:46.134431Z","steps":["trace[199649005] 'agreement among raft nodes before linearized reading' (duration: 296.494547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.134368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.516538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:20:46.134696Z","caller":"traceutil/trace.go:171","msg":"trace[1493608006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1279; }","duration":"296.868746ms","start":"2026-05-14T13:20:45.837811Z","end":"2026-05-14T13:20:46.134679Z","steps":["trace[1493608006] 'agreement among raft nodes before linearized reading' (duration: 296.407385ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.135275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.247345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T13:20:46.135337Z","caller":"traceutil/trace.go:171","msg":"trace[1822822420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1279; }","duration":"298.360397ms","start":"2026-05-14T13:20:45.836962Z","end":"2026-05-14T13:20:46.135322Z","steps":["trace[1822822420] 'agreement among raft nodes before linearized reading' (duration: 298.216644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.134509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.830743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T13:20:46.135642Z","caller":"traceutil/trace.go:171","msg":"trace[995308140] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"296.900327ms","start":"2026-05-14T13:20:45.838651Z","end":"2026-05-14T13:20:46.135552Z","steps":["trace[995308140] 'agreement among raft nodes before linearized reading' (duration: 295.765402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.136205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.259743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:20:46.136402Z","caller":"traceutil/trace.go:171","msg":"trace[526553866] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1279; }","duration":"322.312184ms","start":"2026-05-14T13:20:45.813924Z","end":"2026-05-14T13:20:46.136236Z","steps":["trace[526553866] 'agreement among raft nodes before linearized reading' (duration: 320.236348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.136791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:45.813878Z","time spent":"322.889836ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:20:46.769183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.020981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:20:46.769263Z","caller":"traceutil/trace.go:171","msg":"trace[219615935] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"631.401006ms","start":"2026-05-14T13:20:46.137847Z","end":"2026-05-14T13:20:46.769248Z","steps":["trace[219615935] 'process raft request' (duration: 456.259772ms)","trace[219615935] 'compare' (duration: 174.94705ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:20:46.7693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:46.137824Z","time spent":"631.459018ms","remote":"127.0.0.1:35472","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-14T13:20:46.79715Z","caller":"traceutil/trace.go:171","msg":"trace[138893769] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1332; }","duration":"577.51665ms","start":"2026-05-14T13:20:46.219617Z","end":"2026-05-14T13:20:46.797134Z","steps":["trace[138893769] 'read index received' (duration: 374.61135ms)","trace[138893769] 'applied index is now lower than readState.Index' (duration: 202.9048ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:46.797203Z","caller":"traceutil/trace.go:171","msg":"trace[2040796280] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"656.642107ms","start":"2026-05-14T13:20:46.140545Z","end":"2026-05-14T13:20:46.797187Z","steps":["trace[2040796280] 'process raft request' (duration: 656.547725ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:46.797251Z","caller":"traceutil/trace.go:171","msg":"trace[1117052346] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"656.713339ms","start":"2026-05-14T13:20:46.140511Z","end":"2026-05-14T13:20:46.797224Z","steps":["trace[1117052346] 'process raft request' (duration: 656.557395ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:46.797295Z","caller":"traceutil/trace.go:171","msg":"trace[966508292] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"656.711299ms","start":"2026-05-14T13:20:46.140443Z","end":"2026-05-14T13:20:46.797154Z","steps":["trace[966508292] 'process raft request' (duration: 656.528255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.797356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.726424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-14T13:20:46.797373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:46.140505Z","time spent":"656.80458ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:46.797398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:46.140434Z","time spent":"656.906163ms","remote":"127.0.0.1:35472","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-14T13:20:46.797431Z","caller":"traceutil/trace.go:171","msg":"trace[213845612] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:1284; }","duration":"577.826766ms","start":"2026-05-14T13:20:46.219574Z","end":"2026-05-14T13:20:46.797401Z","steps":["trace[213845612] 'agreement among raft nodes before linearized reading' (duration: 577.660703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.797425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:46.140541Z","time spent":"656.753089ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:46.797476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:46.219557Z","time spent":"577.910679ms","remote":"127.0.0.1:56770","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.23\" "} {"level":"info","ts":"2026-05-14T13:20:46.797576Z","caller":"traceutil/trace.go:171","msg":"trace[1956291604] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"471.028594ms","start":"2026-05-14T13:20:46.326536Z","end":"2026-05-14T13:20:46.797565Z","steps":["trace[1956291604] 'process raft request' (duration: 470.573664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:46.79763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:46.326512Z","time spent":"471.084386ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:47.261327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.795612ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-05-14T13:20:47.261503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:46.798663Z","time spent":"462.815735ms","remote":"127.0.0.1:56770","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-14T13:20:47.707162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.836493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:20:47.707281Z","caller":"traceutil/trace.go:171","msg":"trace[41531319] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"444.112787ms","start":"2026-05-14T13:20:47.263151Z","end":"2026-05-14T13:20:47.707264Z","steps":["trace[41531319] 'read index received' (duration: 292.913726ms)","trace[41531319] 'applied index is now lower than readState.Index' (duration: 151.197831ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:20:47.70739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.24451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:20:47.707423Z","caller":"traceutil/trace.go:171","msg":"trace[1071590891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"444.327361ms","start":"2026-05-14T13:20:47.263087Z","end":"2026-05-14T13:20:47.707414Z","steps":["trace[1071590891] 'agreement among raft nodes before linearized reading' (duration: 444.24767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:47.707454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:47.263076Z","time spent":"444.371132ms","remote":"127.0.0.1:56744","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-14T13:20:47.707624Z","caller":"traceutil/trace.go:171","msg":"trace[816375684] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"444.735471ms","start":"2026-05-14T13:20:47.26285Z","end":"2026-05-14T13:20:47.707585Z","steps":["trace[816375684] 'process raft request' (duration: 293.315705ms)","trace[816375684] 'compare' (duration: 150.632738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:20:47.707782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:47.262832Z","time spent":"444.867884ms","remote":"127.0.0.1:56770","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-14T13:20:49.0857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.53088ms","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-14T13:20:49.085753Z","caller":"traceutil/trace.go:171","msg":"trace[1088569683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"948.630023ms","start":"2026-05-14T13:20:48.137109Z","end":"2026-05-14T13:20:49.085739Z","steps":["trace[1088569683] 'range keys from in-memory index tree' (duration: 948.383497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:49.085776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.137088Z","time spent":"948.682973ms","remote":"127.0.0.1:35472","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-14T13:20:49.085831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.388317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-14T13:20:49.085856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.989171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:20:49.085896Z","caller":"traceutil/trace.go:171","msg":"trace[1914331340] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1285; }","duration":"801.49276ms","start":"2026-05-14T13:20:48.284387Z","end":"2026-05-14T13:20:49.08588Z","steps":["trace[1914331340] 'count revisions from in-memory index tree' (duration: 801.264185ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:49.085904Z","caller":"traceutil/trace.go:171","msg":"trace[890619780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1285; }","duration":"835.069443ms","start":"2026-05-14T13:20:48.250821Z","end":"2026-05-14T13:20:49.08589Z","steps":["trace[890619780] 'range keys from in-memory index tree' (duration: 834.905249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:49.085935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.250804Z","time spent":"835.123804ms","remote":"127.0.0.1:56742","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-14T13:20:49.085934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.284374Z","time spent":"801.55096ms","remote":"127.0.0.1:35588","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-14T13:20:49.274541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954700968530732,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-14T13:20:49.430585Z","caller":"traceutil/trace.go:171","msg":"trace[110390632] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"699.001113ms","start":"2026-05-14T13:20:48.731562Z","end":"2026-05-14T13:20:49.430563Z","steps":["trace[110390632] 'process raft request' (duration: 698.855629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:49.430712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.731541Z","time spent":"699.104995ms","remote":"127.0.0.1:35472","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-14T13:20:49.446447Z","caller":"traceutil/trace.go:171","msg":"trace[906763574] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"356.823172ms","start":"2026-05-14T13:20:49.089606Z","end":"2026-05-14T13:20:49.446429Z","steps":["trace[906763574] 'process raft request' (duration: 356.687719ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:49.446435Z","caller":"traceutil/trace.go:171","msg":"trace[1255581596] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"642.660682ms","start":"2026-05-14T13:20:48.803747Z","end":"2026-05-14T13:20:49.446408Z","steps":["trace[1255581596] 'process raft request' (duration: 642.411107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:49.446808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.497893ms","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-14T13:20:49.446846Z","caller":"traceutil/trace.go:171","msg":"trace[1920318438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"672.580375ms","start":"2026-05-14T13:20:48.774257Z","end":"2026-05-14T13:20:49.446837Z","steps":["trace[1920318438] 'agreement among raft nodes before linearized reading' (duration: 672.454603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:49.446635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:49.08959Z","time spent":"356.925803ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:49.44687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.774232Z","time spent":"672.630776ms","remote":"127.0.0.1:35472","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-14T13:20:49.446378Z","caller":"traceutil/trace.go:171","msg":"trace[896425205] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1339; }","duration":"672.036264ms","start":"2026-05-14T13:20:48.774317Z","end":"2026-05-14T13:20:49.446353Z","steps":["trace[896425205] 'read index received' (duration: 656.094885ms)","trace[896425205] 'applied index is now lower than readState.Index' (duration: 15.940159ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:20:49.447116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.086024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T13:20:49.447145Z","caller":"traceutil/trace.go:171","msg":"trace[1256428163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"645.151006ms","start":"2026-05-14T13:20:48.801986Z","end":"2026-05-14T13:20:49.447137Z","steps":["trace[1256428163] 'agreement among raft nodes before linearized reading' (duration: 645.085944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:49.447163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.801966Z","time spent":"645.192767ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-14T13:20:49.447244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.803737Z","time spent":"643.05121ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:49.447299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.788936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-14T13:20:49.447369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.009592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-14T13:20:49.447366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.328297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:20:49.447402Z","caller":"traceutil/trace.go:171","msg":"trace[2096363466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"359.941ms","start":"2026-05-14T13:20:49.087442Z","end":"2026-05-14T13:20:49.447383Z","steps":["trace[2096363466] 'agreement among raft nodes before linearized reading' (duration: 359.743195ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:49.447429Z","caller":"traceutil/trace.go:171","msg":"trace[744939219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"644.079473ms","start":"2026-05-14T13:20:48.803333Z","end":"2026-05-14T13:20:49.447412Z","steps":["trace[744939219] 'agreement among raft nodes before linearized reading' (duration: 643.897078ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:49.44744Z","caller":"traceutil/trace.go:171","msg":"trace[1673310214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"643.42376ms","start":"2026-05-14T13:20:48.803998Z","end":"2026-05-14T13:20:49.447422Z","steps":["trace[1673310214] 'agreement among raft nodes before linearized reading' (duration: 643.223864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:49.447461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:49.087426Z","time spent":"360.015781ms","remote":"127.0.0.1:56744","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-14T13:20:49.447486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.803992Z","time spent":"643.485491ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:20:49.447487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:48.803326Z","time spent":"644.147615ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T13:20:57.980644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954700968530864,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T13:20:58.481785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10125954700968530864,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-14T13:20:58.725702Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.356999259s","expected-duration":"1s"} {"level":"info","ts":"2026-05-14T13:20:58.726197Z","caller":"traceutil/trace.go:171","msg":"trace[27897436] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.357607242s","start":"2026-05-14T13:20:57.368563Z","end":"2026-05-14T13:20:58.726171Z","steps":["trace[27897436] 'process raft request' (duration: 1.357432459s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:58.726384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:57.368533Z","time spent":"1.357744396s","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:20:58.946176Z","caller":"traceutil/trace.go:171","msg":"trace[565241280] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1410; }","duration":"1.465652841s","start":"2026-05-14T13:20:57.480491Z","end":"2026-05-14T13:20:58.946144Z","steps":["trace[565241280] 'read index received' (duration: 1.245505905s)","trace[565241280] 'applied index is now lower than readState.Index' (duration: 220.145626ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:20:58.946267Z","caller":"traceutil/trace.go:171","msg":"trace[169071406] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.471783005s","start":"2026-05-14T13:20:57.474443Z","end":"2026-05-14T13:20:58.946226Z","steps":["trace[169071406] 'process raft request' (duration: 1.471511789s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:58.946434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:57.474416Z","time spent":"1.471922478s","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-14T13:20:58.946467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463455982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-14T13:20:58.946503Z","caller":"traceutil/trace.go:171","msg":"trace[177710766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"1.463574386s","start":"2026-05-14T13:20:57.48292Z","end":"2026-05-14T13:20:58.946495Z","steps":["trace[177710766] 'agreement among raft nodes before linearized reading' (duration: 1.463394492s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:58.946538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:57.482891Z","time spent":"1.463638397s","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-14T13:20:58.946536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.666028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-05-14T13:20:58.946536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.450085312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T13:20:58.946612Z","caller":"traceutil/trace.go:171","msg":"trace[186543228] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1356; }","duration":"242.778641ms","start":"2026-05-14T13:20:58.703811Z","end":"2026-05-14T13:20:58.946589Z","steps":["trace[186543228] 'agreement among raft nodes before linearized reading' (duration: 242.605747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:58.946656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456042942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-14T13:20:58.946737Z","caller":"traceutil/trace.go:171","msg":"trace[478668876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1356; }","duration":"1.456158814s","start":"2026-05-14T13:20:57.490561Z","end":"2026-05-14T13:20:58.94672Z","steps":["trace[478668876] 'agreement among raft nodes before linearized reading' (duration: 1.455877798s)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:20:58.946671Z","caller":"traceutil/trace.go:171","msg":"trace[123492561] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1356; }","duration":"1.450254635s","start":"2026-05-14T13:20:57.496398Z","end":"2026-05-14T13:20:58.946653Z","steps":["trace[123492561] 'agreement among raft nodes before linearized reading' (duration: 1.449967999s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:58.946564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137194759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-05-14T13:20:58.946825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:57.496388Z","time spent":"1.450422688s","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-14T13:20:58.946853Z","caller":"traceutil/trace.go:171","msg":"trace[8670484] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1356; }","duration":"1.137543697s","start":"2026-05-14T13:20:57.8093Z","end":"2026-05-14T13:20:58.946843Z","steps":["trace[8670484] 'agreement among raft nodes before linearized reading' (duration: 1.137053577s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:58.946868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:57.490548Z","time spent":"1.456305627s","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-14T13:20:58.946896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:57.80928Z","time spent":"1.137604358s","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-14T13:20:58.946943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448359363s","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-14T13:20:58.947009Z","caller":"traceutil/trace.go:171","msg":"trace[1902900501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"1.448466225s","start":"2026-05-14T13:20:57.498533Z","end":"2026-05-14T13:20:58.946999Z","steps":["trace[1902900501] 'agreement among raft nodes before linearized reading' (duration: 1.447831632s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:58.947039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:57.498517Z","time spent":"1.448513387s","remote":"127.0.0.1:35472","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-14T13:20:58.947107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466642462s","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-14T13:20:58.947144Z","caller":"traceutil/trace.go:171","msg":"trace[328225933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"1.466737874s","start":"2026-05-14T13:20:57.480396Z","end":"2026-05-14T13:20:58.947133Z","steps":["trace[328225933] 'agreement among raft nodes before linearized reading' (duration: 1.465922327s)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:20:58.947174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:20:57.480363Z","time spent":"1.466804387s","remote":"127.0.0.1:35472","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-14T13:21:02.113575Z","caller":"traceutil/trace.go:171","msg":"trace[948784325] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"408.159193ms","start":"2026-05-14T13:21:01.705362Z","end":"2026-05-14T13:21:02.113521Z","steps":["trace[948784325] 'process raft request' (duration: 407.777315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:21:02.113821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:21:01.705336Z","time spent":"408.351587ms","remote":"127.0.0.1:56888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6757,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-14T13:21:05.108832Z","caller":"traceutil/trace.go:171","msg":"trace[31392629] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"110.906191ms","start":"2026-05-14T13:21:04.997885Z","end":"2026-05-14T13:21:05.108792Z","steps":["trace[31392629] 'process raft request' (duration: 97.050098ms)","trace[31392629] 'compare' (duration: 13.492025ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:21:05.108872Z","caller":"traceutil/trace.go:171","msg":"trace[991614075] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"110.950882ms","start":"2026-05-14T13:21:04.997901Z","end":"2026-05-14T13:21:05.108852Z","steps":["trace[991614075] 'process raft request' (duration: 110.769129ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:21:05.109017Z","caller":"traceutil/trace.go:171","msg":"trace[1006607818] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"111.029194ms","start":"2026-05-14T13:21:04.997954Z","end":"2026-05-14T13:21:05.108984Z","steps":["trace[1006607818] 'process raft request' (duration: 110.972043ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:21:05.109042Z","caller":"traceutil/trace.go:171","msg":"trace[1375191641] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"111.078645ms","start":"2026-05-14T13:21:04.997926Z","end":"2026-05-14T13:21:05.109005Z","steps":["trace[1375191641] 'process raft request' (duration: 110.8553ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:21:05.109166Z","caller":"traceutil/trace.go:171","msg":"trace[1948544769] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"111.233519ms","start":"2026-05-14T13:21:04.997919Z","end":"2026-05-14T13:21:05.109152Z","steps":["trace[1948544769] 'process raft request' (duration: 110.963783ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:21:21.406482Z","caller":"traceutil/trace.go:171","msg":"trace[1269042136] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"122.648637ms","start":"2026-05-14T13:21:21.283798Z","end":"2026-05-14T13:21:21.406447Z","steps":["trace[1269042136] 'process raft request' (duration: 122.419972ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:21:21.446323Z","caller":"traceutil/trace.go:171","msg":"trace[2014479251] linearizableReadLoop","detail":"{readStateIndex:1523; appliedIndex:1521; }","duration":"140.704513ms","start":"2026-05-14T13:21:21.305579Z","end":"2026-05-14T13:21:21.446284Z","steps":["trace[2014479251] 'read index received' (duration: 72.823391ms)","trace[2014479251] 'applied index is now lower than readState.Index' (duration: 67.880062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:21:21.446567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.960088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-14T13:21:21.446608Z","caller":"traceutil/trace.go:171","msg":"trace[331563885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1462; }","duration":"141.079291ms","start":"2026-05-14T13:21:21.305518Z","end":"2026-05-14T13:21:21.446597Z","steps":["trace[331563885] 'agreement among raft nodes before linearized reading' (duration: 140.910387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:21:21.446793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.070417ms","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-14T13:21:21.446886Z","caller":"traceutil/trace.go:171","msg":"trace[850404611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1462; }","duration":"118.19075ms","start":"2026-05-14T13:21:21.328671Z","end":"2026-05-14T13:21:21.446861Z","steps":["trace[850404611] 'agreement among raft nodes before linearized reading' (duration: 117.944085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:21:21.446798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.342507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-14T13:21:21.447004Z","caller":"traceutil/trace.go:171","msg":"trace[326139585] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1462; }","duration":"114.571192ms","start":"2026-05-14T13:21:21.332408Z","end":"2026-05-14T13:21:21.44698Z","steps":["trace[326139585] 'agreement among raft nodes before linearized reading' (duration: 114.247445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:21:21.446989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.096465ms","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-14T13:21:21.447071Z","caller":"traceutil/trace.go:171","msg":"trace[772582165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1462; }","duration":"116.225927ms","start":"2026-05-14T13:21:21.330824Z","end":"2026-05-14T13:21:21.44705Z","steps":["trace[772582165] 'agreement among raft nodes before linearized reading' (duration: 115.927911ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:21:25.295204Z","caller":"traceutil/trace.go:171","msg":"trace[866681755] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"106.22358ms","start":"2026-05-14T13:21:25.188951Z","end":"2026-05-14T13:21:25.295174Z","steps":["trace[866681755] 'process raft request' (duration: 106.066087ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:21:56.028452Z","caller":"traceutil/trace.go:171","msg":"trace[2059175656] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"100.178094ms","start":"2026-05-14T13:21:55.928245Z","end":"2026-05-14T13:21:56.028423Z","steps":["trace[2059175656] 'process raft request' (duration: 48.854986ms)","trace[2059175656] 'compare' (duration: 50.94694ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:21:57.811564Z","caller":"traceutil/trace.go:171","msg":"trace[380233180] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"137.471249ms","start":"2026-05-14T13:21:57.674051Z","end":"2026-05-14T13:21:57.811523Z","steps":["trace[380233180] 'process raft request' (duration: 137.308815ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:06.021116Z","caller":"traceutil/trace.go:171","msg":"trace[343299736] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"144.752791ms","start":"2026-05-14T13:22:05.876327Z","end":"2026-05-14T13:22:06.021079Z","steps":["trace[343299736] 'process raft request' (duration: 144.585577ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:06.021666Z","caller":"traceutil/trace.go:171","msg":"trace[1730254940] transaction","detail":"{read_only:false; response_revision:1666; number_of_response:1; }","duration":"144.481684ms","start":"2026-05-14T13:22:05.877146Z","end":"2026-05-14T13:22:06.021627Z","steps":["trace[1730254940] 'process raft request' (duration: 143.885222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:06.342164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.842205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-14T13:22:06.342272Z","caller":"traceutil/trace.go:171","msg":"trace[509832823] linearizableReadLoop","detail":"{readStateIndex:1738; appliedIndex:1737; }","duration":"117.174106ms","start":"2026-05-14T13:22:06.225082Z","end":"2026-05-14T13:22:06.342256Z","steps":["trace[509832823] 'read index received' (duration: 42.86µs)","trace[509832823] 'applied index is now lower than readState.Index' (duration: 117.129836ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:06.342365Z","caller":"traceutil/trace.go:171","msg":"trace[1365240149] transaction","detail":"{read_only:false; response_revision:1669; number_of_response:1; }","duration":"182.095466ms","start":"2026-05-14T13:22:06.160255Z","end":"2026-05-14T13:22:06.34235Z","steps":["trace[1365240149] 'process raft request' (duration: 43.967025ms)","trace[1365240149] 'compare' (duration: 137.517439ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:22:06.342818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.707709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.23\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-14T13:22:06.342998Z","caller":"traceutil/trace.go:171","msg":"trace[602658126] range","detail":"{range_begin:/registry/masterleases/199.204.45.23; range_end:; response_count:1; response_revision:1669; }","duration":"117.937794ms","start":"2026-05-14T13:22:06.225027Z","end":"2026-05-14T13:22:06.342965Z","steps":["trace[602658126] 'agreement among raft nodes before linearized reading' (duration: 117.578005ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:07.69691Z","caller":"traceutil/trace.go:171","msg":"trace[1333718546] transaction","detail":"{read_only:false; response_revision:1671; number_of_response:1; }","duration":"150.644753ms","start":"2026-05-14T13:22:07.546234Z","end":"2026-05-14T13:22:07.696878Z","steps":["trace[1333718546] 'process raft request' (duration: 150.448728ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:14.408438Z","caller":"traceutil/trace.go:171","msg":"trace[1559046408] linearizableReadLoop","detail":"{readStateIndex:1776; appliedIndex:1775; }","duration":"140.204555ms","start":"2026-05-14T13:22:14.268215Z","end":"2026-05-14T13:22:14.408419Z","steps":["trace[1559046408] 'read index received' (duration: 140.057802ms)","trace[1559046408] 'applied index is now lower than readState.Index' (duration: 145.943µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:14.408561Z","caller":"traceutil/trace.go:171","msg":"trace[1311535744] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"152.992721ms","start":"2026-05-14T13:22:14.255555Z","end":"2026-05-14T13:22:14.408548Z","steps":["trace[1311535744] 'process raft request' (duration: 152.772466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:14.408825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.587243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-14T13:22:14.408902Z","caller":"traceutil/trace.go:171","msg":"trace[1556252170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1705; }","duration":"140.707515ms","start":"2026-05-14T13:22:14.268177Z","end":"2026-05-14T13:22:14.408885Z","steps":["trace[1556252170] 'agreement among raft nodes before linearized reading' (duration: 140.594443ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:28.310783Z","caller":"traceutil/trace.go:171","msg":"trace[671226577] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"129.973332ms","start":"2026-05-14T13:22:28.180785Z","end":"2026-05-14T13:22:28.310759Z","steps":["trace[671226577] 'process raft request' (duration: 129.831859ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:28.32856Z","caller":"traceutil/trace.go:171","msg":"trace[1545565670] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"145.110927ms","start":"2026-05-14T13:22:28.183401Z","end":"2026-05-14T13:22:28.328512Z","steps":["trace[1545565670] 'process raft request' (duration: 144.75967ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:50.732959Z","caller":"traceutil/trace.go:171","msg":"trace[777411266] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"108.282402ms","start":"2026-05-14T13:22:50.624637Z","end":"2026-05-14T13:22:50.732919Z","steps":["trace[777411266] 'process raft request' (duration: 68.78243ms)","trace[777411266] 'compare' (duration: 39.267078ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:50.733116Z","caller":"traceutil/trace.go:171","msg":"trace[976054980] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"106.880253ms","start":"2026-05-14T13:22:50.62617Z","end":"2026-05-14T13:22:50.73305Z","steps":["trace[976054980] 'process raft request' (duration: 106.643608ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:54.513229Z","caller":"traceutil/trace.go:171","msg":"trace[861960624] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"133.395825ms","start":"2026-05-14T13:22:54.379808Z","end":"2026-05-14T13:22:54.513204Z","steps":["trace[861960624] 'process raft request' (duration: 133.246402ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:22:54.980939Z","caller":"traceutil/trace.go:171","msg":"trace[1436017790] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"107.491535ms","start":"2026-05-14T13:22:54.873417Z","end":"2026-05-14T13:22:54.980908Z","steps":["trace[1436017790] 'process raft request' (duration: 59.380824ms)","trace[1436017790] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:473; } (duration: 48.019329ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:55.636035Z","caller":"traceutil/trace.go:171","msg":"trace[1189176127] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"125.670716ms","start":"2026-05-14T13:22:55.510326Z","end":"2026-05-14T13:22:55.635997Z","steps":["trace[1189176127] 'read index received' (duration: 125.313669ms)","trace[1189176127] 'applied index is now lower than readState.Index' (duration: 355.287µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:22:55.636124Z","caller":"traceutil/trace.go:171","msg":"trace[581721410] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"182.216069ms","start":"2026-05-14T13:22:55.453856Z","end":"2026-05-14T13:22:55.636072Z","steps":["trace[581721410] 'process raft request' (duration: 181.906612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:22:55.636234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.86176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-14T13:22:55.636294Z","caller":"traceutil/trace.go:171","msg":"trace[2119807753] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1892; }","duration":"125.983473ms","start":"2026-05-14T13:22:55.510288Z","end":"2026-05-14T13:22:55.636272Z","steps":["trace[2119807753] 'agreement among raft nodes before linearized reading' (duration: 125.85044ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:23:10.447065Z","caller":"traceutil/trace.go:171","msg":"trace[1919526423] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"129.887001ms","start":"2026-05-14T13:23:10.317152Z","end":"2026-05-14T13:23:10.447039Z","steps":["trace[1919526423] 'process raft request' (duration: 129.3775ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:23:43.345393Z","caller":"traceutil/trace.go:171","msg":"trace[230881218] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"137.761767ms","start":"2026-05-14T13:23:43.207599Z","end":"2026-05-14T13:23:43.345361Z","steps":["trace[230881218] 'process raft request' (duration: 88.161475ms)","trace[230881218] 'compare' (duration: 49.426559ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:23:44.324261Z","caller":"traceutil/trace.go:171","msg":"trace[1043774119] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"169.225778ms","start":"2026-05-14T13:23:44.155005Z","end":"2026-05-14T13:23:44.32423Z","steps":["trace[1043774119] 'read index received' (duration: 168.970912ms)","trace[1043774119] 'applied index is now lower than readState.Index' (duration: 253.616µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:23:44.324387Z","caller":"traceutil/trace.go:171","msg":"trace[561998180] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"195.713019ms","start":"2026-05-14T13:23:44.128636Z","end":"2026-05-14T13:23:44.324349Z","steps":["trace[561998180] 'process raft request' (duration: 195.410022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:23:44.324618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.566885ms","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:534"} {"level":"info","ts":"2026-05-14T13:23:44.324677Z","caller":"traceutil/trace.go:171","msg":"trace[299966643] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2112; }","duration":"169.712317ms","start":"2026-05-14T13:23:44.154937Z","end":"2026-05-14T13:23:44.324649Z","steps":["trace[299966643] 'agreement among raft nodes before linearized reading' (duration: 169.454632ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:23:53.557127Z","caller":"traceutil/trace.go:171","msg":"trace[1820457500] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"117.745917ms","start":"2026-05-14T13:23:53.439356Z","end":"2026-05-14T13:23:53.557102Z","steps":["trace[1820457500] 'process raft request' (duration: 90.813228ms)","trace[1820457500] 'compare' (duration: 26.835027ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:23:56.569866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.571415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-14T13:23:56.569956Z","caller":"traceutil/trace.go:171","msg":"trace[1901104825] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2169; }","duration":"147.700628ms","start":"2026-05-14T13:23:56.422236Z","end":"2026-05-14T13:23:56.569936Z","steps":["trace[1901104825] 'range keys from in-memory index tree' (duration: 147.39127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:23:57.389984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.199956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-14T13:23:57.390069Z","caller":"traceutil/trace.go:171","msg":"trace[536767300] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2174; }","duration":"119.339279ms","start":"2026-05-14T13:23:57.27071Z","end":"2026-05-14T13:23:57.39005Z","steps":["trace[536767300] 'range keys from in-memory index tree' (duration: 119.020502ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:23:57.531504Z","caller":"traceutil/trace.go:171","msg":"trace[1552617009] linearizableReadLoop","detail":"{readStateIndex:2267; appliedIndex:2266; }","duration":"117.557364ms","start":"2026-05-14T13:23:57.413927Z","end":"2026-05-14T13:23:57.531485Z","steps":["trace[1552617009] 'read index received' (duration: 117.492283ms)","trace[1552617009] 'applied index is now lower than readState.Index' (duration: 64.431µs)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:23:57.531572Z","caller":"traceutil/trace.go:171","msg":"trace[1367680981] transaction","detail":"{read_only:false; response_revision:2175; number_of_response:1; }","duration":"137.085915ms","start":"2026-05-14T13:23:57.39446Z","end":"2026-05-14T13:23:57.531546Z","steps":["trace[1367680981] 'process raft request' (duration: 136.924102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:23:57.53165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.703297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-14T13:23:57.531673Z","caller":"traceutil/trace.go:171","msg":"trace[1404334498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2175; }","duration":"117.762848ms","start":"2026-05-14T13:23:57.413905Z","end":"2026-05-14T13:23:57.531668Z","steps":["trace[1404334498] 'agreement among raft nodes before linearized reading' (duration: 117.651455ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:23:57.655362Z","caller":"traceutil/trace.go:171","msg":"trace[435213353] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"119.84418ms","start":"2026-05-14T13:23:57.535498Z","end":"2026-05-14T13:23:57.655342Z","steps":["trace[435213353] 'process raft request' (duration: 81.521502ms)","trace[435213353] 'compare' (duration: 38.216046ms)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:24:16.824295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.670296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-14T13:24:16.824414Z","caller":"traceutil/trace.go:171","msg":"trace[1373462045] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2292; }","duration":"149.834249ms","start":"2026-05-14T13:24:16.674567Z","end":"2026-05-14T13:24:16.824401Z","steps":["trace[1373462045] 'range keys from in-memory index tree' (duration: 149.580504ms)"],"step_count":1} {"level":"info","ts":"2026-05-14T13:24:18.978895Z","caller":"traceutil/trace.go:171","msg":"trace[530702734] transaction","detail":"{read_only:false; response_revision:2307; number_of_response:1; }","duration":"102.936519ms","start":"2026-05-14T13:24:18.87589Z","end":"2026-05-14T13:24:18.978826Z","steps":["trace[530702734] 'process raft request' (duration: 49.751045ms)","trace[530702734] 'compare' (duration: 53.061072ms)"],"step_count":2} {"level":"info","ts":"2026-05-14T13:24:27.729645Z","caller":"traceutil/trace.go:171","msg":"trace[453978557] linearizableReadLoop","detail":"{readStateIndex:2449; appliedIndex:2448; }","duration":"374.882638ms","start":"2026-05-14T13:24:27.354737Z","end":"2026-05-14T13:24:27.729619Z","steps":["trace[453978557] 'read index received' (duration: 374.639554ms)","trace[453978557] 'applied index is now lower than readState.Index' (duration: 242.094µs)"],"step_count":2} {"level":"warn","ts":"2026-05-14T13:24:27.729939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.118018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"warn","ts":"2026-05-14T13:24:27.729982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.056013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:723"} {"level":"info","ts":"2026-05-14T13:24:27.730043Z","caller":"traceutil/trace.go:171","msg":"trace[611063980] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:2349; }","duration":"360.25811ms","start":"2026-05-14T13:24:27.369762Z","end":"2026-05-14T13:24:27.73002Z","steps":["trace[611063980] 'agreement among raft nodes before linearized reading' (duration: 360.075327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:24:27.73009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:24:27.369751Z","time spent":"360.329942ms","remote":"127.0.0.1:35556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":564,"request content":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" "} {"level":"info","ts":"2026-05-14T13:24:27.730182Z","caller":"traceutil/trace.go:171","msg":"trace[1767796744] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:2349; }","duration":"375.348128ms","start":"2026-05-14T13:24:27.354694Z","end":"2026-05-14T13:24:27.730042Z","steps":["trace[1767796744] 'agreement among raft nodes before linearized reading' (duration: 375.029411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:24:27.730241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:24:27.354673Z","time spent":"375.554773ms","remote":"127.0.0.1:35546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":746,"request content":"key:\"/registry/clusterroles/system:controller:namespace-controller\" "} {"level":"info","ts":"2026-05-14T13:24:27.730454Z","caller":"traceutil/trace.go:171","msg":"trace[531219105] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"415.200508ms","start":"2026-05-14T13:24:27.314466Z","end":"2026-05-14T13:24:27.729667Z","steps":["trace[531219105] 'process raft request' (duration: 414.980864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-14T13:24:27.731073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-14T13:24:27.314443Z","time spent":"416.529234ms","remote":"127.0.0.1:35472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}