{"level":"warn","ts":"2026-07-21T13:13:39.077043Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T13:13:39.077134Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.119:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.119:2380","--initial-cluster=instance=https://199.19.213.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.119: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-07-21T13:13:39.077233Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T13:13:39.077262Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-07-21T13:13:39.077278Z","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-07-21T13:13:39.078184Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"]} {"level":"info","ts":"2026-07-21T13:13:39.078279Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.119: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-07-21T13:13:39.084Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.988343ms"} {"level":"info","ts":"2026-07-21T13:13:39.090802Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ec9077c81c499df2","cluster-id":"7e82d3f5f740d3fb"} {"level":"info","ts":"2026-07-21T13:13:39.090869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T13:13:39.090903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 0"} {"level":"info","ts":"2026-07-21T13:13:39.090914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ec9077c81c499df2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T13:13:39.090924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became follower at term 1"} {"level":"info","ts":"2026-07-21T13:13:39.090972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"warn","ts":"2026-07-21T13:13:39.095849Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T13:13:39.097076Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T13:13:39.097984Z","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-07-21T13:13:39.099766Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ec9077c81c499df2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T13:13:39.100079Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ec9077c81c499df2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T13:13:39.100277Z","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-07-21T13:13:39.100711Z","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-07-21T13:13:39.100742Z","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-07-21T13:13:39.101287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 switched to configuration voters=(17046256290949078514)"} {"level":"info","ts":"2026-07-21T13:13:39.101477Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","added-peer-id":"ec9077c81c499df2","added-peer-peer-urls":["https://199.19.213.119:2380"]} {"level":"info","ts":"2026-07-21T13:13:39.103001Z","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-07-21T13:13:39.103156Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ec9077c81c499df2","initial-advertise-peer-urls":["https://199.19.213.119:2380"],"listen-peer-urls":["https://199.19.213.119:2380"],"advertise-client-urls":["https://199.19.213.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T13:13:39.10318Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T13:13:39.103255Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-07-21T13:13:39.103267Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.119:2380"} {"level":"info","ts":"2026-07-21T13:13:39.59171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T13:13:39.591814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T13:13:39.591928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgPreVoteResp from ec9077c81c499df2 at term 1"} {"level":"info","ts":"2026-07-21T13:13:39.592001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became candidate at term 2"} {"level":"info","ts":"2026-07-21T13:13:39.592059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 received MsgVoteResp from ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-07-21T13:13:39.592083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ec9077c81c499df2 became leader at term 2"} {"level":"info","ts":"2026-07-21T13:13:39.592141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ec9077c81c499df2 elected leader ec9077c81c499df2 at term 2"} {"level":"info","ts":"2026-07-21T13:13:39.593501Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ec9077c81c499df2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.119:2379]}","request-path":"/0/members/ec9077c81c499df2/attributes","cluster-id":"7e82d3f5f740d3fb","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T13:13:39.593559Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:13:39.593696Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:13:39.59377Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:13:39.594051Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T13:13:39.594067Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T13:13:39.595449Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7e82d3f5f740d3fb","local-member-id":"ec9077c81c499df2","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:13:39.59561Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:13:39.59566Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:13:39.596056Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T13:13:39.596703Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.119:2379"} {"level":"warn","ts":"2026-07-21T13:13:42.873495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.955528ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:13:42.873573Z","caller":"traceutil/trace.go:171","msg":"trace[1053404925] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"376.325524ms","start":"2026-07-21T13:13:42.497237Z","end":"2026-07-21T13:13:42.873563Z","steps":["trace[1053404925] 'process raft request' (duration: 92.022181ms)","trace[1053404925] 'compare' (duration: 283.862417ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:13:42.873652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:42.497226Z","time spent":"376.410554ms","remote":"127.0.0.1:41936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":646,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:13:43.868248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334601084441836,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:13:45.460193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224050941s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:13:45.460321Z","caller":"traceutil/trace.go:171","msg":"trace[1594819685] linearizableReadLoop","detail":"{readStateIndex:206; appliedIndex:205; }","duration":"2.092914338s","start":"2026-07-21T13:13:43.367389Z","end":"2026-07-21T13:13:45.460303Z","steps":["trace[1594819685] 'read index received' (duration: 868.625913ms)","trace[1594819685] 'applied index is now lower than readState.Index' (duration: 1.224287185s)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:13:45.460467Z","caller":"traceutil/trace.go:171","msg":"trace[902349123] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"2.10986556s","start":"2026-07-21T13:13:43.35059Z","end":"2026-07-21T13:13:45.460455Z","steps":["trace[902349123] 'process raft request' (duration: 885.472864ms)","trace[902349123] 'compare' (duration: 1.223515974s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:13:45.460518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:43.350573Z","time spent":"2.10992028s","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:13:45.460761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.731976935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:13:45.460873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.093398783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:13:45.460969Z","caller":"traceutil/trace.go:171","msg":"trace[1260617376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:201; }","duration":"1.732063345s","start":"2026-07-21T13:13:43.728734Z","end":"2026-07-21T13:13:45.460797Z","steps":["trace[1260617376] 'agreement among raft nodes before linearized reading' (duration: 1.731946264s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:45.46101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:43.728717Z","time spent":"1.732283579s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T13:13:45.460995Z","caller":"traceutil/trace.go:171","msg":"trace[2035595135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:201; }","duration":"2.093599816s","start":"2026-07-21T13:13:43.36735Z","end":"2026-07-21T13:13:45.46095Z","steps":["trace[2035595135] 'agreement among raft nodes before linearized reading' (duration: 2.093297043s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:45.461067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:43.367331Z","time spent":"2.093720808s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T13:13:45.461056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.002292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:13:45.461139Z","caller":"traceutil/trace.go:171","msg":"trace[345386015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:201; }","duration":"749.179456ms","start":"2026-07-21T13:13:44.711939Z","end":"2026-07-21T13:13:45.461118Z","steps":["trace[345386015] 'agreement among raft nodes before linearized reading' (duration: 748.981743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:45.461752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:44.711919Z","time spent":"749.819434ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:13:45.869328Z","caller":"traceutil/trace.go:171","msg":"trace[475745343] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"404.558873ms","start":"2026-07-21T13:13:45.464738Z","end":"2026-07-21T13:13:45.869297Z","steps":["trace[475745343] 'process raft request' (duration: 403.209436ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:13:45.869079Z","caller":"traceutil/trace.go:171","msg":"trace[3839168] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"404.305229ms","start":"2026-07-21T13:13:45.464752Z","end":"2026-07-21T13:13:45.869057Z","steps":["trace[3839168] 'process raft request' (duration: 404.245768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:45.869635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:45.464712Z","time spent":"404.698074ms","remote":"127.0.0.1:41890","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-07-21T13:13:45.869667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:45.464733Z","time spent":"404.885736ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:13:45.871922Z","caller":"traceutil/trace.go:171","msg":"trace[843457987] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"407.008443ms","start":"2026-07-21T13:13:45.464872Z","end":"2026-07-21T13:13:45.87188Z","steps":["trace[843457987] 'process raft request' (duration: 406.854331ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:13:45.871965Z","caller":"traceutil/trace.go:171","msg":"trace[674095069] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"407.054524ms","start":"2026-07-21T13:13:45.464891Z","end":"2026-07-21T13:13:45.871945Z","steps":["trace[674095069] 'process raft request' (duration: 406.934491ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:13:45.871951Z","caller":"traceutil/trace.go:171","msg":"trace[882230243] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"406.203222ms","start":"2026-07-21T13:13:45.465728Z","end":"2026-07-21T13:13:45.871932Z","steps":["trace[882230243] 'process raft request' (duration: 406.135572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:45.872042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:45.46488Z","time spent":"407.127784ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:13:45.872061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:45.464851Z","time spent":"407.146105ms","remote":"127.0.0.1:41890","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-07-21T13:13:45.872106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:45.46572Z","time spent":"406.320445ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:13:52.117425Z","caller":"traceutil/trace.go:171","msg":"trace[1626765435] linearizableReadLoop","detail":"{readStateIndex:366; appliedIndex:365; }","duration":"210.477084ms","start":"2026-07-21T13:13:51.906929Z","end":"2026-07-21T13:13:52.117406Z","steps":["trace[1626765435] 'read index received' (duration: 210.25662ms)","trace[1626765435] 'applied index is now lower than readState.Index' (duration: 203.943µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:13:52.117706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.759187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:13:52.117797Z","caller":"traceutil/trace.go:171","msg":"trace[1934874271] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"337.705493ms","start":"2026-07-21T13:13:51.780079Z","end":"2026-07-21T13:13:52.117785Z","steps":["trace[1934874271] 'process raft request' (duration: 337.195826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:52.117828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.702564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:13:52.117809Z","caller":"traceutil/trace.go:171","msg":"trace[1698770942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:352; }","duration":"210.887449ms","start":"2026-07-21T13:13:51.9069Z","end":"2026-07-21T13:13:52.117787Z","steps":["trace[1698770942] 'agreement among raft nodes before linearized reading' (duration: 210.750708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:52.117882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.279697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T13:13:52.117901Z","caller":"traceutil/trace.go:171","msg":"trace[246059713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:352; }","duration":"204.322318ms","start":"2026-07-21T13:13:51.913574Z","end":"2026-07-21T13:13:52.117896Z","steps":["trace[246059713] 'agreement among raft nodes before linearized reading' (duration: 204.288698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:52.118001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:51.780054Z","time spent":"337.775584ms","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:13:52.118133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.173089ms","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-07-21T13:13:52.118162Z","caller":"traceutil/trace.go:171","msg":"trace[1818026325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:352; }","duration":"205.24108ms","start":"2026-07-21T13:13:51.912914Z","end":"2026-07-21T13:13:52.118155Z","steps":["trace[1818026325] 'agreement among raft nodes before linearized reading' (duration: 205.176188ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:13:52.117852Z","caller":"traceutil/trace.go:171","msg":"trace[1913753207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:352; }","duration":"179.727345ms","start":"2026-07-21T13:13:51.938118Z","end":"2026-07-21T13:13:52.117846Z","steps":["trace[1913753207] 'agreement among raft nodes before linearized reading' (duration: 179.692894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:52.117759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.727485ms","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-07-21T13:13:52.118339Z","caller":"traceutil/trace.go:171","msg":"trace[8593481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:352; }","duration":"210.322233ms","start":"2026-07-21T13:13:51.908009Z","end":"2026-07-21T13:13:52.118331Z","steps":["trace[8593481] 'agreement among raft nodes before linearized reading' (duration: 209.723185ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:52.665757Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334601084442172,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T13:13:52.712107Z","caller":"traceutil/trace.go:171","msg":"trace[134115102] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"589.646442ms","start":"2026-07-21T13:13:52.12212Z","end":"2026-07-21T13:13:52.711767Z","steps":["trace[134115102] 'process raft request' (duration: 578.90138ms)","trace[134115102] 'compare' (duration: 10.5374ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:13:52.712283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:52.122108Z","time spent":"590.071207ms","remote":"127.0.0.1:41890","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-07-21T13:13:52.910359Z","caller":"traceutil/trace.go:171","msg":"trace[1739616754] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"787.918393ms","start":"2026-07-21T13:13:52.122413Z","end":"2026-07-21T13:13:52.910331Z","steps":["trace[1739616754] 'process raft request' (duration: 787.705681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:52.910554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:52.122406Z","time spent":"788.036355ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:13:52.910635Z","caller":"traceutil/trace.go:171","msg":"trace[1122397335] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"788.147307ms","start":"2026-07-21T13:13:52.122464Z","end":"2026-07-21T13:13:52.910611Z","steps":["trace[1122397335] 'process raft request' (duration: 787.811073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:52.910742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:52.122459Z","time spent":"788.232858ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:13:52.910884Z","caller":"traceutil/trace.go:171","msg":"trace[1952834400] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"785.205591ms","start":"2026-07-21T13:13:52.125662Z","end":"2026-07-21T13:13:52.910867Z","steps":["trace[1952834400] 'process raft request' (duration: 784.864726ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:13:52.910949Z","caller":"traceutil/trace.go:171","msg":"trace[250185702] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:366; }","duration":"746.13304ms","start":"2026-07-21T13:13:52.164798Z","end":"2026-07-21T13:13:52.910931Z","steps":["trace[250185702] 'read index received' (duration: 536.232724ms)","trace[250185702] 'applied index is now lower than readState.Index' (duration: 209.899086ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:13:52.910983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:52.125653Z","time spent":"785.280302ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:13:52.911027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.220091ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:13:52.911114Z","caller":"traceutil/trace.go:171","msg":"trace[1539654465] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:357; }","duration":"746.306732ms","start":"2026-07-21T13:13:52.164791Z","end":"2026-07-21T13:13:52.911098Z","steps":["trace[1539654465] 'agreement among raft nodes before linearized reading' (duration: 746.20161ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:13:52.91108Z","caller":"traceutil/trace.go:171","msg":"trace[1797328947] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"781.996581ms","start":"2026-07-21T13:13:52.129052Z","end":"2026-07-21T13:13:52.911049Z","steps":["trace[1797328947] 'process raft request' (duration: 781.741778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:52.911254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:52.129043Z","time spent":"782.134672ms","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:13:53.419964Z","caller":"traceutil/trace.go:171","msg":"trace[2051002575] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"384.893975ms","start":"2026-07-21T13:13:53.035031Z","end":"2026-07-21T13:13:53.419925Z","steps":["trace[2051002575] 'read index received' (duration: 384.668192ms)","trace[2051002575] 'applied index is now lower than readState.Index' (duration: 224.203µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:13:53.420088Z","caller":"traceutil/trace.go:171","msg":"trace[1182590268] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"481.172729ms","start":"2026-07-21T13:13:52.938869Z","end":"2026-07-21T13:13:53.420042Z","steps":["trace[1182590268] 'process raft request' (duration: 480.830945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:53.420241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.158227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T13:13:53.420415Z","caller":"traceutil/trace.go:171","msg":"trace[921987666] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:358; }","duration":"385.432202ms","start":"2026-07-21T13:13:53.034961Z","end":"2026-07-21T13:13:53.420393Z","steps":["trace[921987666] 'agreement among raft nodes before linearized reading' (duration: 385.123827ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:13:53.420478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:53.03493Z","time spent":"385.537323ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.119\" "} {"level":"warn","ts":"2026-07-21T13:13:53.420575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:52.938852Z","time spent":"481.449883ms","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:13:53.724544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.819539ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-21T13:13:53.724632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:13:53.421693Z","time spent":"302.935095ms","remote":"127.0.0.1:41660","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-21T13:13:53.968925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.910258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:13:53.969226Z","caller":"traceutil/trace.go:171","msg":"trace[2056550442] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"243.660077ms","start":"2026-07-21T13:13:53.725514Z","end":"2026-07-21T13:13:53.969174Z","steps":["trace[2056550442] 'process raft request' (duration: 130.412164ms)","trace[2056550442] 'compare' (duration: 112.779407ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:13:54.523277Z","caller":"traceutil/trace.go:171","msg":"trace[472845861] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"104.878628ms","start":"2026-07-21T13:13:54.418373Z","end":"2026-07-21T13:13:54.523251Z","steps":["trace[472845861] 'process raft request' (duration: 104.698646ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:01.02804Z","caller":"traceutil/trace.go:171","msg":"trace[1431462738] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"202.224395ms","start":"2026-07-21T13:14:00.82569Z","end":"2026-07-21T13:14:01.027915Z","steps":["trace[1431462738] 'process raft request' (duration: 201.845431ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:01.492197Z","caller":"traceutil/trace.go:171","msg":"trace[1055327477] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"184.079402ms","start":"2026-07-21T13:14:01.308076Z","end":"2026-07-21T13:14:01.492155Z","steps":["trace[1055327477] 'process raft request' (duration: 183.857019ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:07.609926Z","caller":"traceutil/trace.go:171","msg":"trace[671170066] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"112.809734ms","start":"2026-07-21T13:14:07.497099Z","end":"2026-07-21T13:14:07.609908Z","steps":["trace[671170066] 'process raft request' (duration: 112.683473ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:17.3135Z","caller":"traceutil/trace.go:171","msg":"trace[532716609] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:469; }","duration":"109.730174ms","start":"2026-07-21T13:14:17.203745Z","end":"2026-07-21T13:14:17.313475Z","steps":["trace[532716609] 'read index received' (duration: 109.530051ms)","trace[532716609] 'applied index is now lower than readState.Index' (duration: 199.093µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:17.313627Z","caller":"traceutil/trace.go:171","msg":"trace[561912008] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"113.160625ms","start":"2026-07-21T13:14:17.200445Z","end":"2026-07-21T13:14:17.313606Z","steps":["trace[561912008] 'process raft request' (duration: 112.903641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:17.313802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.047278ms","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-07-21T13:14:17.313844Z","caller":"traceutil/trace.go:171","msg":"trace[460117581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:449; }","duration":"110.117158ms","start":"2026-07-21T13:14:17.203713Z","end":"2026-07-21T13:14:17.31383Z","steps":["trace[460117581] 'agreement among raft nodes before linearized reading' (duration: 110.032057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:17.314025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.771205ms","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-07-21T13:14:17.314024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.747093ms","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-07-21T13:14:17.314124Z","caller":"traceutil/trace.go:171","msg":"trace[1811271328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"109.940236ms","start":"2026-07-21T13:14:17.204158Z","end":"2026-07-21T13:14:17.314098Z","steps":["trace[1811271328] 'agreement among raft nodes before linearized reading' (duration: 109.696573ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:17.314142Z","caller":"traceutil/trace.go:171","msg":"trace[880535729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:449; }","duration":"109.922856ms","start":"2026-07-21T13:14:17.20419Z","end":"2026-07-21T13:14:17.314112Z","steps":["trace[880535729] 'agreement among raft nodes before linearized reading' (duration: 109.713494ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:17.472375Z","caller":"traceutil/trace.go:171","msg":"trace[1670370322] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"154.265787ms","start":"2026-07-21T13:14:17.318085Z","end":"2026-07-21T13:14:17.472351Z","steps":["trace[1670370322] 'process raft request' (duration: 153.862112ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:17.472559Z","caller":"traceutil/trace.go:171","msg":"trace[2101596892] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"153.819951ms","start":"2026-07-21T13:14:17.318705Z","end":"2026-07-21T13:14:17.472525Z","steps":["trace[2101596892] 'process raft request' (duration: 153.588838ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:25.504643Z","caller":"traceutil/trace.go:171","msg":"trace[680532637] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"155.633221ms","start":"2026-07-21T13:14:25.348985Z","end":"2026-07-21T13:14:25.504618Z","steps":["trace[680532637] 'process raft request' (duration: 107.326487ms)","trace[680532637] 'compare' (duration: 48.131552ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:25.683344Z","caller":"traceutil/trace.go:171","msg":"trace[472394760] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"173.724065ms","start":"2026-07-21T13:14:25.50959Z","end":"2026-07-21T13:14:25.683315Z","steps":["trace[472394760] 'process raft request' (duration: 112.064342ms)","trace[472394760] 'compare' (duration: 61.535342ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:25.684168Z","caller":"traceutil/trace.go:171","msg":"trace[1943065572] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"174.488493ms","start":"2026-07-21T13:14:25.50963Z","end":"2026-07-21T13:14:25.684119Z","steps":["trace[1943065572] 'process raft request' (duration: 174.16352ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:25.684363Z","caller":"traceutil/trace.go:171","msg":"trace[1072687910] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"173.525871ms","start":"2026-07-21T13:14:25.510798Z","end":"2026-07-21T13:14:25.684324Z","steps":["trace[1072687910] 'process raft request' (duration: 173.213658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:26.854869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.305832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:26.854969Z","caller":"traceutil/trace.go:171","msg":"trace[746061159] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"158.483853ms","start":"2026-07-21T13:14:26.69647Z","end":"2026-07-21T13:14:26.854954Z","steps":["trace[746061159] 'compare' (duration: 149.212811ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:26.893512Z","caller":"traceutil/trace.go:171","msg":"trace[1570782724] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"172.782278ms","start":"2026-07-21T13:14:26.720709Z","end":"2026-07-21T13:14:26.893491Z","steps":["trace[1570782724] 'process raft request' (duration: 172.613845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:27.141087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.515046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:27.141274Z","caller":"traceutil/trace.go:171","msg":"trace[1243227167] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"272.79713ms","start":"2026-07-21T13:14:26.868462Z","end":"2026-07-21T13:14:27.141259Z","steps":["trace[1243227167] 'process raft request' (duration: 272.711295ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:27.141409Z","caller":"traceutil/trace.go:171","msg":"trace[887304583] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"375.248337ms","start":"2026-07-21T13:14:26.766135Z","end":"2026-07-21T13:14:27.141384Z","steps":["trace[887304583] 'process raft request' (duration: 241.380401ms)","trace[887304583] 'compare' (duration: 133.253103ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:27.141526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:26.766109Z","time spent":"375.360372ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:14:28.599663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294666142s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:28.599793Z","caller":"traceutil/trace.go:171","msg":"trace[194759358] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:566; }","duration":"1.649361395s","start":"2026-07-21T13:14:26.950389Z","end":"2026-07-21T13:14:28.599751Z","steps":["trace[194759358] 'read index received' (duration: 56.957778ms)","trace[194759358] 'applied index is now lower than readState.Index' (duration: 1.592402577s)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:28.599886Z","caller":"traceutil/trace.go:171","msg":"trace[626622671] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.654976881s","start":"2026-07-21T13:14:26.944897Z","end":"2026-07-21T13:14:28.599874Z","steps":["trace[626622671] 'process raft request' (duration: 360.036055ms)","trace[626622671] 'compare' (duration: 1.294519754s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:28.59993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649522943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:14:28.599948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:26.944874Z","time spent":"1.655035863s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:28.59999Z","caller":"traceutil/trace.go:171","msg":"trace[1953794775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"1.649601376s","start":"2026-07-21T13:14:26.95037Z","end":"2026-07-21T13:14:28.599971Z","steps":["trace[1953794775] 'agreement among raft nodes before linearized reading' (duration: 1.649511751s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.600023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:26.950364Z","time spent":"1.649652478s","remote":"127.0.0.1:41630","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-07-21T13:14:28.600542Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.291844684s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T13:14:28.601139Z","caller":"traceutil/trace.go:171","msg":"trace[143602696] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.374285226s","start":"2026-07-21T13:14:27.226833Z","end":"2026-07-21T13:14:28.601118Z","steps":["trace[143602696] 'process raft request' (duration: 1.374135327s)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:28.601261Z","caller":"traceutil/trace.go:171","msg":"trace[1708709513] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.443284066s","start":"2026-07-21T13:14:27.157964Z","end":"2026-07-21T13:14:28.601248Z","steps":["trace[1708709513] 'process raft request' (duration: 1.442836293s)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:28.601301Z","caller":"traceutil/trace.go:171","msg":"trace[1935956075] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.395701853s","start":"2026-07-21T13:14:27.205587Z","end":"2026-07-21T13:14:28.601289Z","steps":["trace[1935956075] 'process raft request' (duration: 1.395327643s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.601331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:27.157944Z","time spent":"1.4433549s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:14:28.601338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:27.205561Z","time spent":"1.395758926s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:14:28.601261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:27.226812Z","time spent":"1.37437259s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:28.763967Z","caller":"traceutil/trace.go:171","msg":"trace[847825454] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"636.538311ms","start":"2026-07-21T13:14:28.127386Z","end":"2026-07-21T13:14:28.763925Z","steps":["trace[847825454] 'process raft request' (duration: 631.866559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.764105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.127361Z","time spent":"636.673218ms","remote":"127.0.0.1:41890","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-07-21T13:14:28.775996Z","caller":"traceutil/trace.go:171","msg":"trace[1375391662] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:569; }","duration":"176.121859ms","start":"2026-07-21T13:14:28.599845Z","end":"2026-07-21T13:14:28.775967Z","steps":["trace[1375391662] 'read index received' (duration: 917.008µs)","trace[1375391662] 'applied index is now lower than readState.Index' (duration: 175.202581ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:28.776154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.289722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:28.776181Z","caller":"traceutil/trace.go:171","msg":"trace[1826442235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"326.363936ms","start":"2026-07-21T13:14:28.449809Z","end":"2026-07-21T13:14:28.776173Z","steps":["trace[1826442235] 'agreement among raft nodes before linearized reading' (duration: 326.270171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.776274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.449792Z","time spent":"326.474421ms","remote":"127.0.0.1:41630","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-07-21T13:14:28.776654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087046667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T13:14:28.776682Z","caller":"traceutil/trace.go:171","msg":"trace[1138437134] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"1.087076979s","start":"2026-07-21T13:14:27.689597Z","end":"2026-07-21T13:14:28.776674Z","steps":["trace[1138437134] 'agreement among raft nodes before linearized reading' (duration: 1.087005175s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.776754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:27.689539Z","time spent":"1.087157503s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T13:14:28.776873Z","caller":"traceutil/trace.go:171","msg":"trace[1271294665] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"169.270464ms","start":"2026-07-21T13:14:28.607583Z","end":"2026-07-21T13:14:28.776853Z","steps":["trace[1271294665] 'process raft request' (duration: 168.268861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.77695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087478451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:14:28.777008Z","caller":"traceutil/trace.go:171","msg":"trace[1867421098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"1.087528104s","start":"2026-07-21T13:14:27.68944Z","end":"2026-07-21T13:14:28.776968Z","steps":["trace[1867421098] 'agreement among raft nodes before linearized reading' (duration: 1.087494262s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.777035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:27.689424Z","time spent":"1.087604478s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:14:28.777061Z","caller":"traceutil/trace.go:171","msg":"trace[607951057] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"132.806062ms","start":"2026-07-21T13:14:28.644227Z","end":"2026-07-21T13:14:28.777033Z","steps":["trace[607951057] 'process raft request' (duration: 131.79303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.777176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269009402s","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-07-21T13:14:28.777214Z","caller":"traceutil/trace.go:171","msg":"trace[1497314942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"1.269084016s","start":"2026-07-21T13:14:27.508121Z","end":"2026-07-21T13:14:28.777205Z","steps":["trace[1497314942] 'agreement among raft nodes before linearized reading' (duration: 1.268930808s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.777238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:27.508106Z","time spent":"1.269125258s","remote":"127.0.0.1:41890","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-07-21T13:14:28.776916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087323173s","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-07-21T13:14:28.777378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.621915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:28.777402Z","caller":"traceutil/trace.go:171","msg":"trace[156984026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"176.677478ms","start":"2026-07-21T13:14:28.600718Z","end":"2026-07-21T13:14:28.777396Z","steps":["trace[156984026] 'agreement among raft nodes before linearized reading' (duration: 176.626216ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:28.777551Z","caller":"traceutil/trace.go:171","msg":"trace[370064194] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"150.433947ms","start":"2026-07-21T13:14:28.627105Z","end":"2026-07-21T13:14:28.777539Z","steps":["trace[370064194] 'process raft request' (duration: 148.840853ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:28.777558Z","caller":"traceutil/trace.go:171","msg":"trace[214717722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"1.087804297s","start":"2026-07-21T13:14:27.689584Z","end":"2026-07-21T13:14:28.777388Z","steps":["trace[214717722] 'agreement among raft nodes before linearized reading' (duration: 1.087303932s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:28.777741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:27.689511Z","time spent":"1.088178867s","remote":"127.0.0.1:41890","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-07-21T13:14:29.780876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.625883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:29.780953Z","caller":"traceutil/trace.go:171","msg":"trace[370961578] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"999.210502ms","start":"2026-07-21T13:14:28.781729Z","end":"2026-07-21T13:14:29.78094Z","steps":["trace[370961578] 'process raft request' (duration: 507.462903ms)","trace[370961578] 'compare' (duration: 491.542398ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:29.780993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.781713Z","time spent":"999.262594ms","remote":"127.0.0.1:41890","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-07-21T13:14:29.810159Z","caller":"traceutil/trace.go:171","msg":"trace[2037921946] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.026637188s","start":"2026-07-21T13:14:28.783496Z","end":"2026-07-21T13:14:29.810134Z","steps":["trace[2037921946] 'process raft request' (duration: 1.026569643s)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:29.81018Z","caller":"traceutil/trace.go:171","msg":"trace[305921646] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.027321803s","start":"2026-07-21T13:14:28.782825Z","end":"2026-07-21T13:14:29.810147Z","steps":["trace[305921646] 'process raft request' (duration: 1.027121702s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:29.810308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.783488Z","time spent":"1.026742283s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:14:29.810323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.782816Z","time spent":"1.027427488s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:29.810587Z","caller":"traceutil/trace.go:171","msg":"trace[1092011746] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.025575802s","start":"2026-07-21T13:14:28.784986Z","end":"2026-07-21T13:14:29.810562Z","steps":["trace[1092011746] 'process raft request' (duration: 1.025111129s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:29.810726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.784977Z","time spent":"1.025681217s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:29.811451Z","caller":"traceutil/trace.go:171","msg":"trace[1715930510] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"972.008012ms","start":"2026-07-21T13:14:28.839426Z","end":"2026-07-21T13:14:29.811434Z","steps":["trace[1715930510] 'process raft request' (duration: 971.604182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:29.811614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.839378Z","time spent":"972.186071ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:29.811846Z","caller":"traceutil/trace.go:171","msg":"trace[13999419] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"951.069555ms","start":"2026-07-21T13:14:28.860745Z","end":"2026-07-21T13:14:29.811814Z","steps":["trace[13999419] 'process raft request' (duration: 950.414321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:29.811971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.860721Z","time spent":"951.184031ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:29.812405Z","caller":"traceutil/trace.go:171","msg":"trace[1780337734] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"947.075558ms","start":"2026-07-21T13:14:28.865314Z","end":"2026-07-21T13:14:29.81239Z","steps":["trace[1780337734] 'process raft request' (duration: 946.990764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:29.812692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:28.865295Z","time spent":"947.360702ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:14:29.966071Z","caller":"traceutil/trace.go:171","msg":"trace[472167724] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"129.715154ms","start":"2026-07-21T13:14:29.836303Z","end":"2026-07-21T13:14:29.966018Z","steps":["trace[472167724] 'process raft request' (duration: 123.773658ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:29.97906Z","caller":"traceutil/trace.go:171","msg":"trace[1743227476] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"140.538164ms","start":"2026-07-21T13:14:29.838422Z","end":"2026-07-21T13:14:29.97896Z","steps":["trace[1743227476] 'process raft request' (duration: 138.211993ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:30.590071Z","caller":"traceutil/trace.go:171","msg":"trace[2054307103] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"229.970875ms","start":"2026-07-21T13:14:30.360079Z","end":"2026-07-21T13:14:30.59005Z","steps":["trace[2054307103] 'process raft request' (duration: 219.74972ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:30.646387Z","caller":"traceutil/trace.go:171","msg":"trace[796258253] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"232.109715ms","start":"2026-07-21T13:14:30.414251Z","end":"2026-07-21T13:14:30.646361Z","steps":["trace[796258253] 'process raft request' (duration: 231.963877ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:31.387813Z","caller":"traceutil/trace.go:171","msg":"trace[226870265] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"101.167054ms","start":"2026-07-21T13:14:31.286616Z","end":"2026-07-21T13:14:31.387783Z","steps":["trace[226870265] 'read index received' (duration: 65.304523ms)","trace[226870265] 'applied index is now lower than readState.Index' (duration: 35.861551ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:31.388099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.45959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:74769"} {"level":"info","ts":"2026-07-21T13:14:31.388176Z","caller":"traceutil/trace.go:171","msg":"trace[476003109] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyextensionpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:585; }","duration":"101.586478ms","start":"2026-07-21T13:14:31.286568Z","end":"2026-07-21T13:14:31.388154Z","steps":["trace[476003109] 'agreement among raft nodes before linearized reading' (duration: 101.341155ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:31.388387Z","caller":"traceutil/trace.go:171","msg":"trace[1564977784] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"109.622727ms","start":"2026-07-21T13:14:31.278738Z","end":"2026-07-21T13:14:31.388361Z","steps":["trace[1564977784] 'process raft request' (duration: 73.302863ms)","trace[1564977784] 'compare' (duration: 35.541484ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:33.081159Z","caller":"traceutil/trace.go:171","msg":"trace[219802960] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"109.639947ms","start":"2026-07-21T13:14:32.971489Z","end":"2026-07-21T13:14:33.081129Z","steps":["trace[219802960] 'process raft request' (duration: 96.956437ms)","trace[219802960] 'compare' (duration: 12.517741ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:33.081338Z","caller":"traceutil/trace.go:171","msg":"trace[123653483] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"109.10976ms","start":"2026-07-21T13:14:32.972197Z","end":"2026-07-21T13:14:33.081307Z","steps":["trace[123653483] 'process raft request' (duration: 108.876869ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:33.984978Z","caller":"traceutil/trace.go:171","msg":"trace[913389996] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"145.401433ms","start":"2026-07-21T13:14:33.839552Z","end":"2026-07-21T13:14:33.984954Z","steps":["trace[913389996] 'process raft request' (duration: 145.238414ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:34.664738Z","caller":"traceutil/trace.go:171","msg":"trace[1855475937] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"751.607251ms","start":"2026-07-21T13:14:33.913106Z","end":"2026-07-21T13:14:34.664713Z","steps":["trace[1855475937] 'read index received' (duration: 71.764144ms)","trace[1855475937] 'applied index is now lower than readState.Index' (duration: 679.842147ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:34.664879Z","caller":"traceutil/trace.go:171","msg":"trace[215157524] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"778.906839ms","start":"2026-07-21T13:14:33.885943Z","end":"2026-07-21T13:14:34.664849Z","steps":["trace[215157524] 'process raft request' (duration: 774.894037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:34.66495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.674511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:14:34.664995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.868504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T13:14:34.665032Z","caller":"traceutil/trace.go:171","msg":"trace[1730703312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"751.951758ms","start":"2026-07-21T13:14:33.913069Z","end":"2026-07-21T13:14:34.665021Z","steps":["trace[1730703312] 'agreement among raft nodes before linearized reading' (duration: 751.78815ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:34.665034Z","caller":"traceutil/trace.go:171","msg":"trace[693799554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"727.763166ms","start":"2026-07-21T13:14:33.937249Z","end":"2026-07-21T13:14:34.665012Z","steps":["trace[693799554] 'agreement among raft nodes before linearized reading' (duration: 727.63421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:34.665032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:33.885917Z","time spent":"779.016596ms","remote":"127.0.0.1:41736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:14:34.665059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:33.913049Z","time spent":"752.00305ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T13:14:34.665074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:33.937197Z","time spent":"727.869912ms","remote":"127.0.0.1:41630","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-07-21T13:14:34.665068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.044468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:14:34.66514Z","caller":"traceutil/trace.go:171","msg":"trace[1678998680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"724.142644ms","start":"2026-07-21T13:14:33.940977Z","end":"2026-07-21T13:14:34.665119Z","steps":["trace[1678998680] 'agreement among raft nodes before linearized reading' (duration: 723.917333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:34.665188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:33.940968Z","time spent":"724.208166ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T13:14:34.665185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.631438ms","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-07-21T13:14:34.665283Z","caller":"traceutil/trace.go:171","msg":"trace[343394893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"724.774485ms","start":"2026-07-21T13:14:33.940479Z","end":"2026-07-21T13:14:34.665253Z","steps":["trace[343394893] 'agreement among raft nodes before linearized reading' (duration: 724.481051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:34.665334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:33.940459Z","time spent":"724.8657ms","remote":"127.0.0.1:41890","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-07-21T13:14:36.338202Z","caller":"traceutil/trace.go:171","msg":"trace[971031174] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"139.588647ms","start":"2026-07-21T13:14:36.198594Z","end":"2026-07-21T13:14:36.338183Z","steps":["trace[971031174] 'process raft request' (duration: 137.342076ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:36.941459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.594172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:36.941592Z","caller":"traceutil/trace.go:171","msg":"trace[1628776472] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"259.416022ms","start":"2026-07-21T13:14:36.682164Z","end":"2026-07-21T13:14:36.94158Z","steps":["trace[1628776472] 'process raft request' (duration: 259.37674ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:36.941876Z","caller":"traceutil/trace.go:171","msg":"trace[509612812] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"259.945458ms","start":"2026-07-21T13:14:36.681889Z","end":"2026-07-21T13:14:36.941835Z","steps":["trace[509612812] 'process raft request' (duration: 102.897433ms)","trace[509612812] 'compare' (duration: 156.510538ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:37.101959Z","caller":"traceutil/trace.go:171","msg":"trace[1137847903] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"209.293486ms","start":"2026-07-21T13:14:36.892642Z","end":"2026-07-21T13:14:37.101935Z","steps":["trace[1137847903] 'process raft request' (duration: 204.895479ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:37.983998Z","caller":"traceutil/trace.go:171","msg":"trace[552713407] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"388.557568ms","start":"2026-07-21T13:14:37.595409Z","end":"2026-07-21T13:14:37.983966Z","steps":["trace[552713407] 'process raft request' (duration: 387.728616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:37.984134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:37.595392Z","time spent":"388.674113ms","remote":"127.0.0.1:41736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036297,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:14:38.524786Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334601084442684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:14:39.025355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334601084442684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:14:39.526335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334601084442684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:14:40.026605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334601084442684,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:14:40.367844Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.363394779s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T13:14:40.440044Z","caller":"traceutil/trace.go:171","msg":"trace[776975166] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"2.435659696s","start":"2026-07-21T13:14:38.004354Z","end":"2026-07-21T13:14:40.440013Z","steps":["trace[776975166] 'process raft request' (duration: 2.363698045s)","trace[776975166] 'compare' (duration: 71.477427ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:40.440265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:38.004337Z","time spent":"2.435788771s","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:14:40.448547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999920976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-21T13:14:40.448629Z","caller":"traceutil/trace.go:171","msg":"trace[716693332] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000053782s","start":"2026-07-21T13:14:38.448554Z","end":"2026-07-21T13:14:40.448608Z","steps":["trace[716693332] 'agreement among raft nodes before linearized reading' (duration: 1.999955148s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:40.448693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:38.448535Z","time spent":"2.000148866s","remote":"127.0.0.1:41630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/21 13:14:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-21T13:14:40.456677Z","caller":"traceutil/trace.go:171","msg":"trace[1784028859] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"2.41461012s","start":"2026-07-21T13:14:38.042044Z","end":"2026-07-21T13:14:40.456654Z","steps":["trace[1784028859] 'process raft request' (duration: 2.414451173s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:40.456913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:38.042022Z","time spent":"2.414834562s","remote":"127.0.0.1:42104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:14:40.481163Z","caller":"traceutil/trace.go:171","msg":"trace[1285312027] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.706056478s","start":"2026-07-21T13:14:38.77509Z","end":"2026-07-21T13:14:40.481146Z","steps":["trace[1285312027] 'process raft request' (duration: 1.7056795s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:40.481261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:38.775065Z","time spent":"1.706144943s","remote":"127.0.0.1:41890","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-07-21T13:14:40.481398Z","caller":"traceutil/trace.go:171","msg":"trace[1713814655] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:644; }","duration":"2.457475456s","start":"2026-07-21T13:14:38.023888Z","end":"2026-07-21T13:14:40.481364Z","steps":["trace[1713814655] 'read index received' (duration: 2.344176994s)","trace[1713814655] 'applied index is now lower than readState.Index' (duration: 113.296232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:40.481543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.457648814s","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-07-21T13:14:40.481585Z","caller":"traceutil/trace.go:171","msg":"trace[412579977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"2.457718917s","start":"2026-07-21T13:14:38.023856Z","end":"2026-07-21T13:14:40.481575Z","steps":["trace[412579977] 'agreement among raft nodes before linearized reading' (duration: 2.457597682s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:40.481611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:38.023841Z","time spent":"2.45776328s","remote":"127.0.0.1:41890","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-07-21T13:14:40.481664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.74960802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T13:14:40.481704Z","caller":"traceutil/trace.go:171","msg":"trace[1826662770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"1.749707065s","start":"2026-07-21T13:14:38.731985Z","end":"2026-07-21T13:14:40.481692Z","steps":["trace[1826662770] 'agreement among raft nodes before linearized reading' (duration: 1.749527306s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:40.481749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:38.731924Z","time spent":"1.74981347s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T13:14:40.481814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.070734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:40.481844Z","caller":"traceutil/trace.go:171","msg":"trace[1727919903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"537.137348ms","start":"2026-07-21T13:14:39.944698Z","end":"2026-07-21T13:14:40.481836Z","steps":["trace[1727919903] 'agreement among raft nodes before linearized reading' (duration: 537.087935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:40.481867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:39.944681Z","time spent":"537.181589ms","remote":"127.0.0.1:41630","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-07-21T13:14:40.482081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536640619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:14:40.482129Z","caller":"traceutil/trace.go:171","msg":"trace[1190256278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"1.536728283s","start":"2026-07-21T13:14:38.945388Z","end":"2026-07-21T13:14:40.482116Z","steps":["trace[1190256278] 'agreement among raft nodes before linearized reading' (duration: 1.536599867s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:40.482167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:38.945364Z","time spent":"1.536792356s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T13:14:40.482223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536840908s","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-07-21T13:14:40.482247Z","caller":"traceutil/trace.go:171","msg":"trace[353740089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"1.536901521s","start":"2026-07-21T13:14:38.945338Z","end":"2026-07-21T13:14:40.48224Z","steps":["trace[353740089] 'agreement among raft nodes before linearized reading' (duration: 1.536821207s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:40.482265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:38.945321Z","time spent":"1.536939173s","remote":"127.0.0.1:41890","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-07-21T13:14:41.969212Z","caller":"traceutil/trace.go:171","msg":"trace[573597990] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"108.372515ms","start":"2026-07-21T13:14:41.860765Z","end":"2026-07-21T13:14:41.969137Z","steps":["trace[573597990] 'process raft request' (duration: 108.148874ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.139715Z","caller":"traceutil/trace.go:171","msg":"trace[963442274] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"146.574139ms","start":"2026-07-21T13:14:41.993119Z","end":"2026-07-21T13:14:42.139693Z","steps":["trace[963442274] 'process raft request' (duration: 124.889564ms)","trace[963442274] 'compare' (duration: 21.59356ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:42.234276Z","caller":"traceutil/trace.go:171","msg":"trace[2121850145] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"235.897998ms","start":"2026-07-21T13:14:41.998358Z","end":"2026-07-21T13:14:42.234256Z","steps":["trace[2121850145] 'process raft request' (duration: 235.685327ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.234303Z","caller":"traceutil/trace.go:171","msg":"trace[1367493962] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"235.373743ms","start":"2026-07-21T13:14:41.998904Z","end":"2026-07-21T13:14:42.234278Z","steps":["trace[1367493962] 'process raft request' (duration: 235.262276ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.234431Z","caller":"traceutil/trace.go:171","msg":"trace[416047305] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"232.045251ms","start":"2026-07-21T13:14:42.002378Z","end":"2026-07-21T13:14:42.234424Z","steps":["trace[416047305] 'process raft request' (duration: 231.849851ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.312421Z","caller":"traceutil/trace.go:171","msg":"trace[79834116] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"131.373965ms","start":"2026-07-21T13:14:42.181027Z","end":"2026-07-21T13:14:42.312401Z","steps":["trace[79834116] 'process raft request' (duration: 123.307677ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.372231Z","caller":"traceutil/trace.go:171","msg":"trace[1853264602] transaction","detail":"{read_only:false; number_of_response:0; response_revision:650; }","duration":"134.80227ms","start":"2026-07-21T13:14:42.237378Z","end":"2026-07-21T13:14:42.37218Z","steps":["trace[1853264602] 'process raft request' (duration: 134.622591ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.372334Z","caller":"traceutil/trace.go:171","msg":"trace[1031324031] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"132.500949ms","start":"2026-07-21T13:14:42.239782Z","end":"2026-07-21T13:14:42.372283Z","steps":["trace[1031324031] 'process raft request' (duration: 132.307649ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.372431Z","caller":"traceutil/trace.go:171","msg":"trace[1654917304] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"132.265187ms","start":"2026-07-21T13:14:42.240136Z","end":"2026-07-21T13:14:42.372401Z","steps":["trace[1654917304] 'process raft request' (duration: 132.021415ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:42.373168Z","caller":"traceutil/trace.go:171","msg":"trace[25347329] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"131.680158ms","start":"2026-07-21T13:14:42.24145Z","end":"2026-07-21T13:14:42.37313Z","steps":["trace[25347329] 'process raft request' (duration: 130.782715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:42.719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.355474ms","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-07-21T13:14:42.719173Z","caller":"traceutil/trace.go:171","msg":"trace[339418474] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:653; }","duration":"130.547133ms","start":"2026-07-21T13:14:42.5886Z","end":"2026-07-21T13:14:42.719147Z","steps":["trace[339418474] 'range keys from in-memory index tree' (duration: 130.334894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:42.719195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.228322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:42.71934Z","caller":"traceutil/trace.go:171","msg":"trace[812816804] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"345.709191ms","start":"2026-07-21T13:14:42.373608Z","end":"2026-07-21T13:14:42.719317Z","steps":["trace[812816804] 'read index received' (duration: 152.377615ms)","trace[812816804] 'applied index is now lower than readState.Index' (duration: 193.329886ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:42.719425Z","caller":"traceutil/trace.go:171","msg":"trace[484435007] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"382.546015ms","start":"2026-07-21T13:14:42.336794Z","end":"2026-07-21T13:14:42.71934Z","steps":["trace[484435007] 'process raft request' (duration: 189.096723ms)","trace[484435007] 'compare' (duration: 193.095016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:42.719505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.336765Z","time spent":"382.704543ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:14:42.71956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.938293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v2.cilium.io\" ","response":"range_response_count:1 size:947"} {"level":"info","ts":"2026-07-21T13:14:42.719594Z","caller":"traceutil/trace.go:171","msg":"trace[1334298443] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v2.cilium.io; range_end:; response_count:1; response_revision:654; }","duration":"346.009177ms","start":"2026-07-21T13:14:42.373574Z","end":"2026-07-21T13:14:42.719583Z","steps":["trace[1334298443] 'agreement among raft nodes before linearized reading' (duration: 345.834378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:42.719625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.37356Z","time spent":"346.057809ms","remote":"127.0.0.1:42136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":970,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v2.cilium.io\" "} {"level":"warn","ts":"2026-07-21T13:14:43.872964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.006556177s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:14:43.873194Z","caller":"traceutil/trace.go:171","msg":"trace[1316591863] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.494423031s","start":"2026-07-21T13:14:42.378747Z","end":"2026-07-21T13:14:43.87317Z","steps":["trace[1316591863] 'process raft request' (duration: 1.494307136s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.873275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.378737Z","time spent":"1.494512357s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:43.873265Z","caller":"traceutil/trace.go:171","msg":"trace[1796299063] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"1.49501371s","start":"2026-07-21T13:14:42.378206Z","end":"2026-07-21T13:14:43.87322Z","steps":["trace[1796299063] 'process raft request' (duration: 488.126418ms)","trace[1796299063] 'compare' (duration: 1.00643366s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:43.873536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.378139Z","time spent":"1.495241232s","remote":"127.0.0.1:42088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:43.873565Z","caller":"traceutil/trace.go:171","msg":"trace[1078238285] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.493008523s","start":"2026-07-21T13:14:42.380539Z","end":"2026-07-21T13:14:43.873547Z","steps":["trace[1078238285] 'process raft request' (duration: 1.492855106s)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:43.87354Z","caller":"traceutil/trace.go:171","msg":"trace[823735726] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.493182732s","start":"2026-07-21T13:14:42.380326Z","end":"2026-07-21T13:14:43.873509Z","steps":["trace[823735726] 'process raft request' (duration: 1.492773952s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.8737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.380314Z","time spent":"1.493321729s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:14:43.873709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.38051Z","time spent":"1.493113429s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:14:43.889896Z","caller":"traceutil/trace.go:171","msg":"trace[66148585] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.311701631s","start":"2026-07-21T13:14:42.578175Z","end":"2026-07-21T13:14:43.889876Z","steps":["trace[66148585] 'process raft request' (duration: 1.311517533s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.890007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.578149Z","time spent":"1.311807366s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:14:43.890294Z","caller":"traceutil/trace.go:171","msg":"trace[1647446110] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.224470692s","start":"2026-07-21T13:14:42.665815Z","end":"2026-07-21T13:14:43.890285Z","steps":["trace[1647446110] 'process raft request' (duration: 1.223966468s)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:43.890452Z","caller":"traceutil/trace.go:171","msg":"trace[259743119] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:683; }","duration":"1.171067881s","start":"2026-07-21T13:14:42.719375Z","end":"2026-07-21T13:14:43.890443Z","steps":["trace[259743119] 'read index received' (duration: 146.961865ms)","trace[259743119] 'applied index is now lower than readState.Index' (duration: 1.024105366s)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:43.890487Z","caller":"traceutil/trace.go:171","msg":"trace[896108643] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.111268263s","start":"2026-07-21T13:14:42.779197Z","end":"2026-07-21T13:14:43.890466Z","steps":["trace[896108643] 'process raft request' (duration: 1.11120852s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.890592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395437192s","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-07-21T13:14:43.8906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.779175Z","time spent":"1.111371047s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:14:43.890623Z","caller":"traceutil/trace.go:171","msg":"trace[1607286926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"1.395503775s","start":"2026-07-21T13:14:42.495111Z","end":"2026-07-21T13:14:43.890615Z","steps":["trace[1607286926] 'agreement among raft nodes before linearized reading' (duration: 1.39538021s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.890628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39539525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-21T13:14:43.890645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.495086Z","time spent":"1.395553228s","remote":"127.0.0.1:41890","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-07-21T13:14:43.890675Z","caller":"traceutil/trace.go:171","msg":"trace[116550936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"1.395490585s","start":"2026-07-21T13:14:42.495171Z","end":"2026-07-21T13:14:43.890662Z","steps":["trace[116550936] 'agreement among raft nodes before linearized reading' (duration: 1.395308626s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.89071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.495146Z","time spent":"1.395555258s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:14:43.890749Z","caller":"traceutil/trace.go:171","msg":"trace[312551511] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.129903849s","start":"2026-07-21T13:14:42.760834Z","end":"2026-07-21T13:14:43.890738Z","steps":["trace[312551511] 'process raft request' (duration: 1.129416506s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.890725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398637936s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-21T13:14:43.890802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.760809Z","time spent":"1.129969992s","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:14:43.890854Z","caller":"traceutil/trace.go:171","msg":"trace[422341292] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"1.398779893s","start":"2026-07-21T13:14:42.492038Z","end":"2026-07-21T13:14:43.890818Z","steps":["trace[422341292] 'agreement among raft nodes before linearized reading' (duration: 1.398484078s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.890904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39539807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T13:14:43.890932Z","caller":"traceutil/trace.go:171","msg":"trace[1584703526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:662; }","duration":"1.395443502s","start":"2026-07-21T13:14:42.495481Z","end":"2026-07-21T13:14:43.890925Z","steps":["trace[1584703526] 'agreement among raft nodes before linearized reading' (duration: 1.395377209s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:43.890906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.492014Z","time spent":"1.398881508s","remote":"127.0.0.1:41890","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-07-21T13:14:43.890952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.495473Z","time spent":"1.395472334s","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-21T13:14:43.891058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.665797Z","time spent":"1.224559676s","remote":"127.0.0.1:41890","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-07-21T13:14:44.085369Z","caller":"traceutil/trace.go:171","msg":"trace[988742164] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"204.496354ms","start":"2026-07-21T13:14:43.880838Z","end":"2026-07-21T13:14:44.085334Z","steps":["trace[988742164] 'process raft request' (duration: 129.464469ms)","trace[988742164] 'compare' (duration: 74.777232ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:44.085523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147004053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:44.085576Z","caller":"traceutil/trace.go:171","msg":"trace[405666811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.147107958s","start":"2026-07-21T13:14:42.938455Z","end":"2026-07-21T13:14:44.085563Z","steps":["trace[405666811] 'agreement among raft nodes before linearized reading' (duration: 1.147018224s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.085615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.938435Z","time spent":"1.147170341s","remote":"127.0.0.1:41630","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-07-21T13:14:44.085649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048029487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.119\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-21T13:14:44.085682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109208924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:44.085776Z","caller":"traceutil/trace.go:171","msg":"trace[84084735] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"203.606769ms","start":"2026-07-21T13:14:43.882156Z","end":"2026-07-21T13:14:44.085763Z","steps":["trace[84084735] 'process raft request' (duration: 203.051053ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:44.085805Z","caller":"traceutil/trace.go:171","msg":"trace[1580134986] range","detail":"{range_begin:/registry/masterleases/199.19.213.119; range_end:; response_count:1; response_revision:665; }","duration":"1.048234567s","start":"2026-07-21T13:14:43.037555Z","end":"2026-07-21T13:14:44.08579Z","steps":["trace[1580134986] 'agreement among raft nodes before linearized reading' (duration: 1.047939103s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.085864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:43.037531Z","time spent":"1.04832118s","remote":"127.0.0.1:41660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.119\" "} {"level":"info","ts":"2026-07-21T13:14:44.085425Z","caller":"traceutil/trace.go:171","msg":"trace[919075115] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:691; }","duration":"194.929585ms","start":"2026-07-21T13:14:43.890475Z","end":"2026-07-21T13:14:44.085405Z","steps":["trace[919075115] 'read index received' (duration: 119.840517ms)","trace[919075115] 'applied index is now lower than readState.Index' (duration: 75.088128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:14:44.086101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.422819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:44.086135Z","caller":"traceutil/trace.go:171","msg":"trace[1598320048] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:665; }","duration":"909.492792ms","start":"2026-07-21T13:14:43.176632Z","end":"2026-07-21T13:14:44.086125Z","steps":["trace[1598320048] 'agreement among raft nodes before linearized reading' (duration: 909.431879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.086164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:43.176615Z","time spent":"909.544175ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-07-21T13:14:44.086251Z","caller":"traceutil/trace.go:171","msg":"trace[282890104] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"203.811009ms","start":"2026-07-21T13:14:43.882428Z","end":"2026-07-21T13:14:44.086239Z","steps":["trace[282890104] 'process raft request' (duration: 202.934088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.086304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.884127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:44.08634Z","caller":"traceutil/trace.go:171","msg":"trace[163570953] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:665; }","duration":"709.012833ms","start":"2026-07-21T13:14:43.377319Z","end":"2026-07-21T13:14:44.086332Z","steps":["trace[163570953] 'agreement among raft nodes before linearized reading' (duration: 708.907248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.08641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:43.3773Z","time spent":"709.103006ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-21T13:14:44.086552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.133672591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-x46t2\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-07-21T13:14:44.086619Z","caller":"traceutil/trace.go:171","msg":"trace[1181002715] range","detail":"{range_begin:/registry/pods/kube-system/cilium-x46t2; range_end:; response_count:1; response_revision:665; }","duration":"1.133745214s","start":"2026-07-21T13:14:42.952825Z","end":"2026-07-21T13:14:44.086571Z","steps":["trace[1181002715] 'agreement among raft nodes before linearized reading' (duration: 1.133068171s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.086668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.95281Z","time spent":"1.133851309s","remote":"127.0.0.1:41808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17547,"request content":"key:\"/registry/pods/kube-system/cilium-x46t2\" "} {"level":"info","ts":"2026-07-21T13:14:44.08688Z","caller":"traceutil/trace.go:171","msg":"trace[1248532468] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"191.826655ms","start":"2026-07-21T13:14:43.895042Z","end":"2026-07-21T13:14:44.086868Z","steps":["trace[1248532468] 'process raft request' (duration: 191.681208ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:44.086938Z","caller":"traceutil/trace.go:171","msg":"trace[1068783133] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"190.742824ms","start":"2026-07-21T13:14:43.896109Z","end":"2026-07-21T13:14:44.086852Z","steps":["trace[1068783133] 'process raft request' (duration: 190.677931ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:44.08699Z","caller":"traceutil/trace.go:171","msg":"trace[11733699] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"192.1272ms","start":"2026-07-21T13:14:43.89483Z","end":"2026-07-21T13:14:44.086957Z","steps":["trace[11733699] 'process raft request' (duration: 191.241069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.086621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.166134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-07-21T13:14:44.087517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.48227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-07-21T13:14:44.08756Z","caller":"traceutil/trace.go:171","msg":"trace[2000418801] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:669; }","duration":"110.562694ms","start":"2026-07-21T13:14:43.976986Z","end":"2026-07-21T13:14:44.087549Z","steps":["trace[2000418801] 'agreement among raft nodes before linearized reading' (duration: 110.330332ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:44.087647Z","caller":"traceutil/trace.go:171","msg":"trace[2108270736] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"191.481859ms","start":"2026-07-21T13:14:43.896152Z","end":"2026-07-21T13:14:44.087633Z","steps":["trace[2108270736] 'process raft request' (duration: 190.67413ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:14:44.087713Z","caller":"traceutil/trace.go:171","msg":"trace[1735161376] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:665; }","duration":"310.258797ms","start":"2026-07-21T13:14:43.777202Z","end":"2026-07-21T13:14:44.087461Z","steps":["trace[1735161376] 'agreement among raft nodes before linearized reading' (duration: 309.036108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.087974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:43.777187Z","time spent":"310.769031ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-07-21T13:14:44.085793Z","caller":"traceutil/trace.go:171","msg":"trace[1867415669] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:665; }","duration":"1.109371001s","start":"2026-07-21T13:14:42.976404Z","end":"2026-07-21T13:14:44.085775Z","steps":["trace[1867415669] 'agreement among raft nodes before linearized reading' (duration: 1.109176362s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.088952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:42.97634Z","time spent":"1.112593976s","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-21T13:14:44.086581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.411501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:44.08918Z","caller":"traceutil/trace.go:171","msg":"trace[1784115676] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:665; }","duration":"513.026845ms","start":"2026-07-21T13:14:43.576133Z","end":"2026-07-21T13:14:44.08916Z","steps":["trace[1784115676] 'agreement among raft nodes before linearized reading' (duration: 510.242843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:44.089737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:43.576117Z","time spent":"513.601193ms","remote":"127.0.0.1:41688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-21T13:14:54.293318Z","caller":"traceutil/trace.go:171","msg":"trace[1110737170] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:793; }","duration":"101.651298ms","start":"2026-07-21T13:14:54.191641Z","end":"2026-07-21T13:14:54.293292Z","steps":["trace[1110737170] 'read index received' (duration: 35.316097ms)","trace[1110737170] 'applied index is now lower than readState.Index' (duration: 66.333901ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:54.293463Z","caller":"traceutil/trace.go:171","msg":"trace[438486609] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"147.501411ms","start":"2026-07-21T13:14:54.145927Z","end":"2026-07-21T13:14:54.293429Z","steps":["trace[438486609] 'process raft request' (duration: 147.23531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:54.293738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.070797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-2jl92\" ","response":"range_response_count:1 size:4741"} {"level":"info","ts":"2026-07-21T13:14:54.294022Z","caller":"traceutil/trace.go:171","msg":"trace[970566993] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-2jl92; range_end:; response_count:1; response_revision:763; }","duration":"102.398552ms","start":"2026-07-21T13:14:54.191609Z","end":"2026-07-21T13:14:54.294008Z","steps":["trace[970566993] 'agreement among raft nodes before linearized reading' (duration: 101.983832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:58.74693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334601084443087,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T13:14:59.09227Z","caller":"traceutil/trace.go:171","msg":"trace[23049230] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"934.595298ms","start":"2026-07-21T13:14:58.15765Z","end":"2026-07-21T13:14:59.092246Z","steps":["trace[23049230] 'process raft request' (duration: 934.413839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:59.09308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:58.157629Z","time spent":"934.722683ms","remote":"127.0.0.1:41890","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-07-21T13:14:59.102874Z","caller":"traceutil/trace.go:171","msg":"trace[961698817] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"856.720453ms","start":"2026-07-21T13:14:58.246136Z","end":"2026-07-21T13:14:59.102856Z","steps":["trace[961698817] 'read index received' (duration: 845.913266ms)","trace[961698817] 'applied index is now lower than readState.Index' (duration: 10.806087ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:14:59.103036Z","caller":"traceutil/trace.go:171","msg":"trace[357341526] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"884.288995ms","start":"2026-07-21T13:14:58.218719Z","end":"2026-07-21T13:14:59.103008Z","steps":["trace[357341526] 'process raft request' (duration: 884.052055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:59.103134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.990066ms","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-07-21T13:14:59.103181Z","caller":"traceutil/trace.go:171","msg":"trace[1937207335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"857.064279ms","start":"2026-07-21T13:14:58.246104Z","end":"2026-07-21T13:14:59.103168Z","steps":["trace[1937207335] 'agreement among raft nodes before linearized reading' (duration: 856.934663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:59.103207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:58.218699Z","time spent":"884.40647ms","remote":"127.0.0.1:41868","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-07-21T13:14:59.103216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:58.246089Z","time spent":"857.11877ms","remote":"127.0.0.1:41890","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-07-21T13:14:59.103415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.763522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:14:59.103448Z","caller":"traceutil/trace.go:171","msg":"trace[1237232085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"164.820074ms","start":"2026-07-21T13:14:58.938618Z","end":"2026-07-21T13:14:59.103438Z","steps":["trace[1237232085] 'agreement among raft nodes before linearized reading' (duration: 164.759332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:59.103642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.228046ms","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-07-21T13:14:59.103717Z","caller":"traceutil/trace.go:171","msg":"trace[1218291677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"857.563601ms","start":"2026-07-21T13:14:58.246141Z","end":"2026-07-21T13:14:59.103705Z","steps":["trace[1218291677] 'agreement among raft nodes before linearized reading' (duration: 857.161282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:59.103845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:58.24613Z","time spent":"857.702377ms","remote":"127.0.0.1:41890","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-07-21T13:14:59.104154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.845195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T13:14:59.104223Z","caller":"traceutil/trace.go:171","msg":"trace[1326364867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:788; }","duration":"779.928549ms","start":"2026-07-21T13:14:58.324258Z","end":"2026-07-21T13:14:59.104187Z","steps":["trace[1326364867] 'agreement among raft nodes before linearized reading' (duration: 779.174486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:59.104249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:58.324235Z","time spent":"780.008263ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T13:14:59.104414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.821572ms","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-07-21T13:14:59.104524Z","caller":"traceutil/trace.go:171","msg":"trace[1296001231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"790.977729ms","start":"2026-07-21T13:14:58.313533Z","end":"2026-07-21T13:14:59.104511Z","steps":["trace[1296001231] 'agreement among raft nodes before linearized reading' (duration: 790.018917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:14:59.104653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:14:58.313504Z","time spent":"791.114215ms","remote":"127.0.0.1:41890","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-07-21T13:15:04.358719Z","caller":"traceutil/trace.go:171","msg":"trace[1328925018] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"111.229524ms","start":"2026-07-21T13:15:04.247467Z","end":"2026-07-21T13:15:04.358697Z","steps":["trace[1328925018] 'process raft request' (duration: 111.082648ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:05.380852Z","caller":"traceutil/trace.go:171","msg":"trace[1311065673] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"183.773476ms","start":"2026-07-21T13:15:05.19705Z","end":"2026-07-21T13:15:05.380823Z","steps":["trace[1311065673] 'process raft request' (duration: 111.740743ms)","trace[1311065673] 'compare' (duration: 71.892238ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:15:05.382563Z","caller":"traceutil/trace.go:171","msg":"trace[272632757] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"184.227625ms","start":"2026-07-21T13:15:05.198312Z","end":"2026-07-21T13:15:05.38254Z","steps":["trace[272632757] 'process raft request' (duration: 184.190064ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:05.382595Z","caller":"traceutil/trace.go:171","msg":"trace[545657219] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"185.125913ms","start":"2026-07-21T13:15:05.197441Z","end":"2026-07-21T13:15:05.382567Z","steps":["trace[545657219] 'process raft request' (duration: 184.885014ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:05.382612Z","caller":"traceutil/trace.go:171","msg":"trace[1712304339] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"184.645633ms","start":"2026-07-21T13:15:05.197947Z","end":"2026-07-21T13:15:05.382593Z","steps":["trace[1712304339] 'process raft request' (duration: 184.517957ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:05.683199Z","caller":"traceutil/trace.go:171","msg":"trace[2104036804] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"344.519687ms","start":"2026-07-21T13:15:05.338652Z","end":"2026-07-21T13:15:05.683171Z","steps":["trace[2104036804] 'process raft request' (duration: 262.935434ms)","trace[2104036804] 'compare' (duration: 80.977667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:05.683327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:05.338628Z","time spent":"344.629792ms","remote":"127.0.0.1:41890","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-07-21T13:15:06.025221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.7179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4919"} {"level":"info","ts":"2026-07-21T13:15:06.025308Z","caller":"traceutil/trace.go:171","msg":"trace[2142312276] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:858; }","duration":"180.853286ms","start":"2026-07-21T13:15:05.844436Z","end":"2026-07-21T13:15:06.025289Z","steps":["trace[2142312276] 'range keys from in-memory index tree' (duration: 180.536463ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:06.299532Z","caller":"traceutil/trace.go:171","msg":"trace[1179418045] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"206.793147ms","start":"2026-07-21T13:15:06.092721Z","end":"2026-07-21T13:15:06.299514Z","steps":["trace[1179418045] 'process raft request' (duration: 206.666012ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:06.306535Z","caller":"traceutil/trace.go:171","msg":"trace[1616899194] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"213.728263ms","start":"2026-07-21T13:15:06.092785Z","end":"2026-07-21T13:15:06.306513Z","steps":["trace[1616899194] 'process raft request' (duration: 213.510974ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:06.306729Z","caller":"traceutil/trace.go:171","msg":"trace[1956957715] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"209.408849ms","start":"2026-07-21T13:15:06.097308Z","end":"2026-07-21T13:15:06.306717Z","steps":["trace[1956957715] 'process raft request' (duration: 209.120327ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:12.847132Z","caller":"traceutil/trace.go:171","msg":"trace[131407067] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"119.025302ms","start":"2026-07-21T13:15:12.728082Z","end":"2026-07-21T13:15:12.847107Z","steps":["trace[131407067] 'process raft request' (duration: 115.431844ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:12.847283Z","caller":"traceutil/trace.go:171","msg":"trace[2095907697] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"119.152739ms","start":"2026-07-21T13:15:12.728108Z","end":"2026-07-21T13:15:12.847261Z","steps":["trace[2095907697] 'process raft request' (duration: 118.931109ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:12.84928Z","caller":"traceutil/trace.go:171","msg":"trace[1431051126] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:982; }","duration":"121.013535ms","start":"2026-07-21T13:15:12.728246Z","end":"2026-07-21T13:15:12.84926Z","steps":["trace[1431051126] 'read index received' (duration: 87.570612ms)","trace[1431051126] 'applied index is now lower than readState.Index' (duration: 33.439603ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:12.849503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.246324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-2jl92\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-07-21T13:15:12.849537Z","caller":"traceutil/trace.go:171","msg":"trace[1942973222] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-2jl92; range_end:; response_count:1; response_revision:948; }","duration":"121.38377ms","start":"2026-07-21T13:15:12.728143Z","end":"2026-07-21T13:15:12.849527Z","steps":["trace[1942973222] 'agreement among raft nodes before linearized reading' (duration: 121.221234ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:13.042944Z","caller":"traceutil/trace.go:171","msg":"trace[1514705495] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:985; }","duration":"107.830622ms","start":"2026-07-21T13:15:12.935088Z","end":"2026-07-21T13:15:13.042919Z","steps":["trace[1514705495] 'read index received' (duration: 2.680371ms)","trace[1514705495] 'applied index is now lower than readState.Index' (duration: 105.149021ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:15:13.043019Z","caller":"traceutil/trace.go:171","msg":"trace[1345674501] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"183.106725ms","start":"2026-07-21T13:15:12.859868Z","end":"2026-07-21T13:15:13.042975Z","steps":["trace[1345674501] 'process raft request' (duration: 179.676454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:13.043102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.995608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:13.043136Z","caller":"traceutil/trace.go:171","msg":"trace[415088632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"108.069881ms","start":"2026-07-21T13:15:12.935057Z","end":"2026-07-21T13:15:13.043127Z","steps":["trace[415088632] 'agreement among raft nodes before linearized reading' (duration: 107.989768ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:13.517929Z","caller":"traceutil/trace.go:171","msg":"trace[537672728] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"168.376782ms","start":"2026-07-21T13:15:13.349532Z","end":"2026-07-21T13:15:13.517909Z","steps":["trace[537672728] 'process raft request' (duration: 137.103274ms)","trace[537672728] 'compare' (duration: 30.938453ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:15:13.625631Z","caller":"traceutil/trace.go:171","msg":"trace[959871728] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"102.434255ms","start":"2026-07-21T13:15:13.523171Z","end":"2026-07-21T13:15:13.625606Z","steps":["trace[959871728] 'process raft request' (duration: 97.684481ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:13.626141Z","caller":"traceutil/trace.go:171","msg":"trace[299202696] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"100.037668ms","start":"2026-07-21T13:15:13.526085Z","end":"2026-07-21T13:15:13.626123Z","steps":["trace[299202696] 'process raft request' (duration: 99.783878ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:14.506491Z","caller":"traceutil/trace.go:171","msg":"trace[747438080] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"149.356289ms","start":"2026-07-21T13:15:14.357117Z","end":"2026-07-21T13:15:14.506473Z","steps":["trace[747438080] 'process raft request' (duration: 144.2883ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:14.673225Z","caller":"traceutil/trace.go:171","msg":"trace[1165336615] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"300.896456ms","start":"2026-07-21T13:15:14.372302Z","end":"2026-07-21T13:15:14.673198Z","steps":["trace[1165336615] 'process raft request' (duration: 240.014012ms)","trace[1165336615] 'compare' (duration: 60.719767ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:14.673374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:14.372283Z","time spent":"301.01328ms","remote":"127.0.0.1:41890","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-07-21T13:15:14.674293Z","caller":"traceutil/trace.go:171","msg":"trace[247879927] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"162.302078ms","start":"2026-07-21T13:15:14.511972Z","end":"2026-07-21T13:15:14.674274Z","steps":["trace[247879927] 'process raft request' (duration: 162.178523ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:15.902017Z","caller":"traceutil/trace.go:171","msg":"trace[264366545] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"118.602434ms","start":"2026-07-21T13:15:15.783384Z","end":"2026-07-21T13:15:15.901987Z","steps":["trace[264366545] 'process raft request' (duration: 118.454539ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:18.107137Z","caller":"traceutil/trace.go:171","msg":"trace[786291951] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"199.305578ms","start":"2026-07-21T13:15:17.907814Z","end":"2026-07-21T13:15:18.10712Z","steps":["trace[786291951] 'process raft request' (duration: 199.151041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:18.567169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.405141ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T13:15:18.567289Z","caller":"traceutil/trace.go:171","msg":"trace[1445987023] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"102.419015ms","start":"2026-07-21T13:15:18.464846Z","end":"2026-07-21T13:15:18.567265Z","steps":["trace[1445987023] 'read index received' (duration: 34.711µs)","trace[1445987023] 'applied index is now lower than readState.Index' (duration: 102.382124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:18.567381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.521209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:18.567416Z","caller":"traceutil/trace.go:171","msg":"trace[220983794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"102.593802ms","start":"2026-07-21T13:15:18.464813Z","end":"2026-07-21T13:15:18.567407Z","steps":["trace[220983794] 'agreement among raft nodes before linearized reading' (duration: 102.517759ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:19.795016Z","caller":"traceutil/trace.go:171","msg":"trace[1297143186] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"199.76983ms","start":"2026-07-21T13:15:19.595218Z","end":"2026-07-21T13:15:19.794988Z","steps":["trace[1297143186] 'process raft request' (duration: 199.418216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:20.133071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.434152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:15:20.133199Z","caller":"traceutil/trace.go:171","msg":"trace[397810697] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"333.000294ms","start":"2026-07-21T13:15:19.800181Z","end":"2026-07-21T13:15:20.133181Z","steps":["trace[397810697] 'process raft request' (duration: 144.361913ms)","trace[397810697] 'compare' (duration: 188.157161ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:20.133246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:19.800154Z","time spent":"333.075007ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:15:20.161318Z","caller":"traceutil/trace.go:171","msg":"trace[1051012183] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1020; }","duration":"221.152755ms","start":"2026-07-21T13:15:19.940144Z","end":"2026-07-21T13:15:20.161296Z","steps":["trace[1051012183] 'read index received' (duration: 4.420677ms)","trace[1051012183] 'applied index is now lower than readState.Index' (duration: 216.731358ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:15:20.161364Z","caller":"traceutil/trace.go:171","msg":"trace[1867599237] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"360.29198ms","start":"2026-07-21T13:15:19.801041Z","end":"2026-07-21T13:15:20.161333Z","steps":["trace[1867599237] 'process raft request' (duration: 360.06533ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:20.161513Z","caller":"traceutil/trace.go:171","msg":"trace[937889584] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"360.388353ms","start":"2026-07-21T13:15:19.801091Z","end":"2026-07-21T13:15:20.161479Z","steps":["trace[937889584] 'process raft request' (duration: 360.151494ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:20.16156Z","caller":"traceutil/trace.go:171","msg":"trace[546634694] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"360.039279ms","start":"2026-07-21T13:15:19.801513Z","end":"2026-07-21T13:15:20.161552Z","steps":["trace[546634694] 'process raft request' (duration: 359.756198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:20.161615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:19.801056Z","time spent":"360.510708ms","remote":"127.0.0.1:41890","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-07-21T13:15:20.161623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:19.801017Z","time spent":"360.424315ms","remote":"127.0.0.1:41890","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-07-21T13:15:20.16167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:19.801503Z","time spent":"360.119322ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:15:20.161427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.260299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:20.161758Z","caller":"traceutil/trace.go:171","msg":"trace[835626344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"221.621253ms","start":"2026-07-21T13:15:19.940109Z","end":"2026-07-21T13:15:20.16173Z","steps":["trace[835626344] 'agreement among raft nodes before linearized reading' (duration: 221.259809ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:26.346645Z","caller":"traceutil/trace.go:171","msg":"trace[854424771] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"163.28489ms","start":"2026-07-21T13:15:26.183337Z","end":"2026-07-21T13:15:26.346622Z","steps":["trace[854424771] 'read index received' (duration: 163.156325ms)","trace[854424771] 'applied index is now lower than readState.Index' (duration: 127.545µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:15:26.346758Z","caller":"traceutil/trace.go:171","msg":"trace[1933526065] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"174.968492ms","start":"2026-07-21T13:15:26.171721Z","end":"2026-07-21T13:15:26.346689Z","steps":["trace[1933526065] 'process raft request' (duration: 174.719402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:26.346877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.510759ms","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-07-21T13:15:26.346921Z","caller":"traceutil/trace.go:171","msg":"trace[444403205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"163.572751ms","start":"2026-07-21T13:15:26.183334Z","end":"2026-07-21T13:15:26.346907Z","steps":["trace[444403205] 'agreement among raft nodes before linearized reading' (duration: 163.412065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:26.350112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.680732ms","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-07-21T13:15:26.350198Z","caller":"traceutil/trace.go:171","msg":"trace[581512269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"166.789676ms","start":"2026-07-21T13:15:26.18339Z","end":"2026-07-21T13:15:26.35018Z","steps":["trace[581512269] 'agreement among raft nodes before linearized reading' (duration: 166.584957ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:26.350272Z","caller":"traceutil/trace.go:171","msg":"trace[1992343990] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"131.453818ms","start":"2026-07-21T13:15:26.218712Z","end":"2026-07-21T13:15:26.350165Z","steps":["trace[1992343990] 'process raft request' (duration: 131.171166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:26.350516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.525396ms","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-07-21T13:15:26.35056Z","caller":"traceutil/trace.go:171","msg":"trace[328289235] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"165.584868ms","start":"2026-07-21T13:15:26.184963Z","end":"2026-07-21T13:15:26.350548Z","steps":["trace[328289235] 'agreement among raft nodes before linearized reading' (duration: 165.463093ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:26.484132Z","caller":"traceutil/trace.go:171","msg":"trace[642397171] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"133.3357ms","start":"2026-07-21T13:15:26.35077Z","end":"2026-07-21T13:15:26.484105Z","steps":["trace[642397171] 'process raft request' (duration: 127.866538ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:26.55874Z","caller":"traceutil/trace.go:171","msg":"trace[422746121] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"202.522818ms","start":"2026-07-21T13:15:26.356199Z","end":"2026-07-21T13:15:26.558722Z","steps":["trace[422746121] 'process raft request' (duration: 202.488317ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:26.558907Z","caller":"traceutil/trace.go:171","msg":"trace[1294934314] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"202.731197ms","start":"2026-07-21T13:15:26.356159Z","end":"2026-07-21T13:15:26.55889Z","steps":["trace[1294934314] 'process raft request' (duration: 202.429804ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:38.605499Z","caller":"traceutil/trace.go:171","msg":"trace[1503501736] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"207.434932ms","start":"2026-07-21T13:15:38.398035Z","end":"2026-07-21T13:15:38.60547Z","steps":["trace[1503501736] 'read index received' (duration: 207.313088ms)","trace[1503501736] 'applied index is now lower than readState.Index' (duration: 120.554µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:15:38.605549Z","caller":"traceutil/trace.go:171","msg":"trace[897994212] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"209.637623ms","start":"2026-07-21T13:15:38.395883Z","end":"2026-07-21T13:15:38.60552Z","steps":["trace[897994212] 'process raft request' (duration: 209.448545ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:38.605727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.66935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:15:38.605769Z","caller":"traceutil/trace.go:171","msg":"trace[1786845817] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"207.742543ms","start":"2026-07-21T13:15:38.398014Z","end":"2026-07-21T13:15:38.605756Z","steps":["trace[1786845817] 'agreement among raft nodes before linearized reading' (duration: 207.588828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:39.121303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.300889ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T13:15:39.121593Z","caller":"traceutil/trace.go:171","msg":"trace[1277393231] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"511.563968ms","start":"2026-07-21T13:15:38.610009Z","end":"2026-07-21T13:15:39.121573Z","steps":["trace[1277393231] 'process raft request' (duration: 511.422003ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:39.121599Z","caller":"traceutil/trace.go:171","msg":"trace[134372759] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"515.99304ms","start":"2026-07-21T13:15:38.605578Z","end":"2026-07-21T13:15:39.121571Z","steps":["trace[134372759] 'read index received' (duration: 198.412731ms)","trace[134372759] 'applied index is now lower than readState.Index' (duration: 317.575999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:15:39.12169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:38.60998Z","time spent":"511.650931ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:15:39.12173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.366323ms","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-07-21T13:15:39.121766Z","caller":"traceutil/trace.go:171","msg":"trace[1604094529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"573.435966ms","start":"2026-07-21T13:15:38.548321Z","end":"2026-07-21T13:15:39.121757Z","steps":["trace[1604094529] 'agreement among raft nodes before linearized reading' (duration: 573.295951ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:39.121759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.61248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:15:39.121787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.261388ms","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-07-21T13:15:39.12179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:38.548305Z","time spent":"573.478747ms","remote":"127.0.0.1:41890","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-07-21T13:15:39.121796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.322644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:39.121815Z","caller":"traceutil/trace.go:171","msg":"trace[1743639186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"463.33761ms","start":"2026-07-21T13:15:38.658468Z","end":"2026-07-21T13:15:39.121806Z","steps":["trace[1743639186] 'agreement among raft nodes before linearized reading' (duration: 463.241187ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:15:39.121816Z","caller":"traceutil/trace.go:171","msg":"trace[1763865526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"672.704763ms","start":"2026-07-21T13:15:38.449097Z","end":"2026-07-21T13:15:39.121802Z","steps":["trace[1763865526] 'agreement among raft nodes before linearized reading' (duration: 672.60382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:39.121803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.253233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:15:39.121832Z","caller":"traceutil/trace.go:171","msg":"trace[936931138] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1047; }","duration":"399.387767ms","start":"2026-07-21T13:15:38.722433Z","end":"2026-07-21T13:15:39.121821Z","steps":["trace[936931138] 'agreement among raft nodes before linearized reading' (duration: 399.321025ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:39.121838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:38.658441Z","time spent":"463.392152ms","remote":"127.0.0.1:41890","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-07-21T13:15:39.121851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:38.449082Z","time spent":"672.763726ms","remote":"127.0.0.1:41630","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-07-21T13:15:39.121863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:38.722413Z","time spent":"399.444088ms","remote":"127.0.0.1:41782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-07-21T13:15:39.121871Z","caller":"traceutil/trace.go:171","msg":"trace[683564965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"184.352787ms","start":"2026-07-21T13:15:38.937499Z","end":"2026-07-21T13:15:39.121852Z","steps":["trace[683564965] 'agreement among raft nodes before linearized reading' (duration: 184.222922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:39.121906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.658846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T13:15:39.121929Z","caller":"traceutil/trace.go:171","msg":"trace[835484266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"459.722757ms","start":"2026-07-21T13:15:38.662199Z","end":"2026-07-21T13:15:39.121922Z","steps":["trace[835484266] 'agreement among raft nodes before linearized reading' (duration: 459.576752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:15:39.121977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:15:38.662176Z","time spent":"459.79463ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T13:15:41.348819Z","caller":"traceutil/trace.go:171","msg":"trace[2111604835] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"153.669927ms","start":"2026-07-21T13:15:41.195119Z","end":"2026-07-21T13:15:41.348789Z","steps":["trace[2111604835] 'process raft request' (duration: 153.498081ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:11.435483Z","caller":"traceutil/trace.go:171","msg":"trace[692950846] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"114.970906ms","start":"2026-07-21T13:16:11.320473Z","end":"2026-07-21T13:16:11.435444Z","steps":["trace[692950846] 'process raft request' (duration: 85.571248ms)","trace[692950846] 'compare' (duration: 29.165111ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:13.520728Z","caller":"traceutil/trace.go:171","msg":"trace[1916407541] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"185.107127ms","start":"2026-07-21T13:16:13.335592Z","end":"2026-07-21T13:16:13.520699Z","steps":["trace[1916407541] 'process raft request' (duration: 184.943382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:13.733419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.914506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:16:13.733689Z","caller":"traceutil/trace.go:171","msg":"trace[2054900143] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"209.378596ms","start":"2026-07-21T13:16:13.524295Z","end":"2026-07-21T13:16:13.733674Z","steps":["trace[2054900143] 'process raft request' (duration: 88.109338ms)","trace[2054900143] 'compare' (duration: 120.779892ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:13.733763Z","caller":"traceutil/trace.go:171","msg":"trace[348774480] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"208.317802ms","start":"2026-07-21T13:16:13.525392Z","end":"2026-07-21T13:16:13.73371Z","steps":["trace[348774480] 'process raft request' (duration: 208.275221ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:13.73386Z","caller":"traceutil/trace.go:171","msg":"trace[543519264] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"209.381265ms","start":"2026-07-21T13:16:13.524474Z","end":"2026-07-21T13:16:13.733855Z","steps":["trace[543519264] 'process raft request' (duration: 209.096377ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:17.890627Z","caller":"traceutil/trace.go:171","msg":"trace[1901602542] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"126.859652ms","start":"2026-07-21T13:16:17.763741Z","end":"2026-07-21T13:16:17.890601Z","steps":["trace[1901602542] 'read index received' (duration: 126.706438ms)","trace[1901602542] 'applied index is now lower than readState.Index' (duration: 152.364µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:17.890689Z","caller":"traceutil/trace.go:171","msg":"trace[1299000405] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"128.453842ms","start":"2026-07-21T13:16:17.762201Z","end":"2026-07-21T13:16:17.890654Z","steps":["trace[1299000405] 'process raft request' (duration: 128.202534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:17.890839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.072869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-07-21T13:16:17.890902Z","caller":"traceutil/trace.go:171","msg":"trace[1937307896] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1174; }","duration":"127.189983ms","start":"2026-07-21T13:16:17.763701Z","end":"2026-07-21T13:16:17.890891Z","steps":["trace[1937307896] 'agreement among raft nodes before linearized reading' (duration: 127.025807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:17.893284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.503431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:16:17.893325Z","caller":"traceutil/trace.go:171","msg":"trace[1733586309] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"129.136893ms","start":"2026-07-21T13:16:17.76415Z","end":"2026-07-21T13:16:17.893286Z","steps":["trace[1733586309] 'process raft request' (duration: 128.964578ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:17.893377Z","caller":"traceutil/trace.go:171","msg":"trace[1507294783] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1176; }","duration":"125.629774ms","start":"2026-07-21T13:16:17.767723Z","end":"2026-07-21T13:16:17.893353Z","steps":["trace[1507294783] 'agreement among raft nodes before linearized reading' (duration: 125.429828ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:17.893511Z","caller":"traceutil/trace.go:171","msg":"trace[1053371808] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"129.408891ms","start":"2026-07-21T13:16:17.764086Z","end":"2026-07-21T13:16:17.893495Z","steps":["trace[1053371808] 'process raft request' (duration: 128.892186ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:22.223271Z","caller":"traceutil/trace.go:171","msg":"trace[453342042] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"229.337391ms","start":"2026-07-21T13:16:21.993907Z","end":"2026-07-21T13:16:22.223245Z","steps":["trace[453342042] 'process raft request' (duration: 225.330558ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:22.223512Z","caller":"traceutil/trace.go:171","msg":"trace[1662336391] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"227.723301ms","start":"2026-07-21T13:16:21.995755Z","end":"2026-07-21T13:16:22.223478Z","steps":["trace[1662336391] 'process raft request' (duration: 227.427512ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:24.174079Z","caller":"traceutil/trace.go:171","msg":"trace[2016512593] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"139.315716ms","start":"2026-07-21T13:16:24.034728Z","end":"2026-07-21T13:16:24.174044Z","steps":["trace[2016512593] 'process raft request' (duration: 137.721428ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:26.257693Z","caller":"traceutil/trace.go:171","msg":"trace[1372156447] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"217.768636ms","start":"2026-07-21T13:16:26.039886Z","end":"2026-07-21T13:16:26.257654Z","steps":["trace[1372156447] 'process raft request' (duration: 217.560779ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:26.525254Z","caller":"traceutil/trace.go:171","msg":"trace[619773254] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"263.300057ms","start":"2026-07-21T13:16:26.261923Z","end":"2026-07-21T13:16:26.525223Z","steps":["trace[619773254] 'process raft request' (duration: 179.287108ms)","trace[619773254] 'compare' (duration: 83.411611ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:26.525537Z","caller":"traceutil/trace.go:171","msg":"trace[490029583] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"263.250025ms","start":"2026-07-21T13:16:26.262268Z","end":"2026-07-21T13:16:26.525518Z","steps":["trace[490029583] 'process raft request' (duration: 262.476162ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:26.525671Z","caller":"traceutil/trace.go:171","msg":"trace[953646834] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"188.670109ms","start":"2026-07-21T13:16:26.336987Z","end":"2026-07-21T13:16:26.525657Z","steps":["trace[953646834] 'process raft request' (duration: 187.801383ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.179042Z","caller":"traceutil/trace.go:171","msg":"trace[1285463850] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"242.06723ms","start":"2026-07-21T13:16:32.936942Z","end":"2026-07-21T13:16:33.179009Z","steps":["trace[1285463850] 'read index received' (duration: 241.899835ms)","trace[1285463850] 'applied index is now lower than readState.Index' (duration: 165.935µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:33.179244Z","caller":"traceutil/trace.go:171","msg":"trace[1736865832] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"256.623638ms","start":"2026-07-21T13:16:32.922604Z","end":"2026-07-21T13:16:33.179227Z","steps":["trace[1736865832] 'process raft request' (duration: 256.244108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.180115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.518979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:16:33.18026Z","caller":"traceutil/trace.go:171","msg":"trace[64258797] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1223; }","duration":"130.721866ms","start":"2026-07-21T13:16:33.049513Z","end":"2026-07-21T13:16:33.180235Z","steps":["trace[64258797] 'agreement among raft nodes before linearized reading' (duration: 130.044126ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:33.181193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.252525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:16:33.181264Z","caller":"traceutil/trace.go:171","msg":"trace[469030678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"244.321267ms","start":"2026-07-21T13:16:32.936924Z","end":"2026-07-21T13:16:33.181246Z","steps":["trace[469030678] 'agreement among raft nodes before linearized reading' (duration: 244.219374ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.418778Z","caller":"traceutil/trace.go:171","msg":"trace[1171703869] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"236.262713ms","start":"2026-07-21T13:16:33.182484Z","end":"2026-07-21T13:16:33.418747Z","steps":["trace[1171703869] 'read index received' (duration: 148.188198ms)","trace[1171703869] 'applied index is now lower than readState.Index' (duration: 88.072715ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:16:33.418939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.434399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:16:33.419238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.807218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:1 size:1086"} {"level":"info","ts":"2026-07-21T13:16:33.419294Z","caller":"traceutil/trace.go:171","msg":"trace[1336097807] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:1; response_revision:1223; }","duration":"193.914091ms","start":"2026-07-21T13:16:33.225367Z","end":"2026-07-21T13:16:33.419281Z","steps":["trace[1336097807] 'agreement among raft nodes before linearized reading' (duration: 193.748076ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:33.419302Z","caller":"traceutil/trace.go:171","msg":"trace[1264342118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"236.527791ms","start":"2026-07-21T13:16:33.182439Z","end":"2026-07-21T13:16:33.418966Z","steps":["trace[1264342118] 'agreement among raft nodes before linearized reading' (duration: 236.441918ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:34.544901Z","caller":"traceutil/trace.go:171","msg":"trace[2020913072] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"158.67483ms","start":"2026-07-21T13:16:34.386205Z","end":"2026-07-21T13:16:34.54488Z","steps":["trace[2020913072] 'process raft request' (duration: 109.731389ms)","trace[2020913072] 'compare' (duration: 48.846308ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:34.780824Z","caller":"traceutil/trace.go:171","msg":"trace[1181665707] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"199.844984ms","start":"2026-07-21T13:16:34.580951Z","end":"2026-07-21T13:16:34.780796Z","steps":["trace[1181665707] 'process raft request' (duration: 198.894545ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:34.783624Z","caller":"traceutil/trace.go:171","msg":"trace[1087426832] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"202.041078ms","start":"2026-07-21T13:16:34.581562Z","end":"2026-07-21T13:16:34.783603Z","steps":["trace[1087426832] 'process raft request' (duration: 201.936425ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:37.335867Z","caller":"traceutil/trace.go:171","msg":"trace[5037965] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"128.012772ms","start":"2026-07-21T13:16:37.207824Z","end":"2026-07-21T13:16:37.335837Z","steps":["trace[5037965] 'process raft request' (duration: 127.817176ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:40.614459Z","caller":"traceutil/trace.go:171","msg":"trace[1396219793] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"210.720221ms","start":"2026-07-21T13:16:40.403699Z","end":"2026-07-21T13:16:40.614419Z","steps":["trace[1396219793] 'process raft request' (duration: 210.496855ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:40.762012Z","caller":"traceutil/trace.go:171","msg":"trace[1975063275] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"144.037383ms","start":"2026-07-21T13:16:40.61794Z","end":"2026-07-21T13:16:40.761978Z","steps":["trace[1975063275] 'process raft request' (duration: 119.080499ms)","trace[1975063275] 'compare' (duration: 24.787029ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:41.00903Z","caller":"traceutil/trace.go:171","msg":"trace[1858106400] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"199.487239ms","start":"2026-07-21T13:16:40.809502Z","end":"2026-07-21T13:16:41.008989Z","steps":["trace[1858106400] 'process raft request' (duration: 127.995674ms)","trace[1858106400] 'compare' (duration: 71.254869ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:41.009179Z","caller":"traceutil/trace.go:171","msg":"trace[606867513] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1303; }","duration":"152.509455ms","start":"2026-07-21T13:16:40.856636Z","end":"2026-07-21T13:16:41.009146Z","steps":["trace[606867513] 'read index received' (duration: 80.888726ms)","trace[606867513] 'applied index is now lower than readState.Index' (duration: 71.618698ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:41.009242Z","caller":"traceutil/trace.go:171","msg":"trace[1848135336] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"197.960754ms","start":"2026-07-21T13:16:40.811254Z","end":"2026-07-21T13:16:41.009214Z","steps":["trace[1848135336] 'process raft request' (duration: 197.684037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:41.009381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.600947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-21T13:16:41.009447Z","caller":"traceutil/trace.go:171","msg":"trace[1447729651] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1249; }","duration":"152.730521ms","start":"2026-07-21T13:16:40.856703Z","end":"2026-07-21T13:16:41.009433Z","steps":["trace[1447729651] 'agreement among raft nodes before linearized reading' (duration: 152.607727ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:16:41.009448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.805883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115770"} {"level":"info","ts":"2026-07-21T13:16:41.009485Z","caller":"traceutil/trace.go:171","msg":"trace[961563385] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1249; }","duration":"152.876965ms","start":"2026-07-21T13:16:40.856598Z","end":"2026-07-21T13:16:41.009475Z","steps":["trace[961563385] 'agreement among raft nodes before linearized reading' (duration: 152.680149ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:41.513015Z","caller":"traceutil/trace.go:171","msg":"trace[1171051019] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"157.242005ms","start":"2026-07-21T13:16:41.355749Z","end":"2026-07-21T13:16:41.512991Z","steps":["trace[1171051019] 'process raft request' (duration: 157.106561ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:42.029471Z","caller":"traceutil/trace.go:171","msg":"trace[1405158729] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"116.540381ms","start":"2026-07-21T13:16:41.912894Z","end":"2026-07-21T13:16:42.029435Z","steps":["trace[1405158729] 'process raft request' (duration: 116.358176ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:42.824344Z","caller":"traceutil/trace.go:171","msg":"trace[369076517] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"203.930915ms","start":"2026-07-21T13:16:42.620378Z","end":"2026-07-21T13:16:42.824309Z","steps":["trace[369076517] 'process raft request' (duration: 203.630857ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:53.255545Z","caller":"traceutil/trace.go:171","msg":"trace[193518009] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"127.188402ms","start":"2026-07-21T13:16:53.128328Z","end":"2026-07-21T13:16:53.255517Z","steps":["trace[193518009] 'process raft request' (duration: 94.292622ms)","trace[193518009] 'compare' (duration: 32.783486ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:16:53.887384Z","caller":"traceutil/trace.go:171","msg":"trace[721692761] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"101.023326ms","start":"2026-07-21T13:16:53.786333Z","end":"2026-07-21T13:16:53.887357Z","steps":["trace[721692761] 'process raft request' (duration: 100.81918ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:59.188477Z","caller":"traceutil/trace.go:171","msg":"trace[1250609009] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"153.247684ms","start":"2026-07-21T13:16:59.035204Z","end":"2026-07-21T13:16:59.188452Z","steps":["trace[1250609009] 'read index received' (duration: 152.672239ms)","trace[1250609009] 'applied index is now lower than readState.Index' (duration: 574.445µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:16:59.188823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.599284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-21T13:16:59.188861Z","caller":"traceutil/trace.go:171","msg":"trace[937733393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1342; }","duration":"153.682816ms","start":"2026-07-21T13:16:59.035169Z","end":"2026-07-21T13:16:59.188852Z","steps":["trace[937733393] 'agreement among raft nodes before linearized reading' (duration: 153.372327ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:16:59.18872Z","caller":"traceutil/trace.go:171","msg":"trace[1528696067] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"174.665438ms","start":"2026-07-21T13:16:59.01402Z","end":"2026-07-21T13:16:59.188685Z","steps":["trace[1528696067] 'process raft request' (duration: 173.99554ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:07.834361Z","caller":"traceutil/trace.go:171","msg":"trace[1086041425] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"216.006675ms","start":"2026-07-21T13:17:07.61832Z","end":"2026-07-21T13:17:07.834327Z","steps":["trace[1086041425] 'process raft request' (duration: 215.711746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:08.675322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.3431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-07-21T13:17:08.675378Z","caller":"traceutil/trace.go:171","msg":"trace[1737978766] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:1380; }","duration":"179.436372ms","start":"2026-07-21T13:17:08.495928Z","end":"2026-07-21T13:17:08.675364Z","steps":["trace[1737978766] 'range keys from in-memory index tree' (duration: 179.187446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:08.675468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.266659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-07-21T13:17:08.675861Z","caller":"traceutil/trace.go:171","msg":"trace[1962311035] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:1380; }","duration":"153.753183ms","start":"2026-07-21T13:17:08.522074Z","end":"2026-07-21T13:17:08.675828Z","steps":["trace[1962311035] 'range keys from in-memory index tree' (duration: 153.004983ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:09.453289Z","caller":"traceutil/trace.go:171","msg":"trace[337790047] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1447; }","duration":"163.217486ms","start":"2026-07-21T13:17:09.29005Z","end":"2026-07-21T13:17:09.453268Z","steps":["trace[337790047] 'read index received' (duration: 161.952023ms)","trace[337790047] 'applied index is now lower than readState.Index' (duration: 1.264223ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:09.453359Z","caller":"traceutil/trace.go:171","msg":"trace[1950260608] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"173.832292ms","start":"2026-07-21T13:17:09.279485Z","end":"2026-07-21T13:17:09.453317Z","steps":["trace[1950260608] 'process raft request' (duration: 172.536788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:09.453421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.346399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:basic-user\" ","response":"range_response_count:1 size:678"} {"level":"warn","ts":"2026-07-21T13:17:09.453445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.412535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient\" ","response":"range_response_count:1 size:706"} {"level":"info","ts":"2026-07-21T13:17:09.453478Z","caller":"traceutil/trace.go:171","msg":"trace[2031240218] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:certificatesigningrequests:nodeclient; range_end:; response_count:1; response_revision:1383; }","duration":"137.491447ms","start":"2026-07-21T13:17:09.315975Z","end":"2026-07-21T13:17:09.453467Z","steps":["trace[2031240218] 'agreement among raft nodes before linearized reading' (duration: 137.424205ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:09.453478Z","caller":"traceutil/trace.go:171","msg":"trace[2118275101] range","detail":"{range_begin:/registry/clusterroles/system:basic-user; range_end:; response_count:1; response_revision:1383; }","duration":"163.445232ms","start":"2026-07-21T13:17:09.290015Z","end":"2026-07-21T13:17:09.45346Z","steps":["trace[2118275101] 'agreement among raft nodes before linearized reading' (duration: 163.330229ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:13.418022Z","caller":"traceutil/trace.go:171","msg":"trace[1821449115] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"125.094905ms","start":"2026-07-21T13:17:13.292901Z","end":"2026-07-21T13:17:13.417996Z","steps":["trace[1821449115] 'process raft request' (duration: 124.940431ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:13.432233Z","caller":"traceutil/trace.go:171","msg":"trace[202052134] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1459; }","duration":"137.958177ms","start":"2026-07-21T13:17:13.294217Z","end":"2026-07-21T13:17:13.432176Z","steps":["trace[202052134] 'read index received' (duration: 123.597577ms)","trace[202052134] 'applied index is now lower than readState.Index' (duration: 14.35573ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:13.432276Z","caller":"traceutil/trace.go:171","msg":"trace[1048839695] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"139.243509ms","start":"2026-07-21T13:17:13.292996Z","end":"2026-07-21T13:17:13.432239Z","steps":["trace[1048839695] 'process raft request' (duration: 139.006433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:13.432535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.272864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-07-21T13:17:13.433215Z","caller":"traceutil/trace.go:171","msg":"trace[1430629876] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1395; }","duration":"138.414878ms","start":"2026-07-21T13:17:13.294167Z","end":"2026-07-21T13:17:13.432582Z","steps":["trace[1430629876] 'agreement among raft nodes before linearized reading' (duration: 138.154101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.03838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11381334601084445081,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T13:17:22.044818Z","caller":"traceutil/trace.go:171","msg":"trace[712293411] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"507.429894ms","start":"2026-07-21T13:17:21.53736Z","end":"2026-07-21T13:17:22.044789Z","steps":["trace[712293411] 'read index received' (duration: 507.267489ms)","trace[712293411] 'applied index is now lower than readState.Index' (duration: 161.315µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:22.045114Z","caller":"traceutil/trace.go:171","msg":"trace[2141703220] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"511.336162ms","start":"2026-07-21T13:17:21.533585Z","end":"2026-07-21T13:17:22.044921Z","steps":["trace[2141703220] 'process raft request' (duration: 511.032784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.045184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.798122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3302"} {"level":"info","ts":"2026-07-21T13:17:22.045267Z","caller":"traceutil/trace.go:171","msg":"trace[2024629475] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/; range_end:/registry/rolebindings/envoy-gateway-system0; response_count:3; response_revision:1420; }","duration":"507.925726ms","start":"2026-07-21T13:17:21.537327Z","end":"2026-07-21T13:17:22.045253Z","steps":["trace[2024629475] 'agreement among raft nodes before linearized reading' (duration: 507.629789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.045272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.533569Z","time spent":"511.606289ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:22.045414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.844194ms","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-07-21T13:17:22.045441Z","caller":"traceutil/trace.go:171","msg":"trace[1057071077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1420; }","duration":"354.887485ms","start":"2026-07-21T13:17:21.690547Z","end":"2026-07-21T13:17:22.045434Z","steps":["trace[1057071077] 'agreement among raft nodes before linearized reading' (duration: 354.792542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.045477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.690538Z","time spent":"354.933346ms","remote":"127.0.0.1:41890","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-07-21T13:17:22.045591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.537312Z","time spent":"508.271804ms","remote":"127.0.0.1:41942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":3,"response size":3325,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/\" range_end:\"/registry/rolebindings/envoy-gateway-system0\" limit:500 "} {"level":"warn","ts":"2026-07-21T13:17:22.04561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.245829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:17:22.045781Z","caller":"traceutil/trace.go:171","msg":"trace[175673875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1420; }","duration":"448.472604ms","start":"2026-07-21T13:17:21.597217Z","end":"2026-07-21T13:17:22.045689Z","steps":["trace[175673875] 'agreement among raft nodes before linearized reading' (duration: 448.020962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.045779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.433469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T13:17:22.045842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.597199Z","time spent":"448.627208ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:17:22.045954Z","caller":"traceutil/trace.go:171","msg":"trace[1526693582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1420; }","duration":"359.614703ms","start":"2026-07-21T13:17:21.686293Z","end":"2026-07-21T13:17:22.045907Z","steps":["trace[1526693582] 'agreement among raft nodes before linearized reading' (duration: 359.10221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.046039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:21.686278Z","time spent":"359.749136ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T13:17:22.046256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.421804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:22.046337Z","caller":"traceutil/trace.go:171","msg":"trace[1185831968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1420; }","duration":"108.125472ms","start":"2026-07-21T13:17:21.938198Z","end":"2026-07-21T13:17:22.046323Z","steps":["trace[1185831968] 'agreement among raft nodes before linearized reading' (duration: 107.214419ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:22.41392Z","caller":"traceutil/trace.go:171","msg":"trace[1378241385] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"361.473356ms","start":"2026-07-21T13:17:22.052402Z","end":"2026-07-21T13:17:22.413876Z","steps":["trace[1378241385] 'process raft request' (duration: 361.372184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.414045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.052361Z","time spent":"361.62203ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:17:22.413912Z","caller":"traceutil/trace.go:171","msg":"trace[1871081926] linearizableReadLoop","detail":"{readStateIndex:1490; appliedIndex:1487; }","duration":"280.54243ms","start":"2026-07-21T13:17:22.133322Z","end":"2026-07-21T13:17:22.413864Z","steps":["trace[1871081926] 'read index received' (duration: 272.936259ms)","trace[1871081926] 'applied index is now lower than readState.Index' (duration: 7.605271ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:22.414452Z","caller":"traceutil/trace.go:171","msg":"trace[619519395] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"362.48707ms","start":"2026-07-21T13:17:22.051953Z","end":"2026-07-21T13:17:22.41444Z","steps":["trace[619519395] 'process raft request' (duration: 354.290845ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:22.414458Z","caller":"traceutil/trace.go:171","msg":"trace[1780412039] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"264.850738ms","start":"2026-07-21T13:17:22.149568Z","end":"2026-07-21T13:17:22.414418Z","steps":["trace[1780412039] 'process raft request' (duration: 264.268523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.414543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.075184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/\" range_end:\"/registry/persistentvolumeclaims/default0\" limit:500 ","response":"range_response_count:1 size:1465"} {"level":"warn","ts":"2026-07-21T13:17:22.414549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.051927Z","time spent":"362.550132ms","remote":"127.0.0.1:41890","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-07-21T13:17:22.414481Z","caller":"traceutil/trace.go:171","msg":"trace[334050725] transaction","detail":"{read_only:false; response_revision:1423; number_of_response:1; }","duration":"362.01767ms","start":"2026-07-21T13:17:22.052389Z","end":"2026-07-21T13:17:22.414407Z","steps":["trace[334050725] 'process raft request' (duration: 361.421325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.414678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.782135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/\" range_end:\"/registry/roles/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3333"} {"level":"info","ts":"2026-07-21T13:17:22.414711Z","caller":"traceutil/trace.go:171","msg":"trace[1326328682] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/; range_end:/registry/roles/envoy-gateway-system0; response_count:3; response_revision:1424; }","duration":"248.837047ms","start":"2026-07-21T13:17:22.165866Z","end":"2026-07-21T13:17:22.414703Z","steps":["trace[1326328682] 'agreement among raft nodes before linearized reading' (duration: 248.729134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.414705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.926854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-07-21T13:17:22.414755Z","caller":"traceutil/trace.go:171","msg":"trace[645090666] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1424; }","duration":"273.985105ms","start":"2026-07-21T13:17:22.140752Z","end":"2026-07-21T13:17:22.414737Z","steps":["trace[645090666] 'agreement among raft nodes before linearized reading' (duration: 273.888993ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:22.414578Z","caller":"traceutil/trace.go:171","msg":"trace[1398478913] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/; range_end:/registry/persistentvolumeclaims/default0; response_count:1; response_revision:1424; }","duration":"279.132284ms","start":"2026-07-21T13:17:22.135435Z","end":"2026-07-21T13:17:22.414568Z","steps":["trace[1398478913] 'agreement among raft nodes before linearized reading' (duration: 279.012032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:22.414871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:22.052361Z","time spent":"362.264976ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:22.414327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.97743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-07-21T13:17:22.415035Z","caller":"traceutil/trace.go:171","msg":"trace[1161484141] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1424; }","duration":"281.739989ms","start":"2026-07-21T13:17:22.133282Z","end":"2026-07-21T13:17:22.415022Z","steps":["trace[1161484141] 'agreement among raft nodes before linearized reading' (duration: 280.688743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:23.360496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.354058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:17:23.36062Z","caller":"traceutil/trace.go:171","msg":"trace[980185217] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"188.797781ms","start":"2026-07-21T13:17:23.171803Z","end":"2026-07-21T13:17:23.360601Z","steps":["trace[980185217] 'process raft request' (duration: 63.247748ms)","trace[980185217] 'compare' (duration: 125.189565ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:23.364849Z","caller":"traceutil/trace.go:171","msg":"trace[1519210156] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1494; }","duration":"123.829391ms","start":"2026-07-21T13:17:23.241007Z","end":"2026-07-21T13:17:23.364836Z","steps":["trace[1519210156] 'read index received' (duration: 123.770399ms)","trace[1519210156] 'applied index is now lower than readState.Index' (duration: 57.852µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:23.364921Z","caller":"traceutil/trace.go:171","msg":"trace[1025216499] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"183.738654ms","start":"2026-07-21T13:17:23.181167Z","end":"2026-07-21T13:17:23.364905Z","steps":["trace[1025216499] 'process raft request' (duration: 183.596931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:23.3651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.175599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-public/\" range_end:\"/registry/cronjobs/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:23.365206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.161489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:4637"} {"level":"info","ts":"2026-07-21T13:17:23.365314Z","caller":"traceutil/trace.go:171","msg":"trace[796683] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1427; }","duration":"124.322903ms","start":"2026-07-21T13:17:23.240969Z","end":"2026-07-21T13:17:23.365292Z","steps":["trace[796683] 'agreement among raft nodes before linearized reading' (duration: 123.967564ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:23.365243Z","caller":"traceutil/trace.go:171","msg":"trace[263085454] range","detail":"{range_begin:/registry/cronjobs/kube-public/; range_end:/registry/cronjobs/kube-public0; response_count:0; response_revision:1427; }","duration":"116.345603ms","start":"2026-07-21T13:17:23.248883Z","end":"2026-07-21T13:17:23.365229Z","steps":["trace[263085454] 'agreement among raft nodes before linearized reading' (duration: 116.174068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:23.365447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.422897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:23.36555Z","caller":"traceutil/trace.go:171","msg":"trace[1891065194] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1427; }","duration":"102.705703ms","start":"2026-07-21T13:17:23.262833Z","end":"2026-07-21T13:17:23.365538Z","steps":["trace[1891065194] 'agreement among raft nodes before linearized reading' (duration: 102.430667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:24.67084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.395986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-07-21T13:17:24.670952Z","caller":"traceutil/trace.go:171","msg":"trace[210407211] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1433; }","duration":"120.564739ms","start":"2026-07-21T13:17:24.550362Z","end":"2026-07-21T13:17:24.670927Z","steps":["trace[210407211] 'range keys from in-memory index tree' (duration: 120.298613ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:24.670848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.66895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/\" range_end:\"/registry/roles/kube-public0\" limit:500 ","response":"range_response_count:2 size:1073"} {"level":"warn","ts":"2026-07-21T13:17:24.671049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.989186ms","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-07-21T13:17:24.671154Z","caller":"traceutil/trace.go:171","msg":"trace[922000142] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1433; }","duration":"157.15024ms","start":"2026-07-21T13:17:24.513977Z","end":"2026-07-21T13:17:24.671128Z","steps":["trace[922000142] 'range keys from in-memory index tree' (duration: 156.786061ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:24.671057Z","caller":"traceutil/trace.go:171","msg":"trace[1970712547] range","detail":"{range_begin:/registry/roles/kube-public/; range_end:/registry/roles/kube-public0; response_count:2; response_revision:1433; }","duration":"152.916185ms","start":"2026-07-21T13:17:24.5181Z","end":"2026-07-21T13:17:24.671016Z","steps":["trace[1970712547] 'range keys from in-memory index tree' (duration: 152.566555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:24.670943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.383586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:24.671305Z","caller":"traceutil/trace.go:171","msg":"trace[1826451434] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:1433; }","duration":"114.802357ms","start":"2026-07-21T13:17:24.556486Z","end":"2026-07-21T13:17:24.671288Z","steps":["trace[1826451434] 'range keys from in-memory index tree' (duration: 114.320425ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.331213Z","caller":"traceutil/trace.go:171","msg":"trace[490332276] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1502; }","duration":"251.046211ms","start":"2026-07-21T13:17:26.08015Z","end":"2026-07-21T13:17:26.331196Z","steps":["trace[490332276] 'read index received' (duration: 250.874407ms)","trace[490332276] 'applied index is now lower than readState.Index' (duration: 171.014µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:17:26.331256Z","caller":"traceutil/trace.go:171","msg":"trace[1770906336] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"260.744281ms","start":"2026-07-21T13:17:26.070495Z","end":"2026-07-21T13:17:26.331239Z","steps":["trace[1770906336] 'process raft request' (duration: 260.584977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.331328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.169174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"warn","ts":"2026-07-21T13:17:26.33139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.838602ms","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-07-21T13:17:26.331399Z","caller":"traceutil/trace.go:171","msg":"trace[734698059] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1435; }","duration":"251.283388ms","start":"2026-07-21T13:17:26.080107Z","end":"2026-07-21T13:17:26.331391Z","steps":["trace[734698059] 'agreement among raft nodes before linearized reading' (duration: 251.159834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.331358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.615814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/\" range_end:\"/registry/rolebindings/kube-system0\" limit:500 ","response":"range_response_count:11 size:7467"} {"level":"warn","ts":"2026-07-21T13:17:26.331555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.082324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 ","response":"range_response_count:11 size:5916"} {"level":"info","ts":"2026-07-21T13:17:26.331655Z","caller":"traceutil/trace.go:171","msg":"trace[1942587714] range","detail":"{range_begin:/registry/roles/kube-system/; range_end:/registry/roles/kube-system0; response_count:11; response_revision:1435; }","duration":"188.252968ms","start":"2026-07-21T13:17:26.143377Z","end":"2026-07-21T13:17:26.33163Z","steps":["trace[1942587714] 'agreement among raft nodes before linearized reading' (duration: 187.96182ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.331558Z","caller":"traceutil/trace.go:171","msg":"trace[1191144699] range","detail":"{range_begin:/registry/rolebindings/kube-system/; range_end:/registry/rolebindings/kube-system0; response_count:11; response_revision:1435; }","duration":"219.838ms","start":"2026-07-21T13:17:26.111692Z","end":"2026-07-21T13:17:26.33153Z","steps":["trace[1191144699] 'agreement among raft nodes before linearized reading' (duration: 219.596953ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.331455Z","caller":"traceutil/trace.go:171","msg":"trace[1436598343] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1435; }","duration":"198.890522ms","start":"2026-07-21T13:17:26.132513Z","end":"2026-07-21T13:17:26.331403Z","steps":["trace[1436598343] 'agreement among raft nodes before linearized reading' (duration: 198.85921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.332336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.131696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-07-21T13:17:26.332513Z","caller":"traceutil/trace.go:171","msg":"trace[153356397] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1435; }","duration":"184.332511ms","start":"2026-07-21T13:17:26.14816Z","end":"2026-07-21T13:17:26.332492Z","steps":["trace[153356397] 'agreement among raft nodes before linearized reading' (duration: 184.114176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.599987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.764424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:17:26.600036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.24542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-07-21T13:17:26.600099Z","caller":"traceutil/trace.go:171","msg":"trace[539774198] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1435; }","duration":"195.342623ms","start":"2026-07-21T13:17:26.404739Z","end":"2026-07-21T13:17:26.600082Z","steps":["trace[539774198] 'range keys from in-memory index tree' (duration: 195.148958ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.600094Z","caller":"traceutil/trace.go:171","msg":"trace[1019224195] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1435; }","duration":"151.915368ms","start":"2026-07-21T13:17:26.44815Z","end":"2026-07-21T13:17:26.600066Z","steps":["trace[1019224195] 'count revisions from in-memory index tree' (duration: 151.683003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.600265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.298958ms","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-07-21T13:17:26.600299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.762715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-21T13:17:26.600316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.942604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:17:26.600343Z","caller":"traceutil/trace.go:171","msg":"trace[1364028460] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1435; }","duration":"131.457172ms","start":"2026-07-21T13:17:26.468868Z","end":"2026-07-21T13:17:26.600325Z","steps":["trace[1364028460] 'range keys from in-memory index tree' (duration: 131.141364ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.600373Z","caller":"traceutil/trace.go:171","msg":"trace[1284577764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1435; }","duration":"136.032526ms","start":"2026-07-21T13:17:26.464329Z","end":"2026-07-21T13:17:26.600362Z","steps":["trace[1284577764] 'range keys from in-memory index tree' (duration: 135.735406ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.600379Z","caller":"traceutil/trace.go:171","msg":"trace[1848309088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1435; }","duration":"132.873067ms","start":"2026-07-21T13:17:26.467479Z","end":"2026-07-21T13:17:26.600352Z","steps":["trace[1848309088] 'range keys from in-memory index tree' (duration: 132.566499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.600261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.989409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-07-21T13:17:26.600516Z","caller":"traceutil/trace.go:171","msg":"trace[799155495] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1435; }","duration":"137.288116ms","start":"2026-07-21T13:17:26.463214Z","end":"2026-07-21T13:17:26.600503Z","steps":["trace[799155495] 'range keys from in-memory index tree' (duration: 136.721351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.600768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.021044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-07-21T13:17:26.600809Z","caller":"traceutil/trace.go:171","msg":"trace[1812799360] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1435; }","duration":"197.779992ms","start":"2026-07-21T13:17:26.403017Z","end":"2026-07-21T13:17:26.600797Z","steps":["trace[1812799360] 'range keys from in-memory index tree' (duration: 196.904241ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.964388Z","caller":"traceutil/trace.go:171","msg":"trace[1728433897] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"494.739709ms","start":"2026-07-21T13:17:26.46963Z","end":"2026-07-21T13:17:26.964369Z","steps":["trace[1728433897] 'process raft request' (duration: 494.627326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.964506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:26.469617Z","time spent":"494.826361ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:17:26.976622Z","caller":"traceutil/trace.go:171","msg":"trace[1870653671] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"372.852704ms","start":"2026-07-21T13:17:26.603747Z","end":"2026-07-21T13:17:26.9766Z","steps":["trace[1870653671] 'process raft request' (duration: 372.630689ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.976613Z","caller":"traceutil/trace.go:171","msg":"trace[473310967] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1503; }","duration":"372.291391ms","start":"2026-07-21T13:17:26.604298Z","end":"2026-07-21T13:17:26.97659Z","steps":["trace[473310967] 'read index received' (duration: 359.976456ms)","trace[473310967] 'applied index is now lower than readState.Index' (duration: 12.313914ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:17:26.976792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:26.603727Z","time spent":"372.979266ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:26.977045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.740572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-07-21T13:17:26.977108Z","caller":"traceutil/trace.go:171","msg":"trace[1838963051] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1439; }","duration":"372.824774ms","start":"2026-07-21T13:17:26.604267Z","end":"2026-07-21T13:17:26.977092Z","steps":["trace[1838963051] 'agreement among raft nodes before linearized reading' (duration: 372.702751ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.976864Z","caller":"traceutil/trace.go:171","msg":"trace[378947096] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"372.261268ms","start":"2026-07-21T13:17:26.604582Z","end":"2026-07-21T13:17:26.976844Z","steps":["trace[378947096] 'process raft request' (duration: 371.925591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.977158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:26.604249Z","time spent":"372.897026ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":1107,"request content":"key:\"/registry/configmaps/kube-system/kubeadm-config\" "} {"level":"warn","ts":"2026-07-21T13:17:26.977269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.262398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/local-path-storage/\" range_end:\"/registry/rolebindings/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:26.976925Z","caller":"traceutil/trace.go:171","msg":"trace[1605422341] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"370.155788ms","start":"2026-07-21T13:17:26.606756Z","end":"2026-07-21T13:17:26.976912Z","steps":["trace[1605422341] 'process raft request' (duration: 369.789588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.977356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:26.604571Z","time spent":"372.630489ms","remote":"127.0.0.1:41890","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-07-21T13:17:26.97738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.902106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/local-path-storage/\" range_end:\"/registry/persistentvolumeclaims/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:17:26.977383Z","caller":"traceutil/trace.go:171","msg":"trace[1317846115] range","detail":"{range_begin:/registry/rolebindings/local-path-storage/; range_end:/registry/rolebindings/local-path-storage0; response_count:0; response_revision:1439; }","duration":"188.400221ms","start":"2026-07-21T13:17:26.788961Z","end":"2026-07-21T13:17:26.977362Z","steps":["trace[1317846115] 'agreement among raft nodes before linearized reading' (duration: 188.209856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.977467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:26.606744Z","time spent":"370.67168ms","remote":"127.0.0.1:41890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:17:26.97715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.269095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"warn","ts":"2026-07-21T13:17:26.977321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.250576ms","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-07-21T13:17:26.977585Z","caller":"traceutil/trace.go:171","msg":"trace[1258957321] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1439; }","duration":"371.730406ms","start":"2026-07-21T13:17:26.605837Z","end":"2026-07-21T13:17:26.977568Z","steps":["trace[1258957321] 'agreement among raft nodes before linearized reading' (duration: 371.230564ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:17:26.977601Z","caller":"traceutil/trace.go:171","msg":"trace[1394860621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1439; }","duration":"230.546754ms","start":"2026-07-21T13:17:26.747039Z","end":"2026-07-21T13:17:26.977586Z","steps":["trace[1394860621] 'agreement among raft nodes before linearized reading' (duration: 230.143763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:17:26.97763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:17:26.605829Z","time spent":"371.789758ms","remote":"127.0.0.1:41742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":1380,"request content":"key:\"/registry/configmaps/kube-system/kubelet-config\" "} {"level":"info","ts":"2026-07-21T13:17:26.977442Z","caller":"traceutil/trace.go:171","msg":"trace[228748657] range","detail":"{range_begin:/registry/persistentvolumeclaims/local-path-storage/; range_end:/registry/persistentvolumeclaims/local-path-storage0; response_count:0; response_revision:1439; }","duration":"237.962117ms","start":"2026-07-21T13:17:26.739464Z","end":"2026-07-21T13:17:26.977426Z","steps":["trace[228748657] 'agreement among raft nodes before linearized reading' (duration: 237.882745ms)"],"step_count":1}