{"level":"warn","ts":"2026-04-23T15:49:54.820842Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T15:49:54.820988Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.155: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.155:2380","--initial-cluster=instance=https://199.204.45.155:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.155:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.155:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-23T15:49:54.821117Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-23T15:49:54.821146Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-23T15:49:54.82118Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T15:49:54.822795Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"]} {"level":"info","ts":"2026-04-23T15:49:54.822933Z","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.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.155:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-23T15:49:54.827144Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.773871ms"} {"level":"info","ts":"2026-04-23T15:49:54.833744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"344940fc142d902f","cluster-id":"c7d65bf2a36fbd4"} {"level":"info","ts":"2026-04-23T15:49:54.83382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=()"} {"level":"info","ts":"2026-04-23T15:49:54.833843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 0"} {"level":"info","ts":"2026-04-23T15:49:54.833858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 344940fc142d902f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-23T15:49:54.83387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became follower at term 1"} {"level":"info","ts":"2026-04-23T15:49:54.833906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"warn","ts":"2026-04-23T15:49:54.839677Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-23T15:49:54.841063Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-23T15:49:54.842319Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-23T15:49:54.844651Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"344940fc142d902f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-23T15:49:54.844868Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T15:49:54.844948Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"344940fc142d902f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-23T15:49:54.845053Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T15:49:54.845072Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-23T15:49:54.850115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f switched to configuration voters=(3767614014686597167)"} {"level":"info","ts":"2026-04-23T15:49:54.850291Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","added-peer-id":"344940fc142d902f","added-peer-peer-urls":["https://199.204.45.155:2380"]} {"level":"info","ts":"2026-04-23T15:49:54.851445Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-23T15:49:54.851552Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-23T15:49:54.851605Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.155:2380"} {"level":"info","ts":"2026-04-23T15:49:54.85172Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"344940fc142d902f","initial-advertise-peer-urls":["https://199.204.45.155:2380"],"listen-peer-urls":["https://199.204.45.155:2380"],"advertise-client-urls":["https://199.204.45.155:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.155:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-23T15:49:54.851764Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-23T15:49:55.53455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f is starting a new election at term 1"} {"level":"info","ts":"2026-04-23T15:49:55.534687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became pre-candidate at term 1"} {"level":"info","ts":"2026-04-23T15:49:55.534735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgPreVoteResp from 344940fc142d902f at term 1"} {"level":"info","ts":"2026-04-23T15:49:55.534808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became candidate at term 2"} {"level":"info","ts":"2026-04-23T15:49:55.534915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f received MsgVoteResp from 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-23T15:49:55.534983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"344940fc142d902f became leader at term 2"} {"level":"info","ts":"2026-04-23T15:49:55.535016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 344940fc142d902f elected leader 344940fc142d902f at term 2"} {"level":"info","ts":"2026-04-23T15:49:55.542038Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T15:49:55.554366Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T15:49:55.554442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-23T15:49:55.554363Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"344940fc142d902f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.155:2379]}","request-path":"/0/members/344940fc142d902f/attributes","cluster-id":"c7d65bf2a36fbd4","publish-timeout":"7s"} {"level":"info","ts":"2026-04-23T15:49:55.554745Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-23T15:49:55.55479Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-23T15:49:55.557757Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-23T15:49:55.557903Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.155:2379"} {"level":"info","ts":"2026-04-23T15:49:55.566482Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c7d65bf2a36fbd4","local-member-id":"344940fc142d902f","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T15:49:55.566654Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-23T15:49:55.566713Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-23T15:50:20.643025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.194075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T15:50:20.643125Z","caller":"traceutil/trace.go:171","msg":"trace[19925698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:459; }","duration":"429.382609ms","start":"2026-04-23T15:50:20.213724Z","end":"2026-04-23T15:50:20.643106Z","steps":["trace[19925698] 'range keys from in-memory index tree' (duration: 429.024971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:20.643201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:20.213691Z","time spent":"429.499781ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T15:50:21.051674Z","caller":"traceutil/trace.go:171","msg":"trace[1415479836] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"403.759373ms","start":"2026-04-23T15:50:20.647885Z","end":"2026-04-23T15:50:21.051644Z","steps":["trace[1415479836] 'process raft request' (duration: 403.573549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:21.052634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:20.64786Z","time spent":"403.900575ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:21.082533Z","caller":"traceutil/trace.go:171","msg":"trace[553024092] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"291.166181ms","start":"2026-04-23T15:50:20.791329Z","end":"2026-04-23T15:50:21.082495Z","steps":["trace[553024092] 'process raft request' (duration: 290.956206ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:24.654443Z","caller":"traceutil/trace.go:171","msg":"trace[1524468122] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"152.908615ms","start":"2026-04-23T15:50:24.501508Z","end":"2026-04-23T15:50:24.654417Z","steps":["trace[1524468122] 'process raft request' (duration: 142.739564ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:24.654711Z","caller":"traceutil/trace.go:171","msg":"trace[1665741554] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"124.358646ms","start":"2026-04-23T15:50:24.530342Z","end":"2026-04-23T15:50:24.6547Z","steps":["trace[1665741554] 'process raft request' (duration: 123.290512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:25.227494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.748886ms","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-04-23T15:50:25.227628Z","caller":"traceutil/trace.go:171","msg":"trace[1605576642] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:534; }","duration":"459.895169ms","start":"2026-04-23T15:50:24.767714Z","end":"2026-04-23T15:50:25.227609Z","steps":["trace[1605576642] 'range keys from in-memory index tree' (duration: 459.736226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:25.228206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.343388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T15:50:25.228394Z","caller":"traceutil/trace.go:171","msg":"trace[617156647] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"534.426845ms","start":"2026-04-23T15:50:24.69392Z","end":"2026-04-23T15:50:25.228347Z","steps":["trace[617156647] 'process raft request' (duration: 240.573866ms)","trace[617156647] 'compare' (duration: 292.857638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:50:25.228489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:24.693893Z","time spent":"534.559307ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:25.232162Z","caller":"traceutil/trace.go:171","msg":"trace[1370679679] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:558; }","duration":"159.13022ms","start":"2026-04-23T15:50:25.073012Z","end":"2026-04-23T15:50:25.232142Z","steps":["trace[1370679679] 'read index received' (duration: 158.742482ms)","trace[1370679679] 'applied index is now lower than readState.Index' (duration: 387.248µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:50:25.232257Z","caller":"traceutil/trace.go:171","msg":"trace[692634507] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"537.668545ms","start":"2026-04-23T15:50:24.694581Z","end":"2026-04-23T15:50:25.23225Z","steps":["trace[692634507] 'process raft request' (duration: 537.246616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:25.232357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:24.694568Z","time spent":"537.710797ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:25.232462Z","caller":"traceutil/trace.go:171","msg":"trace[1136701581] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"521.418474ms","start":"2026-04-23T15:50:24.711038Z","end":"2026-04-23T15:50:25.232457Z","steps":["trace[1136701581] 'process raft request' (duration: 520.929883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:25.23249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:24.711011Z","time spent":"521.463484ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-23T15:50:25.232539Z","caller":"traceutil/trace.go:171","msg":"trace[33731142] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"509.421653ms","start":"2026-04-23T15:50:24.723112Z","end":"2026-04-23T15:50:25.232533Z","steps":["trace[33731142] 'process raft request' (duration: 508.908793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:25.232566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:24.723077Z","time spent":"509.473345ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:50:25.232653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.650651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T15:50:25.232679Z","caller":"traceutil/trace.go:171","msg":"trace[289862417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"159.720212ms","start":"2026-04-23T15:50:25.072952Z","end":"2026-04-23T15:50:25.232673Z","steps":["trace[289862417] 'agreement among raft nodes before linearized reading' (duration: 159.665811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:25.232824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.105505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:50:25.232841Z","caller":"traceutil/trace.go:171","msg":"trace[1392989075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"156.143166ms","start":"2026-04-23T15:50:25.076693Z","end":"2026-04-23T15:50:25.232836Z","steps":["trace[1392989075] 'agreement among raft nodes before linearized reading' (duration: 156.114045ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:25.930315Z","caller":"traceutil/trace.go:171","msg":"trace[1689689373] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"190.814866ms","start":"2026-04-23T15:50:25.739478Z","end":"2026-04-23T15:50:25.930293Z","steps":["trace[1689689373] 'process raft request' (duration: 177.466077ms)","trace[1689689373] 'compare' (duration: 11.800775ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:50:26.721319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.245928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-23T15:50:26.7214Z","caller":"traceutil/trace.go:171","msg":"trace[436289692] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"390.74982ms","start":"2026-04-23T15:50:26.33063Z","end":"2026-04-23T15:50:26.72138Z","steps":["trace[436289692] 'process raft request' (duration: 389.917543ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:26.721448Z","caller":"traceutil/trace.go:171","msg":"trace[1509159540] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:577; }","duration":"407.556794ms","start":"2026-04-23T15:50:26.313859Z","end":"2026-04-23T15:50:26.721416Z","steps":["trace[1509159540] 'range keys from in-memory index tree' (duration: 406.926731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:26.721515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:26.313843Z","time spent":"407.656837ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-23T15:50:26.721522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:26.330576Z","time spent":"390.877833ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:26.733928Z","caller":"traceutil/trace.go:171","msg":"trace[243970727] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"309.380658ms","start":"2026-04-23T15:50:26.424521Z","end":"2026-04-23T15:50:26.733902Z","steps":["trace[243970727] 'process raft request' (duration: 308.668282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:26.734059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:26.424489Z","time spent":"309.49892ms","remote":"127.0.0.1:39696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:29.855992Z","caller":"traceutil/trace.go:171","msg":"trace[1650739765] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"149.297916ms","start":"2026-04-23T15:50:29.705587Z","end":"2026-04-23T15:50:29.854885Z","steps":["trace[1650739765] 'process raft request' (duration: 139.77694ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:31.671792Z","caller":"traceutil/trace.go:171","msg":"trace[721967644] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"342.601157ms","start":"2026-04-23T15:50:31.329158Z","end":"2026-04-23T15:50:31.67176Z","steps":["trace[721967644] 'process raft request' (duration: 341.154587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:31.671981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:31.329131Z","time spent":"342.748281ms","remote":"127.0.0.1:39736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:32.170862Z","caller":"traceutil/trace.go:171","msg":"trace[943855619] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"716.646215ms","start":"2026-04-23T15:50:31.453973Z","end":"2026-04-23T15:50:32.170619Z","steps":["trace[943855619] 'read index received' (duration: 215.552913ms)","trace[943855619] 'applied index is now lower than readState.Index' (duration: 501.092342ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:50:32.171145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.152967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T15:50:32.171246Z","caller":"traceutil/trace.go:171","msg":"trace[1358978315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"717.294029ms","start":"2026-04-23T15:50:31.453929Z","end":"2026-04-23T15:50:32.171223Z","steps":["trace[1358978315] 'agreement among raft nodes before linearized reading' (duration: 716.961802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:32.171297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:31.453913Z","time spent":"717.374851ms","remote":"127.0.0.1:39876","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-04-23T15:50:32.171208Z","caller":"traceutil/trace.go:171","msg":"trace[1298911469] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"771.645388ms","start":"2026-04-23T15:50:31.399106Z","end":"2026-04-23T15:50:32.170751Z","steps":["trace[1298911469] 'process raft request' (duration: 771.266689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:32.171877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:31.399084Z","time spent":"772.543497ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:32.187234Z","caller":"traceutil/trace.go:171","msg":"trace[1987013945] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"456.446074ms","start":"2026-04-23T15:50:31.730773Z","end":"2026-04-23T15:50:32.187219Z","steps":["trace[1987013945] 'process raft request' (duration: 456.348662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:32.187487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:31.730734Z","time spent":"456.546667ms","remote":"127.0.0.1:39944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T15:50:32.188102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.979771ms","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-04-23T15:50:32.188151Z","caller":"traceutil/trace.go:171","msg":"trace[115040871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"686.060642ms","start":"2026-04-23T15:50:31.50208Z","end":"2026-04-23T15:50:32.18814Z","steps":["trace[115040871] 'agreement among raft nodes before linearized reading' (duration: 685.911099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:32.188357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:31.502063Z","time spent":"686.280968ms","remote":"127.0.0.1:39876","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-04-23T15:50:32.188883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.082345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-297tl\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-23T15:50:32.188931Z","caller":"traceutil/trace.go:171","msg":"trace[1367474444] range","detail":"{range_begin:/registry/pods/kube-system/cilium-297tl; range_end:; response_count:1; response_revision:610; }","duration":"687.164276ms","start":"2026-04-23T15:50:31.501757Z","end":"2026-04-23T15:50:32.188921Z","steps":["trace[1367474444] 'agreement among raft nodes before linearized reading' (duration: 687.045163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:32.188961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:31.501721Z","time spent":"687.234208ms","remote":"127.0.0.1:39794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-297tl\" "} {"level":"warn","ts":"2026-04-23T15:50:33.417987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:32.580792Z","time spent":"837.189179ms","remote":"127.0.0.1:49792","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-23T15:50:33.419102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145671316s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T15:50:33.419353Z","caller":"traceutil/trace.go:171","msg":"trace[1066939989] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.221783595s","start":"2026-04-23T15:50:32.197547Z","end":"2026-04-23T15:50:33.419331Z","steps":["trace[1066939989] 'process raft request' (duration: 75.144569ms)","trace[1066939989] 'compare' (duration: 1.144989551s)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:50:33.41946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:32.19753Z","time spent":"1.221877258s","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:33.419513Z","caller":"traceutil/trace.go:171","msg":"trace[1874569066] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:635; }","duration":"1.218303971s","start":"2026-04-23T15:50:32.201186Z","end":"2026-04-23T15:50:33.41949Z","steps":["trace[1874569066] 'read index received' (duration: 71.519011ms)","trace[1874569066] 'applied index is now lower than readState.Index' (duration: 1.14678372s)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:50:33.419661Z","caller":"traceutil/trace.go:171","msg":"trace[908440044] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"1.218635348s","start":"2026-04-23T15:50:32.201013Z","end":"2026-04-23T15:50:33.419649Z","steps":["trace[908440044] 'process raft request' (duration: 1.218161208s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:33.41973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:32.200993Z","time spent":"1.218705949s","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:50:33.419853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.978402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T15:50:33.420246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218970026s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:50:33.420301Z","caller":"traceutil/trace.go:171","msg":"trace[480369470] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:614; }","duration":"1.219120929s","start":"2026-04-23T15:50:32.201164Z","end":"2026-04-23T15:50:33.420285Z","steps":["trace[480369470] 'agreement among raft nodes before linearized reading' (duration: 1.218962285s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:33.420343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:32.201156Z","time spent":"1.2191759s","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-04-23T15:50:33.420548Z","caller":"traceutil/trace.go:171","msg":"trace[1692565597] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.215094811s","start":"2026-04-23T15:50:32.205438Z","end":"2026-04-23T15:50:33.420533Z","steps":["trace[1692565597] 'process raft request' (duration: 1.213839883s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:33.420651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:32.205413Z","time spent":"1.215175883s","remote":"127.0.0.1:39794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:50:33.421165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.433051ms","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-04-23T15:50:33.421208Z","caller":"traceutil/trace.go:171","msg":"trace[803357343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"114.503732ms","start":"2026-04-23T15:50:33.306694Z","end":"2026-04-23T15:50:33.421198Z","steps":["trace[803357343] 'agreement among raft nodes before linearized reading' (duration: 114.38106ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:33.419888Z","caller":"traceutil/trace.go:171","msg":"trace[639138417] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"323.038504ms","start":"2026-04-23T15:50:33.09684Z","end":"2026-04-23T15:50:33.419879Z","steps":["trace[639138417] 'agreement among raft nodes before linearized reading' (duration: 322.978972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:33.425062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:33.096826Z","time spent":"328.219415ms","remote":"127.0.0.1:39634","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-04-23T15:50:33.947226Z","caller":"traceutil/trace.go:171","msg":"trace[1261358204] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"514.564585ms","start":"2026-04-23T15:50:33.432628Z","end":"2026-04-23T15:50:33.947193Z","steps":["trace[1261358204] 'process raft request' (duration: 440.169242ms)","trace[1261358204] 'compare' (duration: 73.916093ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:50:33.94738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:33.432617Z","time spent":"514.689587ms","remote":"127.0.0.1:39876","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-04-23T15:50:33.950203Z","caller":"traceutil/trace.go:171","msg":"trace[1636473257] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"103.596766ms","start":"2026-04-23T15:50:33.846582Z","end":"2026-04-23T15:50:33.950178Z","steps":["trace[1636473257] 'process raft request' (duration: 102.971712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:35.945281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.012068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T15:50:35.945351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.405156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:50:35.945371Z","caller":"traceutil/trace.go:171","msg":"trace[441502904] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:658; }","duration":"153.173021ms","start":"2026-04-23T15:50:35.79218Z","end":"2026-04-23T15:50:35.945353Z","steps":["trace[441502904] 'range keys from in-memory index tree' (duration: 152.950346ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:35.945444Z","caller":"traceutil/trace.go:171","msg":"trace[2100887307] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:658; }","duration":"153.572469ms","start":"2026-04-23T15:50:35.791855Z","end":"2026-04-23T15:50:35.945427Z","steps":["trace[2100887307] 'range keys from in-memory index tree' (duration: 153.314584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:35.945433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.725523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:50:35.945546Z","caller":"traceutil/trace.go:171","msg":"trace[1775050851] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:658; }","duration":"177.843275ms","start":"2026-04-23T15:50:35.767643Z","end":"2026-04-23T15:50:35.945487Z","steps":["trace[1775050851] 'range keys from in-memory index tree' (duration: 177.60891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:35.945918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.449257ms","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-04-23T15:50:35.945984Z","caller":"traceutil/trace.go:171","msg":"trace[2133011651] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:658; }","duration":"154.138892ms","start":"2026-04-23T15:50:35.791836Z","end":"2026-04-23T15:50:35.945975Z","steps":["trace[2133011651] 'count revisions from in-memory index tree' (duration: 153.323814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:35.946111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.729193ms","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":"warn","ts":"2026-04-23T15:50:35.946166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.802674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:50:35.94619Z","caller":"traceutil/trace.go:171","msg":"trace[304451877] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:658; }","duration":"178.5685ms","start":"2026-04-23T15:50:35.7676Z","end":"2026-04-23T15:50:35.946169Z","steps":["trace[304451877] 'count revisions from in-memory index tree' (duration: 177.61202ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:35.946952Z","caller":"traceutil/trace.go:171","msg":"trace[1378404147] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:658; }","duration":"178.629982ms","start":"2026-04-23T15:50:35.768309Z","end":"2026-04-23T15:50:35.946939Z","steps":["trace[1378404147] 'range keys from in-memory index tree' (duration: 176.831243ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:35.947269Z","caller":"traceutil/trace.go:171","msg":"trace[610484154] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"153.893856ms","start":"2026-04-23T15:50:35.793361Z","end":"2026-04-23T15:50:35.947255Z","steps":["trace[610484154] 'process raft request' (duration: 153.769983ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:49.786849Z","caller":"traceutil/trace.go:171","msg":"trace[1885809722] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"290.837085ms","start":"2026-04-23T15:50:49.495989Z","end":"2026-04-23T15:50:49.786826Z","steps":["trace[1885809722] 'read index received' (duration: 290.791364ms)","trace[1885809722] 'applied index is now lower than readState.Index' (duration: 44.701µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:50:49.786933Z","caller":"traceutil/trace.go:171","msg":"trace[1974901030] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"325.73757ms","start":"2026-04-23T15:50:49.461164Z","end":"2026-04-23T15:50:49.786902Z","steps":["trace[1974901030] 'process raft request' (duration: 325.505996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:49.787154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.141881ms","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-04-23T15:50:49.787456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:49.461136Z","time spent":"326.18721ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:50:49.787513Z","caller":"traceutil/trace.go:171","msg":"trace[729117901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"291.519479ms","start":"2026-04-23T15:50:49.495975Z","end":"2026-04-23T15:50:49.787495Z","steps":["trace[729117901] 'agreement among raft nodes before linearized reading' (duration: 291.027169ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:50:49.800157Z","caller":"traceutil/trace.go:171","msg":"trace[913921446] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"180.169968ms","start":"2026-04-23T15:50:49.619954Z","end":"2026-04-23T15:50:49.800124Z","steps":["trace[913921446] 'process raft request' (duration: 180.000235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:49.800312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.07336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T15:50:49.800379Z","caller":"traceutil/trace.go:171","msg":"trace[708702465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"303.155011ms","start":"2026-04-23T15:50:49.49721Z","end":"2026-04-23T15:50:49.800365Z","steps":["trace[708702465] 'agreement among raft nodes before linearized reading' (duration: 302.962047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:50:49.800425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:50:49.497205Z","time spent":"303.209053ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T15:51:24.975551Z","caller":"traceutil/trace.go:171","msg":"trace[1120719530] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"134.566533ms","start":"2026-04-23T15:51:24.840966Z","end":"2026-04-23T15:51:24.975532Z","steps":["trace[1120719530] 'process raft request' (duration: 134.438851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:26.468857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696291995392607,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T15:51:26.561974Z","caller":"traceutil/trace.go:171","msg":"trace[1981999764] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"593.69624ms","start":"2026-04-23T15:51:25.968228Z","end":"2026-04-23T15:51:26.561924Z","steps":["trace[1981999764] 'read index received' (duration: 593.587708ms)","trace[1981999764] 'applied index is now lower than readState.Index' (duration: 107.142µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:51:26.562272Z","caller":"traceutil/trace.go:171","msg":"trace[405182626] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"595.341796ms","start":"2026-04-23T15:51:25.966916Z","end":"2026-04-23T15:51:26.562258Z","steps":["trace[405182626] 'process raft request' (duration: 594.831094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:26.56239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:25.966903Z","time spent":"595.401137ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:51:26.562635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.420286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T15:51:26.562669Z","caller":"traceutil/trace.go:171","msg":"trace[27073506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"594.512858ms","start":"2026-04-23T15:51:25.968146Z","end":"2026-04-23T15:51:26.562659Z","steps":["trace[27073506] 'agreement among raft nodes before linearized reading' (duration: 594.402395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:26.562705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:25.968115Z","time spent":"594.576159ms","remote":"127.0.0.1:39876","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-04-23T15:51:26.56289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.312816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T15:51:26.562921Z","caller":"traceutil/trace.go:171","msg":"trace[104036950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:998; }","duration":"589.363397ms","start":"2026-04-23T15:51:25.973546Z","end":"2026-04-23T15:51:26.562909Z","steps":["trace[104036950] 'agreement among raft nodes before linearized reading' (duration: 589.282865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:26.562947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:25.973534Z","time spent":"589.406729ms","remote":"127.0.0.1:39876","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-04-23T15:51:26.563342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.632753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T15:51:26.563404Z","caller":"traceutil/trace.go:171","msg":"trace[107502936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"371.697825ms","start":"2026-04-23T15:51:26.19167Z","end":"2026-04-23T15:51:26.563368Z","steps":["trace[107502936] 'agreement among raft nodes before linearized reading' (duration: 371.612593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:26.563434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:26.191651Z","time spent":"371.775416ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-23T15:51:26.563568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.475264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:51:26.563595Z","caller":"traceutil/trace.go:171","msg":"trace[1071806353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"480.500204ms","start":"2026-04-23T15:51:26.083085Z","end":"2026-04-23T15:51:26.563586Z","steps":["trace[1071806353] 'agreement among raft nodes before linearized reading' (duration: 480.454453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:26.563628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:26.083018Z","time spent":"480.601007ms","remote":"127.0.0.1:39634","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-04-23T15:51:26.563793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.985688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:51:26.563821Z","caller":"traceutil/trace.go:171","msg":"trace[612325519] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:998; }","duration":"560.0576ms","start":"2026-04-23T15:51:26.003755Z","end":"2026-04-23T15:51:26.563813Z","steps":["trace[612325519] 'agreement among raft nodes before linearized reading' (duration: 560.009269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:26.563849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:26.003733Z","time spent":"560.108041ms","remote":"127.0.0.1:40002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-23T15:51:27.311729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.063503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T15:51:27.311896Z","caller":"traceutil/trace.go:171","msg":"trace[768702710] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"736.632483ms","start":"2026-04-23T15:51:26.575238Z","end":"2026-04-23T15:51:27.31187Z","steps":["trace[768702710] 'process raft request' (duration: 447.140821ms)","trace[768702710] 'compare' (duration: 288.92089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:51:27.311973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:26.575227Z","time spent":"736.707455ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:51:27.314838Z","caller":"traceutil/trace.go:171","msg":"trace[1228130641] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1038; }","duration":"261.214097ms","start":"2026-04-23T15:51:27.053592Z","end":"2026-04-23T15:51:27.314806Z","steps":["trace[1228130641] 'read index received' (duration: 260.87207ms)","trace[1228130641] 'applied index is now lower than readState.Index' (duration: 341.087µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:51:27.315065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.450962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T15:51:27.315113Z","caller":"traceutil/trace.go:171","msg":"trace[2132874031] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1003; }","duration":"261.562754ms","start":"2026-04-23T15:51:27.053532Z","end":"2026-04-23T15:51:27.315095Z","steps":["trace[2132874031] 'agreement among raft nodes before linearized reading' (duration: 261.39375ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:51:27.315504Z","caller":"traceutil/trace.go:171","msg":"trace[709086462] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"734.5767ms","start":"2026-04-23T15:51:26.580916Z","end":"2026-04-23T15:51:27.315493Z","steps":["trace[709086462] 'process raft request' (duration: 733.64817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:27.315598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:26.580906Z","time spent":"734.633671ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:51:27.315697Z","caller":"traceutil/trace.go:171","msg":"trace[1446257824] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"734.649681ms","start":"2026-04-23T15:51:26.581035Z","end":"2026-04-23T15:51:27.315685Z","steps":["trace[1446257824] 'process raft request' (duration: 733.6632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:27.315768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:26.581007Z","time spent":"734.715933ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:51:27.315895Z","caller":"traceutil/trace.go:171","msg":"trace[823415571] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"334.444216ms","start":"2026-04-23T15:51:26.981428Z","end":"2026-04-23T15:51:27.315872Z","steps":["trace[823415571] 'process raft request' (duration: 333.342982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:27.316022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:26.981399Z","time spent":"334.549839ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:51:27.316126Z","caller":"traceutil/trace.go:171","msg":"trace[427307408] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"735.05409ms","start":"2026-04-23T15:51:26.581063Z","end":"2026-04-23T15:51:27.316117Z","steps":["trace[427307408] 'process raft request' (duration: 733.673469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:27.316164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:26.581026Z","time spent":"735.116271ms","remote":"127.0.0.1:39876","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-04-23T15:51:49.828986Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696291995392807,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T15:51:50.115441Z","caller":"traceutil/trace.go:171","msg":"trace[836524493] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"786.767247ms","start":"2026-04-23T15:51:49.328654Z","end":"2026-04-23T15:51:50.115421Z","steps":["trace[836524493] 'process raft request' (duration: 786.647484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:50.115559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.328637Z","time spent":"786.864248ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:51:50.58837Z","caller":"traceutil/trace.go:171","msg":"trace[839162091] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1115; }","duration":"1.259643388s","start":"2026-04-23T15:51:49.328676Z","end":"2026-04-23T15:51:50.58832Z","steps":["trace[839162091] 'read index received' (duration: 786.610313ms)","trace[839162091] 'applied index is now lower than readState.Index' (duration: 473.031525ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:51:50.588431Z","caller":"traceutil/trace.go:171","msg":"trace[1301652864] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"1.184561217s","start":"2026-04-23T15:51:49.403843Z","end":"2026-04-23T15:51:50.588405Z","steps":["trace[1301652864] 'process raft request' (duration: 1.152115542s)","trace[1301652864] 'compare' (duration: 32.113028ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:51:50.588559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.403818Z","time spent":"1.184657719s","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:51:50.588605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259915484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:51:50.588666Z","caller":"traceutil/trace.go:171","msg":"trace[892726807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"1.259996676s","start":"2026-04-23T15:51:49.328653Z","end":"2026-04-23T15:51:50.588649Z","steps":["trace[892726807] 'agreement among raft nodes before linearized reading' (duration: 1.259853353s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:50.588714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169408003s","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-04-23T15:51:50.588718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.328645Z","time spent":"1.260060577s","remote":"127.0.0.1:39634","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-04-23T15:51:50.588751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171716243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T15:51:50.588761Z","caller":"traceutil/trace.go:171","msg":"trace[1435619415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1073; }","duration":"1.169474974s","start":"2026-04-23T15:51:49.419272Z","end":"2026-04-23T15:51:50.588747Z","steps":["trace[1435619415] 'agreement among raft nodes before linearized reading' (duration: 1.169263839s)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:51:50.588786Z","caller":"traceutil/trace.go:171","msg":"trace[774143438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1073; }","duration":"1.171782384s","start":"2026-04-23T15:51:49.416995Z","end":"2026-04-23T15:51:50.588777Z","steps":["trace[774143438] 'agreement among raft nodes before linearized reading' (duration: 1.171693132s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:50.588816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.416978Z","time spent":"1.171831435s","remote":"127.0.0.1:39876","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":"warn","ts":"2026-04-23T15:51:50.58879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.41926Z","time spent":"1.169522776s","remote":"127.0.0.1:39876","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-04-23T15:51:50.588919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166866688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T15:51:50.588946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.177396844s","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-04-23T15:51:50.588669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259401103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:51:50.589074Z","caller":"traceutil/trace.go:171","msg":"trace[1608626963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1073; }","duration":"1.177557247s","start":"2026-04-23T15:51:49.411499Z","end":"2026-04-23T15:51:50.589056Z","steps":["trace[1608626963] 'agreement among raft nodes before linearized reading' (duration: 1.177252401s)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:51:50.588961Z","caller":"traceutil/trace.go:171","msg":"trace[189578135] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1073; }","duration":"1.166914229s","start":"2026-04-23T15:51:49.422037Z","end":"2026-04-23T15:51:50.588951Z","steps":["trace[189578135] 'agreement among raft nodes before linearized reading' (duration: 1.166830697s)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:51:50.589193Z","caller":"traceutil/trace.go:171","msg":"trace[1697096878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1073; }","duration":"1.259941946s","start":"2026-04-23T15:51:49.329221Z","end":"2026-04-23T15:51:50.589163Z","steps":["trace[1697096878] 'agreement among raft nodes before linearized reading' (duration: 1.259313231s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:50.589289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.42203Z","time spent":"1.167241948s","remote":"127.0.0.1:49800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-23T15:51:50.589307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.329215Z","time spent":"1.260065768s","remote":"127.0.0.1:39632","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-04-23T15:51:50.589282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.411486Z","time spent":"1.177687051s","remote":"127.0.0.1:39876","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-04-23T15:51:50.589345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165908579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T15:51:50.589384Z","caller":"traceutil/trace.go:171","msg":"trace[2009327084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1073; }","duration":"1.165963011s","start":"2026-04-23T15:51:49.423411Z","end":"2026-04-23T15:51:50.589374Z","steps":["trace[2009327084] 'agreement among raft nodes before linearized reading' (duration: 1.165137933s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:50.589423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:51:49.423403Z","time spent":"1.166012672s","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-23T15:51:52.839587Z","caller":"traceutil/trace.go:171","msg":"trace[1944259331] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"144.621609ms","start":"2026-04-23T15:51:52.694948Z","end":"2026-04-23T15:51:52.83957Z","steps":["trace[1944259331] 'process raft request' (duration: 144.518027ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:51:52.850443Z","caller":"traceutil/trace.go:171","msg":"trace[1655275701] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"108.942154ms","start":"2026-04-23T15:51:52.741484Z","end":"2026-04-23T15:51:52.850426Z","steps":["trace[1655275701] 'read index received' (duration: 98.331346ms)","trace[1655275701] 'applied index is now lower than readState.Index' (duration: 10.610028ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:51:52.850572Z","caller":"traceutil/trace.go:171","msg":"trace[836272880] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"153.731864ms","start":"2026-04-23T15:51:52.696827Z","end":"2026-04-23T15:51:52.850559Z","steps":["trace[836272880] 'process raft request' (duration: 153.56579ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:51:52.850563Z","caller":"traceutil/trace.go:171","msg":"trace[311828235] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"153.804645ms","start":"2026-04-23T15:51:52.696729Z","end":"2026-04-23T15:51:52.850533Z","steps":["trace[311828235] 'process raft request' (duration: 153.58773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:52.850616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.115228ms","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-04-23T15:51:52.850653Z","caller":"traceutil/trace.go:171","msg":"trace[476685770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1083; }","duration":"109.200469ms","start":"2026-04-23T15:51:52.741443Z","end":"2026-04-23T15:51:52.850644Z","steps":["trace[476685770] 'agreement among raft nodes before linearized reading' (duration: 109.055886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:54.018524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.618454ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-23T15:51:54.749535Z","caller":"traceutil/trace.go:171","msg":"trace[733928592] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"111.721354ms","start":"2026-04-23T15:51:54.637789Z","end":"2026-04-23T15:51:54.74951Z","steps":["trace[733928592] 'read index received' (duration: 111.519819ms)","trace[733928592] 'applied index is now lower than readState.Index' (duration: 198.215µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:51:54.74961Z","caller":"traceutil/trace.go:171","msg":"trace[1354326228] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"133.332117ms","start":"2026-04-23T15:51:54.616246Z","end":"2026-04-23T15:51:54.749578Z","steps":["trace[1354326228] 'process raft request' (duration: 133.057381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:51:54.749789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.96861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T15:51:54.749849Z","caller":"traceutil/trace.go:171","msg":"trace[1893287666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1086; }","duration":"112.095401ms","start":"2026-04-23T15:51:54.637739Z","end":"2026-04-23T15:51:54.749835Z","steps":["trace[1893287666] 'agreement among raft nodes before linearized reading' (duration: 111.854366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:05.395698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.046859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:05.395813Z","caller":"traceutil/trace.go:171","msg":"trace[1988286923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1172; }","duration":"318.141561ms","start":"2026-04-23T15:52:05.077618Z","end":"2026-04-23T15:52:05.395759Z","steps":["trace[1988286923] 'range keys from in-memory index tree' (duration: 317.987988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:05.395856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:05.077577Z","time spent":"318.270404ms","remote":"127.0.0.1:39634","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-04-23T15:52:05.396198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.956004ms","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-04-23T15:52:05.396171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.790308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T15:52:05.396228Z","caller":"traceutil/trace.go:171","msg":"trace[869885587] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1172; }","duration":"348.023016ms","start":"2026-04-23T15:52:05.048196Z","end":"2026-04-23T15:52:05.396219Z","steps":["trace[869885587] 'range keys from in-memory index tree' (duration: 347.780221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:05.396256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:05.048177Z","time spent":"348.072306ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-23T15:52:05.396251Z","caller":"traceutil/trace.go:171","msg":"trace[252231141] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1172; }","duration":"126.9215ms","start":"2026-04-23T15:52:05.269311Z","end":"2026-04-23T15:52:05.396232Z","steps":["trace[252231141] 'count revisions from in-memory index tree' (duration: 126.722397ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:05.396504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.938885ms","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-04-23T15:52:05.396539Z","caller":"traceutil/trace.go:171","msg":"trace[1698069235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1172; }","duration":"342.002396ms","start":"2026-04-23T15:52:05.054526Z","end":"2026-04-23T15:52:05.396528Z","steps":["trace[1698069235] 'range keys from in-memory index tree' (duration: 341.843453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:05.396565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:05.05451Z","time spent":"342.049687ms","remote":"127.0.0.1:39876","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-04-23T15:52:05.396726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.997506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-23T15:52:05.396762Z","caller":"traceutil/trace.go:171","msg":"trace[1088644602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1172; }","duration":"342.055497ms","start":"2026-04-23T15:52:05.054695Z","end":"2026-04-23T15:52:05.39675Z","steps":["trace[1088644602] 'range keys from in-memory index tree' (duration: 341.857793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:05.396793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:05.054684Z","time spent":"342.100118ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T15:52:05.535982Z","caller":"traceutil/trace.go:171","msg":"trace[1700085559] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"134.231629ms","start":"2026-04-23T15:52:05.401729Z","end":"2026-04-23T15:52:05.53596Z","steps":["trace[1700085559] 'process raft request' (duration: 134.112156ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:05.646157Z","caller":"traceutil/trace.go:171","msg":"trace[187017530] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"243.861197ms","start":"2026-04-23T15:52:05.402269Z","end":"2026-04-23T15:52:05.64613Z","steps":["trace[187017530] 'process raft request' (duration: 243.662863ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:05.646263Z","caller":"traceutil/trace.go:171","msg":"trace[541413454] linearizableReadLoop","detail":"{readStateIndex:1224; appliedIndex:1222; }","duration":"168.710753ms","start":"2026-04-23T15:52:05.477534Z","end":"2026-04-23T15:52:05.646244Z","steps":["trace[541413454] 'read index received' (duration: 58.785009ms)","trace[541413454] 'applied index is now lower than readState.Index' (duration: 109.925014ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:52:05.646349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.800865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:05.646366Z","caller":"traceutil/trace.go:171","msg":"trace[296547553] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1175; }","duration":"168.864067ms","start":"2026-04-23T15:52:05.477496Z","end":"2026-04-23T15:52:05.64636Z","steps":["trace[296547553] 'agreement among raft nodes before linearized reading' (duration: 168.808826ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:05.646546Z","caller":"traceutil/trace.go:171","msg":"trace[1119181681] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"244.220845ms","start":"2026-04-23T15:52:05.402317Z","end":"2026-04-23T15:52:05.646538Z","steps":["trace[1119181681] 'process raft request' (duration: 243.759465ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:07.256325Z","caller":"traceutil/trace.go:171","msg":"trace[869570202] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"427.36783ms","start":"2026-04-23T15:52:06.828936Z","end":"2026-04-23T15:52:07.256304Z","steps":["trace[869570202] 'read index received' (duration: 427.173906ms)","trace[869570202] 'applied index is now lower than readState.Index' (duration: 193.034µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:07.25656Z","caller":"traceutil/trace.go:171","msg":"trace[884010969] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"448.535287ms","start":"2026-04-23T15:52:06.808013Z","end":"2026-04-23T15:52:07.256548Z","steps":["trace[884010969] 'process raft request' (duration: 448.157299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:07.256649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:06.807991Z","time spent":"448.598319ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:52:07.256932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.013983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T15:52:07.256965Z","caller":"traceutil/trace.go:171","msg":"trace[2054872572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1176; }","duration":"428.103144ms","start":"2026-04-23T15:52:06.828853Z","end":"2026-04-23T15:52:07.256956Z","steps":["trace[2054872572] 'agreement among raft nodes before linearized reading' (duration: 427.992652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:07.256994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:06.828808Z","time spent":"428.178366ms","remote":"127.0.0.1:39876","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":"warn","ts":"2026-04-23T15:52:07.25718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.937856ms","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-04-23T15:52:07.257207Z","caller":"traceutil/trace.go:171","msg":"trace[399774812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"229.999587ms","start":"2026-04-23T15:52:07.0272Z","end":"2026-04-23T15:52:07.257199Z","steps":["trace[399774812] 'agreement among raft nodes before linearized reading' (duration: 229.922655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:07.257493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.009969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:07.257563Z","caller":"traceutil/trace.go:171","msg":"trace[1320001222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"181.121512ms","start":"2026-04-23T15:52:07.076425Z","end":"2026-04-23T15:52:07.257547Z","steps":["trace[1320001222] 'agreement among raft nodes before linearized reading' (duration: 180.989079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:08.039611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696291995393052,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-23T15:52:08.465791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.996362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T15:52:08.466038Z","caller":"traceutil/trace.go:171","msg":"trace[140976138] linearizableReadLoop","detail":"{readStateIndex:1227; appliedIndex:1225; }","duration":"926.586922ms","start":"2026-04-23T15:52:07.539431Z","end":"2026-04-23T15:52:08.466017Z","steps":["trace[140976138] 'read index received' (duration: 725.284284ms)","trace[140976138] 'applied index is now lower than readState.Index' (duration: 201.301288ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:08.466168Z","caller":"traceutil/trace.go:171","msg":"trace[1952144454] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"1.204164586s","start":"2026-04-23T15:52:07.261987Z","end":"2026-04-23T15:52:08.466152Z","steps":["trace[1952144454] 'process raft request' (duration: 1.203924611s)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:08.466251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:07.261968Z","time spent":"1.204235658s","remote":"127.0.0.1:39876","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-04-23T15:52:08.466367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.130585ms","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-04-23T15:52:08.466431Z","caller":"traceutil/trace.go:171","msg":"trace[1587400311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"816.252938ms","start":"2026-04-23T15:52:07.650162Z","end":"2026-04-23T15:52:08.466415Z","steps":["trace[1587400311] 'agreement among raft nodes before linearized reading' (duration: 816.059104ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:08.466144Z","caller":"traceutil/trace.go:171","msg":"trace[291230579] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"1.205473614s","start":"2026-04-23T15:52:07.260636Z","end":"2026-04-23T15:52:08.46611Z","steps":["trace[291230579] 'process raft request' (duration: 1.004063154s)","trace[291230579] 'compare' (duration: 200.603852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:52:08.466481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:07.650141Z","time spent":"816.331389ms","remote":"127.0.0.1:39876","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-04-23T15:52:08.466557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.921731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T15:52:08.466593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:07.260617Z","time spent":"1.205886783s","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:52:08.466612Z","caller":"traceutil/trace.go:171","msg":"trace[713388451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1178; }","duration":"390.012533ms","start":"2026-04-23T15:52:08.076586Z","end":"2026-04-23T15:52:08.466598Z","steps":["trace[713388451] 'agreement among raft nodes before linearized reading' (duration: 389.904861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:08.466717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.286856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-23T15:52:08.466751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.038414ms","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-04-23T15:52:08.466771Z","caller":"traceutil/trace.go:171","msg":"trace[1412641077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"816.063824ms","start":"2026-04-23T15:52:07.650702Z","end":"2026-04-23T15:52:08.466765Z","steps":["trace[1412641077] 'agreement among raft nodes before linearized reading' (duration: 815.959332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:08.466768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:08.076571Z","time spent":"390.184156ms","remote":"127.0.0.1:39634","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-04-23T15:52:08.466776Z","caller":"traceutil/trace.go:171","msg":"trace[887724239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"927.374148ms","start":"2026-04-23T15:52:07.539381Z","end":"2026-04-23T15:52:08.466755Z","steps":["trace[887724239] 'agreement among raft nodes before linearized reading' (duration: 927.204665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:08.466797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:07.650652Z","time spent":"816.139476ms","remote":"127.0.0.1:39876","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-04-23T15:52:08.46682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:07.539361Z","time spent":"927.448319ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-23T15:52:22.658282Z","caller":"traceutil/trace.go:171","msg":"trace[1543418147] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"119.606623ms","start":"2026-04-23T15:52:22.538648Z","end":"2026-04-23T15:52:22.658255Z","steps":["trace[1543418147] 'process raft request' (duration: 119.255426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:23.704772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.700988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:23.704859Z","caller":"traceutil/trace.go:171","msg":"trace[1461185645] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1237; }","duration":"434.844281ms","start":"2026-04-23T15:52:23.269991Z","end":"2026-04-23T15:52:23.704835Z","steps":["trace[1461185645] 'count revisions from in-memory index tree' (duration: 434.538424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:23.704865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.41104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-23T15:52:23.704903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:23.269968Z","time spent":"434.923862ms","remote":"127.0.0.1:40084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-23T15:52:23.704919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.977987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:23.704943Z","caller":"traceutil/trace.go:171","msg":"trace[1817392686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1237; }","duration":"627.537382ms","start":"2026-04-23T15:52:23.077383Z","end":"2026-04-23T15:52:23.704921Z","steps":["trace[1817392686] 'range keys from in-memory index tree' (duration: 627.301668ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:23.70497Z","caller":"traceutil/trace.go:171","msg":"trace[1956457705] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1237; }","duration":"643.072689ms","start":"2026-04-23T15:52:23.061882Z","end":"2026-04-23T15:52:23.704954Z","steps":["trace[1956457705] 'count revisions from in-memory index tree' (duration: 642.868624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:23.704988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:23.077362Z","time spent":"627.617174ms","remote":"127.0.0.1:39634","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-04-23T15:52:23.705006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:23.061859Z","time spent":"643.13889ms","remote":"127.0.0.1:49952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-04-23T15:52:23.70516Z","caller":"traceutil/trace.go:171","msg":"trace[1216287534] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"354.895664ms","start":"2026-04-23T15:52:23.350225Z","end":"2026-04-23T15:52:23.705121Z","steps":["trace[1216287534] 'process raft request' (duration: 291.460214ms)","trace[1216287534] 'compare' (duration: 62.96968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:52:23.705348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:23.350204Z","time spent":"355.057548ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:52:24.351496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.271848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T15:52:24.351668Z","caller":"traceutil/trace.go:171","msg":"trace[1834992896] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"569.081592ms","start":"2026-04-23T15:52:23.782562Z","end":"2026-04-23T15:52:24.351643Z","steps":["trace[1834992896] 'process raft request' (duration: 348.583249ms)","trace[1834992896] 'compare' (duration: 220.133145ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:52:24.35179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:23.782538Z","time spent":"569.204294ms","remote":"127.0.0.1:39876","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-04-23T15:52:25.577356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10389696291995393198,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-23T15:52:26.004229Z","caller":"traceutil/trace.go:171","msg":"trace[730315075] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"927.530763ms","start":"2026-04-23T15:52:25.076674Z","end":"2026-04-23T15:52:26.004205Z","steps":["trace[730315075] 'read index received' (duration: 927.40213ms)","trace[730315075] 'applied index is now lower than readState.Index' (duration: 124.762µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:26.004259Z","caller":"traceutil/trace.go:171","msg":"trace[1268787078] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"953.518953ms","start":"2026-04-23T15:52:25.050717Z","end":"2026-04-23T15:52:26.004236Z","steps":["trace[1268787078] 'process raft request' (duration: 953.35717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:26.004384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.700746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:26.004417Z","caller":"traceutil/trace.go:171","msg":"trace[392642818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"927.771658ms","start":"2026-04-23T15:52:25.076636Z","end":"2026-04-23T15:52:26.004407Z","steps":["trace[392642818] 'agreement among raft nodes before linearized reading' (duration: 927.684286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:26.004445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:25.076618Z","time spent":"927.821319ms","remote":"127.0.0.1:39634","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-04-23T15:52:26.004377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:25.050698Z","time spent":"953.620866ms","remote":"127.0.0.1:39722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-23T15:52:26.205313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.542841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-23T15:52:26.205452Z","caller":"traceutil/trace.go:171","msg":"trace[1669319183] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"201.147413ms","start":"2026-04-23T15:52:26.004291Z","end":"2026-04-23T15:52:26.205439Z","steps":["trace[1669319183] 'read index received' (duration: 75.392018ms)","trace[1669319183] 'applied index is now lower than readState.Index' (duration: 125.754585ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:26.205567Z","caller":"traceutil/trace.go:171","msg":"trace[115392406] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"495.743657ms","start":"2026-04-23T15:52:25.709794Z","end":"2026-04-23T15:52:26.205538Z","steps":["trace[115392406] 'process raft request' (duration: 369.871828ms)","trace[115392406] 'compare' (duration: 125.446459ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:26.205657Z","caller":"traceutil/trace.go:171","msg":"trace[327400770] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"197.340591ms","start":"2026-04-23T15:52:26.008306Z","end":"2026-04-23T15:52:26.205647Z","steps":["trace[327400770] 'process raft request' (duration: 197.094046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:26.205656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:25.709761Z","time spent":"495.850498ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:52:26.205706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.189743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:26.205763Z","caller":"traceutil/trace.go:171","msg":"trace[1581115019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"200.281185ms","start":"2026-04-23T15:52:26.005467Z","end":"2026-04-23T15:52:26.205748Z","steps":["trace[1581115019] 'agreement among raft nodes before linearized reading' (duration: 200.121222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:26.205764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.144516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:26.20585Z","caller":"traceutil/trace.go:171","msg":"trace[92541091] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1248; }","duration":"416.300521ms","start":"2026-04-23T15:52:25.789527Z","end":"2026-04-23T15:52:26.205827Z","steps":["trace[92541091] 'agreement among raft nodes before linearized reading' (duration: 415.983583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:26.205908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:25.789504Z","time spent":"416.391413ms","remote":"127.0.0.1:49822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-23T15:52:26.205854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.818866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-23T15:52:26.205978Z","caller":"traceutil/trace.go:171","msg":"trace[124399781] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1248; }","duration":"626.01631ms","start":"2026-04-23T15:52:25.579936Z","end":"2026-04-23T15:52:26.205952Z","steps":["trace[124399781] 'agreement among raft nodes before linearized reading' (duration: 625.57619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:26.206028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:25.57992Z","time spent":"626.097882ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-23T15:52:26.350987Z","caller":"traceutil/trace.go:171","msg":"trace[814724811] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"135.337733ms","start":"2026-04-23T15:52:26.215622Z","end":"2026-04-23T15:52:26.35096Z","steps":["trace[814724811] 'read index received' (duration: 107.616784ms)","trace[814724811] 'applied index is now lower than readState.Index' (duration: 27.719818ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:26.351069Z","caller":"traceutil/trace.go:171","msg":"trace[1687353435] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"137.493889ms","start":"2026-04-23T15:52:26.213531Z","end":"2026-04-23T15:52:26.351025Z","steps":["trace[1687353435] 'process raft request' (duration: 109.742209ms)","trace[1687353435] 'compare' (duration: 27.565706ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:52:26.351128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.485945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:26.351166Z","caller":"traceutil/trace.go:171","msg":"trace[578909750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"135.588608ms","start":"2026-04-23T15:52:26.215567Z","end":"2026-04-23T15:52:26.351155Z","steps":["trace[578909750] 'agreement among raft nodes before linearized reading' (duration: 135.502766ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:27.17381Z","caller":"traceutil/trace.go:171","msg":"trace[1301105717] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"417.158539ms","start":"2026-04-23T15:52:26.756618Z","end":"2026-04-23T15:52:27.173777Z","steps":["trace[1301105717] 'process raft request' (duration: 416.878373ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:27.173765Z","caller":"traceutil/trace.go:171","msg":"trace[155711933] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"329.530987ms","start":"2026-04-23T15:52:26.8442Z","end":"2026-04-23T15:52:27.173731Z","steps":["trace[155711933] 'read index received' (duration: 329.20355ms)","trace[155711933] 'applied index is now lower than readState.Index' (duration: 326.137µs)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:52:27.17399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:26.756606Z","time spent":"417.304483ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:52:27.173989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.778092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-23T15:52:27.174054Z","caller":"traceutil/trace.go:171","msg":"trace[77919782] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1251; }","duration":"329.885495ms","start":"2026-04-23T15:52:26.844156Z","end":"2026-04-23T15:52:27.174042Z","steps":["trace[77919782] 'agreement among raft nodes before linearized reading' (duration: 329.69421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:27.174089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:26.844141Z","time spent":"329.943035ms","remote":"127.0.0.1:39876","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":"warn","ts":"2026-04-23T15:52:27.1742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.588117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-23T15:52:27.174218Z","caller":"traceutil/trace.go:171","msg":"trace[1776926292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1251; }","duration":"329.615618ms","start":"2026-04-23T15:52:26.844597Z","end":"2026-04-23T15:52:27.174213Z","steps":["trace[1776926292] 'agreement among raft nodes before linearized reading' (duration: 329.562057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:27.174231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:26.844592Z","time spent":"329.636418ms","remote":"127.0.0.1:39876","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-04-23T15:52:27.17431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.826063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-23T15:52:27.174371Z","caller":"traceutil/trace.go:171","msg":"trace[1384163127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1251; }","duration":"329.969176ms","start":"2026-04-23T15:52:26.844383Z","end":"2026-04-23T15:52:27.174352Z","steps":["trace[1384163127] 'agreement among raft nodes before linearized reading' (duration: 329.706921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:27.174427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:26.844365Z","time spent":"330.051258ms","remote":"127.0.0.1:39876","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-04-23T15:52:27.174681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.887962ms","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-04-23T15:52:27.174762Z","caller":"traceutil/trace.go:171","msg":"trace[1635550183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1251; }","duration":"327.994983ms","start":"2026-04-23T15:52:26.846745Z","end":"2026-04-23T15:52:27.17474Z","steps":["trace[1635550183] 'agreement among raft nodes before linearized reading' (duration: 327.789739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:27.174802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:26.846734Z","time spent":"328.059634ms","remote":"127.0.0.1:39876","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-04-23T15:52:27.388523Z","caller":"traceutil/trace.go:171","msg":"trace[1381256918] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"210.095068ms","start":"2026-04-23T15:52:27.178399Z","end":"2026-04-23T15:52:27.388494Z","steps":["trace[1381256918] 'process raft request' (duration: 180.11219ms)","trace[1381256918] 'compare' (duration: 29.881285ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:27.895734Z","caller":"traceutil/trace.go:171","msg":"trace[1445356193] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"716.346821ms","start":"2026-04-23T15:52:27.179356Z","end":"2026-04-23T15:52:27.895702Z","steps":["trace[1445356193] 'process raft request' (duration: 666.73585ms)","trace[1445356193] 'compare' (duration: 49.426328ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:52:27.895913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:27.179336Z","time spent":"716.485474ms","remote":"127.0.0.1:39876","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-04-23T15:52:27.895965Z","caller":"traceutil/trace.go:171","msg":"trace[1705365530] linearizableReadLoop","detail":"{readStateIndex:1308; appliedIndex:1305; }","duration":"481.058719ms","start":"2026-04-23T15:52:27.414883Z","end":"2026-04-23T15:52:27.895941Z","steps":["trace[1705365530] 'read index received' (duration: 431.239583ms)","trace[1705365530] 'applied index is now lower than readState.Index' (duration: 49.818106ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:52:27.896102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.202372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:27.896162Z","caller":"traceutil/trace.go:171","msg":"trace[151782146] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1255; }","duration":"481.330405ms","start":"2026-04-23T15:52:27.414821Z","end":"2026-04-23T15:52:27.896152Z","steps":["trace[151782146] 'agreement among raft nodes before linearized reading' (duration: 481.207562ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:27.896133Z","caller":"traceutil/trace.go:171","msg":"trace[1421291658] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"715.561915ms","start":"2026-04-23T15:52:27.180542Z","end":"2026-04-23T15:52:27.896104Z","steps":["trace[1421291658] 'process raft request' (duration: 715.122646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:27.896205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:27.41479Z","time spent":"481.404627ms","remote":"127.0.0.1:49808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-23T15:52:27.896285Z","caller":"traceutil/trace.go:171","msg":"trace[660927233] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"714.823348ms","start":"2026-04-23T15:52:27.181408Z","end":"2026-04-23T15:52:27.896232Z","steps":["trace[660927233] 'process raft request' (duration: 714.46617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:27.896304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:27.180523Z","time spent":"715.689617ms","remote":"127.0.0.1:39876","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-04-23T15:52:27.896384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:27.181341Z","time spent":"714.976141ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-23T15:52:28.332486Z","caller":"traceutil/trace.go:171","msg":"trace[214030925] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"121.540526ms","start":"2026-04-23T15:52:28.210904Z","end":"2026-04-23T15:52:28.332444Z","steps":["trace[214030925] 'process raft request' (duration: 121.348232ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:31.579194Z","caller":"traceutil/trace.go:171","msg":"trace[52856898] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"169.731255ms","start":"2026-04-23T15:52:31.409438Z","end":"2026-04-23T15:52:31.579169Z","steps":["trace[52856898] 'process raft request' (duration: 169.603003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:38.193998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.367071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:38.194084Z","caller":"traceutil/trace.go:171","msg":"trace[73064558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"115.521534ms","start":"2026-04-23T15:52:38.078544Z","end":"2026-04-23T15:52:38.194066Z","steps":["trace[73064558] 'range keys from in-memory index tree' (duration: 115.27515ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:39.118632Z","caller":"traceutil/trace.go:171","msg":"trace[1674479750] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"442.854244ms","start":"2026-04-23T15:52:38.67575Z","end":"2026-04-23T15:52:39.118604Z","steps":["trace[1674479750] 'read index received' (duration: 442.618849ms)","trace[1674479750] 'applied index is now lower than readState.Index' (duration: 234.225µs)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:39.119004Z","caller":"traceutil/trace.go:171","msg":"trace[1243785878] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"755.431776ms","start":"2026-04-23T15:52:38.363554Z","end":"2026-04-23T15:52:39.118985Z","steps":["trace[1243785878] 'process raft request' (duration: 754.893454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:39.11916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:38.363527Z","time spent":"755.523747ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-23T15:52:39.119606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.847125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.155\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-23T15:52:39.119665Z","caller":"traceutil/trace.go:171","msg":"trace[1460169763] range","detail":"{range_begin:/registry/masterleases/199.204.45.155; range_end:; response_count:1; response_revision:1341; }","duration":"443.907987ms","start":"2026-04-23T15:52:38.675743Z","end":"2026-04-23T15:52:39.119651Z","steps":["trace[1460169763] 'agreement among raft nodes before linearized reading' (duration: 443.736503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:39.119704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:38.67568Z","time spent":"444.014489ms","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.155\" "} {"level":"warn","ts":"2026-04-23T15:52:39.120081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.204501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:52:39.120132Z","caller":"traceutil/trace.go:171","msg":"trace[816430772] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1341; }","duration":"105.298345ms","start":"2026-04-23T15:52:39.014821Z","end":"2026-04-23T15:52:39.120119Z","steps":["trace[816430772] 'agreement among raft nodes before linearized reading' (duration: 105.218302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:39.120067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.250956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-23T15:52:39.120298Z","caller":"traceutil/trace.go:171","msg":"trace[2114150153] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1341; }","duration":"420.532293ms","start":"2026-04-23T15:52:38.699736Z","end":"2026-04-23T15:52:39.120268Z","steps":["trace[2114150153] 'agreement among raft nodes before linearized reading' (duration: 420.103833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:52:39.120365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:52:38.699716Z","time spent":"420.635996ms","remote":"127.0.0.1:39876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-23T15:52:39.26187Z","caller":"traceutil/trace.go:171","msg":"trace[909276613] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"135.915835ms","start":"2026-04-23T15:52:39.125925Z","end":"2026-04-23T15:52:39.26184Z","steps":["trace[909276613] 'process raft request' (duration: 135.395194ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:52:44.203008Z","caller":"traceutil/trace.go:171","msg":"trace[68648109] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"173.304383ms","start":"2026-04-23T15:52:44.029678Z","end":"2026-04-23T15:52:44.202982Z","steps":["trace[68648109] 'process raft request' (duration: 105.780144ms)","trace[68648109] 'compare' (duration: 67.351715ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:52:44.203244Z","caller":"traceutil/trace.go:171","msg":"trace[712091445] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"173.430276ms","start":"2026-04-23T15:52:44.029766Z","end":"2026-04-23T15:52:44.203196Z","steps":["trace[712091445] 'process raft request' (duration: 173.170221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:54:31.729512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.4891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T15:54:31.729617Z","caller":"traceutil/trace.go:171","msg":"trace[717311672] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1859; }","duration":"106.615475ms","start":"2026-04-23T15:54:31.622983Z","end":"2026-04-23T15:54:31.729598Z","steps":["trace[717311672] 'count revisions from in-memory index tree' (duration: 106.416939ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:55:50.221994Z","caller":"traceutil/trace.go:171","msg":"trace[1829557471] transaction","detail":"{read_only:false; response_revision:2243; number_of_response:1; }","duration":"451.261246ms","start":"2026-04-23T15:55:49.770703Z","end":"2026-04-23T15:55:50.221964Z","steps":["trace[1829557471] 'process raft request' (duration: 450.966269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:55:50.222313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-23T15:55:49.770688Z","time spent":"451.523631ms","remote":"127.0.0.1:39876","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-04-23T15:55:50.223197Z","caller":"traceutil/trace.go:171","msg":"trace[1190126849] linearizableReadLoop","detail":"{readStateIndex:2339; appliedIndex:2337; }","duration":"147.908146ms","start":"2026-04-23T15:55:50.075268Z","end":"2026-04-23T15:55:50.223176Z","steps":["trace[1190126849] 'read index received' (duration: 146.488454ms)","trace[1190126849] 'applied index is now lower than readState.Index' (duration: 1.418291ms)"],"step_count":2} {"level":"info","ts":"2026-04-23T15:55:50.223315Z","caller":"traceutil/trace.go:171","msg":"trace[1559001212] transaction","detail":"{read_only:false; response_revision:2244; number_of_response:1; }","duration":"183.673184ms","start":"2026-04-23T15:55:50.039625Z","end":"2026-04-23T15:55:50.223298Z","steps":["trace[1559001212] 'process raft request' (duration: 183.448039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-23T15:55:50.223614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.332296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-23T15:55:50.223682Z","caller":"traceutil/trace.go:171","msg":"trace[2027524332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2244; }","duration":"148.419647ms","start":"2026-04-23T15:55:50.075247Z","end":"2026-04-23T15:55:50.223667Z","steps":["trace[2027524332] 'agreement among raft nodes before linearized reading' (duration: 148.314025ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:55:55.357585Z","caller":"traceutil/trace.go:171","msg":"trace[1054281694] transaction","detail":"{read_only:false; response_revision:2273; number_of_response:1; }","duration":"112.271389ms","start":"2026-04-23T15:55:55.245285Z","end":"2026-04-23T15:55:55.357556Z","steps":["trace[1054281694] 'process raft request' (duration: 37.445577ms)","trace[1054281694] 'compare' (duration: 74.402713ms)"],"step_count":2} {"level":"warn","ts":"2026-04-23T15:55:56.021027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.5502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-23T15:55:56.021104Z","caller":"traceutil/trace.go:171","msg":"trace[160662220] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2276; }","duration":"203.680313ms","start":"2026-04-23T15:55:55.817407Z","end":"2026-04-23T15:55:56.021088Z","steps":["trace[160662220] 'count revisions from in-memory index tree' (duration: 203.463827ms)"],"step_count":1} {"level":"info","ts":"2026-04-23T15:56:06.866573Z","caller":"traceutil/trace.go:171","msg":"trace[352704755] transaction","detail":"{read_only:false; response_revision:2333; number_of_response:1; }","duration":"133.922992ms","start":"2026-04-23T15:56:06.732624Z","end":"2026-04-23T15:56:06.866547Z","steps":["trace[352704755] 'process raft request' (duration: 133.749288ms)"],"step_count":1}