{"level":"warn","ts":"2026-04-27T20:55:17.109603Z","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-27T20:55:17.109687Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-27T20:55:17.109754Z","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-27T20:55:17.109774Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-04-27T20:55:17.109799Z","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-27T20:55:17.110516Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-04-27T20:55:17.110596Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-27T20:55:17.115006Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.205927ms"} {"level":"info","ts":"2026-04-27T20:55:17.123372Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-04-27T20:55:17.123446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T20:55:17.12347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-04-27T20:55:17.123485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T20:55:17.123494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-04-27T20:55:17.12358Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-04-27T20:55:17.129651Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T20:55:17.131665Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T20:55:17.13314Z","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-27T20:55:17.137264Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T20:55:17.137451Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T20:55:17.137951Z","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-27T20:55:17.138027Z","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-27T20:55:17.138054Z","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-27T20:55:17.141488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-04-27T20:55:17.141731Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-04-27T20:55:17.144328Z","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-27T20:55:17.144472Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-04-27T20:55:17.14451Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-04-27T20:55:17.14455Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T20:55:17.144599Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T20:55:17.726345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T20:55:17.726389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T20:55:17.726409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-04-27T20:55:17.726422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-04-27T20:55:17.726428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-04-27T20:55:17.726435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-04-27T20:55:17.726442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-04-27T20:55:17.75862Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:55:17.75884Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T20:55:17.759012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T20:55:17.75901Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T20:55:17.760033Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:55:17.760151Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:55:17.760201Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T20:55:17.762283Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T20:55:17.762547Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T20:55:17.762646Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T20:55:17.763074Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"warn","ts":"2026-04-27T20:55:19.261895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.203313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T20:55:19.262029Z","caller":"traceutil/trace.go:171","msg":"trace[1253315402] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"114.386324ms","start":"2026-04-27T20:55:19.14762Z","end":"2026-04-27T20:55:19.262006Z","steps":["trace[1253315402] 'range keys from in-memory index tree' (duration: 114.097822ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.261978Z","caller":"traceutil/trace.go:171","msg":"trace[640810680] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"108.719619ms","start":"2026-04-27T20:55:19.153223Z","end":"2026-04-27T20:55:19.261943Z","steps":["trace[640810680] 'process raft request' (duration: 45.644606ms)","trace[640810680] 'compare' (duration: 62.914852ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:19.262385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.085878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T20:55:19.262558Z","caller":"traceutil/trace.go:171","msg":"trace[469974748] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:0; response_revision:3; }","duration":"110.274308ms","start":"2026-04-27T20:55:19.15225Z","end":"2026-04-27T20:55:19.262525Z","steps":["trace[469974748] 'range keys from in-memory index tree' (duration: 109.971766ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.531332Z","caller":"traceutil/trace.go:171","msg":"trace[1285984180] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"377.627479ms","start":"2026-04-27T20:55:19.153672Z","end":"2026-04-27T20:55:19.5313Z","steps":["trace[1285984180] 'process raft request' (duration: 363.942724ms)","trace[1285984180] 'compare' (duration: 13.492214ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:19.531418Z","caller":"traceutil/trace.go:171","msg":"trace[254938750] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"377.566699ms","start":"2026-04-27T20:55:19.153826Z","end":"2026-04-27T20:55:19.531393Z","steps":["trace[254938750] 'process raft request' (duration: 377.427898ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.531488Z","caller":"traceutil/trace.go:171","msg":"trace[944432153] linearizableReadLoop","detail":"{readStateIndex:10; appliedIndex:6; }","duration":"376.983524ms","start":"2026-04-27T20:55:19.154461Z","end":"2026-04-27T20:55:19.531444Z","steps":["trace[944432153] 'read index received' (duration: 44.480728ms)","trace[944432153] 'applied index is now lower than readState.Index' (duration: 332.501836ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:19.531509Z","caller":"traceutil/trace.go:171","msg":"trace[800607177] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"377.507108ms","start":"2026-04-27T20:55:19.15399Z","end":"2026-04-27T20:55:19.531497Z","steps":["trace[800607177] 'process raft request' (duration: 377.206267ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.531591Z","caller":"traceutil/trace.go:171","msg":"trace[585362827] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"377.016354ms","start":"2026-04-27T20:55:19.154555Z","end":"2026-04-27T20:55:19.531572Z","steps":["trace[585362827] 'process raft request' (duration: 376.751132ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.531588Z","caller":"traceutil/trace.go:171","msg":"trace[1814352446] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"373.09301ms","start":"2026-04-27T20:55:19.158482Z","end":"2026-04-27T20:55:19.531575Z","steps":["trace[1814352446] 'process raft request' (duration: 372.888829ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.531698Z","caller":"traceutil/trace.go:171","msg":"trace[450617198] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"377.068564ms","start":"2026-04-27T20:55:19.154611Z","end":"2026-04-27T20:55:19.531679Z","steps":["trace[450617198] 'process raft request' (duration: 376.727702ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.531776Z","caller":"traceutil/trace.go:171","msg":"trace[238316551] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"362.332263ms","start":"2026-04-27T20:55:19.169429Z","end":"2026-04-27T20:55:19.531761Z","steps":["trace[238316551] 'process raft request' (duration: 361.980881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:19.531887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.395746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T20:55:19.531964Z","caller":"traceutil/trace.go:171","msg":"trace[969312028] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"377.506517ms","start":"2026-04-27T20:55:19.154442Z","end":"2026-04-27T20:55:19.531949Z","steps":["trace[969312028] 'agreement among raft nodes before linearized reading' (duration: 377.276126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:19.531996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.154436Z","time spent":"377.553177ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T20:55:19.532042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.153665Z","time spent":"377.76142ms","remote":"127.0.0.1:59882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:19.532115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.154603Z","time spent":"377.125235ms","remote":"127.0.0.1:59882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:19.532259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.158474Z","time spent":"373.18954ms","remote":"127.0.0.1:59558","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:19.532621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.169415Z","time spent":"362.411733ms","remote":"127.0.0.1:59616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:19.532753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.153821Z","time spent":"377.660819ms","remote":"127.0.0.1:59882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:19.532925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.153974Z","time spent":"377.575419ms","remote":"127.0.0.1:59882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:19.533061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.154544Z","time spent":"377.083804ms","remote":"127.0.0.1:59882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:19.545682Z","caller":"traceutil/trace.go:171","msg":"trace[198862111] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"280.18961ms","start":"2026-04-27T20:55:19.265464Z","end":"2026-04-27T20:55:19.545654Z","steps":["trace[198862111] 'process raft request' (duration: 280.071929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:19.545798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.533721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T20:55:19.545838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.094559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T20:55:19.545866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.083721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-27T20:55:19.545893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.330407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T20:55:19.545905Z","caller":"traceutil/trace.go:171","msg":"trace[492515937] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:12; }","duration":"338.136241ms","start":"2026-04-27T20:55:19.207756Z","end":"2026-04-27T20:55:19.545892Z","steps":["trace[492515937] 'agreement among raft nodes before linearized reading' (duration: 338.05117ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.545928Z","caller":"traceutil/trace.go:171","msg":"trace[996666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"281.383997ms","start":"2026-04-27T20:55:19.264535Z","end":"2026-04-27T20:55:19.545919Z","steps":["trace[996666] 'agreement among raft nodes before linearized reading' (duration: 281.340067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:19.545931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.20775Z","time spent":"338.175161ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-04-27T20:55:19.545892Z","caller":"traceutil/trace.go:171","msg":"trace[249860239] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"341.17887ms","start":"2026-04-27T20:55:19.2047Z","end":"2026-04-27T20:55:19.545879Z","steps":["trace[249860239] 'agreement among raft nodes before linearized reading' (duration: 341.080399ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:19.545934Z","caller":"traceutil/trace.go:171","msg":"trace[687276432] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"320.634782ms","start":"2026-04-27T20:55:19.225228Z","end":"2026-04-27T20:55:19.545862Z","steps":["trace[687276432] 'agreement among raft nodes before linearized reading' (duration: 320.468271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:19.545843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.261923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T20:55:19.546076Z","caller":"traceutil/trace.go:171","msg":"trace[862666040] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:12; }","duration":"391.498354ms","start":"2026-04-27T20:55:19.154564Z","end":"2026-04-27T20:55:19.546062Z","steps":["trace[862666040] 'agreement among raft nodes before linearized reading' (duration: 391.247952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:19.54611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.154544Z","time spent":"391.556204ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-27T20:55:19.546176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.225216Z","time spent":"320.832583ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-27T20:55:19.546117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:19.204692Z","time spent":"341.343241ms","remote":"127.0.0.1:59510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-27T20:55:27.720568Z","caller":"traceutil/trace.go:171","msg":"trace[627552772] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"104.457218ms","start":"2026-04-27T20:55:27.61608Z","end":"2026-04-27T20:55:27.720538Z","steps":["trace[627552772] 'process raft request' (duration: 104.263246ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:29.558427Z","caller":"traceutil/trace.go:171","msg":"trace[1577809032] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"100.82797ms","start":"2026-04-27T20:55:29.457573Z","end":"2026-04-27T20:55:29.558401Z","steps":["trace[1577809032] 'process raft request' (duration: 62.731643ms)","trace[1577809032] 'compare' (duration: 37.963136ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:29.911607Z","caller":"traceutil/trace.go:171","msg":"trace[377845339] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:398; }","duration":"159.239859ms","start":"2026-04-27T20:55:29.752342Z","end":"2026-04-27T20:55:29.911582Z","steps":["trace[377845339] 'read index received' (duration: 159.079988ms)","trace[377845339] 'applied index is now lower than readState.Index' (duration: 158.561µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:29.911712Z","caller":"traceutil/trace.go:171","msg":"trace[2097301573] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"183.623174ms","start":"2026-04-27T20:55:29.728059Z","end":"2026-04-27T20:55:29.911682Z","steps":["trace[2097301573] 'process raft request' (duration: 183.383663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:29.912028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.664841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-2ms9v\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-04-27T20:55:29.912082Z","caller":"traceutil/trace.go:171","msg":"trace[1980446854] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-2ms9v; range_end:; response_count:1; response_revision:389; }","duration":"159.767971ms","start":"2026-04-27T20:55:29.752302Z","end":"2026-04-27T20:55:29.91207Z","steps":["trace[1980446854] 'agreement among raft nodes before linearized reading' (duration: 159.374199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:30.235836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.315134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:30.235973Z","caller":"traceutil/trace.go:171","msg":"trace[1236989082] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"450.000883ms","start":"2026-04-27T20:55:29.785954Z","end":"2026-04-27T20:55:30.235955Z","steps":["trace[1236989082] 'process raft request' (duration: 307.216636ms)","trace[1236989082] 'compare' (duration: 142.195764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:30.236035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:29.785928Z","time spent":"450.078123ms","remote":"127.0.0.1:59628","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-27T20:55:30.383007Z","caller":"traceutil/trace.go:171","msg":"trace[1687906996] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"464.223985ms","start":"2026-04-27T20:55:29.918759Z","end":"2026-04-27T20:55:30.382983Z","steps":["trace[1687906996] 'process raft request' (duration: 458.746993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:30.383237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:29.918734Z","time spent":"464.338566ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:30.553899Z","caller":"traceutil/trace.go:171","msg":"trace[1129295981] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"163.827843ms","start":"2026-04-27T20:55:30.390042Z","end":"2026-04-27T20:55:30.55387Z","steps":["trace[1129295981] 'process raft request' (duration: 96.262232ms)","trace[1129295981] 'compare' (duration: 67.4317ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:30.649455Z","caller":"traceutil/trace.go:171","msg":"trace[1247029060] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"254.466899ms","start":"2026-04-27T20:55:30.394964Z","end":"2026-04-27T20:55:30.64943Z","steps":["trace[1247029060] 'process raft request' (duration: 254.329698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:31.104468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.853992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T20:55:31.104672Z","caller":"traceutil/trace.go:171","msg":"trace[1621891536] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:393; }","duration":"254.059694ms","start":"2026-04-27T20:55:30.850549Z","end":"2026-04-27T20:55:31.104609Z","steps":["trace[1621891536] 'range keys from in-memory index tree' (duration: 253.749222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:31.104468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.922285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-04-27T20:55:31.104899Z","caller":"traceutil/trace.go:171","msg":"trace[1414101789] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:393; }","duration":"354.41237ms","start":"2026-04-27T20:55:30.750472Z","end":"2026-04-27T20:55:31.104884Z","steps":["trace[1414101789] 'range keys from in-memory index tree' (duration: 353.818945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:31.104951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:30.750446Z","time spent":"354.49104ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6983,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-04-27T20:55:31.41786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.289874ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:31.418042Z","caller":"traceutil/trace.go:171","msg":"trace[1774120114] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"303.856045ms","start":"2026-04-27T20:55:31.114168Z","end":"2026-04-27T20:55:31.418024Z","steps":["trace[1774120114] 'process raft request' (duration: 157.319309ms)","trace[1774120114] 'compare' (duration: 146.089013ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:31.418111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:31.114142Z","time spent":"303.938685ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:31.418286Z","caller":"traceutil/trace.go:171","msg":"trace[596938214] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:404; }","duration":"183.699046ms","start":"2026-04-27T20:55:31.234562Z","end":"2026-04-27T20:55:31.418261Z","steps":["trace[596938214] 'read index received' (duration: 36.930738ms)","trace[596938214] 'applied index is now lower than readState.Index' (duration: 146.766718ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:31.418339Z","caller":"traceutil/trace.go:171","msg":"trace[1013758946] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"248.386018ms","start":"2026-04-27T20:55:31.169901Z","end":"2026-04-27T20:55:31.418287Z","steps":["trace[1013758946] 'process raft request' (duration: 248.068765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:31.418625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.967317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T20:55:31.420402Z","caller":"traceutil/trace.go:171","msg":"trace[1339485597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:395; }","duration":"184.272689ms","start":"2026-04-27T20:55:31.234517Z","end":"2026-04-27T20:55:31.41879Z","steps":["trace[1339485597] 'agreement among raft nodes before linearized reading' (duration: 183.845746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:31.821647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.759136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:31.821769Z","caller":"traceutil/trace.go:171","msg":"trace[125688539] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"254.748845ms","start":"2026-04-27T20:55:31.567Z","end":"2026-04-27T20:55:31.821749Z","steps":["trace[125688539] 'process raft request' (duration: 124.804817ms)","trace[125688539] 'compare' (duration: 129.603025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:32.113198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.252246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:32.113421Z","caller":"traceutil/trace.go:171","msg":"trace[1120264400] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"193.888924ms","start":"2026-04-27T20:55:31.919506Z","end":"2026-04-27T20:55:32.113395Z","steps":["trace[1120264400] 'process raft request' (duration: 11.330537ms)","trace[1120264400] 'compare' (duration: 182.103324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:33.279407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.054932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-04-27T20:55:33.279509Z","caller":"traceutil/trace.go:171","msg":"trace[642245288] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:399; }","duration":"523.223153ms","start":"2026-04-27T20:55:32.756267Z","end":"2026-04-27T20:55:33.27949Z","steps":["trace[642245288] 'range keys from in-memory index tree' (duration: 522.933151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:33.279553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:32.756236Z","time spent":"523.307753ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4344,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-04-27T20:55:33.365713Z","caller":"traceutil/trace.go:171","msg":"trace[472440888] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"244.391583ms","start":"2026-04-27T20:55:33.121293Z","end":"2026-04-27T20:55:33.365685Z","steps":["trace[472440888] 'process raft request' (duration: 244.205892ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:33.46451Z","caller":"traceutil/trace.go:171","msg":"trace[43568252] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"175.362248ms","start":"2026-04-27T20:55:33.289111Z","end":"2026-04-27T20:55:33.464473Z","steps":["trace[43568252] 'process raft request' (duration: 154.444205ms)","trace[43568252] 'compare' (duration: 20.43313ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:33.621747Z","caller":"traceutil/trace.go:171","msg":"trace[2083907579] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"142.117453ms","start":"2026-04-27T20:55:33.4796Z","end":"2026-04-27T20:55:33.621717Z","steps":["trace[2083907579] 'process raft request' (duration: 96.060974ms)","trace[2083907579] 'compare' (duration: 45.624207ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:34.012683Z","caller":"traceutil/trace.go:171","msg":"trace[1323292889] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"183.13015ms","start":"2026-04-27T20:55:33.829533Z","end":"2026-04-27T20:55:34.012663Z","steps":["trace[1323292889] 'process raft request' (duration: 183.007824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:34.247468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.788247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T20:55:34.247567Z","caller":"traceutil/trace.go:171","msg":"trace[1910787523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"130.938314ms","start":"2026-04-27T20:55:34.116611Z","end":"2026-04-27T20:55:34.247549Z","steps":["trace[1910787523] 'range keys from in-memory index tree' (duration: 130.674741ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:34.363373Z","caller":"traceutil/trace.go:171","msg":"trace[2112666178] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"108.476129ms","start":"2026-04-27T20:55:34.254865Z","end":"2026-04-27T20:55:34.363341Z","steps":["trace[2112666178] 'process raft request' (duration: 108.28392ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:34.603295Z","caller":"traceutil/trace.go:171","msg":"trace[818623112] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"109.024404ms","start":"2026-04-27T20:55:34.494238Z","end":"2026-04-27T20:55:34.603262Z","steps":["trace[818623112] 'process raft request' (duration: 108.868137ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:34.839731Z","caller":"traceutil/trace.go:171","msg":"trace[1222702879] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"224.94105ms","start":"2026-04-27T20:55:34.614763Z","end":"2026-04-27T20:55:34.839704Z","steps":["trace[1222702879] 'process raft request' (duration: 141.194866ms)","trace[1222702879] 'compare' (duration: 83.384427ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:37.993647Z","caller":"traceutil/trace.go:171","msg":"trace[1779737839] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"284.454403ms","start":"2026-04-27T20:55:37.709168Z","end":"2026-04-27T20:55:37.993623Z","steps":["trace[1779737839] 'read index received' (duration: 284.248504ms)","trace[1779737839] 'applied index is now lower than readState.Index' (duration: 204.929µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:37.993711Z","caller":"traceutil/trace.go:171","msg":"trace[1596645503] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"456.826541ms","start":"2026-04-27T20:55:37.536845Z","end":"2026-04-27T20:55:37.993672Z","steps":["trace[1596645503] 'process raft request' (duration: 456.636293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:37.993819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.797693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-04-27T20:55:37.99386Z","caller":"traceutil/trace.go:171","msg":"trace[1168198713] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:416; }","duration":"210.885837ms","start":"2026-04-27T20:55:37.782958Z","end":"2026-04-27T20:55:37.993844Z","steps":["trace[1168198713] 'agreement among raft nodes before linearized reading' (duration: 210.805534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:37.99384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.659593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T20:55:37.993881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:37.536792Z","time spent":"457.00223ms","remote":"127.0.0.1:59628","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-27T20:55:37.993926Z","caller":"traceutil/trace.go:171","msg":"trace[2107963567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"284.795719ms","start":"2026-04-27T20:55:37.709109Z","end":"2026-04-27T20:55:37.993905Z","steps":["trace[2107963567] 'agreement among raft nodes before linearized reading' (duration: 284.634272ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:38.112739Z","caller":"traceutil/trace.go:171","msg":"trace[1745578566] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"113.091551ms","start":"2026-04-27T20:55:37.999607Z","end":"2026-04-27T20:55:38.112699Z","steps":["trace[1745578566] 'process raft request' (duration: 91.13266ms)","trace[1745578566] 'compare' (duration: 21.702789ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:38.491502Z","caller":"traceutil/trace.go:171","msg":"trace[485185327] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"110.169004ms","start":"2026-04-27T20:55:38.38131Z","end":"2026-04-27T20:55:38.491479Z","steps":["trace[485185327] 'process raft request' (duration: 110.006186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:39.575988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219455160026794,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T20:55:39.654954Z","caller":"traceutil/trace.go:171","msg":"trace[947312013] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"873.097065ms","start":"2026-04-27T20:55:38.78184Z","end":"2026-04-27T20:55:39.654937Z","steps":["trace[947312013] 'process raft request' (duration: 873.011351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:39.655059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:38.781821Z","time spent":"873.186478ms","remote":"127.0.0.1:59822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:39.655045Z","caller":"traceutil/trace.go:171","msg":"trace[431480867] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"579.63959ms","start":"2026-04-27T20:55:39.075377Z","end":"2026-04-27T20:55:39.655017Z","steps":["trace[431480867] 'read index received' (duration: 579.42007ms)","trace[431480867] 'applied index is now lower than readState.Index' (duration: 217.2µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:39.655202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.802947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:39.655268Z","caller":"traceutil/trace.go:171","msg":"trace[181407630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"579.921123ms","start":"2026-04-27T20:55:39.075333Z","end":"2026-04-27T20:55:39.655254Z","steps":["trace[181407630] 'agreement among raft nodes before linearized reading' (duration: 579.770076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:39.65542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:39.075308Z","time spent":"580.09487ms","remote":"127.0.0.1:59418","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-27T20:55:39.884507Z","caller":"traceutil/trace.go:171","msg":"trace[1261570516] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"219.694867ms","start":"2026-04-27T20:55:39.664787Z","end":"2026-04-27T20:55:39.884482Z","steps":["trace[1261570516] 'process raft request' (duration: 219.558411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.273205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.517992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:40.273456Z","caller":"traceutil/trace.go:171","msg":"trace[1533535707] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"275.62636ms","start":"2026-04-27T20:55:39.997813Z","end":"2026-04-27T20:55:40.273439Z","steps":["trace[1533535707] 'read index received' (duration: 112.748422ms)","trace[1533535707] 'applied index is now lower than readState.Index' (duration: 162.876978ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:40.273571Z","caller":"traceutil/trace.go:171","msg":"trace[63897181] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"383.745ms","start":"2026-04-27T20:55:39.889816Z","end":"2026-04-27T20:55:40.273561Z","steps":["trace[63897181] 'process raft request' (duration: 383.54922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.27364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:39.889803Z","time spent":"383.794982ms","remote":"127.0.0.1:59822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:40.273653Z","caller":"traceutil/trace.go:171","msg":"trace[1887124724] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"385.708689ms","start":"2026-04-27T20:55:39.887916Z","end":"2026-04-27T20:55:40.273625Z","steps":["trace[1887124724] 'process raft request' (duration: 222.705235ms)","trace[1887124724] 'compare' (duration: 162.366285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:40.27377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:39.887893Z","time spent":"385.819454ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:40.273753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.445186ms","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-27T20:55:40.27382Z","caller":"traceutil/trace.go:171","msg":"trace[158684454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"157.559032ms","start":"2026-04-27T20:55:40.116248Z","end":"2026-04-27T20:55:40.273807Z","steps":["trace[158684454] 'agreement among raft nodes before linearized reading' (duration: 157.378774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.273978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.095324ms","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-27T20:55:40.274011Z","caller":"traceutil/trace.go:171","msg":"trace[634423868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"100.169537ms","start":"2026-04-27T20:55:40.17383Z","end":"2026-04-27T20:55:40.274Z","steps":["trace[634423868] 'agreement among raft nodes before linearized reading' (duration: 100.090304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.274042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.202656ms","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-27T20:55:40.27415Z","caller":"traceutil/trace.go:171","msg":"trace[163601349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:424; }","duration":"276.349133ms","start":"2026-04-27T20:55:39.99777Z","end":"2026-04-27T20:55:40.274119Z","steps":["trace[163601349] 'agreement among raft nodes before linearized reading' (duration: 276.132663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.868705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.371494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:40.868918Z","caller":"traceutil/trace.go:171","msg":"trace[425666073] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"590.659373ms","start":"2026-04-27T20:55:40.278232Z","end":"2026-04-27T20:55:40.868891Z","steps":["trace[425666073] 'process raft request' (duration: 355.033868ms)","trace[425666073] 'compare' (duration: 235.229398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:40.86903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.278212Z","time spent":"590.762237ms","remote":"127.0.0.1:59484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:40.869036Z","caller":"traceutil/trace.go:171","msg":"trace[724865792] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"590.634572ms","start":"2026-04-27T20:55:40.278382Z","end":"2026-04-27T20:55:40.869016Z","steps":["trace[724865792] 'process raft request' (duration: 590.477935ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:40.869028Z","caller":"traceutil/trace.go:171","msg":"trace[348813874] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"590.723855ms","start":"2026-04-27T20:55:40.278264Z","end":"2026-04-27T20:55:40.868988Z","steps":["trace[348813874] 'process raft request' (duration: 590.550228ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:40.869054Z","caller":"traceutil/trace.go:171","msg":"trace[245171972] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"590.593139ms","start":"2026-04-27T20:55:40.278436Z","end":"2026-04-27T20:55:40.86903Z","steps":["trace[245171972] 'process raft request' (duration: 590.478944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.869123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.278373Z","time spent":"590.706084ms","remote":"127.0.0.1:59628","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-27T20:55:40.869144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.278246Z","time spent":"590.843451ms","remote":"127.0.0.1:59484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:40.869166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.278422Z","time spent":"590.684824ms","remote":"127.0.0.1:59628","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-27T20:55:40.869407Z","caller":"traceutil/trace.go:171","msg":"trace[992725152] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"589.901878ms","start":"2026-04-27T20:55:40.279493Z","end":"2026-04-27T20:55:40.869395Z","steps":["trace[992725152] 'process raft request' (duration: 589.457549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.869478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.279484Z","time spent":"589.95276ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:40.869493Z","caller":"traceutil/trace.go:171","msg":"trace[259199215] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"589.593085ms","start":"2026-04-27T20:55:40.279884Z","end":"2026-04-27T20:55:40.869477Z","steps":["trace[259199215] 'process raft request' (duration: 589.392236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.869553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.279876Z","time spent":"589.642077ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:40.869524Z","caller":"traceutil/trace.go:171","msg":"trace[343769983] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:437; }","duration":"374.813448ms","start":"2026-04-27T20:55:40.494687Z","end":"2026-04-27T20:55:40.8695Z","steps":["trace[343769983] 'read index received' (duration: 138.612768ms)","trace[343769983] 'applied index is now lower than readState.Index' (duration: 236.1997ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:40.869569Z","caller":"traceutil/trace.go:171","msg":"trace[19290776] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"588.1241ms","start":"2026-04-27T20:55:40.281435Z","end":"2026-04-27T20:55:40.869559Z","steps":["trace[19290776] 'process raft request' (duration: 587.967273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.869613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.281423Z","time spent":"588.167922ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:40.869737Z","caller":"traceutil/trace.go:171","msg":"trace[1023685304] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"510.014624ms","start":"2026-04-27T20:55:40.359716Z","end":"2026-04-27T20:55:40.869731Z","steps":["trace[1023685304] 'process raft request' (duration: 509.734912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.869741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.045569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T20:55:40.869775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.359689Z","time spent":"510.060966ms","remote":"127.0.0.1:59628","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-27T20:55:40.869785Z","caller":"traceutil/trace.go:171","msg":"trace[99457447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:433; }","duration":"375.143193ms","start":"2026-04-27T20:55:40.494629Z","end":"2026-04-27T20:55:40.869772Z","steps":["trace[99457447] 'agreement among raft nodes before linearized reading' (duration: 375.022858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.86979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.619001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:55:40.869824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.494608Z","time spent":"375.204806ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T20:55:40.869837Z","caller":"traceutil/trace.go:171","msg":"trace[1778126182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"341.691364ms","start":"2026-04-27T20:55:40.528134Z","end":"2026-04-27T20:55:40.869825Z","steps":["trace[1778126182] 'agreement among raft nodes before linearized reading' (duration: 341.608091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:40.869869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.52812Z","time spent":"341.742097ms","remote":"127.0.0.1:59418","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-27T20:55:41.268579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.317066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:41.268697Z","caller":"traceutil/trace.go:171","msg":"trace[978758590] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"360.723629ms","start":"2026-04-27T20:55:40.907962Z","end":"2026-04-27T20:55:41.268685Z","steps":["trace[978758590] 'process raft request' (duration: 160.243136ms)","trace[978758590] 'compare' (duration: 200.215822ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:41.268738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.907942Z","time spent":"360.780482ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:41.268837Z","caller":"traceutil/trace.go:171","msg":"trace[823855314] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:449; }","duration":"331.822813ms","start":"2026-04-27T20:55:40.937008Z","end":"2026-04-27T20:55:41.268831Z","steps":["trace[823855314] 'read index received' (duration: 131.404543ms)","trace[823855314] 'applied index is now lower than readState.Index' (duration: 200.41769ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:41.269032Z","caller":"traceutil/trace.go:171","msg":"trace[1957759984] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"354.90147ms","start":"2026-04-27T20:55:40.914123Z","end":"2026-04-27T20:55:41.269025Z","steps":["trace[1957759984] 'process raft request' (duration: 354.524544ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:41.26908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.914102Z","time spent":"354.961593ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:41.269338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.288884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-27T20:55:41.269445Z","caller":"traceutil/trace.go:171","msg":"trace[1956469372] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:438; }","duration":"332.462351ms","start":"2026-04-27T20:55:40.936965Z","end":"2026-04-27T20:55:41.269427Z","steps":["trace[1956469372] 'agreement among raft nodes before linearized reading' (duration: 332.135587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:41.269486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.936949Z","time spent":"332.526815ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-27T20:55:41.270994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.284689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:41.271049Z","caller":"traceutil/trace.go:171","msg":"trace[1741677559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:438; }","duration":"196.387533ms","start":"2026-04-27T20:55:41.074648Z","end":"2026-04-27T20:55:41.271035Z","steps":["trace[1741677559] 'agreement among raft nodes before linearized reading' (duration: 196.251078ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:41.271213Z","caller":"traceutil/trace.go:171","msg":"trace[621278451] transaction","detail":"{read_only:false; number_of_response:1; response_revision:438; }","duration":"329.952419ms","start":"2026-04-27T20:55:40.941253Z","end":"2026-04-27T20:55:41.271206Z","steps":["trace[621278451] 'process raft request' (duration: 329.561972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:41.271275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:40.941236Z","time spent":"329.994061ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3843,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:41.39437Z","caller":"traceutil/trace.go:171","msg":"trace[431662948] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"118.28444ms","start":"2026-04-27T20:55:41.276022Z","end":"2026-04-27T20:55:41.394307Z","steps":["trace[431662948] 'read index received' (duration: 41.769406ms)","trace[431662948] 'applied index is now lower than readState.Index' (duration: 76.514234ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:41.394687Z","caller":"traceutil/trace.go:171","msg":"trace[416129185] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"117.134949ms","start":"2026-04-27T20:55:41.277528Z","end":"2026-04-27T20:55:41.394663Z","steps":["trace[416129185] 'process raft request' (duration: 116.657708ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:41.394952Z","caller":"traceutil/trace.go:171","msg":"trace[1475550923] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"118.928148ms","start":"2026-04-27T20:55:41.275991Z","end":"2026-04-27T20:55:41.394919Z","steps":["trace[1475550923] 'process raft request' (duration: 117.778987ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:41.394999Z","caller":"traceutil/trace.go:171","msg":"trace[1463569445] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"114.42406ms","start":"2026-04-27T20:55:41.280553Z","end":"2026-04-27T20:55:41.394977Z","steps":["trace[1463569445] 'process raft request' (duration: 113.718799ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:41.395024Z","caller":"traceutil/trace.go:171","msg":"trace[676174793] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"116.752993ms","start":"2026-04-27T20:55:41.278253Z","end":"2026-04-27T20:55:41.395006Z","steps":["trace[676174793] 'process raft request' (duration: 115.99067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:41.395766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.724583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T20:55:41.395823Z","caller":"traceutil/trace.go:171","msg":"trace[1196892646] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:442; }","duration":"119.793406ms","start":"2026-04-27T20:55:41.276016Z","end":"2026-04-27T20:55:41.39581Z","steps":["trace[1196892646] 'agreement among raft nodes before linearized reading' (duration: 118.416286ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:41.683066Z","caller":"traceutil/trace.go:171","msg":"trace[106752099] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"282.712117ms","start":"2026-04-27T20:55:41.400304Z","end":"2026-04-27T20:55:41.683016Z","steps":["trace[106752099] 'process raft request' (duration: 215.258862ms)","trace[106752099] 'compare' (duration: 67.221915ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:41.683203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.378119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4172"} {"level":"info","ts":"2026-04-27T20:55:41.683243Z","caller":"traceutil/trace.go:171","msg":"trace[1067505928] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:448; }","duration":"276.458173ms","start":"2026-04-27T20:55:41.406775Z","end":"2026-04-27T20:55:41.683233Z","steps":["trace[1067505928] 'agreement among raft nodes before linearized reading' (duration: 276.359398ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:41.683605Z","caller":"traceutil/trace.go:171","msg":"trace[30728147] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"282.781351ms","start":"2026-04-27T20:55:41.40078Z","end":"2026-04-27T20:55:41.683561Z","steps":["trace[30728147] 'process raft request' (duration: 282.140653ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:41.683088Z","caller":"traceutil/trace.go:171","msg":"trace[998582495] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:459; }","duration":"230.045362ms","start":"2026-04-27T20:55:41.453021Z","end":"2026-04-27T20:55:41.683066Z","steps":["trace[998582495] 'read index received' (duration: 162.513104ms)","trace[998582495] 'applied index is now lower than readState.Index' (duration: 67.531188ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:41.683664Z","caller":"traceutil/trace.go:171","msg":"trace[1153397295] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"278.494232ms","start":"2026-04-27T20:55:41.405123Z","end":"2026-04-27T20:55:41.683617Z","steps":["trace[1153397295] 'process raft request' (duration: 277.904417ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:41.683728Z","caller":"traceutil/trace.go:171","msg":"trace[1665769469] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"281.212921ms","start":"2026-04-27T20:55:41.402497Z","end":"2026-04-27T20:55:41.68371Z","steps":["trace[1665769469] 'process raft request' (duration: 280.48519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:42.132255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.296257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:42.132426Z","caller":"traceutil/trace.go:171","msg":"trace[1770816922] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:464; }","duration":"351.977653ms","start":"2026-04-27T20:55:41.780434Z","end":"2026-04-27T20:55:42.132411Z","steps":["trace[1770816922] 'read index received' (duration: 110.625023ms)","trace[1770816922] 'applied index is now lower than readState.Index' (duration: 241.35185ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:42.132496Z","caller":"traceutil/trace.go:171","msg":"trace[1980516972] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"440.221272ms","start":"2026-04-27T20:55:41.692266Z","end":"2026-04-27T20:55:42.132487Z","steps":["trace[1980516972] 'process raft request' (duration: 440.084776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:42.132586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:41.692255Z","time spent":"440.284094ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:42.132658Z","caller":"traceutil/trace.go:171","msg":"trace[545369356] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"440.42548ms","start":"2026-04-27T20:55:41.692181Z","end":"2026-04-27T20:55:42.132607Z","steps":["trace[545369356] 'process raft request' (duration: 198.721755ms)","trace[545369356] 'compare' (duration: 241.181183ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:42.132757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.268996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3819"} {"level":"info","ts":"2026-04-27T20:55:42.132899Z","caller":"traceutil/trace.go:171","msg":"trace[1631231245] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:451; }","duration":"352.469534ms","start":"2026-04-27T20:55:41.780392Z","end":"2026-04-27T20:55:42.132861Z","steps":["trace[1631231245] 'agreement among raft nodes before linearized reading' (duration: 352.15079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:42.132803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:41.69216Z","time spent":"440.568256ms","remote":"127.0.0.1:59484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:42.132987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:41.780373Z","time spent":"352.59987ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3841,"request content":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" "} {"level":"info","ts":"2026-04-27T20:55:42.133384Z","caller":"traceutil/trace.go:171","msg":"trace[1122107159] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"347.438893ms","start":"2026-04-27T20:55:41.785932Z","end":"2026-04-27T20:55:42.133371Z","steps":["trace[1122107159] 'process raft request' (duration: 347.112119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:42.13345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:41.785907Z","time spent":"347.507576ms","remote":"127.0.0.1:59822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:42.30958Z","caller":"traceutil/trace.go:171","msg":"trace[303550241] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"166.712709ms","start":"2026-04-27T20:55:42.142841Z","end":"2026-04-27T20:55:42.309553Z","steps":["trace[303550241] 'read index received' (duration: 90.97221ms)","trace[303550241] 'applied index is now lower than readState.Index' (duration: 75.739379ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:42.309639Z","caller":"traceutil/trace.go:171","msg":"trace[723977082] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"167.731524ms","start":"2026-04-27T20:55:42.141865Z","end":"2026-04-27T20:55:42.309596Z","steps":["trace[723977082] 'process raft request' (duration: 91.88227ms)","trace[723977082] 'compare' (duration: 75.595203ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:42.310047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.162859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-27T20:55:42.310228Z","caller":"traceutil/trace.go:171","msg":"trace[1815341552] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:453; }","duration":"167.420901ms","start":"2026-04-27T20:55:42.142764Z","end":"2026-04-27T20:55:42.310185Z","steps":["trace[1815341552] 'agreement among raft nodes before linearized reading' (duration: 166.901208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:43.003269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.936978ms","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-27T20:55:43.003353Z","caller":"traceutil/trace.go:171","msg":"trace[1284825985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:455; }","duration":"129.074135ms","start":"2026-04-27T20:55:42.874261Z","end":"2026-04-27T20:55:43.003335Z","steps":["trace[1284825985] 'range keys from in-memory index tree' (duration: 128.715609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:43.00338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.34108ms","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-27T20:55:43.003504Z","caller":"traceutil/trace.go:171","msg":"trace[745115808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:455; }","duration":"127.528408ms","start":"2026-04-27T20:55:42.875947Z","end":"2026-04-27T20:55:43.003476Z","steps":["trace[745115808] 'range keys from in-memory index tree' (duration: 127.183602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:45.923212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.435859ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:45.923404Z","caller":"traceutil/trace.go:171","msg":"trace[482844046] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"785.412692ms","start":"2026-04-27T20:55:45.137975Z","end":"2026-04-27T20:55:45.923387Z","steps":["trace[482844046] 'process raft request' (duration: 785.34261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:45.92348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:45.137914Z","time spent":"785.529368ms","remote":"127.0.0.1:59628","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-27T20:55:45.923504Z","caller":"traceutil/trace.go:171","msg":"trace[1103707171] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"786.154493ms","start":"2026-04-27T20:55:45.137317Z","end":"2026-04-27T20:55:45.923472Z","steps":["trace[1103707171] 'process raft request' (duration: 77.348788ms)","trace[1103707171] 'compare' (duration: 707.879256ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:45.923631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:45.137307Z","time spent":"786.256228ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:46.609236Z","caller":"traceutil/trace.go:171","msg":"trace[827628099] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"142.734367ms","start":"2026-04-27T20:55:46.466476Z","end":"2026-04-27T20:55:46.609211Z","steps":["trace[827628099] 'process raft request' (duration: 142.58497ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:50.134412Z","caller":"traceutil/trace.go:171","msg":"trace[938574695] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"150.574581ms","start":"2026-04-27T20:55:49.983807Z","end":"2026-04-27T20:55:50.134382Z","steps":["trace[938574695] 'read index received' (duration: 150.412044ms)","trace[938574695] 'applied index is now lower than readState.Index' (duration: 161.217µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:50.134498Z","caller":"traceutil/trace.go:171","msg":"trace[1513416554] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"163.994336ms","start":"2026-04-27T20:55:49.970481Z","end":"2026-04-27T20:55:50.134475Z","steps":["trace[1513416554] 'process raft request' (duration: 163.697905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:50.134578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.767958ms","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-27T20:55:50.13466Z","caller":"traceutil/trace.go:171","msg":"trace[1617024046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"150.866123ms","start":"2026-04-27T20:55:49.983755Z","end":"2026-04-27T20:55:50.134621Z","steps":["trace[1617024046] 'agreement among raft nodes before linearized reading' (duration: 150.739527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:51.030535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.795822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-27T20:55:51.030566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.62076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:51.030619Z","caller":"traceutil/trace.go:171","msg":"trace[46728015] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:472; }","duration":"178.924907ms","start":"2026-04-27T20:55:50.851675Z","end":"2026-04-27T20:55:51.0306Z","steps":["trace[46728015] 'range keys from in-memory index tree' (duration: 178.674917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:51.030572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.561965ms","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-27T20:55:51.030646Z","caller":"traceutil/trace.go:171","msg":"trace[1661654544] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:472; }","duration":"151.752605ms","start":"2026-04-27T20:55:50.878874Z","end":"2026-04-27T20:55:51.030626Z","steps":["trace[1661654544] 'range keys from in-memory index tree' (duration: 151.541627ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:51.030683Z","caller":"traceutil/trace.go:171","msg":"trace[650997912] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:472; }","duration":"212.682879ms","start":"2026-04-27T20:55:50.817983Z","end":"2026-04-27T20:55:51.030666Z","steps":["trace[650997912] 'range keys from in-memory index tree' (duration: 212.547684ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:51.235959Z","caller":"traceutil/trace.go:171","msg":"trace[1429173044] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"124.044208ms","start":"2026-04-27T20:55:51.111893Z","end":"2026-04-27T20:55:51.235937Z","steps":["trace[1429173044] 'read index received' (duration: 28.310294ms)","trace[1429173044] 'applied index is now lower than readState.Index' (duration: 95.733114ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:51.23605Z","caller":"traceutil/trace.go:171","msg":"trace[1633558898] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"125.812847ms","start":"2026-04-27T20:55:51.110193Z","end":"2026-04-27T20:55:51.236005Z","steps":["trace[1633558898] 'process raft request' (duration: 30.076943ms)","trace[1633558898] 'compare' (duration: 95.563737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:51.236223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.303138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:51.236418Z","caller":"traceutil/trace.go:171","msg":"trace[169380607] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:0; response_revision:474; }","duration":"124.544708ms","start":"2026-04-27T20:55:51.111849Z","end":"2026-04-27T20:55:51.236393Z","steps":["trace[169380607] 'agreement among raft nodes before linearized reading' (duration: 124.190104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:51.497289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.820393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:51.497411Z","caller":"traceutil/trace.go:171","msg":"trace[63406730] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:475; }","duration":"120.045461ms","start":"2026-04-27T20:55:51.377345Z","end":"2026-04-27T20:55:51.497391Z","steps":["trace[63406730] 'range keys from in-memory index tree' (duration: 119.728059ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:51.696768Z","caller":"traceutil/trace.go:171","msg":"trace[449986861] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"193.727775ms","start":"2026-04-27T20:55:51.502991Z","end":"2026-04-27T20:55:51.696719Z","steps":["trace[449986861] 'process raft request' (duration: 193.477235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:51.990261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.763596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:51.99057Z","caller":"traceutil/trace.go:171","msg":"trace[538201551] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"271.619853ms","start":"2026-04-27T20:55:51.718931Z","end":"2026-04-27T20:55:51.990551Z","steps":["trace[538201551] 'process raft request' (duration: 271.447666ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:51.990594Z","caller":"traceutil/trace.go:171","msg":"trace[295942678] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"272.495707ms","start":"2026-04-27T20:55:51.718055Z","end":"2026-04-27T20:55:51.99055Z","steps":["trace[295942678] 'process raft request' (duration: 57.375597ms)","trace[295942678] 'compare' (duration: 214.60808ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:52.309522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.99288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:52.309651Z","caller":"traceutil/trace.go:171","msg":"trace[1139548807] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"234.943366ms","start":"2026-04-27T20:55:52.074682Z","end":"2026-04-27T20:55:52.309625Z","steps":["trace[1139548807] 'read index received' (duration: 49.722427ms)","trace[1139548807] 'applied index is now lower than readState.Index' (duration: 185.218798ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:52.309687Z","caller":"traceutil/trace.go:171","msg":"trace[533416936] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"313.889641ms","start":"2026-04-27T20:55:51.99575Z","end":"2026-04-27T20:55:52.30964Z","steps":["trace[533416936] 'process raft request' (duration: 128.693013ms)","trace[533416936] 'compare' (duration: 184.804613ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:52.309822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.136273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:52.309882Z","caller":"traceutil/trace.go:171","msg":"trace[1327340785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"235.231757ms","start":"2026-04-27T20:55:52.074635Z","end":"2026-04-27T20:55:52.309867Z","steps":["trace[1327340785] 'agreement among raft nodes before linearized reading' (duration: 235.117212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:52.31007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:51.995724Z","time spent":"314.189593ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:52.310065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.826253ms","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-27T20:55:52.310101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.835708ms","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-27T20:55:52.310152Z","caller":"traceutil/trace.go:171","msg":"trace[421635241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"171.972698ms","start":"2026-04-27T20:55:52.138162Z","end":"2026-04-27T20:55:52.310134Z","steps":["trace[421635241] 'agreement among raft nodes before linearized reading' (duration: 171.76765ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:52.310179Z","caller":"traceutil/trace.go:171","msg":"trace[1689963400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"147.953523ms","start":"2026-04-27T20:55:52.162205Z","end":"2026-04-27T20:55:52.310159Z","steps":["trace[1689963400] 'agreement among raft nodes before linearized reading' (duration: 147.785537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:52.674787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.456783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:52.675155Z","caller":"traceutil/trace.go:171","msg":"trace[727090067] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"359.33044ms","start":"2026-04-27T20:55:52.315808Z","end":"2026-04-27T20:55:52.675139Z","steps":["trace[727090067] 'process raft request' (duration: 359.06884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:52.675229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:52.315784Z","time spent":"359.419683ms","remote":"127.0.0.1:59716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:52.675248Z","caller":"traceutil/trace.go:171","msg":"trace[841163202] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"359.834219ms","start":"2026-04-27T20:55:52.315383Z","end":"2026-04-27T20:55:52.675218Z","steps":["trace[841163202] 'process raft request' (duration: 252.871726ms)","trace[841163202] 'compare' (duration: 106.332329ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:52.675448Z","caller":"traceutil/trace.go:171","msg":"trace[2037408675] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"359.30445ms","start":"2026-04-27T20:55:52.316133Z","end":"2026-04-27T20:55:52.675438Z","steps":["trace[2037408675] 'process raft request' (duration: 358.788629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:52.67545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:52.315362Z","time spent":"359.986515ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:52.675502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:52.316122Z","time spent":"359.358352ms","remote":"127.0.0.1:59716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:53.579245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.391511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:53.579456Z","caller":"traceutil/trace.go:171","msg":"trace[1313597105] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"898.949842ms","start":"2026-04-27T20:55:52.680472Z","end":"2026-04-27T20:55:53.579422Z","steps":["trace[1313597105] 'process raft request' (duration: 711.74283ms)","trace[1313597105] 'compare' (duration: 185.695845ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:53.579608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:52.680452Z","time spent":"899.091897ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:53.579697Z","caller":"traceutil/trace.go:171","msg":"trace[2146768355] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"899.183161ms","start":"2026-04-27T20:55:52.680487Z","end":"2026-04-27T20:55:53.57967Z","steps":["trace[2146768355] 'process raft request' (duration: 898.870489ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:53.579742Z","caller":"traceutil/trace.go:171","msg":"trace[1881075780] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"898.825247ms","start":"2026-04-27T20:55:52.680887Z","end":"2026-04-27T20:55:53.579713Z","steps":["trace[1881075780] 'process raft request' (duration: 898.62005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:53.579873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:52.68088Z","time spent":"898.921361ms","remote":"127.0.0.1:59732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:53.579944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:52.680468Z","time spent":"899.363288ms","remote":"127.0.0.1:59732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":734,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:55:53.579975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.48113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:53.580061Z","caller":"traceutil/trace.go:171","msg":"trace[1012049272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"504.609965ms","start":"2026-04-27T20:55:53.075432Z","end":"2026-04-27T20:55:53.580042Z","steps":["trace[1012049272] 'agreement among raft nodes before linearized reading' (duration: 504.353355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:53.580292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:53.075417Z","time spent":"504.864505ms","remote":"127.0.0.1:59418","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-27T20:55:53.579622Z","caller":"traceutil/trace.go:171","msg":"trace[1521581244] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:500; }","duration":"504.136656ms","start":"2026-04-27T20:55:53.075465Z","end":"2026-04-27T20:55:53.579601Z","steps":["trace[1521581244] 'read index received' (duration: 316.774968ms)","trace[1521581244] 'applied index is now lower than readState.Index' (duration: 187.360508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:53.580871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.804254ms","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-27T20:55:53.581028Z","caller":"traceutil/trace.go:171","msg":"trace[916253549] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"282.973811ms","start":"2026-04-27T20:55:53.298011Z","end":"2026-04-27T20:55:53.580985Z","steps":["trace[916253549] 'agreement among raft nodes before linearized reading' (duration: 282.799955ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:53.790624Z","caller":"traceutil/trace.go:171","msg":"trace[1443632998] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"197.224804ms","start":"2026-04-27T20:55:53.593372Z","end":"2026-04-27T20:55:53.790597Z","steps":["trace[1443632998] 'process raft request' (duration: 124.107408ms)","trace[1443632998] 'compare' (duration: 72.984871ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:54.086646Z","caller":"traceutil/trace.go:171","msg":"trace[1660462127] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"191.212697ms","start":"2026-04-27T20:55:53.895417Z","end":"2026-04-27T20:55:54.08663Z","steps":["trace[1660462127] 'process raft request' (duration: 191.110863ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:54.389975Z","caller":"traceutil/trace.go:171","msg":"trace[614859985] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"370.713771ms","start":"2026-04-27T20:55:54.019237Z","end":"2026-04-27T20:55:54.389951Z","steps":["trace[614859985] 'process raft request' (duration: 370.613756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:54.390128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.019208Z","time spent":"370.843076ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:55:54.389982Z","caller":"traceutil/trace.go:171","msg":"trace[853036339] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"492.564994ms","start":"2026-04-27T20:55:53.897391Z","end":"2026-04-27T20:55:54.389956Z","steps":["trace[853036339] 'process raft request' (duration: 462.778772ms)","trace[853036339] 'compare' (duration: 29.406807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:54.390364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:53.897362Z","time spent":"492.842454ms","remote":"127.0.0.1:59846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:54.73463Z","caller":"traceutil/trace.go:171","msg":"trace[1582416733] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"642.150362ms","start":"2026-04-27T20:55:54.09245Z","end":"2026-04-27T20:55:54.7346Z","steps":["trace[1582416733] 'process raft request' (duration: 587.286156ms)","trace[1582416733] 'compare' (duration: 54.643527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:54.734753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.092428Z","time spent":"642.267967ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:54.735427Z","caller":"traceutil/trace.go:171","msg":"trace[1745951275] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:510; }","duration":"350.53599ms","start":"2026-04-27T20:55:54.384867Z","end":"2026-04-27T20:55:54.735403Z","steps":["trace[1745951275] 'read index received' (duration: 294.917716ms)","trace[1745951275] 'applied index is now lower than readState.Index' (duration: 55.617494ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:54.735598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.712787ms","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-27T20:55:54.73564Z","caller":"traceutil/trace.go:171","msg":"trace[2041420639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"350.80497ms","start":"2026-04-27T20:55:54.384825Z","end":"2026-04-27T20:55:54.73563Z","steps":["trace[2041420639] 'agreement among raft nodes before linearized reading' (duration: 350.707866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:54.735677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.384807Z","time spent":"350.861252ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T20:55:54.735513Z","caller":"traceutil/trace.go:171","msg":"trace[59063877] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"564.161275ms","start":"2026-04-27T20:55:54.171315Z","end":"2026-04-27T20:55:54.735476Z","steps":["trace[59063877] 'process raft request' (duration: 563.517921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:54.735852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.021549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:54.735945Z","caller":"traceutil/trace.go:171","msg":"trace[2094369914] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:495; }","duration":"338.113203ms","start":"2026-04-27T20:55:54.397776Z","end":"2026-04-27T20:55:54.73589Z","steps":["trace[2094369914] 'agreement among raft nodes before linearized reading' (duration: 338.028129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:54.735987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.397753Z","time spent":"338.225437ms","remote":"127.0.0.1:59518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-27T20:55:54.736224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.171298Z","time spent":"564.600812ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:55.067078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.48544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:55.067196Z","caller":"traceutil/trace.go:171","msg":"trace[1337861918] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"327.705333ms","start":"2026-04-27T20:55:54.739473Z","end":"2026-04-27T20:55:55.067178Z","steps":["trace[1337861918] 'process raft request' (duration: 169.060737ms)","trace[1337861918] 'compare' (duration: 158.324874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:55.067266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.739448Z","time spent":"327.786036ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:55.10734Z","caller":"traceutil/trace.go:171","msg":"trace[1630358171] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"367.764705ms","start":"2026-04-27T20:55:54.739524Z","end":"2026-04-27T20:55:55.107289Z","steps":["trace[1630358171] 'process raft request' (duration: 367.558787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.107486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.739502Z","time spent":"367.90661ms","remote":"127.0.0.1:59628","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-27T20:55:55.107306Z","caller":"traceutil/trace.go:171","msg":"trace[1963820898] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"366.423544ms","start":"2026-04-27T20:55:54.74086Z","end":"2026-04-27T20:55:55.107284Z","steps":["trace[1963820898] 'process raft request' (duration: 366.338611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.107642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.740844Z","time spent":"366.756656ms","remote":"127.0.0.1:59484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:55.107879Z","caller":"traceutil/trace.go:171","msg":"trace[1051404064] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"365.592633ms","start":"2026-04-27T20:55:54.742265Z","end":"2026-04-27T20:55:55.107858Z","steps":["trace[1051404064] 'process raft request' (duration: 365.358864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.107959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.742254Z","time spent":"365.666095ms","remote":"127.0.0.1:59628","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-27T20:55:55.107812Z","caller":"traceutil/trace.go:171","msg":"trace[2016063428] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"366.253327ms","start":"2026-04-27T20:55:54.741544Z","end":"2026-04-27T20:55:55.107798Z","steps":["trace[2016063428] 'process raft request' (duration: 365.694936ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.108088Z","caller":"traceutil/trace.go:171","msg":"trace[122289178] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"360.041643ms","start":"2026-04-27T20:55:54.748035Z","end":"2026-04-27T20:55:55.108076Z","steps":["trace[122289178] 'process raft request' (duration: 359.767283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.108119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.741533Z","time spent":"366.556798ms","remote":"127.0.0.1:59822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:55.108171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:54.74802Z","time spent":"360.119066ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:55.108092Z","caller":"traceutil/trace.go:171","msg":"trace[993354464] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:512; }","duration":"296.50767ms","start":"2026-04-27T20:55:54.811559Z","end":"2026-04-27T20:55:55.108066Z","steps":["trace[993354464] 'read index received' (duration: 97.051105ms)","trace[993354464] 'applied index is now lower than readState.Index' (duration: 199.455635ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:55.108408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.835762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-04-27T20:55:55.108466Z","caller":"traceutil/trace.go:171","msg":"trace[1590363581] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:501; }","duration":"296.895985ms","start":"2026-04-27T20:55:54.811554Z","end":"2026-04-27T20:55:55.10845Z","steps":["trace[1590363581] 'agreement among raft nodes before linearized reading' (duration: 296.579433ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.3586Z","caller":"traceutil/trace.go:171","msg":"trace[1389642137] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"245.861961ms","start":"2026-04-27T20:55:55.112717Z","end":"2026-04-27T20:55:55.358579Z","steps":["trace[1389642137] 'process raft request' (duration: 166.83085ms)","trace[1389642137] 'compare' (duration: 78.853444ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:55.359065Z","caller":"traceutil/trace.go:171","msg":"trace[1018397369] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"244.658346ms","start":"2026-04-27T20:55:55.114382Z","end":"2026-04-27T20:55:55.359041Z","steps":["trace[1018397369] 'process raft request' (duration: 244.408457ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.359119Z","caller":"traceutil/trace.go:171","msg":"trace[761536461] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:521; }","duration":"196.288616ms","start":"2026-04-27T20:55:55.162784Z","end":"2026-04-27T20:55:55.359072Z","steps":["trace[761536461] 'read index received' (duration: 116.670733ms)","trace[761536461] 'applied index is now lower than readState.Index' (duration: 79.616443ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:55.359065Z","caller":"traceutil/trace.go:171","msg":"trace[212311949] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"245.550309ms","start":"2026-04-27T20:55:55.113491Z","end":"2026-04-27T20:55:55.359042Z","steps":["trace[212311949] 'process raft request' (duration: 245.0351ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.359389Z","caller":"traceutil/trace.go:171","msg":"trace[1974732778] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"192.104282ms","start":"2026-04-27T20:55:55.167273Z","end":"2026-04-27T20:55:55.359378Z","steps":["trace[1974732778] 'process raft request' (duration: 191.729937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.359795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.039818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:55:55.359847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.003217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q2p7h\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-04-27T20:55:55.359864Z","caller":"traceutil/trace.go:171","msg":"trace[744671555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"196.163112ms","start":"2026-04-27T20:55:55.163679Z","end":"2026-04-27T20:55:55.359842Z","steps":["trace[744671555] 'agreement among raft nodes before linearized reading' (duration: 196.040917ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.359882Z","caller":"traceutil/trace.go:171","msg":"trace[1267462700] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q2p7h; range_end:; response_count:1; response_revision:509; }","duration":"192.06502ms","start":"2026-04-27T20:55:55.167804Z","end":"2026-04-27T20:55:55.359869Z","steps":["trace[1267462700] 'agreement among raft nodes before linearized reading' (duration: 191.942315ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:55.35933Z","caller":"traceutil/trace.go:171","msg":"trace[2077200210] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"183.713269ms","start":"2026-04-27T20:55:55.175603Z","end":"2026-04-27T20:55:55.359316Z","steps":["trace[2077200210] 'process raft request' (duration: 183.613306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.360575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.474978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-04-27T20:55:55.360644Z","caller":"traceutil/trace.go:171","msg":"trace[1186713972] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:509; }","duration":"144.544451ms","start":"2026-04-27T20:55:55.216056Z","end":"2026-04-27T20:55:55.3606Z","steps":["trace[1186713972] 'agreement among raft nodes before linearized reading' (duration: 143.674089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:55.360744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.27454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-27T20:55:55.360812Z","caller":"traceutil/trace.go:171","msg":"trace[1372626196] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:509; }","duration":"245.401455ms","start":"2026-04-27T20:55:55.115394Z","end":"2026-04-27T20:55:55.360796Z","steps":["trace[1372626196] 'agreement among raft nodes before linearized reading' (duration: 243.949961ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.960376Z","caller":"traceutil/trace.go:171","msg":"trace[962624633] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"227.609075ms","start":"2026-04-27T20:55:56.732731Z","end":"2026-04-27T20:55:56.96034Z","steps":["trace[962624633] 'process raft request' (duration: 227.393507ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.995952Z","caller":"traceutil/trace.go:171","msg":"trace[1209106474] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"255.172169ms","start":"2026-04-27T20:55:56.740727Z","end":"2026-04-27T20:55:56.995899Z","steps":["trace[1209106474] 'process raft request' (duration: 254.871998ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.99624Z","caller":"traceutil/trace.go:171","msg":"trace[353912019] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"229.574268ms","start":"2026-04-27T20:55:56.766607Z","end":"2026-04-27T20:55:56.996181Z","steps":["trace[353912019] 'process raft request' (duration: 229.258456ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.996324Z","caller":"traceutil/trace.go:171","msg":"trace[1319423888] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"224.352216ms","start":"2026-04-27T20:55:56.77195Z","end":"2026-04-27T20:55:56.996303Z","steps":["trace[1319423888] 'process raft request' (duration: 224.107667ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.996356Z","caller":"traceutil/trace.go:171","msg":"trace[345226417] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"228.446436ms","start":"2026-04-27T20:55:56.767891Z","end":"2026-04-27T20:55:56.996337Z","steps":["trace[345226417] 'process raft request' (duration: 228.019761ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.996343Z","caller":"traceutil/trace.go:171","msg":"trace[1104191519] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"225.019689ms","start":"2026-04-27T20:55:56.771265Z","end":"2026-04-27T20:55:56.996284Z","steps":["trace[1104191519] 'process raft request' (duration: 224.737279ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.996421Z","caller":"traceutil/trace.go:171","msg":"trace[766144379] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"190.420077ms","start":"2026-04-27T20:55:56.805994Z","end":"2026-04-27T20:55:56.996414Z","steps":["trace[766144379] 'process raft request' (duration: 190.098455ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.996417Z","caller":"traceutil/trace.go:171","msg":"trace[300028033] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"239.500493ms","start":"2026-04-27T20:55:56.75689Z","end":"2026-04-27T20:55:56.99639Z","steps":["trace[300028033] 'process raft request' (duration: 238.912172ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.996475Z","caller":"traceutil/trace.go:171","msg":"trace[31196704] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"234.779219ms","start":"2026-04-27T20:55:56.761691Z","end":"2026-04-27T20:55:56.99647Z","steps":["trace[31196704] 'process raft request' (duration: 234.143906ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.996441Z","caller":"traceutil/trace.go:171","msg":"trace[1909834824] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"225.409845ms","start":"2026-04-27T20:55:56.770974Z","end":"2026-04-27T20:55:56.996384Z","steps":["trace[1909834824] 'process raft request' (duration: 224.968869ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:56.996524Z","caller":"traceutil/trace.go:171","msg":"trace[599218075] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"245.780114ms","start":"2026-04-27T20:55:56.750729Z","end":"2026-04-27T20:55:56.996509Z","steps":["trace[599218075] 'process raft request' (duration: 244.982095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:57.397623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.221739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:57.397805Z","caller":"traceutil/trace.go:171","msg":"trace[2042732925] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"203.816444ms","start":"2026-04-27T20:55:57.193966Z","end":"2026-04-27T20:55:57.397782Z","steps":["trace[2042732925] 'process raft request' (duration: 35.346396ms)","trace[2042732925] 'compare' (duration: 168.062564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:58.099604Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219455160027030,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T20:55:58.464432Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.216672309s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T20:55:58.471361Z","caller":"traceutil/trace.go:171","msg":"trace[1199272704] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"1.246726329s","start":"2026-04-27T20:55:57.224608Z","end":"2026-04-27T20:55:58.471334Z","steps":["trace[1199272704] 'process raft request' (duration: 1.240124543s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:58.471498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:57.224456Z","time spent":"1.246970488s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T20:55:58.644549Z","caller":"traceutil/trace.go:171","msg":"trace[1137290344] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.415895061s","start":"2026-04-27T20:55:57.228618Z","end":"2026-04-27T20:55:58.644513Z","steps":["trace[1137290344] 'process raft request' (duration: 1.343790649s)","trace[1137290344] 'compare' (duration: 71.647246ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:58.644666Z","caller":"traceutil/trace.go:171","msg":"trace[1797336701] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"114.623282ms","start":"2026-04-27T20:55:58.530008Z","end":"2026-04-27T20:55:58.644631Z","steps":["trace[1797336701] 'process raft request' (duration: 114.446836ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:58.64472Z","caller":"traceutil/trace.go:171","msg":"trace[143426863] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.230336801s","start":"2026-04-27T20:55:57.414342Z","end":"2026-04-27T20:55:58.644679Z","steps":["trace[143426863] 'process raft request' (duration: 1.23003902s)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:58.644762Z","caller":"traceutil/trace.go:171","msg":"trace[651153083] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.235454067s","start":"2026-04-27T20:55:57.40924Z","end":"2026-04-27T20:55:58.644694Z","steps":["trace[651153083] 'process raft request' (duration: 1.235060483s)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:58.645035Z","caller":"traceutil/trace.go:171","msg":"trace[34134013] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:575; }","duration":"1.045926652s","start":"2026-04-27T20:55:57.598882Z","end":"2026-04-27T20:55:58.644809Z","steps":["trace[34134013] 'read index received' (duration: 865.863857ms)","trace[34134013] 'applied index is now lower than readState.Index' (duration: 180.057455ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:55:58.644728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:57.228595Z","time spent":"1.416031746s","remote":"127.0.0.1:59456","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-27T20:55:58.645105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:57.414331Z","time spent":"1.230686683s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:58.645132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:57.409213Z","time spent":"1.23582107s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:55:58.645232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046342028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T20:55:58.645257Z","caller":"traceutil/trace.go:171","msg":"trace[871142722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.046424011s","start":"2026-04-27T20:55:57.598827Z","end":"2026-04-27T20:55:58.645251Z","steps":["trace[871142722] 'agreement among raft nodes before linearized reading' (duration: 1.046290915s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:58.645269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.474755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:55:58.645287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:57.598805Z","time spent":"1.046477212s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T20:55:58.645305Z","caller":"traceutil/trace.go:171","msg":"trace[1638251031] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:556; }","duration":"630.548379ms","start":"2026-04-27T20:55:58.014744Z","end":"2026-04-27T20:55:58.645292Z","steps":["trace[1638251031] 'agreement among raft nodes before linearized reading' (duration: 630.481306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:58.645321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.778236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:55:58.645338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:58.014722Z","time spent":"630.60768ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-04-27T20:55:58.64536Z","caller":"traceutil/trace.go:171","msg":"trace[170211336] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:556; }","duration":"630.85688ms","start":"2026-04-27T20:55:58.01449Z","end":"2026-04-27T20:55:58.645347Z","steps":["trace[170211336] 'agreement among raft nodes before linearized reading' (duration: 630.791277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:58.645364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.304693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T20:55:58.645394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:58.014469Z","time spent":"630.916211ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-27T20:55:58.645425Z","caller":"traceutil/trace.go:171","msg":"trace[2127140273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:556; }","duration":"981.405256ms","start":"2026-04-27T20:55:57.664002Z","end":"2026-04-27T20:55:58.645407Z","steps":["trace[2127140273] 'agreement among raft nodes before linearized reading' (duration: 981.204369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:58.645414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.934211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:55:58.645468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:57.663978Z","time spent":"981.481239ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T20:55:58.645484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.118264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:55:58.645522Z","caller":"traceutil/trace.go:171","msg":"trace[959196161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"570.185667ms","start":"2026-04-27T20:55:58.075327Z","end":"2026-04-27T20:55:58.645512Z","steps":["trace[959196161] 'agreement among raft nodes before linearized reading' (duration: 570.133006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:58.645546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:58.075309Z","time spent":"570.231308ms","remote":"127.0.0.1:59418","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-27T20:55:58.645477Z","caller":"traceutil/trace.go:171","msg":"trace[1696057004] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:556; }","duration":"631.049476ms","start":"2026-04-27T20:55:58.014411Z","end":"2026-04-27T20:55:58.64546Z","steps":["trace[1696057004] 'agreement among raft nodes before linearized reading' (duration: 630.929991ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:55:58.645643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:55:58.014395Z","time spent":"631.224062ms","remote":"127.0.0.1:38102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-04-27T20:55:58.939769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.964012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:55:58.939901Z","caller":"traceutil/trace.go:171","msg":"trace[466113013] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"282.308583ms","start":"2026-04-27T20:55:58.657572Z","end":"2026-04-27T20:55:58.93988Z","steps":["trace[466113013] 'process raft request' (duration: 92.152354ms)","trace[466113013] 'compare' (duration: 189.841538ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:58.962965Z","caller":"traceutil/trace.go:171","msg":"trace[1611397681] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"292.24839ms","start":"2026-04-27T20:55:58.670691Z","end":"2026-04-27T20:55:58.962939Z","steps":["trace[1611397681] 'process raft request' (duration: 292.051623ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:58.963112Z","caller":"traceutil/trace.go:171","msg":"trace[1475825539] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"227.121158ms","start":"2026-04-27T20:55:58.735968Z","end":"2026-04-27T20:55:58.963089Z","steps":["trace[1475825539] 'process raft request' (duration: 226.91191ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:59.128599Z","caller":"traceutil/trace.go:171","msg":"trace[686561941] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"170.043445ms","start":"2026-04-27T20:55:58.95853Z","end":"2026-04-27T20:55:59.128573Z","steps":["trace[686561941] 'process raft request' (duration: 139.881332ms)","trace[686561941] 'compare' (duration: 30.045549ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:59.128803Z","caller":"traceutil/trace.go:171","msg":"trace[1589680347] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"148.52658ms","start":"2026-04-27T20:55:58.980254Z","end":"2026-04-27T20:55:59.128781Z","steps":["trace[1589680347] 'process raft request' (duration: 148.369775ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:59.128873Z","caller":"traceutil/trace.go:171","msg":"trace[334597854] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"137.791544ms","start":"2026-04-27T20:55:58.991071Z","end":"2026-04-27T20:55:59.128862Z","steps":["trace[334597854] 'process raft request' (duration: 137.67313ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:55:59.468891Z","caller":"traceutil/trace.go:171","msg":"trace[1462974239] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"146.859911ms","start":"2026-04-27T20:55:59.321921Z","end":"2026-04-27T20:55:59.468781Z","steps":["trace[1462974239] 'process raft request' (duration: 89.115419ms)","trace[1462974239] 'compare' (duration: 57.612628ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:59.858446Z","caller":"traceutil/trace.go:171","msg":"trace[1883867228] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"120.09087ms","start":"2026-04-27T20:55:59.738326Z","end":"2026-04-27T20:55:59.858417Z","steps":["trace[1883867228] 'process raft request' (duration: 79.569169ms)","trace[1883867228] 'compare' (duration: 40.304952ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:55:59.890719Z","caller":"traceutil/trace.go:171","msg":"trace[1761029276] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"139.852533ms","start":"2026-04-27T20:55:59.750836Z","end":"2026-04-27T20:55:59.890689Z","steps":["trace[1761029276] 'process raft request' (duration: 139.596674ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:00.053299Z","caller":"traceutil/trace.go:171","msg":"trace[1918908095] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"128.568841ms","start":"2026-04-27T20:55:59.924705Z","end":"2026-04-27T20:56:00.053274Z","steps":["trace[1918908095] 'read index received' (duration: 94.346298ms)","trace[1918908095] 'applied index is now lower than readState.Index' (duration: 34.221573ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:56:00.053405Z","caller":"traceutil/trace.go:171","msg":"trace[1414232707] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"135.693594ms","start":"2026-04-27T20:55:59.917675Z","end":"2026-04-27T20:56:00.053369Z","steps":["trace[1414232707] 'process raft request' (duration: 101.632346ms)","trace[1414232707] 'compare' (duration: 33.736616ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:00.053485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.755648ms","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-27T20:56:00.053529Z","caller":"traceutil/trace.go:171","msg":"trace[230183256] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:585; }","duration":"128.840151ms","start":"2026-04-27T20:55:59.924679Z","end":"2026-04-27T20:56:00.053519Z","steps":["trace[230183256] 'agreement among raft nodes before linearized reading' (duration: 128.681115ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:00.102715Z","caller":"traceutil/trace.go:171","msg":"trace[1405312512] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"173.048793ms","start":"2026-04-27T20:55:59.929646Z","end":"2026-04-27T20:56:00.102695Z","steps":["trace[1405312512] 'process raft request' (duration: 172.944549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:00.380092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.11015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-27T20:56:00.380199Z","caller":"traceutil/trace.go:171","msg":"trace[562426949] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:588; }","duration":"226.303196ms","start":"2026-04-27T20:56:00.153872Z","end":"2026-04-27T20:56:00.380175Z","steps":["trace[562426949] 'range keys from in-memory index tree' (duration: 225.858561ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:00.4205Z","caller":"traceutil/trace.go:171","msg":"trace[1320972324] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"250.749065ms","start":"2026-04-27T20:56:00.169724Z","end":"2026-04-27T20:56:00.420473Z","steps":["trace[1320972324] 'process raft request' (duration: 250.563728ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:00.522214Z","caller":"traceutil/trace.go:171","msg":"trace[1240459618] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"105.989277ms","start":"2026-04-27T20:56:00.416202Z","end":"2026-04-27T20:56:00.522191Z","steps":["trace[1240459618] 'read index received' (duration: 4.348913ms)","trace[1240459618] 'applied index is now lower than readState.Index' (duration: 101.639544ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:00.522483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.262606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-04-27T20:56:00.522514Z","caller":"traceutil/trace.go:171","msg":"trace[187040207] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:590; }","duration":"106.343248ms","start":"2026-04-27T20:56:00.416161Z","end":"2026-04-27T20:56:00.522504Z","steps":["trace[187040207] 'agreement among raft nodes before linearized reading' (duration: 106.10936ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:00.522587Z","caller":"traceutil/trace.go:171","msg":"trace[562093430] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"339.927061ms","start":"2026-04-27T20:56:00.182629Z","end":"2026-04-27T20:56:00.522556Z","steps":["trace[562093430] 'process raft request' (duration: 311.707539ms)","trace[562093430] 'compare' (duration: 27.699873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:00.522725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:00.182614Z","time spent":"340.027415ms","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:01.06034Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219455160027088,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T20:56:02.006626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.188226807s","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-27T20:56:02.006743Z","caller":"traceutil/trace.go:171","msg":"trace[704375736] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:591; }","duration":"1.188348351s","start":"2026-04-27T20:56:00.818373Z","end":"2026-04-27T20:56:02.006721Z","steps":["trace[704375736] 'range keys from in-memory index tree' (duration: 1.188211347s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:02.007211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.251316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:56:02.00734Z","caller":"traceutil/trace.go:171","msg":"trace[387993915] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.459154922s","start":"2026-04-27T20:56:00.548161Z","end":"2026-04-27T20:56:02.007315Z","steps":["trace[387993915] 'process raft request' (duration: 526.677868ms)","trace[387993915] 'compare' (duration: 932.06382ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:02.007424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:00.548142Z","time spent":"1.459237114s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:02.299512Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.224808262s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T20:56:02.470487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.229181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:56:02.470698Z","caller":"traceutil/trace.go:171","msg":"trace[1624390109] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.821031142s","start":"2026-04-27T20:56:00.64963Z","end":"2026-04-27T20:56:02.470661Z","steps":["trace[1624390109] 'process raft request' (duration: 1.650523371s)","trace[1624390109] 'compare' (duration: 169.251318ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:56:02.47084Z","caller":"traceutil/trace.go:171","msg":"trace[401270404] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:615; }","duration":"1.911070506s","start":"2026-04-27T20:56:00.55972Z","end":"2026-04-27T20:56:02.47079Z","steps":["trace[401270404] 'read index received' (duration: 515.045559ms)","trace[401270404] 'applied index is now lower than readState.Index' (duration: 1.396021407s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:02.470883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:00.649605Z","time spent":"1.821176186s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:02.47111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.911367066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-27T20:56:02.47116Z","caller":"traceutil/trace.go:171","msg":"trace[1348620054] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:593; }","duration":"1.91144995s","start":"2026-04-27T20:56:00.559696Z","end":"2026-04-27T20:56:02.471146Z","steps":["trace[1348620054] 'agreement among raft nodes before linearized reading' (duration: 1.911248193s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:02.471205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:00.559687Z","time spent":"1.911505661s","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7730,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-27T20:56:02.471285Z","caller":"traceutil/trace.go:171","msg":"trace[1988890093] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"437.955053ms","start":"2026-04-27T20:56:02.033308Z","end":"2026-04-27T20:56:02.471263Z","steps":["trace[1988890093] 'process raft request' (duration: 437.757557ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:02.471379Z","caller":"traceutil/trace.go:171","msg":"trace[1313617111] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.054895258s","start":"2026-04-27T20:56:01.416453Z","end":"2026-04-27T20:56:02.471349Z","steps":["trace[1313617111] 'process raft request' (duration: 1.054185324s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:02.471403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:02.033276Z","time spent":"438.083988ms","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:02.471442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812375576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-27T20:56:02.471473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:01.416391Z","time spent":"1.055034533s","remote":"127.0.0.1:59628","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-27T20:56:02.471484Z","caller":"traceutil/trace.go:171","msg":"trace[1141078411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.812434419s","start":"2026-04-27T20:56:00.659037Z","end":"2026-04-27T20:56:02.471471Z","steps":["trace[1141078411] 'agreement among raft nodes before linearized reading' (duration: 1.812267073s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:02.471515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:00.659028Z","time spent":"1.81248079s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T20:56:02.471585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.396325837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:56:02.471604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618740338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T20:56:02.471627Z","caller":"traceutil/trace.go:171","msg":"trace[1219270518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"1.396407011s","start":"2026-04-27T20:56:01.075209Z","end":"2026-04-27T20:56:02.471616Z","steps":["trace[1219270518] 'agreement among raft nodes before linearized reading' (duration: 1.396345718s)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:02.47163Z","caller":"traceutil/trace.go:171","msg":"trace[1635913849] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:595; }","duration":"1.61880105s","start":"2026-04-27T20:56:00.85282Z","end":"2026-04-27T20:56:02.471621Z","steps":["trace[1635913849] 'agreement among raft nodes before linearized reading' (duration: 1.618756828s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:02.471656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:01.075186Z","time spent":"1.396462903s","remote":"127.0.0.1:59418","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-27T20:56:02.471657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:00.852798Z","time spent":"1.618853702s","remote":"127.0.0.1:59438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-04-27T20:56:02.471762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.811970401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T20:56:02.471865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148582176s","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-27T20:56:02.471857Z","caller":"traceutil/trace.go:171","msg":"trace[2076302905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"1.812077235s","start":"2026-04-27T20:56:00.65975Z","end":"2026-04-27T20:56:02.471828Z","steps":["trace[2076302905] 'agreement among raft nodes before linearized reading' (duration: 1.811829836s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:02.471884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110520442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T20:56:02.471896Z","caller":"traceutil/trace.go:171","msg":"trace[674577675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.148612077s","start":"2026-04-27T20:56:01.323275Z","end":"2026-04-27T20:56:02.471887Z","steps":["trace[674577675] 'agreement among raft nodes before linearized reading' (duration: 1.148530184s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:02.471915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:00.659745Z","time spent":"1.812157617s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T20:56:02.47193Z","caller":"traceutil/trace.go:171","msg":"trace[408154226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"1.110591555s","start":"2026-04-27T20:56:01.361325Z","end":"2026-04-27T20:56:02.471917Z","steps":["trace[408154226] 'agreement among raft nodes before linearized reading' (duration: 1.110469781s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:02.471935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:01.323235Z","time spent":"1.14869331s","remote":"127.0.0.1:59628","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-27T20:56:02.471968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:01.361308Z","time spent":"1.110650167s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T20:56:07.24182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.104979ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-27T20:56:07.242836Z","caller":"traceutil/trace.go:171","msg":"trace[1481863434] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"675.27669ms","start":"2026-04-27T20:56:06.567537Z","end":"2026-04-27T20:56:07.242814Z","steps":["trace[1481863434] 'read index received' (duration: 254.304622ms)","trace[1481863434] 'applied index is now lower than readState.Index' (duration: 420.971148ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:07.243138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.059642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q2p7h\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-27T20:56:07.243176Z","caller":"traceutil/trace.go:171","msg":"trace[261220629] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q2p7h; range_end:; response_count:1; response_revision:625; }","duration":"401.126295ms","start":"2026-04-27T20:56:06.842039Z","end":"2026-04-27T20:56:07.243166Z","steps":["trace[261220629] 'agreement among raft nodes before linearized reading' (duration: 401.005681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:07.243157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.02167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T20:56:07.243248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.662562ms","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-27T20:56:07.243385Z","caller":"traceutil/trace.go:171","msg":"trace[1928854458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"675.859248ms","start":"2026-04-27T20:56:06.567476Z","end":"2026-04-27T20:56:07.243335Z","steps":["trace[1928854458] 'agreement among raft nodes before linearized reading' (duration: 675.618731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:07.24348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.972112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:56:07.243497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:06.567457Z","time spent":"676.021484ms","remote":"127.0.0.1:59628","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":"info","ts":"2026-04-27T20:56:07.243546Z","caller":"traceutil/trace.go:171","msg":"trace[1562534846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"167.030293ms","start":"2026-04-27T20:56:07.076476Z","end":"2026-04-27T20:56:07.243507Z","steps":["trace[1562534846] 'agreement among raft nodes before linearized reading' (duration: 166.867728ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:07.243384Z","caller":"traceutil/trace.go:171","msg":"trace[2125615991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"675.232727ms","start":"2026-04-27T20:56:06.568074Z","end":"2026-04-27T20:56:07.243307Z","steps":["trace[2125615991] 'agreement among raft nodes before linearized reading' (duration: 674.938348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:07.243584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.092264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-27T20:56:07.243649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:06.568066Z","time spent":"675.568298ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T20:56:07.243672Z","caller":"traceutil/trace.go:171","msg":"trace[1744308638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:625; }","duration":"672.220208ms","start":"2026-04-27T20:56:06.57143Z","end":"2026-04-27T20:56:07.24365Z","steps":["trace[1744308638] 'agreement among raft nodes before linearized reading' (duration: 671.961909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:07.243202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:06.842023Z","time spent":"401.173326ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-q2p7h\" "} {"level":"warn","ts":"2026-04-27T20:56:07.24376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:06.571407Z","time spent":"672.338552ms","remote":"127.0.0.1:59628","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-27T20:56:07.243011Z","caller":"traceutil/trace.go:171","msg":"trace[802528722] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"850.240572ms","start":"2026-04-27T20:56:06.392751Z","end":"2026-04-27T20:56:07.242991Z","steps":["trace[802528722] 'process raft request' (duration: 849.476687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:07.244159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:06.392727Z","time spent":"851.306597ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:07.244178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.140678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T20:56:07.24444Z","caller":"traceutil/trace.go:171","msg":"trace[1082823888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"673.363305ms","start":"2026-04-27T20:56:06.570966Z","end":"2026-04-27T20:56:07.24433Z","steps":["trace[1082823888] 'agreement among raft nodes before linearized reading' (duration: 672.454426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:07.244614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:06.570956Z","time spent":"673.590362ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T20:56:07.586161Z","caller":"traceutil/trace.go:171","msg":"trace[931709730] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"336.943768ms","start":"2026-04-27T20:56:07.249196Z","end":"2026-04-27T20:56:07.58614Z","steps":["trace[931709730] 'process raft request' (duration: 336.772833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:07.586296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:07.249186Z","time spent":"337.047872ms","remote":"127.0.0.1:59628","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-27T20:56:07.586563Z","caller":"traceutil/trace.go:171","msg":"trace[2128127897] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"337.327051ms","start":"2026-04-27T20:56:07.249209Z","end":"2026-04-27T20:56:07.586536Z","steps":["trace[2128127897] 'process raft request' (duration: 336.893187ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:07.58662Z","caller":"traceutil/trace.go:171","msg":"trace[908303079] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"335.800942ms","start":"2026-04-27T20:56:07.250802Z","end":"2026-04-27T20:56:07.586603Z","steps":["trace[908303079] 'process raft request' (duration: 335.500192ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:07.58667Z","caller":"traceutil/trace.go:171","msg":"trace[471134540] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"289.061842ms","start":"2026-04-27T20:56:07.29759Z","end":"2026-04-27T20:56:07.586652Z","steps":["trace[471134540] 'process raft request' (duration: 289.01575ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:07.586685Z","caller":"traceutil/trace.go:171","msg":"trace[2125651865] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"329.026101ms","start":"2026-04-27T20:56:07.257636Z","end":"2026-04-27T20:56:07.586662Z","steps":["trace[2125651865] 'process raft request' (duration: 328.896687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:07.586762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:07.250783Z","time spent":"335.882984ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:07.586805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:07.257612Z","time spent":"329.152015ms","remote":"127.0.0.1:59568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:07.586815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:07.2492Z","time spent":"337.434894ms","remote":"127.0.0.1:59628","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-27T20:56:08.48203Z","caller":"traceutil/trace.go:171","msg":"trace[1654629285] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"407.116937ms","start":"2026-04-27T20:56:08.074885Z","end":"2026-04-27T20:56:08.482002Z","steps":["trace[1654629285] 'read index received' (duration: 406.321771ms)","trace[1654629285] 'applied index is now lower than readState.Index' (duration: 794.136µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:56:08.482121Z","caller":"traceutil/trace.go:171","msg":"trace[849433437] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"495.531207ms","start":"2026-04-27T20:56:07.986554Z","end":"2026-04-27T20:56:08.482085Z","steps":["trace[849433437] 'process raft request' (duration: 494.805534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:08.482219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.312342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:56:08.482305Z","caller":"traceutil/trace.go:171","msg":"trace[2137743237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"407.409025ms","start":"2026-04-27T20:56:08.074872Z","end":"2026-04-27T20:56:08.482281Z","steps":["trace[2137743237] 'agreement among raft nodes before linearized reading' (duration: 407.25351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:08.482388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:08.074852Z","time spent":"407.52658ms","remote":"127.0.0.1:59418","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-27T20:56:08.483216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:07.986529Z","time spent":"495.715013ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:10.091469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.463923306s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:56:10.09158Z","caller":"traceutil/trace.go:171","msg":"trace[1778519144] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"760.072437ms","start":"2026-04-27T20:56:09.33149Z","end":"2026-04-27T20:56:10.091563Z","steps":["trace[1778519144] 'read index received' (duration: 43.941µs)","trace[1778519144] 'applied index is now lower than readState.Index' (duration: 760.026986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:10.09183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.480637ms","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-27T20:56:10.091872Z","caller":"traceutil/trace.go:171","msg":"trace[1071647691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"500.566979ms","start":"2026-04-27T20:56:09.591295Z","end":"2026-04-27T20:56:10.091862Z","steps":["trace[1071647691] 'agreement among raft nodes before linearized reading' (duration: 500.443155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:10.09191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:09.591274Z","time spent":"500.627512ms","remote":"127.0.0.1:59628","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-27T20:56:10.091838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.352177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T20:56:10.09206Z","caller":"traceutil/trace.go:171","msg":"trace[104282485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"760.605995ms","start":"2026-04-27T20:56:09.331431Z","end":"2026-04-27T20:56:10.092037Z","steps":["trace[104282485] 'agreement among raft nodes before linearized reading' (duration: 760.219362ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:10.092045Z","caller":"traceutil/trace.go:171","msg":"trace[1074567890] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.542762563s","start":"2026-04-27T20:56:08.549211Z","end":"2026-04-27T20:56:10.091974Z","steps":["trace[1074567890] 'process raft request' (duration: 78.267618ms)","trace[1074567890] 'compare' (duration: 1.463661698s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:10.092188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:09.33141Z","time spent":"760.728969ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T20:56:10.092204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.895311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T20:56:10.092284Z","caller":"traceutil/trace.go:171","msg":"trace[1562464809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"496.025765ms","start":"2026-04-27T20:56:09.596242Z","end":"2026-04-27T20:56:10.092268Z","steps":["trace[1562464809] 'agreement among raft nodes before linearized reading' (duration: 495.793598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:10.092274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:08.549185Z","time spent":"1.54297878s","remote":"127.0.0.1:59732","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-27T20:56:10.092327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:09.596219Z","time spent":"496.099298ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T20:56:10.092206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.634793ms","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-27T20:56:10.092478Z","caller":"traceutil/trace.go:171","msg":"trace[1008693927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"495.956793ms","start":"2026-04-27T20:56:09.5965Z","end":"2026-04-27T20:56:10.092456Z","steps":["trace[1008693927] 'agreement among raft nodes before linearized reading' (duration: 495.575821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:10.092533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:09.596291Z","time spent":"496.228471ms","remote":"127.0.0.1:59628","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-27T20:56:10.602949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219455160027188,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T20:56:11.256552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.584241ms","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-27T20:56:11.256686Z","caller":"traceutil/trace.go:171","msg":"trace[1298940828] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:633; }","duration":"438.740836ms","start":"2026-04-27T20:56:10.817926Z","end":"2026-04-27T20:56:11.256667Z","steps":["trace[1298940828] 'range keys from in-memory index tree' (duration: 438.545089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:11.257249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.998755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:56:11.25736Z","caller":"traceutil/trace.go:171","msg":"trace[1724098089] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.16053338s","start":"2026-04-27T20:56:10.096808Z","end":"2026-04-27T20:56:11.257342Z","steps":["trace[1724098089] 'process raft request' (duration: 911.36507ms)","trace[1724098089] 'compare' (duration: 248.630393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:11.257424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:10.096794Z","time spent":"1.160604262s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:56:11.260008Z","caller":"traceutil/trace.go:171","msg":"trace[331477978] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:659; }","duration":"1.157644128s","start":"2026-04-27T20:56:10.102348Z","end":"2026-04-27T20:56:11.259992Z","steps":["trace[331477978] 'read index received' (duration: 905.975639ms)","trace[331477978] 'applied index is now lower than readState.Index' (duration: 251.667849ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:56:11.260041Z","caller":"traceutil/trace.go:171","msg":"trace[1116070573] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.162949775s","start":"2026-04-27T20:56:10.097064Z","end":"2026-04-27T20:56:11.260014Z","steps":["trace[1116070573] 'process raft request' (duration: 1.16279073s)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:11.260099Z","caller":"traceutil/trace.go:171","msg":"trace[1275029322] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.162846162s","start":"2026-04-27T20:56:10.097246Z","end":"2026-04-27T20:56:11.260092Z","steps":["trace[1275029322] 'process raft request' (duration: 1.162699127s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:11.260174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:10.09724Z","time spent":"1.162878693s","remote":"127.0.0.1:59628","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-27T20:56:11.260198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:10.097054Z","time spent":"1.163058378s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:11.260335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157983279s","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-27T20:56:11.26041Z","caller":"traceutil/trace.go:171","msg":"trace[1054245294] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.161731386s","start":"2026-04-27T20:56:10.098669Z","end":"2026-04-27T20:56:11.2604Z","steps":["trace[1054245294] 'process raft request' (duration: 1.161299343s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:11.260466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:10.098658Z","time spent":"1.161785218s","remote":"127.0.0.1:59628","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-27T20:56:11.260354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.610544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T20:56:11.260549Z","caller":"traceutil/trace.go:171","msg":"trace[1177225319] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:637; }","duration":"406.838042ms","start":"2026-04-27T20:56:10.853701Z","end":"2026-04-27T20:56:11.260539Z","steps":["trace[1177225319] 'agreement among raft nodes before linearized reading' (duration: 406.543903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:11.260579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:10.853679Z","time spent":"406.893144ms","remote":"127.0.0.1:59438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-04-27T20:56:11.260389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.424076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:56:11.260664Z","caller":"traceutil/trace.go:171","msg":"trace[629430122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"185.719655ms","start":"2026-04-27T20:56:11.074935Z","end":"2026-04-27T20:56:11.260655Z","steps":["trace[629430122] 'agreement among raft nodes before linearized reading' (duration: 185.433486ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:11.260619Z","caller":"traceutil/trace.go:171","msg":"trace[957311691] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:637; }","duration":"1.158155534s","start":"2026-04-27T20:56:10.102275Z","end":"2026-04-27T20:56:11.260431Z","steps":["trace[957311691] 'agreement among raft nodes before linearized reading' (duration: 1.157916447s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:11.261454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:10.102261Z","time spent":"1.159173215s","remote":"127.0.0.1:59866","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-27T20:56:12.360773Z","caller":"traceutil/trace.go:171","msg":"trace[605590380] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"159.06644ms","start":"2026-04-27T20:56:12.20167Z","end":"2026-04-27T20:56:12.360736Z","steps":["trace[605590380] 'process raft request' (duration: 158.565335ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:12.518061Z","caller":"traceutil/trace.go:171","msg":"trace[2025003408] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"102.22317ms","start":"2026-04-27T20:56:12.415816Z","end":"2026-04-27T20:56:12.518039Z","steps":["trace[2025003408] 'process raft request' (duration: 102.065185ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:12.728658Z","caller":"traceutil/trace.go:171","msg":"trace[1172854367] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"190.255352ms","start":"2026-04-27T20:56:12.538379Z","end":"2026-04-27T20:56:12.728635Z","steps":["trace[1172854367] 'process raft request' (duration: 190.101877ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:12.782541Z","caller":"traceutil/trace.go:171","msg":"trace[2089824551] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"168.952255ms","start":"2026-04-27T20:56:12.613568Z","end":"2026-04-27T20:56:12.78252Z","steps":["trace[2089824551] 'process raft request' (duration: 168.826862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:13.602132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.78074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:56:13.602206Z","caller":"traceutil/trace.go:171","msg":"trace[879672784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"526.892573ms","start":"2026-04-27T20:56:13.075293Z","end":"2026-04-27T20:56:13.602186Z","steps":["trace[879672784] 'range keys from in-memory index tree' (duration: 526.698477ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:13.602255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:13.075272Z","time spent":"526.971186ms","remote":"127.0.0.1:59422","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-27T20:56:13.60242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.787139ms","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-27T20:56:13.602501Z","caller":"traceutil/trace.go:171","msg":"trace[1293593337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:659; }","duration":"337.913582ms","start":"2026-04-27T20:56:13.264567Z","end":"2026-04-27T20:56:13.602481Z","steps":["trace[1293593337] 'range keys from in-memory index tree' (duration: 337.599313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:13.602543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:13.264554Z","time spent":"337.980094ms","remote":"127.0.0.1:59628","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-27T20:56:13.602562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.583622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T20:56:13.602605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.312004ms","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-27T20:56:13.602614Z","caller":"traceutil/trace.go:171","msg":"trace[2125279564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"337.676655ms","start":"2026-04-27T20:56:13.264922Z","end":"2026-04-27T20:56:13.602598Z","steps":["trace[2125279564] 'range keys from in-memory index tree' (duration: 337.419887ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:13.602642Z","caller":"traceutil/trace.go:171","msg":"trace[1751953307] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:659; }","duration":"337.388856ms","start":"2026-04-27T20:56:13.265243Z","end":"2026-04-27T20:56:13.602632Z","steps":["trace[1751953307] 'range keys from in-memory index tree' (duration: 337.18202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:13.602635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.347147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-27T20:56:13.602665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:13.26522Z","time spent":"337.438448ms","remote":"127.0.0.1:59628","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-27T20:56:13.602659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:13.264894Z","time spent":"337.753127ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T20:56:13.602682Z","caller":"traceutil/trace.go:171","msg":"trace[1136753284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"341.437209ms","start":"2026-04-27T20:56:13.261231Z","end":"2026-04-27T20:56:13.602668Z","steps":["trace[1136753284] 'range keys from in-memory index tree' (duration: 341.207502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:13.60273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:13.26121Z","time spent":"341.507072ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T20:56:16.081209Z","caller":"traceutil/trace.go:171","msg":"trace[1893612832] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"177.625038ms","start":"2026-04-27T20:56:15.903551Z","end":"2026-04-27T20:56:16.081176Z","steps":["trace[1893612832] 'process raft request' (duration: 131.567057ms)","trace[1893612832] 'compare' (duration: 45.886395ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:16.4855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.916187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-27T20:56:16.485693Z","caller":"traceutil/trace.go:171","msg":"trace[1380345238] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:713; }","duration":"260.169795ms","start":"2026-04-27T20:56:16.225499Z","end":"2026-04-27T20:56:16.485669Z","steps":["trace[1380345238] 'range keys from in-memory index tree' (duration: 259.726712ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:17.57838Z","caller":"traceutil/trace.go:171","msg":"trace[2002018657] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"553.219791ms","start":"2026-04-27T20:56:17.02514Z","end":"2026-04-27T20:56:17.57836Z","steps":["trace[2002018657] 'read index received' (duration: 463.796247ms)","trace[2002018657] 'applied index is now lower than readState.Index' (duration: 89.422874ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:56:17.57842Z","caller":"traceutil/trace.go:171","msg":"trace[1260705760] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"593.436842ms","start":"2026-04-27T20:56:16.984958Z","end":"2026-04-27T20:56:17.578395Z","steps":["trace[1260705760] 'process raft request' (duration: 503.875834ms)","trace[1260705760] 'compare' (duration: 89.202408ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:17.578551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.411157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-27T20:56:17.578575Z","caller":"traceutil/trace.go:171","msg":"trace[218283129] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"553.483959ms","start":"2026-04-27T20:56:17.025084Z","end":"2026-04-27T20:56:17.578568Z","steps":["trace[218283129] 'agreement among raft nodes before linearized reading' (duration: 553.364086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:17.578597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:17.025054Z","time spent":"553.535911ms","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9342,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-04-27T20:56:17.578553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:16.984935Z","time spent":"593.535416ms","remote":"127.0.0.1:59484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:56:17.591667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.917901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:56:17.591668Z","caller":"traceutil/trace.go:171","msg":"trace[353628969] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"339.950088ms","start":"2026-04-27T20:56:17.251692Z","end":"2026-04-27T20:56:17.591642Z","steps":["trace[353628969] 'process raft request' (duration: 339.785093ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:17.591739Z","caller":"traceutil/trace.go:171","msg":"trace[1145561353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:716; }","duration":"516.182009ms","start":"2026-04-27T20:56:17.075532Z","end":"2026-04-27T20:56:17.591714Z","steps":["trace[1145561353] 'agreement among raft nodes before linearized reading' (duration: 516.082136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:17.591785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:17.075524Z","time spent":"516.251052ms","remote":"127.0.0.1:59418","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-27T20:56:17.591795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:17.251659Z","time spent":"340.083222ms","remote":"127.0.0.1:59456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T20:56:17.5918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.281441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-27T20:56:17.591843Z","caller":"traceutil/trace.go:171","msg":"trace[701323312] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:716; }","duration":"166.356933ms","start":"2026-04-27T20:56:17.425474Z","end":"2026-04-27T20:56:17.591831Z","steps":["trace[701323312] 'agreement among raft nodes before linearized reading' (duration: 166.23311ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:25.47498Z","caller":"traceutil/trace.go:171","msg":"trace[227674551] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"322.390417ms","start":"2026-04-27T20:56:25.152572Z","end":"2026-04-27T20:56:25.474963Z","steps":["trace[227674551] 'process raft request' (duration: 322.285124ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:25.475086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:25.152546Z","time spent":"322.48739ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:56:29.575246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219455160027600,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T20:56:29.656668Z","caller":"traceutil/trace.go:171","msg":"trace[979387264] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"582.010608ms","start":"2026-04-27T20:56:29.074635Z","end":"2026-04-27T20:56:29.656645Z","steps":["trace[979387264] 'read index received' (duration: 581.825122ms)","trace[979387264] 'applied index is now lower than readState.Index' (duration: 184.606µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:29.656781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.128521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:56:29.656811Z","caller":"traceutil/trace.go:171","msg":"trace[11159601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"582.220533ms","start":"2026-04-27T20:56:29.074582Z","end":"2026-04-27T20:56:29.656802Z","steps":["trace[11159601] 'agreement among raft nodes before linearized reading' (duration: 582.155571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:29.656842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:29.074556Z","time spent":"582.279065ms","remote":"127.0.0.1:59418","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-27T20:56:29.658706Z","caller":"traceutil/trace.go:171","msg":"trace[1649399683] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"602.779886ms","start":"2026-04-27T20:56:29.054717Z","end":"2026-04-27T20:56:29.657496Z","steps":["trace[1649399683] 'process raft request' (duration: 601.785679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:56:29.658833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:56:29.054685Z","time spent":"604.083229ms","remote":"127.0.0.1:59606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:56:45.960041Z","caller":"traceutil/trace.go:171","msg":"trace[592814618] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"142.813692ms","start":"2026-04-27T20:56:45.817185Z","end":"2026-04-27T20:56:45.959998Z","steps":["trace[592814618] 'process raft request' (duration: 80.152772ms)","trace[592814618] 'compare' (duration: 62.418974ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:56:45.960322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.639527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T20:56:45.960374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.893442ms","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-27T20:56:45.96042Z","caller":"traceutil/trace.go:171","msg":"trace[1420848542] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"129.78237ms","start":"2026-04-27T20:56:45.830615Z","end":"2026-04-27T20:56:45.960397Z","steps":["trace[1420848542] 'agreement among raft nodes before linearized reading' (duration: 129.35081ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:45.960468Z","caller":"traceutil/trace.go:171","msg":"trace[459962827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"109.041626ms","start":"2026-04-27T20:56:45.851405Z","end":"2026-04-27T20:56:45.960446Z","steps":["trace[459962827] 'agreement among raft nodes before linearized reading' (duration: 108.715459ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:56:46.273319Z","caller":"traceutil/trace.go:171","msg":"trace[1488956506] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"107.847179ms","start":"2026-04-27T20:56:46.165421Z","end":"2026-04-27T20:56:46.273268Z","steps":["trace[1488956506] 'process raft request' (duration: 43.471023ms)","trace[1488956506] 'compare' (duration: 64.257374ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:57:03.336366Z","caller":"traceutil/trace.go:171","msg":"trace[1479443737] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1047; }","duration":"171.427075ms","start":"2026-04-27T20:57:03.16491Z","end":"2026-04-27T20:57:03.336337Z","steps":["trace[1479443737] 'process raft request' (duration: 129.761511ms)","trace[1479443737] 'compare' (duration: 41.583992ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:57:40.578876Z","caller":"traceutil/trace.go:171","msg":"trace[875943978] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"103.64095ms","start":"2026-04-27T20:57:40.47521Z","end":"2026-04-27T20:57:40.578851Z","steps":["trace[875943978] 'read index received' (duration: 38.612835ms)","trace[875943978] 'applied index is now lower than readState.Index' (duration: 65.027055ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:57:40.578966Z","caller":"traceutil/trace.go:171","msg":"trace[607773857] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"161.094272ms","start":"2026-04-27T20:57:40.417837Z","end":"2026-04-27T20:57:40.578931Z","steps":["trace[607773857] 'process raft request' (duration: 96.000535ms)","trace[607773857] 'compare' (duration: 64.897423ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:57:40.579316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.603754ms","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-27T20:57:40.579383Z","caller":"traceutil/trace.go:171","msg":"trace[1181594936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1170; }","duration":"100.733476ms","start":"2026-04-27T20:57:40.478634Z","end":"2026-04-27T20:57:40.579367Z","steps":["trace[1181594936] 'agreement among raft nodes before linearized reading' (duration: 100.482622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:57:40.57913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.909744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-27T20:57:40.579574Z","caller":"traceutil/trace.go:171","msg":"trace[733823480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1170; }","duration":"104.403472ms","start":"2026-04-27T20:57:40.475157Z","end":"2026-04-27T20:57:40.57956Z","steps":["trace[733823480] 'agreement among raft nodes before linearized reading' (duration: 103.845164ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:14.904538Z","caller":"traceutil/trace.go:171","msg":"trace[681148122] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"113.927019ms","start":"2026-04-27T20:58:14.790563Z","end":"2026-04-27T20:58:14.90449Z","steps":["trace[681148122] 'process raft request' (duration: 86.658635ms)","trace[681148122] 'compare' (duration: 27.139073ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:58:17.040955Z","caller":"traceutil/trace.go:171","msg":"trace[761761810] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"201.585226ms","start":"2026-04-27T20:58:16.839343Z","end":"2026-04-27T20:58:17.040929Z","steps":["trace[761761810] 'process raft request' (duration: 176.762738ms)","trace[761761810] 'compare' (duration: 24.679607ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:58:17.041333Z","caller":"traceutil/trace.go:171","msg":"trace[250930642] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"132.042781ms","start":"2026-04-27T20:58:16.909274Z","end":"2026-04-27T20:58:17.041317Z","steps":["trace[250930642] 'process raft request' (duration: 131.617315ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:20.565932Z","caller":"traceutil/trace.go:171","msg":"trace[741560729] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"154.782898ms","start":"2026-04-27T20:58:20.411123Z","end":"2026-04-27T20:58:20.565906Z","steps":["trace[741560729] 'read index received' (duration: 154.544825ms)","trace[741560729] 'applied index is now lower than readState.Index' (duration: 237.163µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:58:20.565983Z","caller":"traceutil/trace.go:171","msg":"trace[517154407] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"155.528998ms","start":"2026-04-27T20:58:20.410415Z","end":"2026-04-27T20:58:20.565944Z","steps":["trace[517154407] 'process raft request' (duration: 155.349365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:20.566233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.080612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2580"} {"level":"info","ts":"2026-04-27T20:58:20.56641Z","caller":"traceutil/trace.go:171","msg":"trace[1589012938] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1337; }","duration":"155.306155ms","start":"2026-04-27T20:58:20.41108Z","end":"2026-04-27T20:58:20.566386Z","steps":["trace[1589012938] 'agreement among raft nodes before linearized reading' (duration: 154.956021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.083697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.53126ms","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":"warn","ts":"2026-04-27T20:58:21.083781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.662632ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:58:21.083897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.387041ms","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-27T20:58:21.083851Z","caller":"traceutil/trace.go:171","msg":"trace[2034313921] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1337; }","duration":"102.787433ms","start":"2026-04-27T20:58:20.981031Z","end":"2026-04-27T20:58:21.083818Z","steps":["trace[2034313921] 'count revisions from in-memory index tree' (duration: 102.425639ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:21.083925Z","caller":"traceutil/trace.go:171","msg":"trace[1363664223] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1337; }","duration":"350.475712ms","start":"2026-04-27T20:58:20.733438Z","end":"2026-04-27T20:58:21.083914Z","steps":["trace[1363664223] 'count revisions from in-memory index tree' (duration: 350.31242ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:21.083928Z","caller":"traceutil/trace.go:171","msg":"trace[721691032] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1337; }","duration":"264.832484ms","start":"2026-04-27T20:58:20.81907Z","end":"2026-04-27T20:58:21.083903Z","steps":["trace[721691032] 'range keys from in-memory index tree' (duration: 264.63979ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.083957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:20.733417Z","time spent":"350.531612ms","remote":"127.0.0.1:59778","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-27T20:58:21.083743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.26643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T20:58:21.084074Z","caller":"traceutil/trace.go:171","msg":"trace[948848961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1337; }","duration":"321.624526ms","start":"2026-04-27T20:58:20.762424Z","end":"2026-04-27T20:58:21.084049Z","steps":["trace[948848961] 'range keys from in-memory index tree' (duration: 321.116468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.084138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:20.762415Z","time spent":"321.705346ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T20:58:21.083743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.004585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T20:58:21.084286Z","caller":"traceutil/trace.go:171","msg":"trace[1312015244] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:1337; }","duration":"221.605833ms","start":"2026-04-27T20:58:20.862661Z","end":"2026-04-27T20:58:21.084267Z","steps":["trace[1312015244] 'range keys from in-memory index tree' (duration: 220.840374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.083745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.827714ms","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-27T20:58:21.084398Z","caller":"traceutil/trace.go:171","msg":"trace[406632522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1337; }","duration":"147.519283ms","start":"2026-04-27T20:58:20.936861Z","end":"2026-04-27T20:58:21.08438Z","steps":["trace[406632522] 'range keys from in-memory index tree' (duration: 146.693442ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.801688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.531228ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-04-27T20:58:21.802163Z","caller":"traceutil/trace.go:171","msg":"trace[740796608] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1395; }","duration":"655.988329ms","start":"2026-04-27T20:58:21.146153Z","end":"2026-04-27T20:58:21.802141Z","steps":["trace[740796608] 'read index received' (duration: 267.961605ms)","trace[740796608] 'applied index is now lower than readState.Index' (duration: 388.025614ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:21.80237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.085686Z","time spent":"716.677068ms","remote":"127.0.0.1:59438","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-27T20:58:21.802441Z","caller":"traceutil/trace.go:171","msg":"trace[1281212279] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"713.497668ms","start":"2026-04-27T20:58:21.088934Z","end":"2026-04-27T20:58:21.802432Z","steps":["trace[1281212279] 'process raft request' (duration: 712.87653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.802588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.088907Z","time spent":"713.586668ms","remote":"127.0.0.1:59628","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-27T20:58:21.802377Z","caller":"traceutil/trace.go:171","msg":"trace[847509629] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"712.502164ms","start":"2026-04-27T20:58:21.089838Z","end":"2026-04-27T20:58:21.80234Z","steps":["trace[847509629] 'process raft request' (duration: 712.17056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.802746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.907437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:58:21.802802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.08982Z","time spent":"712.92616ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:58:21.802887Z","caller":"traceutil/trace.go:171","msg":"trace[2037899770] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1340; }","duration":"352.037138ms","start":"2026-04-27T20:58:21.45076Z","end":"2026-04-27T20:58:21.802797Z","steps":["trace[2037899770] 'agreement among raft nodes before linearized reading' (duration: 351.867726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.802953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.450739Z","time spent":"352.19163ms","remote":"127.0.0.1:55120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T20:58:21.803058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.234118ms","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-27T20:58:21.803087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.610028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T20:58:21.803115Z","caller":"traceutil/trace.go:171","msg":"trace[1479563467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"668.661619ms","start":"2026-04-27T20:58:21.134447Z","end":"2026-04-27T20:58:21.803108Z","steps":["trace[1479563467] 'agreement among raft nodes before linearized reading' (duration: 668.194633ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:21.803121Z","caller":"traceutil/trace.go:171","msg":"trace[1934546366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"572.345119ms","start":"2026-04-27T20:58:21.230762Z","end":"2026-04-27T20:58:21.803107Z","steps":["trace[1934546366] 'agreement among raft nodes before linearized reading' (duration: 572.114476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.803135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.134434Z","time spent":"668.697619ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T20:58:21.803172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.230744Z","time spent":"572.41078ms","remote":"127.0.0.1:59628","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-27T20:58:21.803704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.320715ms","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-27T20:58:21.803788Z","caller":"traceutil/trace.go:171","msg":"trace[1931751863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"593.462267ms","start":"2026-04-27T20:58:21.210293Z","end":"2026-04-27T20:58:21.803755Z","steps":["trace[1931751863] 'agreement among raft nodes before linearized reading' (duration: 592.239701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:21.803833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.210267Z","time spent":"593.554069ms","remote":"127.0.0.1:59628","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-27T20:58:22.135502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.904422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:58:22.135617Z","caller":"traceutil/trace.go:171","msg":"trace[781032650] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"332.163861ms","start":"2026-04-27T20:58:21.803433Z","end":"2026-04-27T20:58:22.135597Z","steps":["trace[781032650] 'process raft request' (duration: 175.078458ms)","trace[781032650] 'compare' (duration: 156.774569ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:22.135719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.803408Z","time spent":"332.282212ms","remote":"127.0.0.1:59438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:58:22.868561Z","caller":"traceutil/trace.go:171","msg":"trace[2012240559] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.060465846s","start":"2026-04-27T20:58:21.808071Z","end":"2026-04-27T20:58:22.868537Z","steps":["trace[2012240559] 'process raft request' (duration: 1.060387495s)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:22.868608Z","caller":"traceutil/trace.go:171","msg":"trace[1052478686] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.062444631s","start":"2026-04-27T20:58:21.806127Z","end":"2026-04-27T20:58:22.868572Z","steps":["trace[1052478686] 'process raft request' (duration: 1.062183498s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:22.868712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.808033Z","time spent":"1.060603107s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:58:22.868742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.806109Z","time spent":"1.062551692s","remote":"127.0.0.1:59628","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-27T20:58:22.868813Z","caller":"traceutil/trace.go:171","msg":"trace[515563529] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1398; }","duration":"923.233057ms","start":"2026-04-27T20:58:21.945553Z","end":"2026-04-27T20:58:22.868786Z","steps":["trace[515563529] 'read index received' (duration: 32.955907ms)","trace[515563529] 'applied index is now lower than readState.Index' (duration: 890.27507ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:22.868932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.378729ms","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-27T20:58:22.868966Z","caller":"traceutil/trace.go:171","msg":"trace[1806381768] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1344; }","duration":"923.45587ms","start":"2026-04-27T20:58:21.945501Z","end":"2026-04-27T20:58:22.868957Z","steps":["trace[1806381768] 'agreement among raft nodes before linearized reading' (duration: 923.398479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:22.868989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.94548Z","time spent":"923.50239ms","remote":"127.0.0.1:55146","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":"warn","ts":"2026-04-27T20:58:22.869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.044645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:58:22.869037Z","caller":"traceutil/trace.go:171","msg":"trace[739674685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"794.123586ms","start":"2026-04-27T20:58:22.074903Z","end":"2026-04-27T20:58:22.869026Z","steps":["trace[739674685] 'agreement among raft nodes before linearized reading' (duration: 794.032535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:22.869077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:22.074889Z","time spent":"794.180516ms","remote":"127.0.0.1:59418","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-27T20:58:22.868857Z","caller":"traceutil/trace.go:171","msg":"trace[1434426434] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"1.060677968s","start":"2026-04-27T20:58:21.808146Z","end":"2026-04-27T20:58:22.868824Z","steps":["trace[1434426434] 'process raft request' (duration: 1.060357634s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:22.869199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.14195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-04-27T20:58:22.86924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.864913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T20:58:22.869251Z","caller":"traceutil/trace.go:171","msg":"trace[863384716] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1344; }","duration":"731.251971ms","start":"2026-04-27T20:58:22.137986Z","end":"2026-04-27T20:58:22.869238Z","steps":["trace[863384716] 'agreement among raft nodes before linearized reading' (duration: 731.13501ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:22.869377Z","caller":"traceutil/trace.go:171","msg":"trace[212121635] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1344; }","duration":"767.955145ms","start":"2026-04-27T20:58:22.101328Z","end":"2026-04-27T20:58:22.869283Z","steps":["trace[212121635] 'agreement among raft nodes before linearized reading' (duration: 767.809143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:22.869357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:22.13797Z","time spent":"731.375533ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-27T20:58:22.869428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:22.101317Z","time spent":"768.099227ms","remote":"127.0.0.1:59716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":69,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-27T20:58:22.869441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:21.808117Z","time spent":"1.061055583s","remote":"127.0.0.1:59628","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-27T20:58:23.3785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.722864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T20:58:23.378578Z","caller":"traceutil/trace.go:171","msg":"trace[786273726] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1344; }","duration":"285.847236ms","start":"2026-04-27T20:58:23.092713Z","end":"2026-04-27T20:58:23.37856Z","steps":["trace[786273726] 'count revisions from in-memory index tree' (duration: 285.582733ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:23.379062Z","caller":"traceutil/trace.go:171","msg":"trace[2004193398] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"227.520992ms","start":"2026-04-27T20:58:23.151523Z","end":"2026-04-27T20:58:23.379044Z","steps":["trace[2004193398] 'process raft request' (duration: 181.918798ms)","trace[2004193398] 'compare' (duration: 44.829914ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:23.973495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.635173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T20:58:23.97358Z","caller":"traceutil/trace.go:171","msg":"trace[1074285124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1345; }","duration":"165.742675ms","start":"2026-04-27T20:58:23.807822Z","end":"2026-04-27T20:58:23.973565Z","steps":["trace[1074285124] 'range keys from in-memory index tree' (duration: 165.471781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:23.973613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.983557ms","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-27T20:58:23.974225Z","caller":"traceutil/trace.go:171","msg":"trace[1494714051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1345; }","duration":"166.639325ms","start":"2026-04-27T20:58:23.807569Z","end":"2026-04-27T20:58:23.974209Z","steps":["trace[1494714051] 'range keys from in-memory index tree' (duration: 165.740364ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:24.258587Z","caller":"traceutil/trace.go:171","msg":"trace[714777322] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"280.834183ms","start":"2026-04-27T20:58:23.977728Z","end":"2026-04-27T20:58:24.258562Z","steps":["trace[714777322] 'process raft request' (duration: 280.65739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:25.669034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236765191s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T20:58:25.669131Z","caller":"traceutil/trace.go:171","msg":"trace[913390429] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1403; }","duration":"1.594467226s","start":"2026-04-27T20:58:24.074648Z","end":"2026-04-27T20:58:25.669115Z","steps":["trace[913390429] 'read index received' (duration: 183.822215ms)","trace[913390429] 'applied index is now lower than readState.Index' (duration: 1.410643591s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:25.669346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59469807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:58:25.66944Z","caller":"traceutil/trace.go:171","msg":"trace[720617760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"1.594812631s","start":"2026-04-27T20:58:24.074616Z","end":"2026-04-27T20:58:25.669429Z","steps":["trace[720617760] 'agreement among raft nodes before linearized reading' (duration: 1.594696739s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:25.669483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:24.074605Z","time spent":"1.594868362s","remote":"127.0.0.1:59418","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-27T20:58:25.669282Z","caller":"traceutil/trace.go:171","msg":"trace[1361675297] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"1.691368823s","start":"2026-04-27T20:58:23.977875Z","end":"2026-04-27T20:58:25.669244Z","steps":["trace[1361675297] 'process raft request' (duration: 454.309658ms)","trace[1361675297] 'compare' (duration: 1.236600619s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:25.670032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:23.977858Z","time spent":"1.692046622s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:58:25.713544Z","caller":"traceutil/trace.go:171","msg":"trace[1361900912] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"328.9135ms","start":"2026-04-27T20:58:25.384609Z","end":"2026-04-27T20:58:25.713522Z","steps":["trace[1361900912] 'process raft request' (duration: 328.792319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:25.713863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:25.38458Z","time spent":"329.174364ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T20:58:26.16539Z","caller":"traceutil/trace.go:171","msg":"trace[1136616777] linearizableReadLoop","detail":"{readStateIndex:1407; appliedIndex:1405; }","duration":"496.208036ms","start":"2026-04-27T20:58:25.669158Z","end":"2026-04-27T20:58:26.165366Z","steps":["trace[1136616777] 'read index received' (duration: 44.323534ms)","trace[1136616777] 'applied index is now lower than readState.Index' (duration: 451.883592ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:26.165488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.060656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:58:26.16556Z","caller":"traceutil/trace.go:171","msg":"trace[1400243912] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1349; }","duration":"721.188548ms","start":"2026-04-27T20:58:25.444353Z","end":"2026-04-27T20:58:26.165542Z","steps":["trace[1400243912] 'agreement among raft nodes before linearized reading' (duration: 721.026296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:26.165637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:25.444334Z","time spent":"721.282769ms","remote":"127.0.0.1:59518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-04-27T20:58:26.165877Z","caller":"traceutil/trace.go:171","msg":"trace[2145138934] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"506.464574ms","start":"2026-04-27T20:58:25.659397Z","end":"2026-04-27T20:58:26.165862Z","steps":["trace[2145138934] 'process raft request' (duration: 492.264407ms)","trace[2145138934] 'compare' (duration: 13.522489ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:26.165972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:25.659372Z","time spent":"506.544455ms","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T20:58:26.166058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.536244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T20:58:26.166099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292122739s","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-27T20:58:26.166143Z","caller":"traceutil/trace.go:171","msg":"trace[520416518] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1349; }","duration":"686.652636ms","start":"2026-04-27T20:58:25.479475Z","end":"2026-04-27T20:58:26.166128Z","steps":["trace[520416518] 'agreement among raft nodes before linearized reading' (duration: 686.536675ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:26.166177Z","caller":"traceutil/trace.go:171","msg":"trace[153759380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1349; }","duration":"1.292217129s","start":"2026-04-27T20:58:24.873929Z","end":"2026-04-27T20:58:26.166147Z","steps":["trace[153759380] 'agreement among raft nodes before linearized reading' (duration: 1.292024707s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:26.166215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:25.479458Z","time spent":"686.743277ms","remote":"127.0.0.1:57498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T20:58:26.166221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:24.873922Z","time spent":"1.29228934s","remote":"127.0.0.1:59628","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-27T20:58:26.165669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.291234957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T20:58:26.166623Z","caller":"traceutil/trace.go:171","msg":"trace[1469650214] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1349; }","duration":"1.29223594s","start":"2026-04-27T20:58:24.874368Z","end":"2026-04-27T20:58:26.166604Z","steps":["trace[1469650214] 'agreement among raft nodes before linearized reading' (duration: 1.291118607s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:26.166677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:24.874305Z","time spent":"1.292357231s","remote":"127.0.0.1:59628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T20:58:26.166673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292751636s","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-27T20:58:26.167251Z","caller":"traceutil/trace.go:171","msg":"trace[898352850] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1349; }","duration":"1.293367814s","start":"2026-04-27T20:58:24.873864Z","end":"2026-04-27T20:58:26.167232Z","steps":["trace[898352850] 'agreement among raft nodes before linearized reading' (duration: 1.292132599s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:26.167779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:24.873842Z","time spent":"1.293507255s","remote":"127.0.0.1:59628","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-27T20:58:26.257683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.942897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T20:58:26.257697Z","caller":"traceutil/trace.go:171","msg":"trace[22705955] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"216.61339ms","start":"2026-04-27T20:58:26.041033Z","end":"2026-04-27T20:58:26.257646Z","steps":["trace[22705955] 'process raft request' (duration: 216.321325ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T20:58:26.257756Z","caller":"traceutil/trace.go:171","msg":"trace[1596835971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"587.060268ms","start":"2026-04-27T20:58:25.670678Z","end":"2026-04-27T20:58:26.257738Z","steps":["trace[1596835971] 'agreement among raft nodes before linearized reading' (duration: 586.900936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:26.257797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:25.670657Z","time spent":"587.131769ms","remote":"127.0.0.1:59422","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-27T20:58:28.87736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680219455160028941,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T20:58:29.152205Z","caller":"traceutil/trace.go:171","msg":"trace[950007392] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"774.908638ms","start":"2026-04-27T20:58:28.377268Z","end":"2026-04-27T20:58:29.152176Z","steps":["trace[950007392] 'read index received' (duration: 739.484251ms)","trace[950007392] 'applied index is now lower than readState.Index' (duration: 35.422977ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T20:58:29.152333Z","caller":"traceutil/trace.go:171","msg":"trace[130487494] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"835.799063ms","start":"2026-04-27T20:58:28.316504Z","end":"2026-04-27T20:58:29.152303Z","steps":["trace[130487494] 'process raft request' (duration: 800.291754ms)","trace[130487494] 'compare' (duration: 34.585288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T20:58:29.152482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.205342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12751"} {"level":"warn","ts":"2026-04-27T20:58:29.152494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:28.316491Z","time spent":"835.923034ms","remote":"127.0.0.1:59628","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-27T20:58:29.152525Z","caller":"traceutil/trace.go:171","msg":"trace[1918900526] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1365; }","duration":"775.313683ms","start":"2026-04-27T20:58:28.3772Z","end":"2026-04-27T20:58:29.152514Z","steps":["trace[1918900526] 'agreement among raft nodes before linearized reading' (duration: 775.113671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T20:58:29.152564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T20:58:28.377174Z","time spent":"775.380974ms","remote":"127.0.0.1:59492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12773,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-04-27T20:58:45.352052Z","caller":"traceutil/trace.go:171","msg":"trace[1166069055] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"107.432529ms","start":"2026-04-27T20:58:45.244594Z","end":"2026-04-27T20:58:45.352027Z","steps":["trace[1166069055] 'process raft request' (duration: 107.251047ms)"],"step_count":1}