{"level":"warn","ts":"2026-07-25T12:52:29.755472Z","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-25T12:52:29.755563Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-25T12:52:29.75563Z","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-25T12:52:29.755639Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-25T12:52:29.755658Z","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-25T12:52:29.756313Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-07-25T12:52:29.756393Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-25T12:52:29.760708Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.086614ms"} {"level":"info","ts":"2026-07-25T12:52:29.772328Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-07-25T12:52:29.77242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T12:52:29.772451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-07-25T12:52:29.772465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T12:52:29.772479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-07-25T12:52:29.77252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-07-25T12:52:29.777532Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T12:52:29.778848Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T12:52:29.779844Z","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-25T12:52:29.785726Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T12:52:29.785896Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T12:52:29.786039Z","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-25T12:52:29.78614Z","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-25T12:52:29.786172Z","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-25T12:52:29.787289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-07-25T12:52:29.787521Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-07-25T12:52:29.789624Z","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-25T12:52:29.789743Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-25T12:52:29.789778Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-07-25T12:52:29.789869Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T12:52:29.789931Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T12:52:29.873221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T12:52:29.873349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T12:52:29.873403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-07-25T12:52:29.873456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-07-25T12:52:29.873511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-25T12:52:29.873575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-07-25T12:52:29.873618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-07-25T12:52:29.874565Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T12:52:29.875541Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T12:52:29.875647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T12:52:29.875978Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T12:52:29.876003Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T12:52:29.875997Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T12:52:29.875702Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T12:52:29.876104Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T12:52:29.876144Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T12:52:29.877653Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"info","ts":"2026-07-25T12:52:29.881084Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T12:52:33.684965Z","caller":"traceutil/trace.go:171","msg":"trace[1450867951] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"133.641033ms","start":"2026-07-25T12:52:33.551267Z","end":"2026-07-25T12:52:33.684908Z","steps":["trace[1450867951] 'process raft request' (duration: 131.107418ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:52:34.626284Z","caller":"traceutil/trace.go:171","msg":"trace[380439747] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"774.13443ms","start":"2026-07-25T12:52:33.852112Z","end":"2026-07-25T12:52:34.626247Z","steps":["trace[380439747] 'process raft request' (duration: 721.639587ms)","trace[380439747] 'compare' (duration: 52.338297ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:52:34.626943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:52:33.852098Z","time spent":"774.280084ms","remote":"127.0.0.1:34878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T12:52:35.513692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.904264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T12:52:35.513911Z","caller":"traceutil/trace.go:171","msg":"trace[1543603927] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"1.555965007s","start":"2026-07-25T12:52:33.9579Z","end":"2026-07-25T12:52:35.513865Z","steps":["trace[1543603927] 'process raft request' (duration: 1.12669354s)","trace[1543603927] 'compare' (duration: 428.764549ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:52:35.514022Z","caller":"traceutil/trace.go:171","msg":"trace[1700894428] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"882.950686ms","start":"2026-07-25T12:52:34.631053Z","end":"2026-07-25T12:52:35.514003Z","steps":["trace[1700894428] 'process raft request' (duration: 882.768959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:52:35.514116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:52:34.631033Z","time spent":"883.05555ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":330,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T12:52:35.514117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:52:33.957875Z","time spent":"1.556130833s","remote":"127.0.0.1:34968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T12:52:35.514089Z","caller":"traceutil/trace.go:171","msg":"trace[1988891346] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:246; }","duration":"1.106087128s","start":"2026-07-25T12:52:34.407914Z","end":"2026-07-25T12:52:35.514001Z","steps":["trace[1988891346] 'read index received' (duration: 165.788832ms)","trace[1988891346] 'applied index is now lower than readState.Index' (duration: 940.292175ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:52:35.514349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.027145607s","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-25T12:52:35.514361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10642039s","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-25T12:52:35.514397Z","caller":"traceutil/trace.go:171","msg":"trace[1816542592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:244; }","duration":"1.027233001s","start":"2026-07-25T12:52:34.487148Z","end":"2026-07-25T12:52:35.514381Z","steps":["trace[1816542592] 'agreement among raft nodes before linearized reading' (duration: 1.027124257s)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:52:35.514419Z","caller":"traceutil/trace.go:171","msg":"trace[301533598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:244; }","duration":"1.106537794s","start":"2026-07-25T12:52:34.407861Z","end":"2026-07-25T12:52:35.514399Z","steps":["trace[301533598] 'agreement among raft nodes before linearized reading' (duration: 1.106378198s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:52:35.514501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:52:34.407839Z","time spent":"1.106646998s","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T12:52:35.514428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:52:34.487125Z","time spent":"1.027296703s","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T12:52:35.514717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.456326ms","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-25T12:52:35.51478Z","caller":"traceutil/trace.go:171","msg":"trace[72125259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:244; }","duration":"369.907151ms","start":"2026-07-25T12:52:35.144858Z","end":"2026-07-25T12:52:35.514765Z","steps":["trace[72125259] 'agreement among raft nodes before linearized reading' (duration: 369.427605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:52:35.514827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:52:35.144836Z","time spent":"369.977904ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T12:53:08.694512Z","caller":"traceutil/trace.go:171","msg":"trace[413211593] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"423.650326ms","start":"2026-07-25T12:53:08.270799Z","end":"2026-07-25T12:53:08.694449Z","steps":["trace[413211593] 'process raft request' (duration: 422.894424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:08.694736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:08.270775Z","time spent":"423.90006ms","remote":"127.0.0.1:34866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T12:53:17.376116Z","caller":"traceutil/trace.go:171","msg":"trace[1722109882] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"235.862352ms","start":"2026-07-25T12:53:17.140223Z","end":"2026-07-25T12:53:17.376086Z","steps":["trace[1722109882] 'read index received' (duration: 235.169981ms)","trace[1722109882] 'applied index is now lower than readState.Index' (duration: 691.741µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:53:17.376281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.038552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pqdnb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-25T12:53:17.376303Z","caller":"traceutil/trace.go:171","msg":"trace[674933392] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pqdnb; range_end:; response_count:1; response_revision:640; }","duration":"236.107557ms","start":"2026-07-25T12:53:17.140188Z","end":"2026-07-25T12:53:17.376295Z","steps":["trace[674933392] 'agreement among raft nodes before linearized reading' (duration: 235.985949ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:53:17.376451Z","caller":"traceutil/trace.go:171","msg":"trace[1125476198] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"414.066176ms","start":"2026-07-25T12:53:16.962374Z","end":"2026-07-25T12:53:17.37644Z","steps":["trace[1125476198] 'process raft request' (duration: 413.256398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:17.376727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:16.962348Z","time spent":"414.132839ms","remote":"127.0.0.1:34866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:53:17.85818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.001199ms","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-25T12:53:17.858332Z","caller":"traceutil/trace.go:171","msg":"trace[831346165] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:640; }","duration":"336.168589ms","start":"2026-07-25T12:53:17.52214Z","end":"2026-07-25T12:53:17.858309Z","steps":["trace[831346165] 'range keys from in-memory index tree' (duration: 335.981638ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:53:17.85843Z","caller":"traceutil/trace.go:171","msg":"trace[265960309] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"462.799397ms","start":"2026-07-25T12:53:17.395589Z","end":"2026-07-25T12:53:17.858388Z","steps":["trace[265960309] 'process raft request' (duration: 378.356946ms)","trace[265960309] 'compare' (duration: 84.171755ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:53:17.858626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:17.395562Z","time spent":"462.978658ms","remote":"127.0.0.1:34968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:53:17.877444Z","caller":"traceutil/trace.go:171","msg":"trace[440214528] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"132.005986ms","start":"2026-07-25T12:53:17.745415Z","end":"2026-07-25T12:53:17.877421Z","steps":["trace[440214528] 'read index received' (duration: 28.539643ms)","trace[440214528] 'applied index is now lower than readState.Index' (duration: 103.465703ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:53:17.877478Z","caller":"traceutil/trace.go:171","msg":"trace[1423014588] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"445.437104ms","start":"2026-07-25T12:53:17.432016Z","end":"2026-07-25T12:53:17.877453Z","steps":["trace[1423014588] 'process raft request' (duration: 445.330127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:17.877659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.187322ms","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-25T12:53:17.877705Z","caller":"traceutil/trace.go:171","msg":"trace[1102094877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:642; }","duration":"118.246035ms","start":"2026-07-25T12:53:17.759451Z","end":"2026-07-25T12:53:17.877697Z","steps":["trace[1102094877] 'agreement among raft nodes before linearized reading' (duration: 118.065184ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:17.877685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.251361ms","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-25T12:53:17.877772Z","caller":"traceutil/trace.go:171","msg":"trace[194603162] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:642; }","duration":"132.375528ms","start":"2026-07-25T12:53:17.745375Z","end":"2026-07-25T12:53:17.877751Z","steps":["trace[194603162] 'agreement among raft nodes before linearized reading' (duration: 132.119753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:17.877782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:17.432Z","time spent":"445.702569ms","remote":"127.0.0.1:35326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T12:53:17.877766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.034273ms","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-25T12:53:17.877931Z","caller":"traceutil/trace.go:171","msg":"trace[869082836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:642; }","duration":"117.32086ms","start":"2026-07-25T12:53:17.76059Z","end":"2026-07-25T12:53:17.877911Z","steps":["trace[869082836] 'agreement among raft nodes before linearized reading' (duration: 116.972329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:18.647232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.963083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T12:53:18.64738Z","caller":"traceutil/trace.go:171","msg":"trace[2012766262] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"753.463109ms","start":"2026-07-25T12:53:17.893904Z","end":"2026-07-25T12:53:18.647367Z","steps":["trace[2012766262] 'read index received' (duration: 73.229429ms)","trace[2012766262] 'applied index is now lower than readState.Index' (duration: 680.233ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:53:18.647438Z","caller":"traceutil/trace.go:171","msg":"trace[1346130823] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"764.086196ms","start":"2026-07-25T12:53:17.883345Z","end":"2026-07-25T12:53:18.647431Z","steps":["trace[1346130823] 'process raft request' (duration: 83.848266ms)","trace[1346130823] 'compare' (duration: 679.782593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:53:18.647479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:17.883323Z","time spent":"764.135948ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:53:18.647576Z","caller":"traceutil/trace.go:171","msg":"trace[1184294259] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"763.561585ms","start":"2026-07-25T12:53:17.883985Z","end":"2026-07-25T12:53:18.647547Z","steps":["trace[1184294259] 'process raft request' (duration: 763.31679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:18.647632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.706713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:53:18.647708Z","caller":"traceutil/trace.go:171","msg":"trace[1813926947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"753.81663ms","start":"2026-07-25T12:53:17.893869Z","end":"2026-07-25T12:53:18.647686Z","steps":["trace[1813926947] 'agreement among raft nodes before linearized reading' (duration: 753.694243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:18.647734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:17.883974Z","time spent":"763.678282ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:53:18.64776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:17.893856Z","time spent":"753.896025ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T12:53:18.65665Z","caller":"traceutil/trace.go:171","msg":"trace[1215244460] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"484.32668ms","start":"2026-07-25T12:53:18.172301Z","end":"2026-07-25T12:53:18.656628Z","steps":["trace[1215244460] 'process raft request' (duration: 484.151579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:18.656757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.754046ms","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-25T12:53:18.656829Z","caller":"traceutil/trace.go:171","msg":"trace[1369581820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"708.874003ms","start":"2026-07-25T12:53:17.947938Z","end":"2026-07-25T12:53:18.656812Z","steps":["trace[1369581820] 'agreement among raft nodes before linearized reading' (duration: 708.66461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:18.656871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:17.947919Z","time spent":"708.943577ms","remote":"127.0.0.1:35048","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-25T12:53:18.656699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.725989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:53:18.656915Z","caller":"traceutil/trace.go:171","msg":"trace[389847844] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:646; }","duration":"762.980794ms","start":"2026-07-25T12:53:17.893921Z","end":"2026-07-25T12:53:18.656902Z","steps":["trace[389847844] 'agreement among raft nodes before linearized reading' (duration: 762.683376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:18.656964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:17.893904Z","time spent":"763.037127ms","remote":"127.0.0.1:35024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-25T12:53:18.657869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:18.172275Z","time spent":"484.50009ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:53:19.34743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.048398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T12:53:19.347908Z","caller":"traceutil/trace.go:171","msg":"trace[1944464693] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"687.50895ms","start":"2026-07-25T12:53:18.660333Z","end":"2026-07-25T12:53:19.347842Z","steps":["trace[1944464693] 'process raft request' (duration: 330.933071ms)","trace[1944464693] 'compare' (duration: 355.844355ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:53:19.348303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:18.660306Z","time spent":"687.727683ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:53:19.353196Z","caller":"traceutil/trace.go:171","msg":"trace[1832945164] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"270.112216ms","start":"2026-07-25T12:53:19.083064Z","end":"2026-07-25T12:53:19.353176Z","steps":["trace[1832945164] 'process raft request' (duration: 268.420095ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:53:35.082275Z","caller":"traceutil/trace.go:171","msg":"trace[616598922] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"358.00194ms","start":"2026-07-25T12:53:34.724246Z","end":"2026-07-25T12:53:35.082248Z","steps":["trace[616598922] 'read index received' (duration: 357.788456ms)","trace[616598922] 'applied index is now lower than readState.Index' (duration: 212.394µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:53:35.082472Z","caller":"traceutil/trace.go:171","msg":"trace[1438680261] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"359.638916ms","start":"2026-07-25T12:53:34.722799Z","end":"2026-07-25T12:53:35.082438Z","steps":["trace[1438680261] 'process raft request' (duration: 359.310645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:35.082522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.281878ms","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-25T12:53:35.082606Z","caller":"traceutil/trace.go:171","msg":"trace[956737069] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:809; }","duration":"358.420398ms","start":"2026-07-25T12:53:34.724175Z","end":"2026-07-25T12:53:35.082595Z","steps":["trace[956737069] 'agreement among raft nodes before linearized reading' (duration: 358.225555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:35.082603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.559564ms","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-25T12:53:35.082665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:34.724155Z","time spent":"358.501213ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-25T12:53:35.082712Z","caller":"traceutil/trace.go:171","msg":"trace[1644761079] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:809; }","duration":"293.783978ms","start":"2026-07-25T12:53:34.788907Z","end":"2026-07-25T12:53:35.082691Z","steps":["trace[1644761079] 'agreement among raft nodes before linearized reading' (duration: 293.51513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:53:35.083002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:53:34.722778Z","time spent":"359.822378ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:53:35.237019Z","caller":"traceutil/trace.go:171","msg":"trace[880456210] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"149.721182ms","start":"2026-07-25T12:53:35.087276Z","end":"2026-07-25T12:53:35.236998Z","steps":["trace[880456210] 'process raft request' (duration: 149.526719ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:53:35.239366Z","caller":"traceutil/trace.go:171","msg":"trace[900644071] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"150.201553ms","start":"2026-07-25T12:53:35.089146Z","end":"2026-07-25T12:53:35.239347Z","steps":["trace[900644071] 'process raft request' (duration: 149.981549ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:54:05.454401Z","caller":"traceutil/trace.go:171","msg":"trace[2071825508] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"247.085929ms","start":"2026-07-25T12:54:05.207288Z","end":"2026-07-25T12:54:05.454374Z","steps":["trace[2071825508] 'read index received' (duration: 246.794498ms)","trace[2071825508] 'applied index is now lower than readState.Index' (duration: 290.251µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:54:05.454496Z","caller":"traceutil/trace.go:171","msg":"trace[1201159555] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"335.756796ms","start":"2026-07-25T12:54:05.118702Z","end":"2026-07-25T12:54:05.454459Z","steps":["trace[1201159555] 'process raft request' (duration: 335.485726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:54:05.454608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.294404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:54:05.454656Z","caller":"traceutil/trace.go:171","msg":"trace[1396143527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"247.406863ms","start":"2026-07-25T12:54:05.207239Z","end":"2026-07-25T12:54:05.454646Z","steps":["trace[1396143527] 'agreement among raft nodes before linearized reading' (duration: 247.303795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:54:05.454736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.012184ms","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-25T12:54:05.454827Z","caller":"traceutil/trace.go:171","msg":"trace[1867505400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"239.148604ms","start":"2026-07-25T12:54:05.215654Z","end":"2026-07-25T12:54:05.454802Z","steps":["trace[1867505400] 'agreement among raft nodes before linearized reading' (duration: 238.9534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:54:05.455909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:54:05.118675Z","time spent":"335.95483ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:54:34.42016Z","caller":"traceutil/trace.go:171","msg":"trace[563247123] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"127.494985ms","start":"2026-07-25T12:54:34.292636Z","end":"2026-07-25T12:54:34.420131Z","steps":["trace[563247123] 'process raft request' (duration: 127.343302ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:54:35.908014Z","caller":"traceutil/trace.go:171","msg":"trace[1145415215] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1151; }","duration":"270.314253ms","start":"2026-07-25T12:54:35.637679Z","end":"2026-07-25T12:54:35.907993Z","steps":["trace[1145415215] 'read index received' (duration: 270.129328ms)","trace[1145415215] 'applied index is now lower than readState.Index' (duration: 184.085µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:54:35.908122Z","caller":"traceutil/trace.go:171","msg":"trace[298730978] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"318.823678ms","start":"2026-07-25T12:54:35.589268Z","end":"2026-07-25T12:54:35.908092Z","steps":["trace[298730978] 'process raft request' (duration: 318.60252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:54:35.908213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.5141ms","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-25T12:54:35.908249Z","caller":"traceutil/trace.go:171","msg":"trace[1459460511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1106; }","duration":"270.592546ms","start":"2026-07-25T12:54:35.637646Z","end":"2026-07-25T12:54:35.908239Z","steps":["trace[1459460511] 'agreement among raft nodes before linearized reading' (duration: 270.456974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:54:35.908295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:54:35.58924Z","time spent":"318.959129ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:54:35.908299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.263294ms","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-25T12:54:35.908299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.423437ms","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-25T12:54:35.908358Z","caller":"traceutil/trace.go:171","msg":"trace[1678584307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"226.34546ms","start":"2026-07-25T12:54:35.681998Z","end":"2026-07-25T12:54:35.908344Z","steps":["trace[1678584307] 'agreement among raft nodes before linearized reading' (duration: 226.23325ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:54:35.908365Z","caller":"traceutil/trace.go:171","msg":"trace[1730169258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"269.511935ms","start":"2026-07-25T12:54:35.63884Z","end":"2026-07-25T12:54:35.908351Z","steps":["trace[1730169258] 'agreement among raft nodes before linearized reading' (duration: 269.315049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:54:35.908404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.279119ms","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-25T12:54:35.908527Z","caller":"traceutil/trace.go:171","msg":"trace[55776795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1106; }","duration":"193.41528ms","start":"2026-07-25T12:54:35.715057Z","end":"2026-07-25T12:54:35.908472Z","steps":["trace[55776795] 'agreement among raft nodes before linearized reading' (duration: 193.164158ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:02.2587Z","caller":"traceutil/trace.go:171","msg":"trace[321306112] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"240.311531ms","start":"2026-07-25T12:55:02.018359Z","end":"2026-07-25T12:55:02.25867Z","steps":["trace[321306112] 'process raft request' (duration: 240.159408ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:02.375011Z","caller":"traceutil/trace.go:171","msg":"trace[1928123110] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"326.913693ms","start":"2026-07-25T12:55:02.048071Z","end":"2026-07-25T12:55:02.374984Z","steps":["trace[1928123110] 'read index received' (duration: 210.609353ms)","trace[1928123110] 'applied index is now lower than readState.Index' (duration: 116.3034ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:55:02.375339Z","caller":"traceutil/trace.go:171","msg":"trace[1874761582] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"354.984583ms","start":"2026-07-25T12:55:02.020311Z","end":"2026-07-25T12:55:02.375295Z","steps":["trace[1874761582] 'process raft request' (duration: 349.909175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:02.375475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.349109ms","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-25T12:55:02.375521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:02.020302Z","time spent":"355.126925ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:55:02.375571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.147859ms","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-25T12:55:02.37565Z","caller":"traceutil/trace.go:171","msg":"trace[1895281033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1192; }","duration":"324.299782ms","start":"2026-07-25T12:55:02.051332Z","end":"2026-07-25T12:55:02.375631Z","steps":["trace[1895281033] 'agreement among raft nodes before linearized reading' (duration: 324.062642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:02.375701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:02.05131Z","time spent":"324.382199ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T12:55:02.375596Z","caller":"traceutil/trace.go:171","msg":"trace[1271400007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1192; }","duration":"327.531634ms","start":"2026-07-25T12:55:02.048018Z","end":"2026-07-25T12:55:02.37555Z","steps":["trace[1271400007] 'agreement among raft nodes before linearized reading' (duration: 327.229969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:02.375533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.1185ms","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-25T12:55:02.375846Z","caller":"traceutil/trace.go:171","msg":"trace[901701911] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1192; }","duration":"327.461279ms","start":"2026-07-25T12:55:02.048365Z","end":"2026-07-25T12:55:02.375827Z","steps":["trace[901701911] 'agreement among raft nodes before linearized reading' (duration: 326.976378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:02.375959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:02.047999Z","time spent":"327.94405ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T12:55:02.375973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:02.048359Z","time spent":"327.595371ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T12:55:04.629307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.524636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T12:55:04.62943Z","caller":"traceutil/trace.go:171","msg":"trace[289906301] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"736.08654ms","start":"2026-07-25T12:55:03.893323Z","end":"2026-07-25T12:55:04.629409Z","steps":["trace[289906301] 'read index received' (duration: 6.253128ms)","trace[289906301] 'applied index is now lower than readState.Index' (duration: 729.830602ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:55:04.629527Z","caller":"traceutil/trace.go:171","msg":"trace[32477454] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"1.089076235s","start":"2026-07-25T12:55:03.540435Z","end":"2026-07-25T12:55:04.629511Z","steps":["trace[32477454] 'process raft request' (duration: 359.219251ms)","trace[32477454] 'compare' (duration: 729.360622ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:55:04.629618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:03.540419Z","time spent":"1.089153391s","remote":"127.0.0.1:34812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:55:04.629764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.397736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:55:04.629833Z","caller":"traceutil/trace.go:171","msg":"trace[1855636677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1197; }","duration":"736.546719ms","start":"2026-07-25T12:55:03.893269Z","end":"2026-07-25T12:55:04.629816Z","steps":["trace[1855636677] 'agreement among raft nodes before linearized reading' (duration: 736.338572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.629887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:03.893248Z","time spent":"736.626665ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T12:55:04.719628Z","caller":"traceutil/trace.go:171","msg":"trace[881100557] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"455.414041ms","start":"2026-07-25T12:55:04.26419Z","end":"2026-07-25T12:55:04.719604Z","steps":["trace[881100557] 'process raft request' (duration: 455.272169ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.719991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:04.264165Z","time spent":"455.741999ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:55:04.741978Z","caller":"traceutil/trace.go:171","msg":"trace[186106504] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1249; }","duration":"112.465736ms","start":"2026-07-25T12:55:04.629472Z","end":"2026-07-25T12:55:04.741938Z","steps":["trace[186106504] 'read index received' (duration: 89.999399ms)","trace[186106504] 'applied index is now lower than readState.Index' (duration: 22.465207ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:55:04.742097Z","caller":"traceutil/trace.go:171","msg":"trace[402139506] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"248.639143ms","start":"2026-07-25T12:55:04.493399Z","end":"2026-07-25T12:55:04.742038Z","steps":["trace[402139506] 'process raft request' (duration: 240.336143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.742252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.326864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-25T12:55:04.74229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.021366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-25T12:55:04.742307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.415181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-25T12:55:04.742346Z","caller":"traceutil/trace.go:171","msg":"trace[1014506165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1199; }","duration":"134.122334ms","start":"2026-07-25T12:55:04.608211Z","end":"2026-07-25T12:55:04.742334Z","steps":["trace[1014506165] 'agreement among raft nodes before linearized reading' (duration: 133.958981ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:04.742375Z","caller":"traceutil/trace.go:171","msg":"trace[265360957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"342.52242ms","start":"2026-07-25T12:55:04.399835Z","end":"2026-07-25T12:55:04.742358Z","steps":["trace[265360957] 'agreement among raft nodes before linearized reading' (duration: 342.319933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.742409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.484667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T12:55:04.7425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:04.399806Z","time spent":"342.685575ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T12:55:04.742541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.7772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:55:04.74259Z","caller":"traceutil/trace.go:171","msg":"trace[965217442] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1199; }","duration":"256.89098ms","start":"2026-07-25T12:55:04.485686Z","end":"2026-07-25T12:55:04.742577Z","steps":["trace[965217442] 'agreement among raft nodes before linearized reading' (duration: 256.797532ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.742644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.395791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T12:55:04.742548Z","caller":"traceutil/trace.go:171","msg":"trace[885086711] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1199; }","duration":"403.715416ms","start":"2026-07-25T12:55:04.33876Z","end":"2026-07-25T12:55:04.742475Z","steps":["trace[885086711] 'agreement among raft nodes before linearized reading' (duration: 403.493238ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:04.742382Z","caller":"traceutil/trace.go:171","msg":"trace[450457296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1199; }","duration":"342.438403ms","start":"2026-07-25T12:55:04.399869Z","end":"2026-07-25T12:55:04.742307Z","steps":["trace[450457296] 'agreement among raft nodes before linearized reading' (duration: 342.115466ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.742689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.30943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-25T12:55:04.742729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:04.338738Z","time spent":"403.944846ms","remote":"127.0.0.1:46156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-25T12:55:04.742784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.017974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-25T12:55:04.742822Z","caller":"traceutil/trace.go:171","msg":"trace[1740345226] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1199; }","duration":"111.073328ms","start":"2026-07-25T12:55:04.63174Z","end":"2026-07-25T12:55:04.742813Z","steps":["trace[1740345226] 'agreement among raft nodes before linearized reading' (duration: 111.001562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.74271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:04.399847Z","time spent":"342.852538ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T12:55:04.742989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.32897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:55:04.742755Z","caller":"traceutil/trace.go:171","msg":"trace[1172660895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1199; }","duration":"353.42536ms","start":"2026-07-25T12:55:04.389314Z","end":"2026-07-25T12:55:04.74274Z","steps":["trace[1172660895] 'agreement among raft nodes before linearized reading' (duration: 353.141756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.74319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:04.389304Z","time spent":"353.870827ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T12:55:04.74316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.891789ms","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-25T12:55:04.742672Z","caller":"traceutil/trace.go:171","msg":"trace[764684420] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1199; }","duration":"762.468337ms","start":"2026-07-25T12:55:03.980197Z","end":"2026-07-25T12:55:04.742665Z","steps":["trace[764684420] 'agreement among raft nodes before linearized reading' (duration: 762.409442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.743288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:03.980173Z","time spent":"763.102971ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-07-25T12:55:04.743489Z","caller":"traceutil/trace.go:171","msg":"trace[228666110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1199; }","duration":"363.025211ms","start":"2026-07-25T12:55:04.380214Z","end":"2026-07-25T12:55:04.743239Z","steps":["trace[228666110] 'agreement among raft nodes before linearized reading' (duration: 362.214612ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:04.743565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:04.380191Z","time spent":"363.329187ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T12:55:04.743072Z","caller":"traceutil/trace.go:171","msg":"trace[607833057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"111.670509ms","start":"2026-07-25T12:55:04.631367Z","end":"2026-07-25T12:55:04.743037Z","steps":["trace[607833057] 'agreement among raft nodes before linearized reading' (duration: 111.203979ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:06.859848Z","caller":"traceutil/trace.go:171","msg":"trace[1111150964] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"134.599705ms","start":"2026-07-25T12:55:06.725219Z","end":"2026-07-25T12:55:06.859819Z","steps":["trace[1111150964] 'process raft request' (duration: 134.325612ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:06.859966Z","caller":"traceutil/trace.go:171","msg":"trace[734585731] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"100.152616ms","start":"2026-07-25T12:55:06.759574Z","end":"2026-07-25T12:55:06.859727Z","steps":["trace[734585731] 'read index received' (duration: 99.877233ms)","trace[734585731] 'applied index is now lower than readState.Index' (duration: 274.473µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:55:06.860677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.623566ms","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-25T12:55:06.860839Z","caller":"traceutil/trace.go:171","msg":"trace[424339907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1205; }","duration":"101.280872ms","start":"2026-07-25T12:55:06.759539Z","end":"2026-07-25T12:55:06.86082Z","steps":["trace[424339907] 'agreement among raft nodes before linearized reading' (duration: 100.356784ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:07.167188Z","caller":"traceutil/trace.go:171","msg":"trace[1897758893] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"302.383822ms","start":"2026-07-25T12:55:06.864784Z","end":"2026-07-25T12:55:07.167168Z","steps":["trace[1897758893] 'process raft request' (duration: 302.222098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:07.167448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:06.864756Z","time spent":"302.498221ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:55:07.568563Z","caller":"traceutil/trace.go:171","msg":"trace[489072380] linearizableReadLoop","detail":"{readStateIndex:1262; appliedIndex:1257; }","duration":"675.284767ms","start":"2026-07-25T12:55:06.893255Z","end":"2026-07-25T12:55:07.56854Z","steps":["trace[489072380] 'read index received' (duration: 273.780257ms)","trace[489072380] 'applied index is now lower than readState.Index' (duration: 401.50356ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:55:07.568582Z","caller":"traceutil/trace.go:171","msg":"trace[885502440] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"703.620599ms","start":"2026-07-25T12:55:06.864931Z","end":"2026-07-25T12:55:07.568551Z","steps":["trace[885502440] 'process raft request' (duration: 703.325914ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:07.568678Z","caller":"traceutil/trace.go:171","msg":"trace[931801391] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"702.534628ms","start":"2026-07-25T12:55:06.866132Z","end":"2026-07-25T12:55:07.568667Z","steps":["trace[931801391] 'process raft request' (duration: 702.347852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:07.56873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:06.864904Z","time spent":"703.74931ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:55:07.568779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:06.866124Z","time spent":"702.579571ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:55:07.568862Z","caller":"traceutil/trace.go:171","msg":"trace[1853719470] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"703.301603ms","start":"2026-07-25T12:55:06.865553Z","end":"2026-07-25T12:55:07.568854Z","steps":["trace[1853719470] 'process raft request' (duration: 702.854015ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:55:07.568642Z","caller":"traceutil/trace.go:171","msg":"trace[1663244896] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"703.011318ms","start":"2026-07-25T12:55:06.865597Z","end":"2026-07-25T12:55:07.568609Z","steps":["trace[1663244896] 'process raft request' (duration: 702.845454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:07.568906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:06.865544Z","time spent":"703.338885ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:55:07.568927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.831495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:55:07.568985Z","caller":"traceutil/trace.go:171","msg":"trace[1152558465] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1210; }","duration":"441.933325ms","start":"2026-07-25T12:55:07.127038Z","end":"2026-07-25T12:55:07.568971Z","steps":["trace[1152558465] 'agreement among raft nodes before linearized reading' (duration: 441.844107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:07.568994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.740215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:55:07.56902Z","caller":"traceutil/trace.go:171","msg":"trace[809873301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"675.79247ms","start":"2026-07-25T12:55:06.893221Z","end":"2026-07-25T12:55:07.569013Z","steps":["trace[809873301] 'agreement among raft nodes before linearized reading' (duration: 675.742436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:55:07.569024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:07.127016Z","time spent":"441.999409ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-25T12:55:07.569038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:06.893208Z","time spent":"675.826113ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T12:55:07.569129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:55:06.865593Z","time spent":"703.317694ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:56:30.301837Z","caller":"traceutil/trace.go:171","msg":"trace[606454396] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"359.061411ms","start":"2026-07-25T12:56:29.942753Z","end":"2026-07-25T12:56:30.301814Z","steps":["trace[606454396] 'process raft request' (duration: 358.899235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:30.301973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:29.942578Z","time spent":"359.326769ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:56:30.307333Z","caller":"traceutil/trace.go:171","msg":"trace[711078107] linearizableReadLoop","detail":"{readStateIndex:1547; appliedIndex:1545; }","duration":"361.479231ms","start":"2026-07-25T12:56:29.945832Z","end":"2026-07-25T12:56:30.307311Z","steps":["trace[711078107] 'read index received' (duration: 338.828734ms)","trace[711078107] 'applied index is now lower than readState.Index' (duration: 22.649417ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:56:30.307571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.494898ms","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-25T12:56:30.307571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.713518ms","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-25T12:56:30.307619Z","caller":"traceutil/trace.go:171","msg":"trace[23496646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1478; }","duration":"360.57344ms","start":"2026-07-25T12:56:29.947034Z","end":"2026-07-25T12:56:30.307607Z","steps":["trace[23496646] 'agreement among raft nodes before linearized reading' (duration: 360.413855ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:56:30.30763Z","caller":"traceutil/trace.go:171","msg":"trace[523063942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1478; }","duration":"361.799442ms","start":"2026-07-25T12:56:29.945816Z","end":"2026-07-25T12:56:30.307615Z","steps":["trace[523063942] 'agreement among raft nodes before linearized reading' (duration: 361.615676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:30.307659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:29.947027Z","time spent":"360.621992ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T12:56:30.307664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:29.945809Z","time spent":"361.846813ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T12:56:30.307778Z","caller":"traceutil/trace.go:171","msg":"trace[693054486] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"361.916876ms","start":"2026-07-25T12:56:29.945457Z","end":"2026-07-25T12:56:30.307374Z","steps":["trace[693054486] 'process raft request' (duration: 361.691288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:30.307894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:29.945447Z","time spent":"362.36364ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:56:31.725922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085746265469081955,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-25T12:56:31.754923Z","caller":"traceutil/trace.go:171","msg":"trace[2139987017] linearizableReadLoop","detail":"{readStateIndex:1550; appliedIndex:1549; }","duration":"529.616767ms","start":"2026-07-25T12:56:31.225284Z","end":"2026-07-25T12:56:31.754901Z","steps":["trace[2139987017] 'read index received' (duration: 529.4205ms)","trace[2139987017] 'applied index is now lower than readState.Index' (duration: 195.587µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:56:31.755084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.183001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:56:31.755039Z","caller":"traceutil/trace.go:171","msg":"trace[140091683] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"597.365063ms","start":"2026-07-25T12:56:31.157628Z","end":"2026-07-25T12:56:31.754993Z","steps":["trace[140091683] 'process raft request' (duration: 597.138415ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:56:31.755116Z","caller":"traceutil/trace.go:171","msg":"trace[1570068080] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1481; }","duration":"520.298555ms","start":"2026-07-25T12:56:31.234807Z","end":"2026-07-25T12:56:31.755105Z","steps":["trace[1570068080] 'agreement among raft nodes before linearized reading' (duration: 520.207822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:31.755146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:31.2348Z","time spent":"520.338546ms","remote":"127.0.0.1:46076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-25T12:56:31.755131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.817953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:56:31.755239Z","caller":"traceutil/trace.go:171","msg":"trace[1629409705] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1481; }","duration":"529.977739ms","start":"2026-07-25T12:56:31.225242Z","end":"2026-07-25T12:56:31.755219Z","steps":["trace[1629409705] 'agreement among raft nodes before linearized reading' (duration: 529.768322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:31.75525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:31.157601Z","time spent":"597.536469ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:56:31.755285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:31.225225Z","time spent":"530.048391ms","remote":"127.0.0.1:46126","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-07-25T12:56:31.755335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.906721ms","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-25T12:56:31.755379Z","caller":"traceutil/trace.go:171","msg":"trace[2078713541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1481; }","duration":"206.982544ms","start":"2026-07-25T12:56:31.548384Z","end":"2026-07-25T12:56:31.755367Z","steps":["trace[2078713541] 'agreement among raft nodes before linearized reading' (duration: 206.835829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:32.177873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.160079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T12:56:32.17811Z","caller":"traceutil/trace.go:171","msg":"trace[1604921915] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1550; }","duration":"284.230868ms","start":"2026-07-25T12:56:31.893857Z","end":"2026-07-25T12:56:32.178087Z","steps":["trace[1604921915] 'read index received' (duration: 169.7786ms)","trace[1604921915] 'applied index is now lower than readState.Index' (duration: 114.450958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:56:32.178221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.372872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:56:32.178272Z","caller":"traceutil/trace.go:171","msg":"trace[1798643704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1483; }","duration":"284.469446ms","start":"2026-07-25T12:56:31.893789Z","end":"2026-07-25T12:56:32.178258Z","steps":["trace[1798643704] 'agreement among raft nodes before linearized reading' (duration: 284.363662ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:56:32.178324Z","caller":"traceutil/trace.go:171","msg":"trace[127728426] transaction","detail":"{read_only:false; response_revision:1482; number_of_response:1; }","duration":"418.779378ms","start":"2026-07-25T12:56:31.759522Z","end":"2026-07-25T12:56:32.178302Z","steps":["trace[127728426] 'process raft request' (duration: 304.112003ms)","trace[127728426] 'compare' (duration: 114.039514ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:56:32.178362Z","caller":"traceutil/trace.go:171","msg":"trace[1286749282] transaction","detail":"{read_only:false; response_revision:1483; number_of_response:1; }","duration":"325.010932ms","start":"2026-07-25T12:56:31.853326Z","end":"2026-07-25T12:56:32.178337Z","steps":["trace[1286749282] 'process raft request' (duration: 324.66309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:32.178417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:31.759504Z","time spent":"418.863232ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:56:32.178462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:31.853296Z","time spent":"325.120935ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:56:33.254133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.747157ms","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-25T12:56:33.254247Z","caller":"traceutil/trace.go:171","msg":"trace[1830348465] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1483; }","duration":"864.892931ms","start":"2026-07-25T12:56:32.389315Z","end":"2026-07-25T12:56:33.254207Z","steps":["trace[1830348465] 'range keys from in-memory index tree' (duration: 864.502759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:33.254301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:32.389298Z","time spent":"864.988175ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T12:56:33.254525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.526456ms","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-25T12:56:33.254573Z","caller":"traceutil/trace.go:171","msg":"trace[122825953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1483; }","duration":"941.616148ms","start":"2026-07-25T12:56:32.312944Z","end":"2026-07-25T12:56:33.254561Z","steps":["trace[122825953] 'range keys from in-memory index tree' (duration: 941.35627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:33.254574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.283775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-25T12:56:33.254575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.978122ms","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-25T12:56:33.254637Z","caller":"traceutil/trace.go:171","msg":"trace[1586060503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1483; }","duration":"949.373678ms","start":"2026-07-25T12:56:32.305244Z","end":"2026-07-25T12:56:33.254618Z","steps":["trace[1586060503] 'range keys from in-memory index tree' (duration: 949.14841ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:56:33.254645Z","caller":"traceutil/trace.go:171","msg":"trace[1436328781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1483; }","duration":"899.082476ms","start":"2026-07-25T12:56:32.355546Z","end":"2026-07-25T12:56:33.254628Z","steps":["trace[1436328781] 'range keys from in-memory index tree' (duration: 898.841567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:33.254684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:32.305228Z","time spent":"949.443381ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-25T12:56:33.254599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:32.312886Z","time spent":"941.705051ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T12:56:33.254688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:32.355525Z","time spent":"899.150998ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T12:56:46.362817Z","caller":"traceutil/trace.go:171","msg":"trace[291981134] transaction","detail":"{read_only:false; response_revision:1529; number_of_response:1; }","duration":"112.299315ms","start":"2026-07-25T12:56:46.250488Z","end":"2026-07-25T12:56:46.362788Z","steps":["trace[291981134] 'process raft request' (duration: 112.15075ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:56:47.585311Z","caller":"traceutil/trace.go:171","msg":"trace[415215224] linearizableReadLoop","detail":"{readStateIndex:1603; appliedIndex:1602; }","duration":"169.966149ms","start":"2026-07-25T12:56:47.415322Z","end":"2026-07-25T12:56:47.585288Z","steps":["trace[415215224] 'read index received' (duration: 169.832474ms)","trace[415215224] 'applied index is now lower than readState.Index' (duration: 132.745µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T12:56:47.585421Z","caller":"traceutil/trace.go:171","msg":"trace[1083107851] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"201.235916ms","start":"2026-07-25T12:56:47.384174Z","end":"2026-07-25T12:56:47.58541Z","steps":["trace[1083107851] 'process raft request' (duration: 200.973017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:47.585549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.792653ms","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-25T12:56:47.585584Z","caller":"traceutil/trace.go:171","msg":"trace[898538461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1531; }","duration":"169.858224ms","start":"2026-07-25T12:56:47.415718Z","end":"2026-07-25T12:56:47.585576Z","steps":["trace[898538461] 'agreement among raft nodes before linearized reading' (duration: 169.749541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:47.585716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.405433ms","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-25T12:56:47.585739Z","caller":"traceutil/trace.go:171","msg":"trace[1767826740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1531; }","duration":"170.477715ms","start":"2026-07-25T12:56:47.415255Z","end":"2026-07-25T12:56:47.585733Z","steps":["trace[1767826740] 'agreement among raft nodes before linearized reading' (duration: 170.420093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:47.585816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.815214ms","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-25T12:56:47.585857Z","caller":"traceutil/trace.go:171","msg":"trace[677456565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1531; }","duration":"163.876666ms","start":"2026-07-25T12:56:47.42197Z","end":"2026-07-25T12:56:47.585847Z","steps":["trace[677456565] 'agreement among raft nodes before linearized reading' (duration: 163.771973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:48.120162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.091046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T12:56:48.120279Z","caller":"traceutil/trace.go:171","msg":"trace[463257092] transaction","detail":"{read_only:false; response_revision:1532; number_of_response:1; }","duration":"530.58848ms","start":"2026-07-25T12:56:47.589671Z","end":"2026-07-25T12:56:48.12026Z","steps":["trace[463257092] 'process raft request' (duration: 337.300099ms)","trace[463257092] 'compare' (duration: 192.970331ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:56:48.120367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:47.589656Z","time spent":"530.669584ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:56:48.25833Z","caller":"traceutil/trace.go:171","msg":"trace[1355543607] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"668.226595ms","start":"2026-07-25T12:56:47.590074Z","end":"2026-07-25T12:56:48.258301Z","steps":["trace[1355543607] 'process raft request' (duration: 655.138931ms)","trace[1355543607] 'compare' (duration: 12.863937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:56:48.258503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:47.590025Z","time spent":"668.38453ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:56:48.258831Z","caller":"traceutil/trace.go:171","msg":"trace[1905243812] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"668.714201ms","start":"2026-07-25T12:56:47.590084Z","end":"2026-07-25T12:56:48.258798Z","steps":["trace[1905243812] 'process raft request' (duration: 668.170843ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:56:48.258889Z","caller":"traceutil/trace.go:171","msg":"trace[1114259464] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"454.201255ms","start":"2026-07-25T12:56:47.804647Z","end":"2026-07-25T12:56:48.258849Z","steps":["trace[1114259464] 'process raft request' (duration: 453.890295ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T12:56:48.258831Z","caller":"traceutil/trace.go:171","msg":"trace[1696847030] linearizableReadLoop","detail":"{readStateIndex:1607; appliedIndex:1604; }","duration":"221.877119ms","start":"2026-07-25T12:56:48.036921Z","end":"2026-07-25T12:56:48.258798Z","steps":["trace[1696847030] 'read index received' (duration: 208.321419ms)","trace[1696847030] 'applied index is now lower than readState.Index' (duration: 13.55402ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T12:56:48.259106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:47.804623Z","time spent":"454.368041ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T12:56:48.259016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.025184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T12:56:48.259271Z","caller":"traceutil/trace.go:171","msg":"trace[1024700445] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1535; }","duration":"222.382706ms","start":"2026-07-25T12:56:48.036868Z","end":"2026-07-25T12:56:48.259251Z","steps":["trace[1024700445] 'agreement among raft nodes before linearized reading' (duration: 222.025514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T12:56:48.259562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T12:56:47.590024Z","time spent":"668.882166ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T12:56:49.739612Z","caller":"traceutil/trace.go:171","msg":"trace[878247549] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"145.860189ms","start":"2026-07-25T12:56:49.593723Z","end":"2026-07-25T12:56:49.739583Z","steps":["trace[878247549] 'process raft request' (duration: 145.674443ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:01:27.519447Z","caller":"traceutil/trace.go:171","msg":"trace[1273983676] transaction","detail":"{read_only:false; response_revision:2450; number_of_response:1; }","duration":"328.470383ms","start":"2026-07-25T13:01:27.190959Z","end":"2026-07-25T13:01:27.519429Z","steps":["trace[1273983676] 'process raft request' (duration: 328.196574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:01:27.519549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:01:27.190931Z","time spent":"328.563126ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T13:01:49.989442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.244207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T13:01:49.989506Z","caller":"traceutil/trace.go:171","msg":"trace[468350309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2526; }","duration":"211.342081ms","start":"2026-07-25T13:01:49.778152Z","end":"2026-07-25T13:01:49.989494Z","steps":["trace[468350309] 'range keys from in-memory index tree' (duration: 211.128364ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:01:50.196108Z","caller":"traceutil/trace.go:171","msg":"trace[1268023283] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"202.634587ms","start":"2026-07-25T13:01:49.993147Z","end":"2026-07-25T13:01:50.195782Z","steps":["trace[1268023283] 'process raft request' (duration: 202.487973ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:01:51.489172Z","caller":"traceutil/trace.go:171","msg":"trace[1628214727] transaction","detail":"{read_only:false; response_revision:2528; number_of_response:1; }","duration":"208.168927ms","start":"2026-07-25T13:01:51.280975Z","end":"2026-07-25T13:01:51.489144Z","steps":["trace[1628214727] 'process raft request' (duration: 207.95384ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:01:51.75171Z","caller":"traceutil/trace.go:171","msg":"trace[534713341] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"108.618057ms","start":"2026-07-25T13:01:51.643056Z","end":"2026-07-25T13:01:51.751674Z","steps":["trace[534713341] 'process raft request' (duration: 108.40628ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:01:52.139361Z","caller":"traceutil/trace.go:171","msg":"trace[2053297203] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"383.725013ms","start":"2026-07-25T13:01:51.75561Z","end":"2026-07-25T13:01:52.139335Z","steps":["trace[2053297203] 'process raft request' (duration: 383.594899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:01:52.139593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:01:51.755588Z","time spent":"383.843557ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:01:52.149261Z","caller":"traceutil/trace.go:171","msg":"trace[1767509776] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"392.937734ms","start":"2026-07-25T13:01:51.756303Z","end":"2026-07-25T13:01:52.149241Z","steps":["trace[1767509776] 'process raft request' (duration: 392.782308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:01:52.149458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:01:51.756282Z","time spent":"393.052567ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:01:52.149532Z","caller":"traceutil/trace.go:171","msg":"trace[604403876] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"393.109258ms","start":"2026-07-25T13:01:51.756398Z","end":"2026-07-25T13:01:52.149507Z","steps":["trace[604403876] 'process raft request' (duration: 392.809968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:01:52.149662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.183484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-25T13:01:52.149712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:01:51.756371Z","time spent":"393.230272ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:01:52.149693Z","caller":"traceutil/trace.go:171","msg":"trace[1092386959] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:2532; }","duration":"355.247526ms","start":"2026-07-25T13:01:51.794438Z","end":"2026-07-25T13:01:52.149686Z","steps":["trace[1092386959] 'agreement among raft nodes before linearized reading' (duration: 355.196074ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:01:52.149664Z","caller":"traceutil/trace.go:171","msg":"trace[2047705868] linearizableReadLoop","detail":"{readStateIndex:2665; appliedIndex:2663; }","duration":"355.05991ms","start":"2026-07-25T13:01:51.794486Z","end":"2026-07-25T13:01:52.149546Z","steps":["trace[2047705868] 'read index received' (duration: 344.861929ms)","trace[2047705868] 'applied index is now lower than readState.Index' (duration: 10.15757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:01:52.149817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:01:51.794425Z","time spent":"355.381631ms","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-25T13:01:52.149961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.669844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:01:52.150005Z","caller":"traceutil/trace.go:171","msg":"trace[150417768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2532; }","duration":"255.750797ms","start":"2026-07-25T13:01:51.894242Z","end":"2026-07-25T13:01:52.149993Z","steps":["trace[150417768] 'agreement among raft nodes before linearized reading' (duration: 255.695115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:01:53.748401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.956976ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T13:01:53.748641Z","caller":"traceutil/trace.go:171","msg":"trace[465599111] transaction","detail":"{read_only:false; response_revision:2534; number_of_response:1; }","duration":"281.260597ms","start":"2026-07-25T13:01:53.467349Z","end":"2026-07-25T13:01:53.74861Z","steps":["trace[465599111] 'process raft request' (duration: 109.984021ms)","trace[465599111] 'compare' (duration: 170.797221ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:01:53.748705Z","caller":"traceutil/trace.go:171","msg":"trace[1345387500] linearizableReadLoop","detail":"{readStateIndex:2669; appliedIndex:2667; }","duration":"255.8567ms","start":"2026-07-25T13:01:53.49283Z","end":"2026-07-25T13:01:53.748687Z","steps":["trace[1345387500] 'read index received' (duration: 84.513361ms)","trace[1345387500] 'applied index is now lower than readState.Index' (duration: 171.342349ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:01:53.748774Z","caller":"traceutil/trace.go:171","msg":"trace[607817212] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"258.9051ms","start":"2026-07-25T13:01:53.489839Z","end":"2026-07-25T13:01:53.748744Z","steps":["trace[607817212] 'process raft request' (duration: 258.721404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:01:53.749789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.932656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T13:01:53.749871Z","caller":"traceutil/trace.go:171","msg":"trace[1025838691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2535; }","duration":"257.037819ms","start":"2026-07-25T13:01:53.492816Z","end":"2026-07-25T13:01:53.749854Z","steps":["trace[1025838691] 'agreement among raft nodes before linearized reading' (duration: 255.979864ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:01:53.953107Z","caller":"traceutil/trace.go:171","msg":"trace[760803041] linearizableReadLoop","detail":"{readStateIndex:2670; appliedIndex:2669; }","duration":"199.32522ms","start":"2026-07-25T13:01:53.753764Z","end":"2026-07-25T13:01:53.953089Z","steps":["trace[760803041] 'read index received' (duration: 156.266028ms)","trace[760803041] 'applied index is now lower than readState.Index' (duration: 43.058352ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:01:53.953165Z","caller":"traceutil/trace.go:171","msg":"trace[156992744] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"199.618759ms","start":"2026-07-25T13:01:53.753507Z","end":"2026-07-25T13:01:53.953125Z","steps":["trace[156992744] 'process raft request' (duration: 156.434443ms)","trace[156992744] 'compare' (duration: 43.024221ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:01:53.953291Z","caller":"traceutil/trace.go:171","msg":"trace[1544772880] transaction","detail":"{read_only:false; response_revision:2537; number_of_response:1; }","duration":"197.045446ms","start":"2026-07-25T13:01:53.756216Z","end":"2026-07-25T13:01:53.953262Z","steps":["trace[1544772880] 'process raft request' (duration: 196.850089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:01:53.953282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.508696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-25T13:01:53.953447Z","caller":"traceutil/trace.go:171","msg":"trace[979050021] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2537; }","duration":"199.696012ms","start":"2026-07-25T13:01:53.75373Z","end":"2026-07-25T13:01:53.953426Z","steps":["trace[979050021] 'agreement among raft nodes before linearized reading' (duration: 199.462444ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:10.129369Z","caller":"traceutil/trace.go:171","msg":"trace[1083879494] linearizableReadLoop","detail":"{readStateIndex:2779; appliedIndex:2778; }","duration":"109.644927ms","start":"2026-07-25T13:02:10.019703Z","end":"2026-07-25T13:02:10.129348Z","steps":["trace[1083879494] 'read index received' (duration: 109.479862ms)","trace[1083879494] 'applied index is now lower than readState.Index' (duration: 162.995µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:02:10.129522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.803502ms","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-25T13:02:10.129542Z","caller":"traceutil/trace.go:171","msg":"trace[1334438664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2640; }","duration":"109.867994ms","start":"2026-07-25T13:02:10.019669Z","end":"2026-07-25T13:02:10.129537Z","steps":["trace[1334438664] 'agreement among raft nodes before linearized reading' (duration: 109.75879ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:10.129765Z","caller":"traceutil/trace.go:171","msg":"trace[833705685] transaction","detail":"{read_only:false; response_revision:2640; number_of_response:1; }","duration":"124.43958ms","start":"2026-07-25T13:02:10.005318Z","end":"2026-07-25T13:02:10.129758Z","steps":["trace[833705685] 'process raft request' (duration: 123.918083ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:12.26343Z","caller":"traceutil/trace.go:171","msg":"trace[1552159780] linearizableReadLoop","detail":"{readStateIndex:2785; appliedIndex:2784; }","duration":"123.355826ms","start":"2026-07-25T13:02:12.14005Z","end":"2026-07-25T13:02:12.263406Z","steps":["trace[1552159780] 'read index received' (duration: 123.206491ms)","trace[1552159780] 'applied index is now lower than readState.Index' (duration: 148.424µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:02:12.263514Z","caller":"traceutil/trace.go:171","msg":"trace[1997132196] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"129.371368ms","start":"2026-07-25T13:02:12.13411Z","end":"2026-07-25T13:02:12.263482Z","steps":["trace[1997132196] 'process raft request' (duration: 129.167571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:12.263907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.842531ms","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-25T13:02:12.263981Z","caller":"traceutil/trace.go:171","msg":"trace[541161786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2646; }","duration":"123.960125ms","start":"2026-07-25T13:02:12.140002Z","end":"2026-07-25T13:02:12.263962Z","steps":["trace[541161786] 'agreement among raft nodes before linearized reading' (duration: 123.549342ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:12.444906Z","caller":"traceutil/trace.go:171","msg":"trace[547555798] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"176.34122ms","start":"2026-07-25T13:02:12.268537Z","end":"2026-07-25T13:02:12.444878Z","steps":["trace[547555798] 'process raft request' (duration: 111.60407ms)","trace[547555798] 'compare' (duration: 64.648177ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:02:12.450566Z","caller":"traceutil/trace.go:171","msg":"trace[1167674152] linearizableReadLoop","detail":"{readStateIndex:2789; appliedIndex:2785; }","duration":"132.848779ms","start":"2026-07-25T13:02:12.317706Z","end":"2026-07-25T13:02:12.450555Z","steps":["trace[1167674152] 'read index received' (duration: 62.454088ms)","trace[1167674152] 'applied index is now lower than readState.Index' (duration: 70.394031ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:02:12.450701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.978883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T13:02:12.450721Z","caller":"traceutil/trace.go:171","msg":"trace[1768092203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2650; }","duration":"133.044655ms","start":"2026-07-25T13:02:12.31767Z","end":"2026-07-25T13:02:12.450715Z","steps":["trace[1768092203] 'agreement among raft nodes before linearized reading' (duration: 132.928721ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:12.450891Z","caller":"traceutil/trace.go:171","msg":"trace[213239495] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"182.221259ms","start":"2026-07-25T13:02:12.268663Z","end":"2026-07-25T13:02:12.450884Z","steps":["trace[213239495] 'process raft request' (duration: 181.786715ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:12.450963Z","caller":"traceutil/trace.go:171","msg":"trace[805876630] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"182.248059ms","start":"2026-07-25T13:02:12.268708Z","end":"2026-07-25T13:02:12.450956Z","steps":["trace[805876630] 'process raft request' (duration: 181.792954ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:12.45103Z","caller":"traceutil/trace.go:171","msg":"trace[511392861] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"179.860573ms","start":"2026-07-25T13:02:12.271163Z","end":"2026-07-25T13:02:12.451024Z","steps":["trace[511392861] 'process raft request' (duration: 179.364007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:28.650099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.826271ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T13:02:28.65074Z","caller":"traceutil/trace.go:171","msg":"trace[1308741251] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"124.977597ms","start":"2026-07-25T13:02:28.525739Z","end":"2026-07-25T13:02:28.650717Z","steps":["trace[1308741251] 'process raft request' (duration: 124.45829ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:28.650721Z","caller":"traceutil/trace.go:171","msg":"trace[2094088158] linearizableReadLoop","detail":"{readStateIndex:2861; appliedIndex:2859; }","duration":"122.466107ms","start":"2026-07-25T13:02:28.528222Z","end":"2026-07-25T13:02:28.650688Z","steps":["trace[2094088158] 'read index received' (duration: 8.035227ms)","trace[2094088158] 'applied index is now lower than readState.Index' (duration: 114.42888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:02:28.652086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.931532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-25T13:02:28.652167Z","caller":"traceutil/trace.go:171","msg":"trace[518304028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2718; }","duration":"123.039755ms","start":"2026-07-25T13:02:28.529112Z","end":"2026-07-25T13:02:28.652152Z","steps":["trace[518304028] 'agreement among raft nodes before linearized reading' (duration: 121.851797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:28.652258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.004646ms","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-25T13:02:28.652332Z","caller":"traceutil/trace.go:171","msg":"trace[872139853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2718; }","duration":"124.12854ms","start":"2026-07-25T13:02:28.528186Z","end":"2026-07-25T13:02:28.652314Z","steps":["trace[872139853] 'agreement among raft nodes before linearized reading' (duration: 122.593541ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:30.556736Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1674} {"level":"info","ts":"2026-07-25T13:02:30.602531Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1674,"took":"45.3382ms","hash":3223742563,"current-db-size-bytes":24059904,"current-db-size":"24 MB","current-db-size-in-use-bytes":6062080,"current-db-size-in-use":"6.1 MB"} {"level":"info","ts":"2026-07-25T13:02:30.602592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3223742563,"revision":1674,"compact-revision":-1} {"level":"info","ts":"2026-07-25T13:02:32.595401Z","caller":"traceutil/trace.go:171","msg":"trace[167404019] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"257.823196ms","start":"2026-07-25T13:02:32.337539Z","end":"2026-07-25T13:02:32.595362Z","steps":["trace[167404019] 'process raft request' (duration: 257.622889ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T13:02:32.880282Z","caller":"traceutil/trace.go:171","msg":"trace[1178328297] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"206.99196ms","start":"2026-07-25T13:02:32.673255Z","end":"2026-07-25T13:02:32.880247Z","steps":["trace[1178328297] 'process raft request' (duration: 185.973028ms)","trace[1178328297] 'compare' (duration: 20.725483ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:02:32.880297Z","caller":"traceutil/trace.go:171","msg":"trace[672544587] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"206.881826ms","start":"2026-07-25T13:02:32.673372Z","end":"2026-07-25T13:02:32.880254Z","steps":["trace[672544587] 'process raft request' (duration: 206.783103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:34.224056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:02:33.839541Z","time spent":"384.508378ms","remote":"127.0.0.1:43448","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-25T13:02:34.224677Z","caller":"traceutil/trace.go:171","msg":"trace[138744353] linearizableReadLoop","detail":"{readStateIndex:2899; appliedIndex:2898; }","duration":"330.883523ms","start":"2026-07-25T13:02:33.893752Z","end":"2026-07-25T13:02:34.224635Z","steps":["trace[138744353] 'read index received' (duration: 288.777326ms)","trace[138744353] 'applied index is now lower than readState.Index' (duration: 42.023074ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:02:34.224892Z","caller":"traceutil/trace.go:171","msg":"trace[1079359074] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"527.838681ms","start":"2026-07-25T13:02:33.697004Z","end":"2026-07-25T13:02:34.224843Z","steps":["trace[1079359074] 'process raft request' (duration: 485.504968ms)","trace[1079359074] 'compare' (duration: 41.935801ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:02:34.224995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.034837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T13:02:34.225074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:02:33.696984Z","time spent":"527.998327ms","remote":"127.0.0.1:34812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:02:34.225091Z","caller":"traceutil/trace.go:171","msg":"trace[1231406971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2754; }","duration":"331.372278ms","start":"2026-07-25T13:02:33.893704Z","end":"2026-07-25T13:02:34.225076Z","steps":["trace[1231406971] 'agreement among raft nodes before linearized reading' (duration: 331.022447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:34.225151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:02:33.893684Z","time spent":"331.456041ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T13:02:34.949065Z","caller":"traceutil/trace.go:171","msg":"trace[1289502926] transaction","detail":"{read_only:false; response_revision:2766; number_of_response:1; }","duration":"203.585341ms","start":"2026-07-25T13:02:34.745437Z","end":"2026-07-25T13:02:34.949023Z","steps":["trace[1289502926] 'process raft request' (duration: 165.551695ms)","trace[1289502926] 'compare' (duration: 37.705706ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T13:02:36.857806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.090165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T13:02:36.857885Z","caller":"traceutil/trace.go:171","msg":"trace[315887091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2771; }","duration":"112.208839ms","start":"2026-07-25T13:02:36.745659Z","end":"2026-07-25T13:02:36.857867Z","steps":["trace[315887091] 'range keys from in-memory index tree' (duration: 112.014313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:57.422437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.158255ms","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-25T13:02:57.422539Z","caller":"traceutil/trace.go:171","msg":"trace[1329985691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2881; }","duration":"328.274409ms","start":"2026-07-25T13:02:57.094243Z","end":"2026-07-25T13:02:57.422517Z","steps":["trace[1329985691] 'range keys from in-memory index tree' (duration: 327.889516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:57.42258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:02:57.094187Z","time spent":"328.383682ms","remote":"127.0.0.1:35048","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-25T13:02:57.422576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.924028ms","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-25T13:02:57.422659Z","caller":"traceutil/trace.go:171","msg":"trace[1610392212] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2881; }","duration":"328.052472ms","start":"2026-07-25T13:02:57.094587Z","end":"2026-07-25T13:02:57.422639Z","steps":["trace[1610392212] 'range keys from in-memory index tree' (duration: 327.725231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:57.422703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:02:57.094572Z","time spent":"328.121664ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T13:02:57.422696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.9994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-25T13:02:57.422758Z","caller":"traceutil/trace.go:171","msg":"trace[802772021] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2881; }","duration":"479.102093ms","start":"2026-07-25T13:02:56.943639Z","end":"2026-07-25T13:02:57.422741Z","steps":["trace[802772021] 'range keys from in-memory index tree' (duration: 478.908286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:57.422833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:02:56.943616Z","time spent":"479.204706ms","remote":"127.0.0.1:35048","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-25T13:02:57.423002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.804736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T13:02:57.423035Z","caller":"traceutil/trace.go:171","msg":"trace[2032511363] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2881; }","duration":"308.875758ms","start":"2026-07-25T13:02:57.114149Z","end":"2026-07-25T13:02:57.423025Z","steps":["trace[2032511363] 'count revisions from in-memory index tree' (duration: 308.714383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:57.423057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.912753ms","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-25T13:02:57.4231Z","caller":"traceutil/trace.go:171","msg":"trace[1131911945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2881; }","duration":"356.017766ms","start":"2026-07-25T13:02:57.067071Z","end":"2026-07-25T13:02:57.423088Z","steps":["trace[1131911945] 'range keys from in-memory index tree' (duration: 355.766078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:02:57.423104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:02:57.114124Z","time spent":"308.951501ms","remote":"127.0.0.1:34982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-25T13:02:57.423129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:02:57.067049Z","time spent":"356.073058ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T13:03:02.855065Z","caller":"traceutil/trace.go:171","msg":"trace[1016511489] transaction","detail":"{read_only:false; response_revision:2906; number_of_response:1; }","duration":"331.888104ms","start":"2026-07-25T13:03:02.523146Z","end":"2026-07-25T13:03:02.855034Z","steps":["trace[1016511489] 'process raft request' (duration: 331.684378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:03:02.855243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:03:02.523138Z","time spent":"332.016178ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:03:03.252233Z","caller":"traceutil/trace.go:171","msg":"trace[881922720] linearizableReadLoop","detail":"{readStateIndex:3057; appliedIndex:3055; }","duration":"410.053675ms","start":"2026-07-25T13:03:02.842152Z","end":"2026-07-25T13:03:03.252206Z","steps":["trace[881922720] 'read index received' (duration: 12.715957ms)","trace[881922720] 'applied index is now lower than readState.Index' (duration: 397.336928ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T13:03:03.252328Z","caller":"traceutil/trace.go:171","msg":"trace[2134353261] transaction","detail":"{read_only:false; response_revision:2907; number_of_response:1; }","duration":"551.788967ms","start":"2026-07-25T13:03:02.700494Z","end":"2026-07-25T13:03:03.252283Z","steps":["trace[2134353261] 'process raft request' (duration: 551.56943ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:03:03.25247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.298502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-25T13:03:03.252491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:03:02.700469Z","time spent":"551.942942ms","remote":"127.0.0.1:35048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T13:03:03.252503Z","caller":"traceutil/trace.go:171","msg":"trace[1238984791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2907; }","duration":"410.385025ms","start":"2026-07-25T13:03:02.842108Z","end":"2026-07-25T13:03:03.252494Z","steps":["trace[1238984791] 'agreement among raft nodes before linearized reading' (duration: 410.23969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:03:03.252541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:03:02.842095Z","time spent":"410.438337ms","remote":"127.0.0.1:35048","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-25T13:03:03.252535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.37658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T13:03:03.252611Z","caller":"traceutil/trace.go:171","msg":"trace[2062808554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2907; }","duration":"357.494493ms","start":"2026-07-25T13:03:02.895098Z","end":"2026-07-25T13:03:03.252593Z","steps":["trace[2062808554] 'agreement among raft nodes before linearized reading' (duration: 357.326818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T13:03:03.252652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T13:03:02.895081Z","time spent":"357.563326ms","remote":"127.0.0.1:34788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T13:07:30.5682Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2736} {"level":"info","ts":"2026-07-25T13:07:30.589509Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2736,"took":"20.579981ms","hash":3423326958,"current-db-size-bytes":24059904,"current-db-size":"24 MB","current-db-size-in-use-bytes":6500352,"current-db-size-in-use":"6.5 MB"} {"level":"info","ts":"2026-07-25T13:07:30.589558Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3423326958,"revision":2736,"compact-revision":1674}