{"level":"warn","ts":"2026-04-16T22:51:27.848721Z","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-16T22:51:27.848826Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.118:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.118:2380","--initial-cluster=instance=https://199.19.213.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.118: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-16T22:51:27.848894Z","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-16T22:51:27.848901Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.118:2380"]} {"level":"info","ts":"2026-04-16T22:51:27.848917Z","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-16T22:51:27.849573Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"]} {"level":"info","ts":"2026-04-16T22:51:27.849646Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.118:2380"],"listen-peer-urls":["https://199.19.213.118:2380"],"advertise-client-urls":["https://199.19.213.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.118: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-16T22:51:27.863008Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.095034ms"} {"level":"info","ts":"2026-04-16T22:51:27.891961Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"511d8c552db01fa8","cluster-id":"8d545d29b73b98f3"} {"level":"info","ts":"2026-04-16T22:51:27.892097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T22:51:27.89213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became follower at term 0"} {"level":"info","ts":"2026-04-16T22:51:27.892149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 511d8c552db01fa8 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T22:51:27.892177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became follower at term 1"} {"level":"info","ts":"2026-04-16T22:51:27.892214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=(5844982188863397800)"} {"level":"warn","ts":"2026-04-16T22:51:27.906262Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T22:51:27.909166Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T22:51:27.912804Z","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-16T22:51:27.915497Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"511d8c552db01fa8","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T22:51:27.916117Z","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-16T22:51:27.9162Z","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-16T22:51:27.916252Z","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-16T22:51:27.916845Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"511d8c552db01fa8","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T22:51:27.917342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 switched to configuration voters=(5844982188863397800)"} {"level":"info","ts":"2026-04-16T22:51:27.918186Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8d545d29b73b98f3","local-member-id":"511d8c552db01fa8","added-peer-id":"511d8c552db01fa8","added-peer-peer-urls":["https://199.19.213.118:2380"]} {"level":"info","ts":"2026-04-16T22:51:27.920394Z","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-16T22:51:27.920541Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.118:2380"} {"level":"info","ts":"2026-04-16T22:51:27.920569Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"511d8c552db01fa8","initial-advertise-peer-urls":["https://199.19.213.118:2380"],"listen-peer-urls":["https://199.19.213.118:2380"],"advertise-client-urls":["https://199.19.213.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T22:51:27.9206Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T22:51:27.920582Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.118:2380"} {"level":"info","ts":"2026-04-16T22:51:28.693206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T22:51:28.693273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T22:51:28.693314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 received MsgPreVoteResp from 511d8c552db01fa8 at term 1"} {"level":"info","ts":"2026-04-16T22:51:28.693344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became candidate at term 2"} {"level":"info","ts":"2026-04-16T22:51:28.693359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 received MsgVoteResp from 511d8c552db01fa8 at term 2"} {"level":"info","ts":"2026-04-16T22:51:28.693376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"511d8c552db01fa8 became leader at term 2"} {"level":"info","ts":"2026-04-16T22:51:28.693392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 511d8c552db01fa8 elected leader 511d8c552db01fa8 at term 2"} {"level":"info","ts":"2026-04-16T22:51:28.694532Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"511d8c552db01fa8","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.118:2379]}","request-path":"/0/members/511d8c552db01fa8/attributes","cluster-id":"8d545d29b73b98f3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T22:51:28.69457Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:51:28.694661Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:51:28.694914Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T22:51:28.694973Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T22:51:28.695544Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8d545d29b73b98f3","local-member-id":"511d8c552db01fa8","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:51:28.695686Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:51:28.69574Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T22:51:28.697117Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.118:2379"} {"level":"info","ts":"2026-04-16T22:51:28.694551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T22:51:28.701391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T22:51:33.88549Z","caller":"traceutil/trace.go:171","msg":"trace[288667140] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"338.034746ms","start":"2026-04-16T22:51:33.547423Z","end":"2026-04-16T22:51:33.885458Z","steps":["trace[288667140] 'process raft request' (duration: 337.891431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:33.886507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:33.547395Z","time spent":"338.20847ms","remote":"127.0.0.1:39842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:34.346281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.174951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:34.346429Z","caller":"traceutil/trace.go:171","msg":"trace[1223150166] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:216; }","duration":"128.439098ms","start":"2026-04-16T22:51:34.217969Z","end":"2026-04-16T22:51:34.346408Z","steps":["trace[1223150166] 'range keys from in-memory index tree' (duration: 128.061349ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:34.346529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.368403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-07x19x\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:34.346626Z","caller":"traceutil/trace.go:171","msg":"trace[1760593599] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-07x19x; range_end:; response_count:0; response_revision:216; }","duration":"455.498176ms","start":"2026-04-16T22:51:33.8911Z","end":"2026-04-16T22:51:34.346599Z","steps":["trace[1760593599] 'range keys from in-memory index tree' (duration: 455.306152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:34.346663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:33.891082Z","time spent":"455.573568ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-07x19x\" "} {"level":"warn","ts":"2026-04-16T22:51:34.346738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.164893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:51:34.3466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.050555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T22:51:34.34677Z","caller":"traceutil/trace.go:171","msg":"trace[1912583141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:216; }","duration":"237.238226ms","start":"2026-04-16T22:51:34.109525Z","end":"2026-04-16T22:51:34.346764Z","steps":["trace[1912583141] 'range keys from in-memory index tree' (duration: 237.057641ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:34.346833Z","caller":"traceutil/trace.go:171","msg":"trace[1289625640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:216; }","duration":"125.374753ms","start":"2026-04-16T22:51:34.221399Z","end":"2026-04-16T22:51:34.346774Z","steps":["trace[1289625640] 'range keys from in-memory index tree' (duration: 124.750307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:34.3469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.042046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-16T22:51:34.347004Z","caller":"traceutil/trace.go:171","msg":"trace[1792470739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:216; }","duration":"123.164349ms","start":"2026-04-16T22:51:34.223816Z","end":"2026-04-16T22:51:34.346981Z","steps":["trace[1792470739] 'range keys from in-memory index tree' (duration: 122.932703ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:34.689067Z","caller":"traceutil/trace.go:171","msg":"trace[1670470121] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"337.854832ms","start":"2026-04-16T22:51:34.351172Z","end":"2026-04-16T22:51:34.689027Z","steps":["trace[1670470121] 'process raft request' (duration: 242.170025ms)","trace[1670470121] 'compare' (duration: 95.282948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:34.689232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.35115Z","time spent":"338.007076ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:51:34.689194Z","caller":"traceutil/trace.go:171","msg":"trace[795908841] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"337.416562ms","start":"2026-04-16T22:51:34.351731Z","end":"2026-04-16T22:51:34.689148Z","steps":["trace[795908841] 'process raft request' (duration: 337.128495ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:34.689325Z","caller":"traceutil/trace.go:171","msg":"trace[1214529740] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"336.760556ms","start":"2026-04-16T22:51:34.352534Z","end":"2026-04-16T22:51:34.689294Z","steps":["trace[1214529740] 'process raft request' (duration: 336.716805ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:34.689335Z","caller":"traceutil/trace.go:171","msg":"trace[401142473] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"337.452603ms","start":"2026-04-16T22:51:34.351839Z","end":"2026-04-16T22:51:34.689292Z","steps":["trace[401142473] 'process raft request' (duration: 337.090864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:34.689443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.351724Z","time spent":"337.592536ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:34.689495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.352527Z","time spent":"336.89174ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:51:34.689534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.351833Z","time spent":"337.615277ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":677,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:51:34.689555Z","caller":"traceutil/trace.go:171","msg":"trace[1354478064] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"337.372702ms","start":"2026-04-16T22:51:34.352157Z","end":"2026-04-16T22:51:34.68953Z","steps":["trace[1354478064] 'process raft request' (duration: 337.053494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:34.689716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.35215Z","time spent":"337.468875ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:34.760177Z","caller":"traceutil/trace.go:171","msg":"trace[283233115] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"273.887181ms","start":"2026-04-16T22:51:34.486258Z","end":"2026-04-16T22:51:34.760146Z","steps":["trace[283233115] 'process raft request' (duration: 273.753138ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:34.940171Z","caller":"traceutil/trace.go:171","msg":"trace[1012000683] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:228; }","duration":"179.966096ms","start":"2026-04-16T22:51:34.760178Z","end":"2026-04-16T22:51:34.940144Z","steps":["trace[1012000683] 'read index received' (duration: 179.833403ms)","trace[1012000683] 'applied index is now lower than readState.Index' (duration: 131.263µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:34.940196Z","caller":"traceutil/trace.go:171","msg":"trace[1072479219] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"245.99743ms","start":"2026-04-16T22:51:34.69416Z","end":"2026-04-16T22:51:34.940157Z","steps":["trace[1072479219] 'process raft request' (duration: 245.758504ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:34.94026Z","caller":"traceutil/trace.go:171","msg":"trace[1638512488] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"244.905333ms","start":"2026-04-16T22:51:34.695313Z","end":"2026-04-16T22:51:34.940218Z","steps":["trace[1638512488] 'process raft request' (duration: 244.79346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:34.940341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.667372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:34.940382Z","caller":"traceutil/trace.go:171","msg":"trace[252063222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:225; }","duration":"245.792444ms","start":"2026-04-16T22:51:34.694579Z","end":"2026-04-16T22:51:34.940371Z","steps":["trace[252063222] 'agreement among raft nodes before linearized reading' (duration: 245.682572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:34.940601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.721873ms","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-16T22:51:34.940775Z","caller":"traceutil/trace.go:171","msg":"trace[1183298538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:225; }","duration":"245.885697ms","start":"2026-04-16T22:51:34.694817Z","end":"2026-04-16T22:51:34.940702Z","steps":["trace[1183298538] 'agreement among raft nodes before linearized reading' (duration: 245.419256ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:34.94069Z","caller":"traceutil/trace.go:171","msg":"trace[624714904] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"245.675862ms","start":"2026-04-16T22:51:34.694934Z","end":"2026-04-16T22:51:34.94061Z","steps":["trace[624714904] 'process raft request' (duration: 245.103848ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:35.356709Z","caller":"traceutil/trace.go:171","msg":"trace[1183395532] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"591.350635ms","start":"2026-04-16T22:51:34.765334Z","end":"2026-04-16T22:51:35.356685Z","steps":["trace[1183395532] 'process raft request' (duration: 591.283323ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:35.356807Z","caller":"traceutil/trace.go:171","msg":"trace[2120958115] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"591.89895ms","start":"2026-04-16T22:51:34.76485Z","end":"2026-04-16T22:51:35.356749Z","steps":["trace[2120958115] 'process raft request' (duration: 496.10043ms)","trace[2120958115] 'compare' (duration: 95.493172ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:35.356883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.765324Z","time spent":"591.469578ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:35.357154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.764821Z","time spent":"592.140646ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:51:35.357863Z","caller":"traceutil/trace.go:171","msg":"trace[1323910467] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"410.160269ms","start":"2026-04-16T22:51:34.947686Z","end":"2026-04-16T22:51:35.357846Z","steps":["trace[1323910467] 'process raft request' (duration: 410.011115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:35.357947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.947641Z","time spent":"410.264881ms","remote":"127.0.0.1:40024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":454,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:51:35.358345Z","caller":"traceutil/trace.go:171","msg":"trace[683474670] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"410.342593ms","start":"2026-04-16T22:51:34.947927Z","end":"2026-04-16T22:51:35.35827Z","steps":["trace[683474670] 'process raft request' (duration: 409.860092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:35.358598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.46033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:51:35.35868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.947902Z","time spent":"410.597719ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:35.357862Z","caller":"traceutil/trace.go:171","msg":"trace[1420519450] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:231; }","duration":"403.733412ms","start":"2026-04-16T22:51:34.954101Z","end":"2026-04-16T22:51:35.357835Z","steps":["trace[1420519450] 'read index received' (duration: 306.944228ms)","trace[1420519450] 'applied index is now lower than readState.Index' (duration: 96.788124ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:35.358764Z","caller":"traceutil/trace.go:171","msg":"trace[33012233] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:0; response_revision:229; }","duration":"404.589912ms","start":"2026-04-16T22:51:34.954048Z","end":"2026-04-16T22:51:35.358638Z","steps":["trace[33012233] 'agreement among raft nodes before linearized reading' (duration: 404.417128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:35.358894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:34.954028Z","time spent":"404.850619ms","remote":"127.0.0.1:39868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-04-16T22:51:35.771726Z","caller":"traceutil/trace.go:171","msg":"trace[1315571405] linearizableReadLoop","detail":"{readStateIndex:236; appliedIndex:235; }","duration":"408.94952ms","start":"2026-04-16T22:51:35.362746Z","end":"2026-04-16T22:51:35.771696Z","steps":["trace[1315571405] 'read index received' (duration: 361.274645ms)","trace[1315571405] 'applied index is now lower than readState.Index' (duration: 47.673685ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:35.771792Z","caller":"traceutil/trace.go:171","msg":"trace[1553254445] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"409.145144ms","start":"2026-04-16T22:51:35.362586Z","end":"2026-04-16T22:51:35.771731Z","steps":["trace[1553254445] 'process raft request' (duration: 361.188723ms)","trace[1553254445] 'compare' (duration: 47.776287ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:35.771904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.124824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-16T22:51:35.771947Z","caller":"traceutil/trace.go:171","msg":"trace[439952210] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:230; }","duration":"409.223846ms","start":"2026-04-16T22:51:35.362709Z","end":"2026-04-16T22:51:35.771933Z","steps":["trace[439952210] 'agreement among raft nodes before linearized reading' (duration: 409.097343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:35.771994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:35.362695Z","time spent":"409.290788ms","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-16T22:51:35.771996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:35.362565Z","time spent":"409.325888ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:51:35.773568Z","caller":"traceutil/trace.go:171","msg":"trace[963172888] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"410.293452ms","start":"2026-04-16T22:51:35.363257Z","end":"2026-04-16T22:51:35.77355Z","steps":["trace[963172888] 'process raft request' (duration: 410.154939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:35.773774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:35.363219Z","time spent":"410.396236ms","remote":"127.0.0.1:40024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:51:47.592383Z","caller":"traceutil/trace.go:171","msg":"trace[801543418] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"172.3455ms","start":"2026-04-16T22:51:47.420006Z","end":"2026-04-16T22:51:47.592352Z","steps":["trace[801543418] 'process raft request' (duration: 172.185326ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:51:47.679146Z","caller":"traceutil/trace.go:171","msg":"trace[1906605150] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"245.578168ms","start":"2026-04-16T22:51:47.433546Z","end":"2026-04-16T22:51:47.679124Z","steps":["trace[1906605150] 'read index received' (duration: 159.035844ms)","trace[1906605150] 'applied index is now lower than readState.Index' (duration: 86.541104ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:51:47.679239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.67578ms","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-16T22:51:47.6793Z","caller":"traceutil/trace.go:171","msg":"trace[305517951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:431; }","duration":"245.798163ms","start":"2026-04-16T22:51:47.433496Z","end":"2026-04-16T22:51:47.679294Z","steps":["trace[305517951] 'agreement among raft nodes before linearized reading' (duration: 245.66375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:53.352008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537746973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T22:51:53.852579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537746973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T22:51:54.163213Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.37629361s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T22:51:54.163829Z","caller":"traceutil/trace.go:171","msg":"trace[1976507045] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"1.377023457s","start":"2026-04-16T22:51:52.786772Z","end":"2026-04-16T22:51:54.163795Z","steps":["trace[1976507045] 'process raft request' (duration: 1.376798411s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:54.164056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:52.786734Z","time spent":"1.377178711s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:51:54.661206Z","caller":"traceutil/trace.go:171","msg":"trace[1961021283] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:488; }","duration":"1.809515802s","start":"2026-04-16T22:51:52.85166Z","end":"2026-04-16T22:51:54.661175Z","steps":["trace[1961021283] 'read index received' (duration: 1.311912496s)","trace[1961021283] 'applied index is now lower than readState.Index' (duration: 497.602066ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:51:54.661363Z","caller":"traceutil/trace.go:171","msg":"trace[951311905] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.874481559s","start":"2026-04-16T22:51:52.786865Z","end":"2026-04-16T22:51:54.661346Z","steps":["trace[951311905] 'process raft request' (duration: 1.874119691s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:54.661494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:52.786827Z","time spent":"1.874566512s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:51:54.661591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042214608s","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-16T22:51:54.661702Z","caller":"traceutil/trace.go:171","msg":"trace[1731399564] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"1.042396053s","start":"2026-04-16T22:51:53.619278Z","end":"2026-04-16T22:51:54.661674Z","steps":["trace[1731399564] 'agreement among raft nodes before linearized reading' (duration: 1.042191818s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:54.661753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:53.619244Z","time spent":"1.042497495s","remote":"127.0.0.1:39932","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-16T22:51:54.66183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.810182208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:51:54.661867Z","caller":"traceutil/trace.go:171","msg":"trace[142202873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"1.810264551s","start":"2026-04-16T22:51:52.851591Z","end":"2026-04-16T22:51:54.661856Z","steps":["trace[142202873] 'agreement among raft nodes before linearized reading' (duration: 1.810203429s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:54.661891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:52.851562Z","time spent":"1.810324951s","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:51:54.661997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.976472ms","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-16T22:51:54.662034Z","caller":"traceutil/trace.go:171","msg":"trace[404884745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"947.064815ms","start":"2026-04-16T22:51:53.714957Z","end":"2026-04-16T22:51:54.662022Z","steps":["trace[404884745] 'agreement among raft nodes before linearized reading' (duration: 947.002193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:51:54.662062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:51:53.714921Z","time spent":"947.135007ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T22:52:00.908956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.047619ms","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-16T22:52:00.909047Z","caller":"traceutil/trace.go:171","msg":"trace[1961509126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"117.207874ms","start":"2026-04-16T22:52:00.791817Z","end":"2026-04-16T22:52:00.909025Z","steps":["trace[1961509126] 'range keys from in-memory index tree' (duration: 116.831084ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:01.114088Z","caller":"traceutil/trace.go:171","msg":"trace[419213431] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"199.974154ms","start":"2026-04-16T22:52:00.914083Z","end":"2026-04-16T22:52:01.114057Z","steps":["trace[419213431] 'process raft request' (duration: 199.890282ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:01.114349Z","caller":"traceutil/trace.go:171","msg":"trace[1587402925] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"201.197645ms","start":"2026-04-16T22:52:00.913104Z","end":"2026-04-16T22:52:01.114301Z","steps":["trace[1587402925] 'process raft request' (duration: 171.530381ms)","trace[1587402925] 'compare' (duration: 29.154552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:01.828671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.984239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/cilium-config-agent\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:01.828794Z","caller":"traceutil/trace.go:171","msg":"trace[1794611181] range","detail":"{range_begin:/registry/rolebindings/kube-system/cilium-config-agent; range_end:; response_count:0; response_revision:490; }","duration":"614.121732ms","start":"2026-04-16T22:52:01.214648Z","end":"2026-04-16T22:52:01.82877Z","steps":["trace[1794611181] 'range keys from in-memory index tree' (duration: 613.883186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:01.828844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:01.214604Z","time spent":"614.228574ms","remote":"127.0.0.1:40002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/kube-system/cilium-config-agent\" "} {"level":"info","ts":"2026-04-16T22:52:02.349502Z","caller":"traceutil/trace.go:171","msg":"trace[1545378887] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"248.237093ms","start":"2026-04-16T22:52:02.101228Z","end":"2026-04-16T22:52:02.349465Z","steps":["trace[1545378887] 'process raft request' (duration: 177.820383ms)","trace[1545378887] 'compare' (duration: 69.802975ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:02.349845Z","caller":"traceutil/trace.go:171","msg":"trace[1748350080] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"224.096704ms","start":"2026-04-16T22:52:02.125738Z","end":"2026-04-16T22:52:02.349835Z","steps":["trace[1748350080] 'process raft request' (duration: 223.557201ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.350707Z","caller":"traceutil/trace.go:171","msg":"trace[1839788792] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:521; }","duration":"113.659837ms","start":"2026-04-16T22:52:02.237014Z","end":"2026-04-16T22:52:02.350674Z","steps":["trace[1839788792] 'read index received' (duration: 42.13609ms)","trace[1839788792] 'applied index is now lower than readState.Index' (duration: 71.522317ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:02.350999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.924423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-16T22:52:02.351069Z","caller":"traceutil/trace.go:171","msg":"trace[1854932958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"114.103647ms","start":"2026-04-16T22:52:02.236943Z","end":"2026-04-16T22:52:02.351047Z","steps":["trace[1854932958] 'agreement among raft nodes before linearized reading' (duration: 113.878672ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.351493Z","caller":"traceutil/trace.go:171","msg":"trace[846507447] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"148.954638ms","start":"2026-04-16T22:52:02.202501Z","end":"2026-04-16T22:52:02.351456Z","steps":["trace[846507447] 'process raft request' (duration: 147.275227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:02.758871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.910892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:52:02.759049Z","caller":"traceutil/trace.go:171","msg":"trace[1052474185] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"350.336777ms","start":"2026-04-16T22:52:02.408688Z","end":"2026-04-16T22:52:02.759024Z","steps":["trace[1052474185] 'process raft request' (duration: 181.846352ms)","trace[1052474185] 'compare' (duration: 167.694946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:02.759274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:02.408658Z","time spent":"350.551943ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:02.76001Z","caller":"traceutil/trace.go:171","msg":"trace[590133312] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"350.222775ms","start":"2026-04-16T22:52:02.409745Z","end":"2026-04-16T22:52:02.759967Z","steps":["trace[590133312] 'process raft request' (duration: 350.119303ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.76002Z","caller":"traceutil/trace.go:171","msg":"trace[9550201] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"350.747578ms","start":"2026-04-16T22:52:02.409239Z","end":"2026-04-16T22:52:02.759986Z","steps":["trace[9550201] 'process raft request' (duration: 350.44222ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.76029Z","caller":"traceutil/trace.go:171","msg":"trace[233760141] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"350.321037ms","start":"2026-04-16T22:52:02.409939Z","end":"2026-04-16T22:52:02.76026Z","steps":["trace[233760141] 'process raft request' (duration: 349.982389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:02.760279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:02.409206Z","time spent":"350.950043ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15061,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:52:02.760291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:02.409733Z","time spent":"350.401929ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:52:02.760449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:02.409925Z","time spent":"350.46986ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:52:02.760503Z","caller":"traceutil/trace.go:171","msg":"trace[1375327593] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"348.492893ms","start":"2026-04-16T22:52:02.41199Z","end":"2026-04-16T22:52:02.760483Z","steps":["trace[1375327593] 'process raft request' (duration: 348.185265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:02.760607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:02.411964Z","time spent":"348.588694ms","remote":"127.0.0.1:40130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:02.760769Z","caller":"traceutil/trace.go:171","msg":"trace[749171407] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:529; }","duration":"318.616453ms","start":"2026-04-16T22:52:02.442132Z","end":"2026-04-16T22:52:02.760749Z","steps":["trace[749171407] 'read index received' (duration: 148.493487ms)","trace[749171407] 'applied index is now lower than readState.Index' (duration: 170.121816ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:02.760801Z","caller":"traceutil/trace.go:171","msg":"trace[1490164183] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"347.734484ms","start":"2026-04-16T22:52:02.413053Z","end":"2026-04-16T22:52:02.760788Z","steps":["trace[1490164183] 'process raft request' (duration: 347.315714ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.760803Z","caller":"traceutil/trace.go:171","msg":"trace[1395541424] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"344.32756ms","start":"2026-04-16T22:52:02.416444Z","end":"2026-04-16T22:52:02.760771Z","steps":["trace[1395541424] 'process raft request' (duration: 344.214488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:02.760896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:02.413038Z","time spent":"347.807075ms","remote":"127.0.0.1:40122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:52:02.760914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:02.416432Z","time spent":"344.422233ms","remote":"127.0.0.1:40112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4617,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:52:02.761282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.131956ms","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-16T22:52:02.761341Z","caller":"traceutil/trace.go:171","msg":"trace[823611285] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:518; }","duration":"319.240199ms","start":"2026-04-16T22:52:02.44208Z","end":"2026-04-16T22:52:02.76132Z","steps":["trace[823611285] 'agreement among raft nodes before linearized reading' (duration: 318.772357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:02.761385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:02.442056Z","time spent":"319.3164ms","remote":"127.0.0.1:39782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329979,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-04-16T22:52:02.970837Z","caller":"traceutil/trace.go:171","msg":"trace[840063098] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"190.409671ms","start":"2026-04-16T22:52:02.780394Z","end":"2026-04-16T22:52:02.970804Z","steps":["trace[840063098] 'process raft request' (duration: 190.217436ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.970839Z","caller":"traceutil/trace.go:171","msg":"trace[1533879725] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"201.351979ms","start":"2026-04-16T22:52:02.769444Z","end":"2026-04-16T22:52:02.970796Z","steps":["trace[1533879725] 'process raft request' (duration: 185.152103ms)","trace[1533879725] 'compare' (duration: 15.738405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:02.971143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.217134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-04-16T22:52:02.971176Z","caller":"traceutil/trace.go:171","msg":"trace[2075676470] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:522; }","duration":"194.316396ms","start":"2026-04-16T22:52:02.776851Z","end":"2026-04-16T22:52:02.971168Z","steps":["trace[2075676470] 'agreement among raft nodes before linearized reading' (duration: 194.002078ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.970889Z","caller":"traceutil/trace.go:171","msg":"trace[1373311808] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:537; }","duration":"140.720917ms","start":"2026-04-16T22:52:02.83012Z","end":"2026-04-16T22:52:02.970841Z","steps":["trace[1373311808] 'read index received' (duration: 124.495951ms)","trace[1373311808] 'applied index is now lower than readState.Index' (duration: 16.215456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:02.971239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.852511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4632"} {"level":"info","ts":"2026-04-16T22:52:02.970951Z","caller":"traceutil/trace.go:171","msg":"trace[329608894] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"129.895092ms","start":"2026-04-16T22:52:02.841029Z","end":"2026-04-16T22:52:02.970925Z","steps":["trace[329608894] 'process raft request' (duration: 129.696817ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:02.971309Z","caller":"traceutil/trace.go:171","msg":"trace[1357288875] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:522; }","duration":"201.992044ms","start":"2026-04-16T22:52:02.769302Z","end":"2026-04-16T22:52:02.971294Z","steps":["trace[1357288875] 'agreement among raft nodes before linearized reading' (duration: 201.660866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:02.971465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.433246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:02.971494Z","caller":"traceutil/trace.go:171","msg":"trace[2032817929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"121.490008ms","start":"2026-04-16T22:52:02.849996Z","end":"2026-04-16T22:52:02.971486Z","steps":["trace[2032817929] 'agreement among raft nodes before linearized reading' (duration: 121.446876ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:04.805597Z","caller":"traceutil/trace.go:171","msg":"trace[1446473467] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"358.239571ms","start":"2026-04-16T22:52:04.447325Z","end":"2026-04-16T22:52:04.805564Z","steps":["trace[1446473467] 'process raft request' (duration: 358.17896ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:04.805981Z","caller":"traceutil/trace.go:171","msg":"trace[1358370603] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"371.647018ms","start":"2026-04-16T22:52:04.434296Z","end":"2026-04-16T22:52:04.805943Z","steps":["trace[1358370603] 'process raft request' (duration: 371.048784ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:04.806Z","caller":"traceutil/trace.go:171","msg":"trace[776509494] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"384.319558ms","start":"2026-04-16T22:52:04.421591Z","end":"2026-04-16T22:52:04.805911Z","steps":["trace[776509494] 'process raft request' (duration: 383.645001ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:04.806056Z","caller":"traceutil/trace.go:171","msg":"trace[27891705] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"413.925122ms","start":"2026-04-16T22:52:04.392109Z","end":"2026-04-16T22:52:04.806034Z","steps":["trace[27891705] 'process raft request' (duration: 413.02338ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:04.806234Z","caller":"traceutil/trace.go:171","msg":"trace[349657525] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"413.181072ms","start":"2026-04-16T22:52:04.393032Z","end":"2026-04-16T22:52:04.806213Z","steps":["trace[349657525] 'process raft request' (duration: 412.154097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:04.806334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.393028Z","time spent":"413.244924ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:52:04.806089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.434289Z","time spent":"371.752651ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:52:04.806139Z","caller":"traceutil/trace.go:171","msg":"trace[484192955] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"373.798891ms","start":"2026-04-16T22:52:04.43233Z","end":"2026-04-16T22:52:04.806129Z","steps":["trace[484192955] 'process raft request' (duration: 372.967311ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:04.806452Z","caller":"traceutil/trace.go:171","msg":"trace[1573590861] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"419.087377ms","start":"2026-04-16T22:52:04.387151Z","end":"2026-04-16T22:52:04.806239Z","steps":["trace[1573590861] 'process raft request' (duration: 417.723164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:04.806578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.432312Z","time spent":"374.233581ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:52:04.806729Z","caller":"traceutil/trace.go:171","msg":"trace[423283875] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"358.741103ms","start":"2026-04-16T22:52:04.447244Z","end":"2026-04-16T22:52:04.805985Z","steps":["trace[423283875] 'process raft request' (duration: 358.148469ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:04.80673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.392093Z","time spent":"414.180047ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:52:04.806752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.387127Z","time spent":"419.490257ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:52:04.806805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.447226Z","time spent":"359.544952ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:52:04.806805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.421573Z","time spent":"384.650026ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:52:04.806604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.447319Z","time spent":"358.376134ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:52:04.821826Z","caller":"traceutil/trace.go:171","msg":"trace[995468163] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"215.022762ms","start":"2026-04-16T22:52:04.606781Z","end":"2026-04-16T22:52:04.821804Z","steps":["trace[995468163] 'process raft request' (duration: 214.959761ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:04.822042Z","caller":"traceutil/trace.go:171","msg":"trace[1653039581] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"370.133192ms","start":"2026-04-16T22:52:04.451889Z","end":"2026-04-16T22:52:04.822022Z","steps":["trace[1653039581] 'process raft request' (duration: 369.541537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:04.822178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.451879Z","time spent":"370.229724ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:52:04.822303Z","caller":"traceutil/trace.go:171","msg":"trace[1036820808] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"365.199561ms","start":"2026-04-16T22:52:04.457094Z","end":"2026-04-16T22:52:04.822293Z","steps":["trace[1036820808] 'process raft request' (duration: 364.468804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:04.82237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.457082Z","time spent":"365.264312ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:52:04.822508Z","caller":"traceutil/trace.go:171","msg":"trace[1297674446] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"332.469992ms","start":"2026-04-16T22:52:04.490026Z","end":"2026-04-16T22:52:04.822496Z","steps":["trace[1297674446] 'process raft request' (duration: 331.589971ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:04.822568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:04.490002Z","time spent":"332.540563ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:52:05.02298Z","caller":"traceutil/trace.go:171","msg":"trace[673915499] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"108.502651ms","start":"2026-04-16T22:52:04.91444Z","end":"2026-04-16T22:52:05.022942Z","steps":["trace[673915499] 'process raft request' (duration: 79.00562ms)","trace[673915499] 'compare' (duration: 29.206053ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:05.023396Z","caller":"traceutil/trace.go:171","msg":"trace[510462183] transaction","detail":"{read_only:false; number_of_response:1; response_revision:557; }","duration":"105.16114ms","start":"2026-04-16T22:52:04.918189Z","end":"2026-04-16T22:52:05.02335Z","steps":["trace[510462183] 'process raft request' (duration: 104.659798ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:05.025669Z","caller":"traceutil/trace.go:171","msg":"trace[656668099] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"102.554636ms","start":"2026-04-16T22:52:04.921047Z","end":"2026-04-16T22:52:05.023602Z","steps":["trace[656668099] 'process raft request' (duration: 101.876369ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:05.516237Z","caller":"traceutil/trace.go:171","msg":"trace[1962284055] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"141.638491ms","start":"2026-04-16T22:52:05.374566Z","end":"2026-04-16T22:52:05.516205Z","steps":["trace[1962284055] 'process raft request' (duration: 141.337694ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:05.601721Z","caller":"traceutil/trace.go:171","msg":"trace[1379452887] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"193.081647ms","start":"2026-04-16T22:52:05.408616Z","end":"2026-04-16T22:52:05.601698Z","steps":["trace[1379452887] 'process raft request' (duration: 188.363591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:08.060983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.79397ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T22:52:08.061143Z","caller":"traceutil/trace.go:171","msg":"trace[1681470969] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"209.827906ms","start":"2026-04-16T22:52:07.851293Z","end":"2026-04-16T22:52:08.061121Z","steps":["trace[1681470969] 'read index received' (duration: 62.802µs)","trace[1681470969] 'applied index is now lower than readState.Index' (duration: 209.763324ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:08.061234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.937088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:08.06127Z","caller":"traceutil/trace.go:171","msg":"trace[274838175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"210.03019ms","start":"2026-04-16T22:52:07.851225Z","end":"2026-04-16T22:52:08.061255Z","steps":["trace[274838175] 'agreement among raft nodes before linearized reading' (duration: 209.952438ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:12.489608Z","caller":"traceutil/trace.go:171","msg":"trace[34801613] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"143.71096ms","start":"2026-04-16T22:52:12.345863Z","end":"2026-04-16T22:52:12.489574Z","steps":["trace[34801613] 'process raft request' (duration: 140.977813ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:12.813783Z","caller":"traceutil/trace.go:171","msg":"trace[816894918] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"155.405276ms","start":"2026-04-16T22:52:12.65834Z","end":"2026-04-16T22:52:12.813746Z","steps":["trace[816894918] 'process raft request' (duration: 109.73773ms)","trace[816894918] 'compare' (duration: 45.331288ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:13.584335Z","caller":"traceutil/trace.go:171","msg":"trace[1592701783] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"366.893212ms","start":"2026-04-16T22:52:13.217415Z","end":"2026-04-16T22:52:13.584308Z","steps":["trace[1592701783] 'process raft request' (duration: 366.720218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:13.584516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:13.217394Z","time spent":"367.039116ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:13.772184Z","caller":"traceutil/trace.go:171","msg":"trace[65693111] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:676; }","duration":"350.41422ms","start":"2026-04-16T22:52:13.421742Z","end":"2026-04-16T22:52:13.772156Z","steps":["trace[65693111] 'read index received' (duration: 162.363736ms)","trace[65693111] 'applied index is now lower than readState.Index' (duration: 188.049494ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:13.772288Z","caller":"traceutil/trace.go:171","msg":"trace[771538260] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"553.45735ms","start":"2026-04-16T22:52:13.218788Z","end":"2026-04-16T22:52:13.772246Z","steps":["trace[771538260] 'process raft request' (duration: 478.245173ms)","trace[771538260] 'compare' (duration: 74.995332ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:13.772404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.641015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bz9q6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-16T22:52:13.772444Z","caller":"traceutil/trace.go:171","msg":"trace[991555719] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bz9q6; range_end:; response_count:1; response_revision:653; }","duration":"350.746268ms","start":"2026-04-16T22:52:13.421687Z","end":"2026-04-16T22:52:13.772433Z","steps":["trace[991555719] 'agreement among raft nodes before linearized reading' (duration: 350.579684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:13.772479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:13.421564Z","time spent":"350.908002ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-bz9q6\" "} {"level":"warn","ts":"2026-04-16T22:52:13.772491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:13.218783Z","time spent":"553.615784ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:14.075473Z","caller":"traceutil/trace.go:171","msg":"trace[1466013546] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"276.765252ms","start":"2026-04-16T22:52:13.798683Z","end":"2026-04-16T22:52:14.075448Z","steps":["trace[1466013546] 'process raft request' (duration: 276.620608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:14.746472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.052156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T22:52:14.746583Z","caller":"traceutil/trace.go:171","msg":"trace[619671738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"213.22406ms","start":"2026-04-16T22:52:14.533344Z","end":"2026-04-16T22:52:14.746568Z","steps":["trace[619671738] 'range keys from in-memory index tree' (duration: 212.875379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:15.901262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050232125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:52:15.901339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.181787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T22:52:15.901388Z","caller":"traceutil/trace.go:171","msg":"trace[866013331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"1.05043209s","start":"2026-04-16T22:52:14.850931Z","end":"2026-04-16T22:52:15.901363Z","steps":["trace[866013331] 'range keys from in-memory index tree' (duration: 1.050150373s)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:15.901408Z","caller":"traceutil/trace.go:171","msg":"trace[1498410380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"312.30745ms","start":"2026-04-16T22:52:15.589087Z","end":"2026-04-16T22:52:15.901394Z","steps":["trace[1498410380] 'range keys from in-memory index tree' (duration: 312.021833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:15.901448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:15.58906Z","time spent":"312.381551ms","remote":"127.0.0.1:39932","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-16T22:52:15.901445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:14.850911Z","time spent":"1.050520333s","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:52:15.901784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.540719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:52:15.901847Z","caller":"traceutil/trace.go:171","msg":"trace[1771941114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"925.631431ms","start":"2026-04-16T22:52:14.976193Z","end":"2026-04-16T22:52:15.901824Z","steps":["trace[1771941114] 'range keys from in-memory index tree' (duration: 925.367225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:15.901897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:14.976168Z","time spent":"925.717102ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T22:52:15.90199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.721422ms","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-16T22:52:15.902034Z","caller":"traceutil/trace.go:171","msg":"trace[2037719253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"123.803094ms","start":"2026-04-16T22:52:15.778222Z","end":"2026-04-16T22:52:15.902025Z","steps":["trace[2037719253] 'range keys from in-memory index tree' (duration: 123.552759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:16.412161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537747388,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T22:52:16.625629Z","caller":"traceutil/trace.go:171","msg":"trace[2108390246] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"717.933947ms","start":"2026-04-16T22:52:15.907672Z","end":"2026-04-16T22:52:16.625606Z","steps":["trace[2108390246] 'process raft request' (duration: 717.804294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:16.625824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:15.907651Z","time spent":"718.097381ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:16.765231Z","caller":"traceutil/trace.go:171","msg":"trace[861318745] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"856.660867ms","start":"2026-04-16T22:52:15.908528Z","end":"2026-04-16T22:52:16.765188Z","steps":["trace[861318745] 'process raft request' (duration: 856.389951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:16.765971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:15.908515Z","time spent":"857.355164ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:16.765351Z","caller":"traceutil/trace.go:171","msg":"trace[1940129731] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:680; }","duration":"853.424916ms","start":"2026-04-16T22:52:15.911904Z","end":"2026-04-16T22:52:16.765329Z","steps":["trace[1940129731] 'read index received' (duration: 713.677042ms)","trace[1940129731] 'applied index is now lower than readState.Index' (duration: 139.746564ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:16.765501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.547699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:16.766274Z","caller":"traceutil/trace.go:171","msg":"trace[1173252774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"854.378841ms","start":"2026-04-16T22:52:15.911882Z","end":"2026-04-16T22:52:16.76626Z","steps":["trace[1173252774] 'agreement among raft nodes before linearized reading' (duration: 853.521379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:16.766347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:15.911874Z","time spent":"854.426532ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:52:16.765519Z","caller":"traceutil/trace.go:171","msg":"trace[1941218508] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"856.387629ms","start":"2026-04-16T22:52:15.909093Z","end":"2026-04-16T22:52:16.765481Z","steps":["trace[1941218508] 'process raft request' (duration: 856.00894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:16.766591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:15.909087Z","time spent":"857.370064ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:52:18.951359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.331543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:52:18.951521Z","caller":"traceutil/trace.go:171","msg":"trace[512528327] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"331.15218ms","start":"2026-04-16T22:52:18.620346Z","end":"2026-04-16T22:52:18.951498Z","steps":["trace[512528327] 'process raft request' (duration: 191.452067ms)","trace[512528327] 'compare' (duration: 139.17491ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:18.951614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:18.620327Z","time spent":"331.244932ms","remote":"127.0.0.1:40186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T22:52:18.955411Z","caller":"traceutil/trace.go:171","msg":"trace[1486117846] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:702; }","duration":"324.305292ms","start":"2026-04-16T22:52:18.631092Z","end":"2026-04-16T22:52:18.955397Z","steps":["trace[1486117846] 'read index received' (duration: 180.740665ms)","trace[1486117846] 'applied index is now lower than readState.Index' (duration: 143.563807ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:18.95549Z","caller":"traceutil/trace.go:171","msg":"trace[616367967] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"335.075015ms","start":"2026-04-16T22:52:18.620368Z","end":"2026-04-16T22:52:18.955443Z","steps":["trace[616367967] 'process raft request' (duration: 334.908731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:18.955727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.717132ms","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-16T22:52:18.955816Z","caller":"traceutil/trace.go:171","msg":"trace[1567922301] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"184.853086ms","start":"2026-04-16T22:52:18.770942Z","end":"2026-04-16T22:52:18.955795Z","steps":["trace[1567922301] 'agreement among raft nodes before linearized reading' (duration: 184.594279ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:18.955784Z","caller":"traceutil/trace.go:171","msg":"trace[204338954] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"191.071067ms","start":"2026-04-16T22:52:18.764416Z","end":"2026-04-16T22:52:18.955487Z","steps":["trace[204338954] 'process raft request' (duration: 190.949094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:18.955866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.286562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:52:18.955937Z","caller":"traceutil/trace.go:171","msg":"trace[982591710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:679; }","duration":"184.380014ms","start":"2026-04-16T22:52:18.771537Z","end":"2026-04-16T22:52:18.955917Z","steps":["trace[982591710] 'agreement among raft nodes before linearized reading' (duration: 184.21128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:18.955945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:18.620339Z","time spent":"335.325001ms","remote":"127.0.0.1:39732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:52:18.956245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.158263ms","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-16T22:52:18.956307Z","caller":"traceutil/trace.go:171","msg":"trace[1325011941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:679; }","duration":"325.250854ms","start":"2026-04-16T22:52:18.631042Z","end":"2026-04-16T22:52:18.956293Z","steps":["trace[1325011941] 'agreement among raft nodes before linearized reading' (duration: 324.442735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:18.956349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:18.631018Z","time spent":"325.319067ms","remote":"127.0.0.1:39932","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-16T22:52:19.511145Z","caller":"traceutil/trace.go:171","msg":"trace[629156001] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"116.094985ms","start":"2026-04-16T22:52:19.39501Z","end":"2026-04-16T22:52:19.511105Z","steps":["trace[629156001] 'process raft request' (duration: 115.510641ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:19.688737Z","caller":"traceutil/trace.go:171","msg":"trace[777121406] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"171.55611ms","start":"2026-04-16T22:52:19.517139Z","end":"2026-04-16T22:52:19.688695Z","steps":["trace[777121406] 'process raft request' (duration: 98.986417ms)","trace[777121406] 'compare' (duration: 72.382609ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:19.693955Z","caller":"traceutil/trace.go:171","msg":"trace[1522731779] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"128.584741ms","start":"2026-04-16T22:52:19.565351Z","end":"2026-04-16T22:52:19.693935Z","steps":["trace[1522731779] 'process raft request' (duration: 128.5313ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:19.694642Z","caller":"traceutil/trace.go:171","msg":"trace[984888144] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"171.482328ms","start":"2026-04-16T22:52:19.523114Z","end":"2026-04-16T22:52:19.694596Z","steps":["trace[984888144] 'process raft request' (duration: 170.625908ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:19.823396Z","caller":"traceutil/trace.go:171","msg":"trace[1581461367] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"119.027128ms","start":"2026-04-16T22:52:19.704343Z","end":"2026-04-16T22:52:19.82337Z","steps":["trace[1581461367] 'process raft request' (duration: 118.71453ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:19.853622Z","caller":"traceutil/trace.go:171","msg":"trace[484337077] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"146.070429ms","start":"2026-04-16T22:52:19.707515Z","end":"2026-04-16T22:52:19.853585Z","steps":["trace[484337077] 'process raft request' (duration: 145.661898ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:20.00939Z","caller":"traceutil/trace.go:171","msg":"trace[115901130] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"139.086287ms","start":"2026-04-16T22:52:19.870276Z","end":"2026-04-16T22:52:20.009362Z","steps":["trace[115901130] 'process raft request' (duration: 139.007615ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:20.010006Z","caller":"traceutil/trace.go:171","msg":"trace[1869959868] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"145.642618ms","start":"2026-04-16T22:52:19.864304Z","end":"2026-04-16T22:52:20.009946Z","steps":["trace[1869959868] 'process raft request' (duration: 144.90359ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:20.01023Z","caller":"traceutil/trace.go:171","msg":"trace[1995636637] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"146.253683ms","start":"2026-04-16T22:52:19.863963Z","end":"2026-04-16T22:52:20.010217Z","steps":["trace[1995636637] 'process raft request' (duration: 144.326396ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:20.141967Z","caller":"traceutil/trace.go:171","msg":"trace[1974189308] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"122.355809ms","start":"2026-04-16T22:52:20.01958Z","end":"2026-04-16T22:52:20.141936Z","steps":["trace[1974189308] 'process raft request' (duration: 122.154873ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:20.142287Z","caller":"traceutil/trace.go:171","msg":"trace[1746155068] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"117.982632ms","start":"2026-04-16T22:52:20.02427Z","end":"2026-04-16T22:52:20.142253Z","steps":["trace[1746155068] 'process raft request' (duration: 117.615424ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:20.14244Z","caller":"traceutil/trace.go:171","msg":"trace[874074527] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"118.000792ms","start":"2026-04-16T22:52:20.02441Z","end":"2026-04-16T22:52:20.142411Z","steps":["trace[874074527] 'process raft request' (duration: 117.776907ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:20.192978Z","caller":"traceutil/trace.go:171","msg":"trace[1771989841] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"123.66006ms","start":"2026-04-16T22:52:20.069296Z","end":"2026-04-16T22:52:20.192956Z","steps":["trace[1771989841] 'process raft request' (duration: 123.455055ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:22.612051Z","caller":"traceutil/trace.go:171","msg":"trace[326770255] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"122.032541ms","start":"2026-04-16T22:52:22.489992Z","end":"2026-04-16T22:52:22.612024Z","steps":["trace[326770255] 'process raft request' (duration: 121.834656ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:22.645927Z","caller":"traceutil/trace.go:171","msg":"trace[1177372339] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"134.429514ms","start":"2026-04-16T22:52:22.51146Z","end":"2026-04-16T22:52:22.64589Z","steps":["trace[1177372339] 'process raft request' (duration: 134.102396ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:31.421055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.473401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:31.421275Z","caller":"traceutil/trace.go:171","msg":"trace[757023269] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"571.758957ms","start":"2026-04-16T22:52:30.849486Z","end":"2026-04-16T22:52:31.421245Z","steps":["trace[757023269] 'range keys from in-memory index tree' (duration: 571.353257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:31.421365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:30.849454Z","time spent":"571.896641ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:52:31.421489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.389329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T22:52:31.421654Z","caller":"traceutil/trace.go:171","msg":"trace[1818819996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:799; }","duration":"314.632555ms","start":"2026-04-16T22:52:31.106992Z","end":"2026-04-16T22:52:31.421624Z","steps":["trace[1818819996] 'range keys from in-memory index tree' (duration: 314.108873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:31.421731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:31.106965Z","time spent":"314.748518ms","remote":"127.0.0.1:39932","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-16T22:52:31.421819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.931355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T22:52:31.421907Z","caller":"traceutil/trace.go:171","msg":"trace[1077088241] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:799; }","duration":"311.08769ms","start":"2026-04-16T22:52:31.110799Z","end":"2026-04-16T22:52:31.421887Z","steps":["trace[1077088241] 'range keys from in-memory index tree' (duration: 310.70424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:31.421949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:31.110788Z","time spent":"311.151971ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T22:52:31.421987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.185375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-16T22:52:31.42201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.453936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:52:31.422056Z","caller":"traceutil/trace.go:171","msg":"trace[120079162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"312.292658ms","start":"2026-04-16T22:52:31.109746Z","end":"2026-04-16T22:52:31.422039Z","steps":["trace[120079162] 'range keys from in-memory index tree' (duration: 312.068703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:31.422126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:31.109737Z","time spent":"312.37598ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T22:52:31.422172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.811465ms","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-16T22:52:31.422196Z","caller":"traceutil/trace.go:171","msg":"trace[441526443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"365.886387ms","start":"2026-04-16T22:52:31.0563Z","end":"2026-04-16T22:52:31.422186Z","steps":["trace[441526443] 'range keys from in-memory index tree' (duration: 365.5933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:31.42222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:31.056262Z","time spent":"365.953198ms","remote":"127.0.0.1:39932","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-16T22:52:31.42215Z","caller":"traceutil/trace.go:171","msg":"trace[2034328384] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:799; }","duration":"157.59783ms","start":"2026-04-16T22:52:31.2645Z","end":"2026-04-16T22:52:31.422097Z","steps":["trace[2034328384] 'count revisions from in-memory index tree' (duration: 157.238141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.057895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488006909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:52:36.057989Z","caller":"traceutil/trace.go:171","msg":"trace[1736591685] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:818; }","duration":"1.488350367s","start":"2026-04-16T22:52:34.569619Z","end":"2026-04-16T22:52:36.057969Z","steps":["trace[1736591685] 'count revisions from in-memory index tree' (duration: 1.487895786s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.058048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:34.569586Z","time spent":"1.488449499s","remote":"127.0.0.1:39906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:52:36.058123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.497024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T22:52:36.058203Z","caller":"traceutil/trace.go:171","msg":"trace[1163714397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:818; }","duration":"575.138059ms","start":"2026-04-16T22:52:35.483047Z","end":"2026-04-16T22:52:36.058185Z","steps":["trace[1163714397] 'range keys from in-memory index tree' (duration: 574.288078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.058252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:35.483028Z","time spent":"575.21555ms","remote":"127.0.0.1:39932","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-16T22:52:36.058295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.586326ms","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-16T22:52:36.058412Z","caller":"traceutil/trace.go:171","msg":"trace[962432267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:818; }","duration":"574.728369ms","start":"2026-04-16T22:52:35.483665Z","end":"2026-04-16T22:52:36.058394Z","steps":["trace[962432267] 'range keys from in-memory index tree' (duration: 574.413441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.058573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.501652272s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rs2f8\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-16T22:52:36.058626Z","caller":"traceutil/trace.go:171","msg":"trace[1399732971] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rs2f8; range_end:; response_count:1; response_revision:818; }","duration":"1.501743954s","start":"2026-04-16T22:52:34.556868Z","end":"2026-04-16T22:52:36.058612Z","steps":["trace[1399732971] 'range keys from in-memory index tree' (duration: 1.50152481s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.05866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:34.556842Z","time spent":"1.501810896s","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rs2f8\" "} {"level":"warn","ts":"2026-04-16T22:52:36.058759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.654488ms","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-16T22:52:36.058813Z","caller":"traceutil/trace.go:171","msg":"trace[1574749846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:818; }","duration":"608.73566ms","start":"2026-04-16T22:52:35.450066Z","end":"2026-04-16T22:52:36.058802Z","steps":["trace[1574749846] 'range keys from in-memory index tree' (duration: 608.414753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.058844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:35.450043Z","time spent":"608.792552ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T22:52:36.05895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.254684808s","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-16T22:52:36.05903Z","caller":"traceutil/trace.go:171","msg":"trace[712313363] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:818; }","duration":"1.254835603s","start":"2026-04-16T22:52:34.804181Z","end":"2026-04-16T22:52:36.059017Z","steps":["trace[712313363] 'range keys from in-memory index tree' (duration: 1.254530405s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.059104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:34.804155Z","time spent":"1.254935736s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T22:52:36.059032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.800235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-16T22:52:36.059196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207797604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:36.059207Z","caller":"traceutil/trace.go:171","msg":"trace[302245263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:818; }","duration":"576.01358ms","start":"2026-04-16T22:52:35.483181Z","end":"2026-04-16T22:52:36.059194Z","steps":["trace[302245263] 'range keys from in-memory index tree' (duration: 575.636871ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:36.059247Z","caller":"traceutil/trace.go:171","msg":"trace[1907073965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"1.207889127s","start":"2026-04-16T22:52:34.851345Z","end":"2026-04-16T22:52:36.059235Z","steps":["trace[1907073965] 'range keys from in-memory index tree' (duration: 1.207684192s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.059256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:35.483146Z","time spent":"576.096863ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T22:52:36.059282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:34.851321Z","time spent":"1.207950749s","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:52:36.058481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:35.483659Z","time spent":"574.805221ms","remote":"127.0.0.1:39932","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-16T22:52:36.263667Z","caller":"traceutil/trace.go:171","msg":"trace[141210889] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"198.780855ms","start":"2026-04-16T22:52:36.064859Z","end":"2026-04-16T22:52:36.26364Z","steps":["trace[141210889] 'process raft request' (duration: 198.651681ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:36.370913Z","caller":"traceutil/trace.go:171","msg":"trace[1911962054] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:848; }","duration":"302.044968ms","start":"2026-04-16T22:52:36.068844Z","end":"2026-04-16T22:52:36.370889Z","steps":["trace[1911962054] 'read index received' (duration: 194.673095ms)","trace[1911962054] 'applied index is now lower than readState.Index' (duration: 107.371043ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:52:36.371289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.407597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:36.371302Z","caller":"traceutil/trace.go:171","msg":"trace[234112100] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"305.088123ms","start":"2026-04-16T22:52:36.066171Z","end":"2026-04-16T22:52:36.371259Z","steps":["trace[234112100] 'process raft request' (duration: 304.606521ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:36.371382Z","caller":"traceutil/trace.go:171","msg":"trace[914542392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"302.551641ms","start":"2026-04-16T22:52:36.068819Z","end":"2026-04-16T22:52:36.371371Z","steps":["trace[914542392] 'agreement among raft nodes before linearized reading' (duration: 302.400877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.371523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:36.068801Z","time spent":"302.711294ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:52:36.371561Z","caller":"traceutil/trace.go:171","msg":"trace[423919787] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"306.528497ms","start":"2026-04-16T22:52:36.065021Z","end":"2026-04-16T22:52:36.37155Z","steps":["trace[423919787] 'process raft request' (duration: 304.21704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.371634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:36.064987Z","time spent":"306.616039ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:36.37162Z","caller":"traceutil/trace.go:171","msg":"trace[1368000444] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"306.181349ms","start":"2026-04-16T22:52:36.065409Z","end":"2026-04-16T22:52:36.37159Z","steps":["trace[1368000444] 'process raft request' (duration: 305.318678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.371791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:36.065398Z","time spent":"306.304212ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:52:36.37151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:36.06616Z","time spent":"305.249967ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:36.372075Z","caller":"traceutil/trace.go:171","msg":"trace[768905632] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"305.82511ms","start":"2026-04-16T22:52:36.06624Z","end":"2026-04-16T22:52:36.372065Z","steps":["trace[768905632] 'process raft request' (duration: 304.56449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.372123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:36.066224Z","time spent":"305.875851ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:36.372245Z","caller":"traceutil/trace.go:171","msg":"trace[1178283621] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"303.291878ms","start":"2026-04-16T22:52:36.068938Z","end":"2026-04-16T22:52:36.37223Z","steps":["trace[1178283621] 'process raft request' (duration: 301.891365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:36.372388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:52:36.068926Z","time spent":"303.37202ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:52:42.545812Z","caller":"traceutil/trace.go:171","msg":"trace[1448375307] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"137.151237ms","start":"2026-04-16T22:52:42.408645Z","end":"2026-04-16T22:52:42.545796Z","steps":["trace[1448375307] 'process raft request' (duration: 136.87606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:42.546107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.50454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T22:52:42.545716Z","caller":"traceutil/trace.go:171","msg":"trace[288787923] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"120.140061ms","start":"2026-04-16T22:52:42.425547Z","end":"2026-04-16T22:52:42.545687Z","steps":["trace[288787923] 'read index received' (duration: 119.931666ms)","trace[288787923] 'applied index is now lower than readState.Index' (duration: 206.955µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:42.546175Z","caller":"traceutil/trace.go:171","msg":"trace[945742439] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"120.661004ms","start":"2026-04-16T22:52:42.425498Z","end":"2026-04-16T22:52:42.546159Z","steps":["trace[945742439] 'agreement among raft nodes before linearized reading' (duration: 120.423098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:42.546488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.48908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T22:52:42.54651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.348897ms","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-16T22:52:42.546537Z","caller":"traceutil/trace.go:171","msg":"trace[1724467535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:886; }","duration":"120.542781ms","start":"2026-04-16T22:52:42.425983Z","end":"2026-04-16T22:52:42.546526Z","steps":["trace[1724467535] 'agreement among raft nodes before linearized reading' (duration: 120.399858ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:42.546546Z","caller":"traceutil/trace.go:171","msg":"trace[88965261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"120.408428ms","start":"2026-04-16T22:52:42.426127Z","end":"2026-04-16T22:52:42.546536Z","steps":["trace[88965261] 'agreement among raft nodes before linearized reading' (duration: 120.261284ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:44.364697Z","caller":"traceutil/trace.go:171","msg":"trace[939344890] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"178.988447ms","start":"2026-04-16T22:52:44.185679Z","end":"2026-04-16T22:52:44.364667Z","steps":["trace[939344890] 'process raft request' (duration: 178.803082ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:44.917199Z","caller":"traceutil/trace.go:171","msg":"trace[223289840] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:937; }","duration":"283.859925ms","start":"2026-04-16T22:52:44.633302Z","end":"2026-04-16T22:52:44.917162Z","steps":["trace[223289840] 'read index received' (duration: 34.187824ms)","trace[223289840] 'applied index is now lower than readState.Index' (duration: 249.670761ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:44.917296Z","caller":"traceutil/trace.go:171","msg":"trace[1255755558] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"287.848803ms","start":"2026-04-16T22:52:44.629401Z","end":"2026-04-16T22:52:44.91725Z","steps":["trace[1255755558] 'process raft request' (duration: 222.57127ms)","trace[1255755558] 'compare' (duration: 64.964435ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:52:44.917385Z","caller":"traceutil/trace.go:171","msg":"trace[138023056] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"287.708769ms","start":"2026-04-16T22:52:44.629658Z","end":"2026-04-16T22:52:44.917367Z","steps":["trace[138023056] 'process raft request' (duration: 287.437962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:44.917557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.132693ms","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-16T22:52:44.917682Z","caller":"traceutil/trace.go:171","msg":"trace[1327536052] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:907; }","duration":"259.271546ms","start":"2026-04-16T22:52:44.658387Z","end":"2026-04-16T22:52:44.917659Z","steps":["trace[1327536052] 'agreement among raft nodes before linearized reading' (duration: 259.057791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:44.917912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.608089ms","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-16T22:52:44.917954Z","caller":"traceutil/trace.go:171","msg":"trace[1671883961] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:907; }","duration":"126.696951ms","start":"2026-04-16T22:52:44.791248Z","end":"2026-04-16T22:52:44.917945Z","steps":["trace[1671883961] 'agreement among raft nodes before linearized reading' (duration: 126.619749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:52:44.917966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.636215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:52:44.918041Z","caller":"traceutil/trace.go:171","msg":"trace[443177662] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:907; }","duration":"284.784249ms","start":"2026-04-16T22:52:44.633238Z","end":"2026-04-16T22:52:44.918022Z","steps":["trace[443177662] 'agreement among raft nodes before linearized reading' (duration: 284.661946ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:45.322695Z","caller":"traceutil/trace.go:171","msg":"trace[377100426] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"128.24334ms","start":"2026-04-16T22:52:45.194418Z","end":"2026-04-16T22:52:45.322661Z","steps":["trace[377100426] 'process raft request' (duration: 118.797149ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:45.334173Z","caller":"traceutil/trace.go:171","msg":"trace[1902759651] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"139.271877ms","start":"2026-04-16T22:52:45.194861Z","end":"2026-04-16T22:52:45.334133Z","steps":["trace[1902759651] 'process raft request' (duration: 139.096403ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:52:58.813198Z","caller":"traceutil/trace.go:171","msg":"trace[586751022] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"165.909347ms","start":"2026-04-16T22:52:58.647269Z","end":"2026-04-16T22:52:58.813179Z","steps":["trace[586751022] 'process raft request' (duration: 165.759554ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:01.573711Z","caller":"traceutil/trace.go:171","msg":"trace[426734917] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"162.460814ms","start":"2026-04-16T22:53:01.41122Z","end":"2026-04-16T22:53:01.573681Z","steps":["trace[426734917] 'process raft request' (duration: 162.28268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:02.943837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.820396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:53:02.943939Z","caller":"traceutil/trace.go:171","msg":"trace[1263478023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"113.98929ms","start":"2026-04-16T22:53:02.829928Z","end":"2026-04-16T22:53:02.943917Z","steps":["trace[1263478023] 'range keys from in-memory index tree' (duration: 113.6132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:03.201628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.745387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:53:03.201747Z","caller":"traceutil/trace.go:171","msg":"trace[576324324] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"163.864608ms","start":"2026-04-16T22:53:03.037864Z","end":"2026-04-16T22:53:03.201728Z","steps":["trace[576324324] 'read index received' (duration: 44.902316ms)","trace[576324324] 'applied index is now lower than readState.Index' (duration: 118.960722ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:53:03.201832Z","caller":"traceutil/trace.go:171","msg":"trace[1671237400] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"253.064315ms","start":"2026-04-16T22:53:02.948756Z","end":"2026-04-16T22:53:03.201821Z","steps":["trace[1671237400] 'process raft request' (duration: 134.031831ms)","trace[1671237400] 'compare' (duration: 118.610103ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:53:03.202099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.231874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-16T22:53:03.202196Z","caller":"traceutil/trace.go:171","msg":"trace[2125406556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"136.404679ms","start":"2026-04-16T22:53:03.065773Z","end":"2026-04-16T22:53:03.202177Z","steps":["trace[2125406556] 'agreement among raft nodes before linearized reading' (duration: 136.102942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:03.202277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.405082ms","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-16T22:53:03.202392Z","caller":"traceutil/trace.go:171","msg":"trace[175337871] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"164.584766ms","start":"2026-04-16T22:53:03.037789Z","end":"2026-04-16T22:53:03.202374Z","steps":["trace[175337871] 'agreement among raft nodes before linearized reading' (duration: 164.3433ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:03.37541Z","caller":"traceutil/trace.go:171","msg":"trace[477519281] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"166.348178ms","start":"2026-04-16T22:53:03.20902Z","end":"2026-04-16T22:53:03.375368Z","steps":["trace[477519281] 'process raft request' (duration: 166.275617ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:03.375428Z","caller":"traceutil/trace.go:171","msg":"trace[1859682711] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"167.218259ms","start":"2026-04-16T22:53:03.208143Z","end":"2026-04-16T22:53:03.375361Z","steps":["trace[1859682711] 'process raft request' (duration: 107.348259ms)","trace[1859682711] 'compare' (duration: 59.528312ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:53:05.497366Z","caller":"traceutil/trace.go:171","msg":"trace[462288764] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"110.947837ms","start":"2026-04-16T22:53:05.386359Z","end":"2026-04-16T22:53:05.497307Z","steps":["trace[462288764] 'process raft request' (duration: 110.732262ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:11.682391Z","caller":"traceutil/trace.go:171","msg":"trace[445645247] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"130.72762ms","start":"2026-04-16T22:53:11.551577Z","end":"2026-04-16T22:53:11.682305Z","steps":["trace[445645247] 'process raft request' (duration: 130.465194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:11.682586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.422823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T22:53:11.682674Z","caller":"traceutil/trace.go:171","msg":"trace[1414866114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"130.526755ms","start":"2026-04-16T22:53:11.552127Z","end":"2026-04-16T22:53:11.682654Z","steps":["trace[1414866114] 'agreement among raft nodes before linearized reading' (duration: 130.233768ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:11.683053Z","caller":"traceutil/trace.go:171","msg":"trace[1422330169] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1070; }","duration":"130.132325ms","start":"2026-04-16T22:53:11.552132Z","end":"2026-04-16T22:53:11.682265Z","steps":["trace[1422330169] 'read index received' (duration: 129.869129ms)","trace[1422330169] 'applied index is now lower than readState.Index' (duration: 262.096µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:53:11.981154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.694074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:53:11.981329Z","caller":"traceutil/trace.go:171","msg":"trace[2023812543] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1071; }","duration":"252.284126ms","start":"2026-04-16T22:53:11.729027Z","end":"2026-04-16T22:53:11.981312Z","steps":["trace[2023812543] 'read index received' (duration: 238.94835ms)","trace[2023812543] 'applied index is now lower than readState.Index' (duration: 13.334676ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:53:11.98139Z","caller":"traceutil/trace.go:171","msg":"trace[699262021] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"341.960503ms","start":"2026-04-16T22:53:11.639366Z","end":"2026-04-16T22:53:11.981326Z","steps":["trace[699262021] 'process raft request' (duration: 79.989622ms)","trace[699262021] 'compare' (duration: 261.505329ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:53:11.981424Z","caller":"traceutil/trace.go:171","msg":"trace[582662405] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"293.8733ms","start":"2026-04-16T22:53:11.687516Z","end":"2026-04-16T22:53:11.981389Z","steps":["trace[582662405] 'process raft request' (duration: 293.751346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:11.981537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.999533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:53:11.98159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:11.639343Z","time spent":"342.130498ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:53:11.981621Z","caller":"traceutil/trace.go:171","msg":"trace[1694463778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"130.142756ms","start":"2026-04-16T22:53:11.851461Z","end":"2026-04-16T22:53:11.981604Z","steps":["trace[1694463778] 'agreement among raft nodes before linearized reading' (duration: 130.006853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:11.981443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.42996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:53:11.981709Z","caller":"traceutil/trace.go:171","msg":"trace[1142951750] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1034; }","duration":"252.733987ms","start":"2026-04-16T22:53:11.728963Z","end":"2026-04-16T22:53:11.981697Z","steps":["trace[1142951750] 'agreement among raft nodes before linearized reading' (duration: 252.43759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:12.545155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.642231ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-16T22:53:12.545279Z","caller":"traceutil/trace.go:171","msg":"trace[1846865408] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"557.922762ms","start":"2026-04-16T22:53:11.987334Z","end":"2026-04-16T22:53:12.545257Z","steps":["trace[1846865408] 'read index received' (duration: 235.099196ms)","trace[1846865408] 'applied index is now lower than readState.Index' (duration: 322.822016ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:53:12.54537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.032565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:53:12.545399Z","caller":"traceutil/trace.go:171","msg":"trace[1689625238] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1034; }","duration":"558.128197ms","start":"2026-04-16T22:53:11.98726Z","end":"2026-04-16T22:53:12.545388Z","steps":["trace[1689625238] 'agreement among raft nodes before linearized reading' (duration: 558.050765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:12.545437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:11.987234Z","time spent":"558.192649ms","remote":"127.0.0.1:34766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:53:12.545393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:11.982758Z","time spent":"562.625797ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-16T22:53:22.864588Z","caller":"traceutil/trace.go:171","msg":"trace[1963602903] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"228.405321ms","start":"2026-04-16T22:53:22.636139Z","end":"2026-04-16T22:53:22.864544Z","steps":["trace[1963602903] 'process raft request' (duration: 228.120844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:38.512347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.758995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T22:53:38.512532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.613804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:53:38.512595Z","caller":"traceutil/trace.go:171","msg":"trace[2010146308] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1121; }","duration":"109.722747ms","start":"2026-04-16T22:53:38.402858Z","end":"2026-04-16T22:53:38.512581Z","steps":["trace[2010146308] 'count revisions from in-memory index tree' (duration: 109.526842ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:38.512458Z","caller":"traceutil/trace.go:171","msg":"trace[819750274] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1121; }","duration":"181.937349ms","start":"2026-04-16T22:53:38.330502Z","end":"2026-04-16T22:53:38.512439Z","steps":["trace[819750274] 'count revisions from in-memory index tree' (duration: 181.651412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.544324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10190649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:53:40.544641Z","caller":"traceutil/trace.go:171","msg":"trace[509462209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1123; }","duration":"1.10232336s","start":"2026-04-16T22:53:39.442288Z","end":"2026-04-16T22:53:40.544611Z","steps":["trace[509462209] 'range keys from in-memory index tree' (duration: 1.101643864s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.544778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:39.442264Z","time spent":"1.102492885s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T22:53:40.545062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.296741ms","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-16T22:53:40.545133Z","caller":"traceutil/trace.go:171","msg":"trace[1929489717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1123; }","duration":"672.454994ms","start":"2026-04-16T22:53:39.872659Z","end":"2026-04-16T22:53:40.545114Z","steps":["trace[1929489717] 'range keys from in-memory index tree' (duration: 672.085865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.545214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:39.872625Z","time spent":"672.575977ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T22:53:40.544809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.139624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:53:40.545487Z","caller":"traceutil/trace.go:171","msg":"trace[1443986576] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1123; }","duration":"819.856131ms","start":"2026-04-16T22:53:39.725612Z","end":"2026-04-16T22:53:40.545469Z","steps":["trace[1443986576] 'count revisions from in-memory index tree' (duration: 819.056391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.545593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:39.725589Z","time spent":"819.980863ms","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:53:40.544481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.689665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T22:53:40.544372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.169925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:53:40.545758Z","caller":"traceutil/trace.go:171","msg":"trace[900697204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"695.606738ms","start":"2026-04-16T22:53:39.850135Z","end":"2026-04-16T22:53:40.545741Z","steps":["trace[900697204] 'range keys from in-memory index tree' (duration: 694.087142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.54593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:39.850117Z","time spent":"695.795063ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:53:40.54617Z","caller":"traceutil/trace.go:171","msg":"trace[1599121759] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1123; }","duration":"461.405465ms","start":"2026-04-16T22:53:40.084741Z","end":"2026-04-16T22:53:40.546146Z","steps":["trace[1599121759] 'count revisions from in-memory index tree' (duration: 459.551881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.546252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:40.084718Z","time spent":"461.511248ms","remote":"127.0.0.1:40024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:53:40.65815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537748418,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T22:53:40.866008Z","caller":"traceutil/trace.go:171","msg":"trace[1649769186] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"749.850562ms","start":"2026-04-16T22:53:40.116114Z","end":"2026-04-16T22:53:40.865964Z","steps":["trace[1649769186] 'process raft request' (duration: 749.628827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.866308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:40.116084Z","time spent":"750.113209ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:53:40.890571Z","caller":"traceutil/trace.go:171","msg":"trace[1508628259] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"338.990271ms","start":"2026-04-16T22:53:40.551556Z","end":"2026-04-16T22:53:40.890547Z","steps":["trace[1508628259] 'process raft request' (duration: 338.798726ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:40.890569Z","caller":"traceutil/trace.go:171","msg":"trace[1234638932] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"340.343153ms","start":"2026-04-16T22:53:40.550182Z","end":"2026-04-16T22:53:40.890525Z","steps":["trace[1234638932] 'process raft request' (duration: 339.991265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.890747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.784636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-16T22:53:40.890974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:40.551544Z","time spent":"339.096013ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:53:40.890436Z","caller":"traceutil/trace.go:171","msg":"trace[912520724] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1168; }","duration":"732.452128ms","start":"2026-04-16T22:53:40.157958Z","end":"2026-04-16T22:53:40.89041Z","steps":["trace[912520724] 'read index received' (duration: 707.844247ms)","trace[912520724] 'applied index is now lower than readState.Index' (duration: 24.606711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:53:40.891234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:40.550154Z","time spent":"340.519457ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:53:40.891379Z","caller":"traceutil/trace.go:171","msg":"trace[637259422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1126; }","duration":"732.880608ms","start":"2026-04-16T22:53:40.157898Z","end":"2026-04-16T22:53:40.890778Z","steps":["trace[637259422] 'agreement among raft nodes before linearized reading' (duration: 732.744085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.891277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.06117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T22:53:40.891416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:40.157873Z","time spent":"733.532934ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-16T22:53:40.891446Z","caller":"traceutil/trace.go:171","msg":"trace[1780511374] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1126; }","duration":"518.260454ms","start":"2026-04-16T22:53:40.37316Z","end":"2026-04-16T22:53:40.89142Z","steps":["trace[1780511374] 'agreement among raft nodes before linearized reading' (duration: 518.051839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:40.891519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:40.373141Z","time spent":"518.356237ms","remote":"127.0.0.1:40162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-16T22:53:41.061942Z","caller":"traceutil/trace.go:171","msg":"trace[6448821] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"166.001109ms","start":"2026-04-16T22:53:40.895908Z","end":"2026-04-16T22:53:41.061909Z","steps":["trace[6448821] 'process raft request' (duration: 165.764014ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.396156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537748438,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T22:53:43.470733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.699346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:53:43.470914Z","caller":"traceutil/trace.go:171","msg":"trace[2126645126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1130; }","duration":"621.883101ms","start":"2026-04-16T22:53:42.848969Z","end":"2026-04-16T22:53:43.470852Z","steps":["trace[2126645126] 'range keys from in-memory index tree' (duration: 621.625394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.470968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:42.848947Z","time spent":"622.011403ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:53:43.517733Z","caller":"traceutil/trace.go:171","msg":"trace[1011848822] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"645.299741ms","start":"2026-04-16T22:53:42.872396Z","end":"2026-04-16T22:53:43.517695Z","steps":["trace[1011848822] 'process raft request' (duration: 645.072546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.517994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:42.872367Z","time spent":"645.485176ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:53:43.831968Z","caller":"traceutil/trace.go:171","msg":"trace[1919926751] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1176; }","duration":"936.631559ms","start":"2026-04-16T22:53:42.895309Z","end":"2026-04-16T22:53:43.831941Z","steps":["trace[1919926751] 'read index received' (duration: 622.188977ms)","trace[1919926751] 'applied index is now lower than readState.Index' (duration: 314.441622ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:53:43.832008Z","caller":"traceutil/trace.go:171","msg":"trace[1097494600] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"854.246486ms","start":"2026-04-16T22:53:42.97772Z","end":"2026-04-16T22:53:43.831967Z","steps":["trace[1097494600] 'process raft request' (duration: 805.170338ms)","trace[1097494600] 'compare' (duration: 48.940695ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:53:43.83221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:42.97769Z","time spent":"854.416811ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:53:43.832277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.895125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:53:43.832331Z","caller":"traceutil/trace.go:171","msg":"trace[297886024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1132; }","duration":"936.994388ms","start":"2026-04-16T22:53:42.895322Z","end":"2026-04-16T22:53:43.832316Z","steps":["trace[297886024] 'agreement among raft nodes before linearized reading' (duration: 936.801403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.832383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:42.895294Z","time spent":"937.0783ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T22:53:43.832583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.292511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T22:53:43.832748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.754955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-16T22:53:43.832768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.984457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:53:43.832812Z","caller":"traceutil/trace.go:171","msg":"trace[564827436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1132; }","duration":"765.866647ms","start":"2026-04-16T22:53:43.066931Z","end":"2026-04-16T22:53:43.832798Z","steps":["trace[564827436] 'agreement among raft nodes before linearized reading' (duration: 765.633272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.832617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.056764ms","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-16T22:53:43.83283Z","caller":"traceutil/trace.go:171","msg":"trace[552621070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1132; }","duration":"361.08283ms","start":"2026-04-16T22:53:43.471731Z","end":"2026-04-16T22:53:43.832814Z","steps":["trace[552621070] 'agreement among raft nodes before linearized reading' (duration: 360.985207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.832855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:43.066918Z","time spent":"765.927949ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T22:53:43.832903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:43.471709Z","time spent":"361.181822ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:53:43.832874Z","caller":"traceutil/trace.go:171","msg":"trace[1311267660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1132; }","duration":"748.34509ms","start":"2026-04-16T22:53:43.084513Z","end":"2026-04-16T22:53:43.832858Z","steps":["trace[1311267660] 'agreement among raft nodes before linearized reading' (duration: 747.998141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.832921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.608512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-16T22:53:43.832966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:43.084499Z","time spent":"748.446693ms","remote":"127.0.0.1:39932","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-16T22:53:43.832967Z","caller":"traceutil/trace.go:171","msg":"trace[1098488800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1132; }","duration":"937.678284ms","start":"2026-04-16T22:53:42.895275Z","end":"2026-04-16T22:53:43.832953Z","steps":["trace[1098488800] 'agreement among raft nodes before linearized reading' (duration: 936.822883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.833024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:42.895207Z","time spent":"937.805908ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T22:53:43.833084Z","caller":"traceutil/trace.go:171","msg":"trace[1397056158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1132; }","duration":"378.470915ms","start":"2026-04-16T22:53:43.454208Z","end":"2026-04-16T22:53:43.832679Z","steps":["trace[1397056158] 'agreement among raft nodes before linearized reading' (duration: 378.091376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:43.833162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:43.454185Z","time spent":"378.959179ms","remote":"127.0.0.1:39932","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-16T22:53:44.004086Z","caller":"traceutil/trace.go:171","msg":"trace[1742085799] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"165.472582ms","start":"2026-04-16T22:53:43.83859Z","end":"2026-04-16T22:53:44.004063Z","steps":["trace[1742085799] 'process raft request' (duration: 165.438691ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:44.004096Z","caller":"traceutil/trace.go:171","msg":"trace[406769466] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"166.487838ms","start":"2026-04-16T22:53:43.837571Z","end":"2026-04-16T22:53:44.004059Z","steps":["trace[406769466] 'process raft request' (duration: 110.398548ms)","trace[406769466] 'compare' (duration: 55.883505ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:53:44.004375Z","caller":"traceutil/trace.go:171","msg":"trace[1191795842] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"166.455028ms","start":"2026-04-16T22:53:43.837911Z","end":"2026-04-16T22:53:44.004366Z","steps":["trace[1191795842] 'process raft request' (duration: 166.064918ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:44.004341Z","caller":"traceutil/trace.go:171","msg":"trace[1601252406] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"166.025606ms","start":"2026-04-16T22:53:43.838292Z","end":"2026-04-16T22:53:44.004317Z","steps":["trace[1601252406] 'process raft request' (duration: 165.715519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:46.501395Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537748451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T22:53:47.001685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537748451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T22:53:47.313101Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.392711581s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T22:53:47.313784Z","caller":"traceutil/trace.go:171","msg":"trace[1178053847] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"1.31246053s","start":"2026-04-16T22:53:46.001299Z","end":"2026-04-16T22:53:47.313759Z","steps":["trace[1178053847] 'read index received' (duration: 1.31204931s)","trace[1178053847] 'applied index is now lower than readState.Index' (duration: 410.13µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:53:47.313834Z","caller":"traceutil/trace.go:171","msg":"trace[1886275106] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"1.39351653s","start":"2026-04-16T22:53:45.920283Z","end":"2026-04-16T22:53:47.3138Z","steps":["trace[1886275106] 'process raft request' (duration: 1.393054658s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:47.313984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:45.920267Z","time spent":"1.393633893s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:53:47.314054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.312728057s","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-16T22:53:47.31413Z","caller":"traceutil/trace.go:171","msg":"trace[1636150227] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1139; }","duration":"1.312831199s","start":"2026-04-16T22:53:46.001282Z","end":"2026-04-16T22:53:47.314113Z","steps":["trace[1636150227] 'agreement among raft nodes before linearized reading' (duration: 1.312623784s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:47.314175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:46.001216Z","time spent":"1.312949442s","remote":"127.0.0.1:40008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:53:47.656208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.546566ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T22:53:47.65648Z","caller":"traceutil/trace.go:171","msg":"trace[1534234374] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1185; }","duration":"342.573888ms","start":"2026-04-16T22:53:47.313854Z","end":"2026-04-16T22:53:47.656428Z","steps":["trace[1534234374] 'read index received' (duration: 119.773516ms)","trace[1534234374] 'applied index is now lower than readState.Index' (duration: 222.795302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:53:47.656599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.751447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:53:47.656683Z","caller":"traceutil/trace.go:171","msg":"trace[1435771649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1140; }","duration":"806.88215ms","start":"2026-04-16T22:53:46.84978Z","end":"2026-04-16T22:53:47.656662Z","steps":["trace[1435771649] 'agreement among raft nodes before linearized reading' (duration: 806.740447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:47.656724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:46.849759Z","time spent":"806.957222ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:53:47.656752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648215202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-04-16T22:53:47.65675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647924204s","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-16T22:53:47.656788Z","caller":"traceutil/trace.go:171","msg":"trace[246787932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1140; }","duration":"1.648313054s","start":"2026-04-16T22:53:46.008464Z","end":"2026-04-16T22:53:47.656777Z","steps":["trace[246787932] 'agreement among raft nodes before linearized reading' (duration: 1.648112389s)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:53:47.65682Z","caller":"traceutil/trace.go:171","msg":"trace[1841921988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"1.648014387s","start":"2026-04-16T22:53:46.008791Z","end":"2026-04-16T22:53:47.656805Z","steps":["trace[1841921988] 'agreement among raft nodes before linearized reading' (duration: 1.647891084s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:47.656829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:46.008445Z","time spent":"1.648375915s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T22:53:47.656854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:46.008786Z","time spent":"1.648062579s","remote":"127.0.0.1:39932","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-16T22:53:47.656841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648294243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:53:47.656894Z","caller":"traceutil/trace.go:171","msg":"trace[103665625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1140; }","duration":"1.648362716s","start":"2026-04-16T22:53:46.008515Z","end":"2026-04-16T22:53:47.656878Z","steps":["trace[103665625] 'agreement among raft nodes before linearized reading' (duration: 1.64819389s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:47.656938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:46.008511Z","time spent":"1.648415627s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T22:53:47.657285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647809842s","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-16T22:53:47.657315Z","caller":"traceutil/trace.go:171","msg":"trace[1028244704] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1140; }","duration":"1.648487879s","start":"2026-04-16T22:53:46.00882Z","end":"2026-04-16T22:53:47.657308Z","steps":["trace[1028244704] 'agreement among raft nodes before linearized reading' (duration: 1.647661428s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:53:47.657333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:53:46.008815Z","time spent":"1.648513239s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-16T22:54:03.659058Z","caller":"traceutil/trace.go:171","msg":"trace[1497218468] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"267.403162ms","start":"2026-04-16T22:54:03.391623Z","end":"2026-04-16T22:54:03.659027Z","steps":["trace[1497218468] 'process raft request' (duration: 267.216767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:04.291216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.743386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:54:04.291415Z","caller":"traceutil/trace.go:171","msg":"trace[804712659] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"556.017471ms","start":"2026-04-16T22:54:03.735366Z","end":"2026-04-16T22:54:04.291383Z","steps":["trace[804712659] 'process raft request' (duration: 392.965319ms)","trace[804712659] 'compare' (duration: 162.52714ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:04.291598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:03.735338Z","time spent":"556.162276ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:04.291976Z","caller":"traceutil/trace.go:171","msg":"trace[185571913] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"106.42597ms","start":"2026-04-16T22:54:04.185519Z","end":"2026-04-16T22:54:04.291945Z","steps":["trace[185571913] 'process raft request' (duration: 106.097122ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:04.292031Z","caller":"traceutil/trace.go:171","msg":"trace[963436646] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"492.626923ms","start":"2026-04-16T22:54:03.799389Z","end":"2026-04-16T22:54:04.292016Z","steps":["trace[963436646] 'read index received' (duration: 328.33634ms)","trace[963436646] 'applied index is now lower than readState.Index' (duration: 164.289033ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:04.292247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.854789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:54:04.29229Z","caller":"traceutil/trace.go:171","msg":"trace[926606344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"492.90163ms","start":"2026-04-16T22:54:03.799378Z","end":"2026-04-16T22:54:04.29228Z","steps":["trace[926606344] 'agreement among raft nodes before linearized reading' (duration: 492.733606ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:04.292325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:03.799282Z","time spent":"493.035604ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T22:54:04.292487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.015533ms","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-16T22:54:04.292524Z","caller":"traceutil/trace.go:171","msg":"trace[1560577754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1254; }","duration":"493.095714ms","start":"2026-04-16T22:54:03.799417Z","end":"2026-04-16T22:54:04.292512Z","steps":["trace[1560577754] 'agreement among raft nodes before linearized reading' (duration: 493.002582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:04.292559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:03.799381Z","time spent":"493.171896ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T22:54:04.292591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.337345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:04.292625Z","caller":"traceutil/trace.go:171","msg":"trace[614872494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"442.386346ms","start":"2026-04-16T22:54:03.850228Z","end":"2026-04-16T22:54:04.292614Z","steps":["trace[614872494] 'agreement among raft nodes before linearized reading' (duration: 442.328584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:04.29265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:03.850218Z","time spent":"442.427198ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:54:04.292697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.149536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T22:54:04.292735Z","caller":"traceutil/trace.go:171","msg":"trace[809925887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"493.199448ms","start":"2026-04-16T22:54:03.799527Z","end":"2026-04-16T22:54:04.292727Z","steps":["trace[809925887] 'agreement among raft nodes before linearized reading' (duration: 493.096985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:04.29277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:03.799521Z","time spent":"493.242618ms","remote":"127.0.0.1:39932","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-16T22:54:04.29277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.705729ms","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-16T22:54:04.292851Z","caller":"traceutil/trace.go:171","msg":"trace[922926020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1254; }","duration":"486.816222ms","start":"2026-04-16T22:54:03.806015Z","end":"2026-04-16T22:54:04.292831Z","steps":["trace[922926020] 'agreement among raft nodes before linearized reading' (duration: 486.583216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:04.292912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:03.806001Z","time spent":"486.897404ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T22:54:08.909029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537748728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T22:54:09.223634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.837008ms","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-16T22:54:09.223739Z","caller":"traceutil/trace.go:171","msg":"trace[1941206272] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1269; }","duration":"318.962651ms","start":"2026-04-16T22:54:08.90476Z","end":"2026-04-16T22:54:09.223722Z","steps":["trace[1941206272] 'range keys from in-memory index tree' (duration: 318.818848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:09.225469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.096722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:54:09.225537Z","caller":"traceutil/trace.go:171","msg":"trace[97544492] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"817.486669ms","start":"2026-04-16T22:54:08.40804Z","end":"2026-04-16T22:54:09.225527Z","steps":["trace[97544492] 'read index received' (duration: 702.398867ms)","trace[97544492] 'applied index is now lower than readState.Index' (duration: 115.087062ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:54:09.225594Z","caller":"traceutil/trace.go:171","msg":"trace[355943393] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"825.267529ms","start":"2026-04-16T22:54:08.400288Z","end":"2026-04-16T22:54:09.225556Z","steps":["trace[355943393] 'process raft request' (duration: 710.021054ms)","trace[355943393] 'compare' (duration: 114.139938ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:09.225671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.642723ms","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-16T22:54:09.22569Z","caller":"traceutil/trace.go:171","msg":"trace[151734956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"817.663064ms","start":"2026-04-16T22:54:08.408021Z","end":"2026-04-16T22:54:09.225684Z","steps":["trace[151734956] 'agreement among raft nodes before linearized reading' (duration: 817.555771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:09.225712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:08.407994Z","time spent":"817.713015ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T22:54:09.225762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:08.400263Z","time spent":"825.412373ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:09.67085Z","caller":"traceutil/trace.go:171","msg":"trace[1233094441] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"445.260657ms","start":"2026-04-16T22:54:09.225555Z","end":"2026-04-16T22:54:09.670816Z","steps":["trace[1233094441] 'read index received' (duration: 433.048238ms)","trace[1233094441] 'applied index is now lower than readState.Index' (duration: 12.211119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:09.671081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261498435s","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-16T22:54:09.671107Z","caller":"traceutil/trace.go:171","msg":"trace[1590940110] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1271; }","duration":"1.261584927s","start":"2026-04-16T22:54:08.409515Z","end":"2026-04-16T22:54:09.6711Z","steps":["trace[1590940110] 'agreement among raft nodes before linearized reading' (duration: 1.261405703s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:09.67113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:08.409494Z","time spent":"1.261630168s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T22:54:09.67111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.226097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:09.671218Z","caller":"traceutil/trace.go:171","msg":"trace[1392695877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1271; }","duration":"820.37273ms","start":"2026-04-16T22:54:08.850819Z","end":"2026-04-16T22:54:09.671192Z","steps":["trace[1392695877] 'agreement among raft nodes before linearized reading' (duration: 820.207417ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:09.671281Z","caller":"traceutil/trace.go:171","msg":"trace[101353913] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"691.599094ms","start":"2026-04-16T22:54:08.979652Z","end":"2026-04-16T22:54:09.671251Z","steps":["trace[101353913] 'process raft request' (duration: 679.052227ms)","trace[101353913] 'compare' (duration: 11.654415ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:09.67128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:08.850803Z","time spent":"820.462702ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:54:09.671392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261154627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-16T22:54:09.671393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:08.979627Z","time spent":"691.696207ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:09.671414Z","caller":"traceutil/trace.go:171","msg":"trace[2075432790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1271; }","duration":"1.261184358s","start":"2026-04-16T22:54:08.410222Z","end":"2026-04-16T22:54:09.671406Z","steps":["trace[2075432790] 'agreement among raft nodes before linearized reading' (duration: 1.261132256s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:09.671442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:08.410215Z","time spent":"1.261222498s","remote":"127.0.0.1:39932","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-16T22:54:09.671441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261350512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:54:09.671509Z","caller":"traceutil/trace.go:171","msg":"trace[1882375252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1271; }","duration":"1.261458894s","start":"2026-04-16T22:54:08.410034Z","end":"2026-04-16T22:54:09.671493Z","steps":["trace[1882375252] 'agreement among raft nodes before linearized reading' (duration: 1.261224768s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:09.671554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:08.410026Z","time spent":"1.261518756s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T22:54:09.679893Z","caller":"traceutil/trace.go:171","msg":"trace[2084805473] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"449.322185ms","start":"2026-04-16T22:54:09.230545Z","end":"2026-04-16T22:54:09.679867Z","steps":["trace[2084805473] 'process raft request' (duration: 449.097119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:09.680084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:09.230512Z","time spent":"449.457378ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:21.582307Z","caller":"traceutil/trace.go:171","msg":"trace[73160365] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"261.294132ms","start":"2026-04-16T22:54:21.320996Z","end":"2026-04-16T22:54:21.58229Z","steps":["trace[73160365] 'process raft request' (duration: 261.172209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:21.996859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.884203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:54:21.996935Z","caller":"traceutil/trace.go:171","msg":"trace[1506502877] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1311; }","duration":"261.006766ms","start":"2026-04-16T22:54:21.735909Z","end":"2026-04-16T22:54:21.996916Z","steps":["trace[1506502877] 'count revisions from in-memory index tree' (duration: 260.79739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:21.996961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.916269ms","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-16T22:54:21.997005Z","caller":"traceutil/trace.go:171","msg":"trace[1099282274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1311; }","duration":"266.982041ms","start":"2026-04-16T22:54:21.730009Z","end":"2026-04-16T22:54:21.996991Z","steps":["trace[1099282274] 'range keys from in-memory index tree' (duration: 266.669744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:21.997174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.263589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T22:54:21.99726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.182435ms","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-16T22:54:21.99727Z","caller":"traceutil/trace.go:171","msg":"trace[1601453935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"247.380852ms","start":"2026-04-16T22:54:21.749862Z","end":"2026-04-16T22:54:21.997242Z","steps":["trace[1601453935] 'range keys from in-memory index tree' (duration: 246.971383ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:21.997292Z","caller":"traceutil/trace.go:171","msg":"trace[1399835706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"253.264316ms","start":"2026-04-16T22:54:21.744019Z","end":"2026-04-16T22:54:21.997283Z","steps":["trace[1399835706] 'range keys from in-memory index tree' (duration: 252.733743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:21.997395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.563945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:54:21.997428Z","caller":"traceutil/trace.go:171","msg":"trace[153872993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1311; }","duration":"260.616616ms","start":"2026-04-16T22:54:21.736802Z","end":"2026-04-16T22:54:21.997418Z","steps":["trace[153872993] 'range keys from in-memory index tree' (duration: 260.152195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:21.997059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.658654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:21.997723Z","caller":"traceutil/trace.go:171","msg":"trace[459041649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"147.667998ms","start":"2026-04-16T22:54:21.850038Z","end":"2026-04-16T22:54:21.997706Z","steps":["trace[459041649] 'range keys from in-memory index tree' (duration: 146.51117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:21.996799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.878578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T22:54:21.998019Z","caller":"traceutil/trace.go:171","msg":"trace[443489967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"254.146169ms","start":"2026-04-16T22:54:21.743856Z","end":"2026-04-16T22:54:21.998002Z","steps":["trace[443489967] 'range keys from in-memory index tree' (duration: 252.728904ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:22.144124Z","caller":"traceutil/trace.go:171","msg":"trace[644143537] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"141.863974ms","start":"2026-04-16T22:54:22.002233Z","end":"2026-04-16T22:54:22.144097Z","steps":["trace[644143537] 'process raft request' (duration: 140.883901ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:22.144448Z","caller":"traceutil/trace.go:171","msg":"trace[645000501] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"142.028058ms","start":"2026-04-16T22:54:22.002403Z","end":"2026-04-16T22:54:22.144431Z","steps":["trace[645000501] 'process raft request' (duration: 141.609798ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:22.14451Z","caller":"traceutil/trace.go:171","msg":"trace[1303026771] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"141.815013ms","start":"2026-04-16T22:54:22.002663Z","end":"2026-04-16T22:54:22.144478Z","steps":["trace[1303026771] 'process raft request' (duration: 141.723441ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:22.14453Z","caller":"traceutil/trace.go:171","msg":"trace[1873107629] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"141.131937ms","start":"2026-04-16T22:54:22.003386Z","end":"2026-04-16T22:54:22.144518Z","steps":["trace[1873107629] 'process raft request' (duration: 141.049685ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:22.144912Z","caller":"traceutil/trace.go:171","msg":"trace[1748136470] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"142.172903ms","start":"2026-04-16T22:54:22.002459Z","end":"2026-04-16T22:54:22.144632Z","steps":["trace[1748136470] 'process raft request' (duration: 141.816754ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:22.186721Z","caller":"traceutil/trace.go:171","msg":"trace[721236812] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"123.195689ms","start":"2026-04-16T22:54:22.063503Z","end":"2026-04-16T22:54:22.186699Z","steps":["trace[721236812] 'process raft request' (duration: 123.059534ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:23.712293Z","caller":"traceutil/trace.go:171","msg":"trace[1412172611] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"123.273941ms","start":"2026-04-16T22:54:23.588994Z","end":"2026-04-16T22:54:23.712268Z","steps":["trace[1412172611] 'process raft request' (duration: 123.105877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:24.58901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.051099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:54:24.58911Z","caller":"traceutil/trace.go:171","msg":"trace[1618863460] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1319; }","duration":"440.203003ms","start":"2026-04-16T22:54:24.148885Z","end":"2026-04-16T22:54:24.589088Z","steps":["trace[1618863460] 'range keys from in-memory index tree' (duration: 439.798303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:24.589086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.538327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-16T22:54:24.589212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.148863Z","time spent":"440.335547ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T22:54:24.589245Z","caller":"traceutil/trace.go:171","msg":"trace[551700293] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"439.732802ms","start":"2026-04-16T22:54:24.149487Z","end":"2026-04-16T22:54:24.58922Z","steps":["trace[551700293] 'range keys from in-memory index tree' (duration: 439.371663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:24.589303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.149478Z","time spent":"439.808944ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T22:54:24.589401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.84237ms","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-16T22:54:24.589541Z","caller":"traceutil/trace.go:171","msg":"trace[1821296158] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1319; }","duration":"299.039384ms","start":"2026-04-16T22:54:24.290483Z","end":"2026-04-16T22:54:24.589522Z","steps":["trace[1821296158] 'count revisions from in-memory index tree' (duration: 298.767228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:24.589619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.866815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T22:54:24.589664Z","caller":"traceutil/trace.go:171","msg":"trace[21764417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"439.925206ms","start":"2026-04-16T22:54:24.149725Z","end":"2026-04-16T22:54:24.58965Z","steps":["trace[21764417] 'range keys from in-memory index tree' (duration: 439.776923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:24.5897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.149718Z","time spent":"439.972877ms","remote":"127.0.0.1:39932","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-16T22:54:24.589805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.330368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T22:54:24.589838Z","caller":"traceutil/trace.go:171","msg":"trace[1009128155] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1319; }","duration":"262.40784ms","start":"2026-04-16T22:54:24.327421Z","end":"2026-04-16T22:54:24.589829Z","steps":["trace[1009128155] 'count revisions from in-memory index tree' (duration: 262.211595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:24.589893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.880079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T22:54:24.589933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.360978ms","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-16T22:54:24.589941Z","caller":"traceutil/trace.go:171","msg":"trace[1195218371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1319; }","duration":"440.942861ms","start":"2026-04-16T22:54:24.148985Z","end":"2026-04-16T22:54:24.589928Z","steps":["trace[1195218371] 'range keys from in-memory index tree' (duration: 440.785498ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:24.589973Z","caller":"traceutil/trace.go:171","msg":"trace[753709852] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1319; }","duration":"438.417869ms","start":"2026-04-16T22:54:24.151543Z","end":"2026-04-16T22:54:24.589961Z","steps":["trace[753709852] 'range keys from in-memory index tree' (duration: 438.262156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:24.589979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.148979Z","time spent":"440.989822ms","remote":"127.0.0.1:39932","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-16T22:54:24.590012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.151535Z","time spent":"438.46569ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-16T22:54:24.590291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.122068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-16T22:54:24.590355Z","caller":"traceutil/trace.go:171","msg":"trace[728234040] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1319; }","duration":"123.22394ms","start":"2026-04-16T22:54:24.467122Z","end":"2026-04-16T22:54:24.590345Z","steps":["trace[728234040] 'count revisions from in-memory index tree' (duration: 123.034935ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:25.182807Z","caller":"traceutil/trace.go:171","msg":"trace[122285514] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"586.557658ms","start":"2026-04-16T22:54:24.596221Z","end":"2026-04-16T22:54:25.182779Z","steps":["trace[122285514] 'process raft request' (duration: 586.383524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:25.182988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.596196Z","time spent":"586.698591ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:25.559886Z","caller":"traceutil/trace.go:171","msg":"trace[1413353557] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"963.613829ms","start":"2026-04-16T22:54:24.596241Z","end":"2026-04-16T22:54:25.559855Z","steps":["trace[1413353557] 'process raft request' (duration: 963.383553ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:25.560078Z","caller":"traceutil/trace.go:171","msg":"trace[1692079398] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"963.766553ms","start":"2026-04-16T22:54:24.596298Z","end":"2026-04-16T22:54:25.560065Z","steps":["trace[1692079398] 'process raft request' (duration: 963.64935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:25.560083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.596219Z","time spent":"963.757173ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:25.56009Z","caller":"traceutil/trace.go:171","msg":"trace[1014865627] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"963.793493ms","start":"2026-04-16T22:54:24.596254Z","end":"2026-04-16T22:54:25.560048Z","steps":["trace[1014865627] 'process raft request' (duration: 963.542237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:25.560195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.596219Z","time spent":"963.902216ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:54:25.560223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.596246Z","time spent":"963.904466ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:25.560334Z","caller":"traceutil/trace.go:171","msg":"trace[18662650] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"933.723869ms","start":"2026-04-16T22:54:24.626601Z","end":"2026-04-16T22:54:25.560325Z","steps":["trace[18662650] 'process raft request' (duration: 933.454702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:25.560397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.626583Z","time spent":"933.784851ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:25.560255Z","caller":"traceutil/trace.go:171","msg":"trace[1343672782] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1375; }","duration":"710.065525ms","start":"2026-04-16T22:54:24.850099Z","end":"2026-04-16T22:54:25.560165Z","steps":["trace[1343672782] 'read index received' (duration: 332.540773ms)","trace[1343672782] 'applied index is now lower than readState.Index' (duration: 377.520682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:25.560567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.468775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:25.56063Z","caller":"traceutil/trace.go:171","msg":"trace[2066027143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"710.565208ms","start":"2026-04-16T22:54:24.850049Z","end":"2026-04-16T22:54:25.560614Z","steps":["trace[2066027143] 'agreement among raft nodes before linearized reading' (duration: 710.408484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:25.560681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.850025Z","time spent":"710.644479ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:54:25.560112Z","caller":"traceutil/trace.go:171","msg":"trace[1015881109] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"963.796183ms","start":"2026-04-16T22:54:24.596297Z","end":"2026-04-16T22:54:25.560093Z","steps":["trace[1015881109] 'process raft request' (duration: 963.729742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:25.560893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:24.596292Z","time spent":"964.526141ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:27.509507Z","caller":"traceutil/trace.go:171","msg":"trace[886496704] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"318.987412ms","start":"2026-04-16T22:54:27.190494Z","end":"2026-04-16T22:54:27.509481Z","steps":["trace[886496704] 'process raft request' (duration: 318.792998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:27.509722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:27.190427Z","time spent":"319.197837ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:54:27.697662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.498556ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-16T22:54:27.69778Z","caller":"traceutil/trace.go:171","msg":"trace[2075249314] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"132.562378ms","start":"2026-04-16T22:54:27.565199Z","end":"2026-04-16T22:54:27.697762Z","steps":["trace[2075249314] 'read index received' (duration: 73.612µs)","trace[2075249314] 'applied index is now lower than readState.Index' (duration: 132.486906ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:27.697968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.758294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-16T22:54:27.698017Z","caller":"traceutil/trace.go:171","msg":"trace[1688703656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1327; }","duration":"132.835875ms","start":"2026-04-16T22:54:27.565159Z","end":"2026-04-16T22:54:27.697995Z","steps":["trace[1688703656] 'agreement among raft nodes before linearized reading' (duration: 132.696431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:27.6981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.721201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-16T22:54:27.698202Z","caller":"traceutil/trace.go:171","msg":"trace[1968736748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1327; }","duration":"132.852844ms","start":"2026-04-16T22:54:27.565321Z","end":"2026-04-16T22:54:27.698174Z","steps":["trace[1968736748] 'agreement among raft nodes before linearized reading' (duration: 132.577768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:27.698424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.004018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-16T22:54:27.698425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.741278ms","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-16T22:54:27.698462Z","caller":"traceutil/trace.go:171","msg":"trace[1836735937] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"133.08015ms","start":"2026-04-16T22:54:27.565373Z","end":"2026-04-16T22:54:27.698453Z","steps":["trace[1836735937] 'agreement among raft nodes before linearized reading' (duration: 132.984438ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:27.698485Z","caller":"traceutil/trace.go:171","msg":"trace[1608683683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1327; }","duration":"131.8234ms","start":"2026-04-16T22:54:27.566645Z","end":"2026-04-16T22:54:27.698468Z","steps":["trace[1608683683] 'agreement among raft nodes before linearized reading' (duration: 131.633646ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:28.205087Z","caller":"traceutil/trace.go:171","msg":"trace[335093033] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"502.437553ms","start":"2026-04-16T22:54:27.702625Z","end":"2026-04-16T22:54:28.205062Z","steps":["trace[335093033] 'process raft request' (duration: 502.270989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:28.205247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:27.702597Z","time spent":"502.569006ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:54:29.177295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.590399ms","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-16T22:54:29.177445Z","caller":"traceutil/trace.go:171","msg":"trace[2125732494] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1328; }","duration":"272.759743ms","start":"2026-04-16T22:54:28.904666Z","end":"2026-04-16T22:54:29.177426Z","steps":["trace[2125732494] 'range keys from in-memory index tree' (duration: 272.574018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:29.177513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.636278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:54:29.177673Z","caller":"traceutil/trace.go:171","msg":"trace[707575994] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"1.473839172s","start":"2026-04-16T22:54:27.703818Z","end":"2026-04-16T22:54:29.177658Z","steps":["trace[707575994] 'process raft request' (duration: 1.47376045s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:29.177733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:27.703785Z","time spent":"1.473917884s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:29.177866Z","caller":"traceutil/trace.go:171","msg":"trace[1705610097] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"1.472849448s","start":"2026-04-16T22:54:27.705005Z","end":"2026-04-16T22:54:29.177855Z","steps":["trace[1705610097] 'process raft request' (duration: 1.472620693s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:29.177936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:27.704989Z","time spent":"1.47290739s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:29.177985Z","caller":"traceutil/trace.go:171","msg":"trace[1790816701] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"1.474295143s","start":"2026-04-16T22:54:27.70367Z","end":"2026-04-16T22:54:29.177965Z","steps":["trace[1790816701] 'process raft request' (duration: 996.136412ms)","trace[1790816701] 'compare' (duration: 477.511815ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:29.178085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:27.703647Z","time spent":"1.474388825s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:29.178171Z","caller":"traceutil/trace.go:171","msg":"trace[1289350893] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1385; }","duration":"625.518859ms","start":"2026-04-16T22:54:28.552637Z","end":"2026-04-16T22:54:29.178158Z","steps":["trace[1289350893] 'read index received' (duration: 147.117573ms)","trace[1289350893] 'applied index is now lower than readState.Index' (duration: 478.400356ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:29.178452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.796967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:29.178454Z","caller":"traceutil/trace.go:171","msg":"trace[212436136] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"1.394816962s","start":"2026-04-16T22:54:27.7836Z","end":"2026-04-16T22:54:29.178417Z","steps":["trace[212436136] 'process raft request' (duration: 1.394204627s)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:29.178516Z","caller":"traceutil/trace.go:171","msg":"trace[112357707] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1332; }","duration":"625.902779ms","start":"2026-04-16T22:54:28.552598Z","end":"2026-04-16T22:54:29.178501Z","steps":["trace[112357707] 'agreement among raft nodes before linearized reading' (duration: 625.795266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:29.178547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.150728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:54:29.17857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:28.552582Z","time spent":"625.971201ms","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-04-16T22:54:29.178606Z","caller":"traceutil/trace.go:171","msg":"trace[501776688] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1332; }","duration":"349.255751ms","start":"2026-04-16T22:54:28.829336Z","end":"2026-04-16T22:54:29.178591Z","steps":["trace[501776688] 'agreement among raft nodes before linearized reading' (duration: 349.160999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:29.178646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:28.829313Z","time spent":"349.322672ms","remote":"127.0.0.1:39898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:54:29.178642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:27.78357Z","time spent":"1.394972786s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:54:29.178739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.339801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:29.17942Z","caller":"traceutil/trace.go:171","msg":"trace[1942226141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"329.058319ms","start":"2026-04-16T22:54:28.850343Z","end":"2026-04-16T22:54:29.179401Z","steps":["trace[1942226141] 'agreement among raft nodes before linearized reading' (duration: 328.331391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:29.17952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:28.850296Z","time spent":"329.197843ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:54:29.456931Z","caller":"traceutil/trace.go:171","msg":"trace[1517984372] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"166.802425ms","start":"2026-04-16T22:54:29.290071Z","end":"2026-04-16T22:54:29.456873Z","steps":["trace[1517984372] 'process raft request' (duration: 166.641131ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:29.615392Z","caller":"traceutil/trace.go:171","msg":"trace[2094084361] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"100.766271ms","start":"2026-04-16T22:54:29.514605Z","end":"2026-04-16T22:54:29.615371Z","steps":["trace[2094084361] 'read index received' (duration: 45.601685ms)","trace[2094084361] 'applied index is now lower than readState.Index' (duration: 55.163566ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:54:29.615442Z","caller":"traceutil/trace.go:171","msg":"trace[1088743806] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"121.952919ms","start":"2026-04-16T22:54:29.493446Z","end":"2026-04-16T22:54:29.615398Z","steps":["trace[1088743806] 'process raft request' (duration: 66.832353ms)","trace[1088743806] 'compare' (duration: 55.002153ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:29.615643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.031447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-16T22:54:29.61568Z","caller":"traceutil/trace.go:171","msg":"trace[406984649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1334; }","duration":"101.11842ms","start":"2026-04-16T22:54:29.514551Z","end":"2026-04-16T22:54:29.61567Z","steps":["trace[406984649] 'agreement among raft nodes before linearized reading' (duration: 100.898774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:30.210734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.61817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:54:30.210925Z","caller":"traceutil/trace.go:171","msg":"trace[1012139110] linearizableReadLoop","detail":"{readStateIndex:1393; appliedIndex:1391; }","duration":"576.987094ms","start":"2026-04-16T22:54:29.633922Z","end":"2026-04-16T22:54:30.210909Z","steps":["trace[1012139110] 'read index received' (duration: 112.111128ms)","trace[1012139110] 'applied index is now lower than readState.Index' (duration: 464.875076ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:30.211255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.631199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:30.211291Z","caller":"traceutil/trace.go:171","msg":"trace[821941822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"360.697091ms","start":"2026-04-16T22:54:29.850583Z","end":"2026-04-16T22:54:30.21128Z","steps":["trace[821941822] 'agreement among raft nodes before linearized reading' (duration: 360.634389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:30.211283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.185837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-16T22:54:30.211322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:29.850567Z","time spent":"360.748523ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:54:30.21133Z","caller":"traceutil/trace.go:171","msg":"trace[2017291763] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1336; }","duration":"118.233108ms","start":"2026-04-16T22:54:30.093084Z","end":"2026-04-16T22:54:30.211317Z","steps":["trace[2017291763] 'agreement among raft nodes before linearized reading' (duration: 118.156666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:30.21132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.366284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:30.211179Z","caller":"traceutil/trace.go:171","msg":"trace[1943816629] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"591.578141ms","start":"2026-04-16T22:54:29.619571Z","end":"2026-04-16T22:54:30.211149Z","steps":["trace[1943816629] 'process raft request' (duration: 591.265253ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:30.211438Z","caller":"traceutil/trace.go:171","msg":"trace[27732080] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1336; }","duration":"577.511697ms","start":"2026-04-16T22:54:29.633887Z","end":"2026-04-16T22:54:30.211398Z","steps":["trace[27732080] 'agreement among raft nodes before linearized reading' (duration: 577.321292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:30.211487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:29.633874Z","time spent":"577.6ms","remote":"127.0.0.1:39952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-16T22:54:30.211239Z","caller":"traceutil/trace.go:171","msg":"trace[1051422783] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"591.940099ms","start":"2026-04-16T22:54:29.619207Z","end":"2026-04-16T22:54:30.211147Z","steps":["trace[1051422783] 'process raft request' (duration: 126.839378ms)","trace[1051422783] 'compare' (duration: 464.129567ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:30.211664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:29.619182Z","time spent":"592.447531ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T22:54:30.211635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:29.619556Z","time spent":"591.911528ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:54:30.211837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.444572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:118650"} {"level":"info","ts":"2026-04-16T22:54:30.211932Z","caller":"traceutil/trace.go:171","msg":"trace[1952560483] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1336; }","duration":"120.560724ms","start":"2026-04-16T22:54:30.091357Z","end":"2026-04-16T22:54:30.211918Z","steps":["trace[1952560483] 'agreement among raft nodes before linearized reading' (duration: 120.142984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:30.547245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.536675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:54:30.547363Z","caller":"traceutil/trace.go:171","msg":"trace[1499350285] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"324.927947ms","start":"2026-04-16T22:54:30.222417Z","end":"2026-04-16T22:54:30.547345Z","steps":["trace[1499350285] 'process raft request' (duration: 107.205659ms)","trace[1499350285] 'compare' (duration: 217.419211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:30.547423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:30.222397Z","time spent":"324.997999ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:30.569969Z","caller":"traceutil/trace.go:171","msg":"trace[2122939640] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"207.56009ms","start":"2026-04-16T22:54:30.362382Z","end":"2026-04-16T22:54:30.569942Z","steps":["trace[2122939640] 'process raft request' (duration: 207.360415ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:30.744104Z","caller":"traceutil/trace.go:171","msg":"trace[483302125] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"165.229927ms","start":"2026-04-16T22:54:30.57884Z","end":"2026-04-16T22:54:30.74407Z","steps":["trace[483302125] 'process raft request' (duration: 165.009521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:31.78236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2281246489537748914,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T22:54:32.266253Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080814871s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T22:54:32.266599Z","caller":"traceutil/trace.go:171","msg":"trace[272238765] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"1.081250682s","start":"2026-04-16T22:54:31.185328Z","end":"2026-04-16T22:54:32.266578Z","steps":["trace[272238765] 'process raft request' (duration: 1.081118849s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:32.266729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:31.185313Z","time spent":"1.081341654s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:54:32.761296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.817026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T22:54:32.761859Z","caller":"traceutil/trace.go:171","msg":"trace[285611829] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"1.574172672s","start":"2026-04-16T22:54:31.187639Z","end":"2026-04-16T22:54:32.761811Z","steps":["trace[285611829] 'process raft request' (duration: 1.383764141s)","trace[285611829] 'compare' (duration: 189.581681ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:54:32.762028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:31.187627Z","time spent":"1.574319817s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:32.762831Z","caller":"traceutil/trace.go:171","msg":"trace[641857757] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1398; }","duration":"1.481154781s","start":"2026-04-16T22:54:31.281657Z","end":"2026-04-16T22:54:32.762812Z","steps":["trace[641857757] 'read index received' (duration: 984.923809ms)","trace[641857757] 'applied index is now lower than readState.Index' (duration: 496.229872ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:54:32.762912Z","caller":"traceutil/trace.go:171","msg":"trace[546949459] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"1.574373387s","start":"2026-04-16T22:54:31.188514Z","end":"2026-04-16T22:54:32.762888Z","steps":["trace[546949459] 'process raft request' (duration: 1.573223639s)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:32.76296Z","caller":"traceutil/trace.go:171","msg":"trace[732591851] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"1.572802171s","start":"2026-04-16T22:54:31.190137Z","end":"2026-04-16T22:54:32.762939Z","steps":["trace[732591851] 'process raft request' (duration: 1.572576845s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:32.762991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481327595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T22:54:32.763019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:31.188492Z","time spent":"1.57447513s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:32.763043Z","caller":"traceutil/trace.go:171","msg":"trace[2097046444] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1344; }","duration":"1.481420908s","start":"2026-04-16T22:54:31.281606Z","end":"2026-04-16T22:54:32.763027Z","steps":["trace[2097046444] 'agreement among raft nodes before linearized reading' (duration: 1.481333756s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:32.763068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:31.190127Z","time spent":"1.572887202s","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:54:32.763088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:31.281584Z","time spent":"1.481490129s","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:54:32.763211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.691386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T22:54:32.763217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.071204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:32.763274Z","caller":"traceutil/trace.go:171","msg":"trace[930837837] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1344; }","duration":"456.789198ms","start":"2026-04-16T22:54:32.30647Z","end":"2026-04-16T22:54:32.763259Z","steps":["trace[930837837] 'agreement among raft nodes before linearized reading' (duration: 456.687286ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:32.76331Z","caller":"traceutil/trace.go:171","msg":"trace[724166586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1344; }","duration":"913.188937ms","start":"2026-04-16T22:54:31.850083Z","end":"2026-04-16T22:54:32.763272Z","steps":["trace[724166586] 'agreement among raft nodes before linearized reading' (duration: 913.078454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:32.763329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:32.30645Z","time spent":"456.86414ms","remote":"127.0.0.1:40100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:54:32.763351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:31.850064Z","time spent":"913.278819ms","remote":"127.0.0.1:39664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T22:54:32.763409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.550237ms","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-16T22:54:32.763436Z","caller":"traceutil/trace.go:171","msg":"trace[1962959188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1344; }","duration":"211.622068ms","start":"2026-04-16T22:54:32.551807Z","end":"2026-04-16T22:54:32.763429Z","steps":["trace[1962959188] 'agreement among raft nodes before linearized reading' (duration: 211.527526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:32.763648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.597182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.118\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-16T22:54:32.763671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.162746ms","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-16T22:54:32.763715Z","caller":"traceutil/trace.go:171","msg":"trace[149285621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1344; }","duration":"545.244878ms","start":"2026-04-16T22:54:32.21846Z","end":"2026-04-16T22:54:32.763705Z","steps":["trace[149285621] 'agreement among raft nodes before linearized reading' (duration: 545.109605ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:54:32.763717Z","caller":"traceutil/trace.go:171","msg":"trace[643362542] range","detail":"{range_begin:/registry/masterleases/199.19.213.118; range_end:; response_count:1; response_revision:1344; }","duration":"783.718575ms","start":"2026-04-16T22:54:31.979981Z","end":"2026-04-16T22:54:32.7637Z","steps":["trace[643362542] 'agreement among raft nodes before linearized reading' (duration: 783.51633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:32.763757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:32.218436Z","time spent":"545.31269ms","remote":"127.0.0.1:39932","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-16T22:54:32.763774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:31.979963Z","time spent":"783.798036ms","remote":"127.0.0.1:39726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.118\" "} {"level":"info","ts":"2026-04-16T22:54:33.402282Z","caller":"traceutil/trace.go:171","msg":"trace[1410901459] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"311.328915ms","start":"2026-04-16T22:54:33.090913Z","end":"2026-04-16T22:54:33.402242Z","steps":["trace[1410901459] 'process raft request' (duration: 310.484255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:33.402494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:33.090898Z","time spent":"311.515769ms","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T22:54:33.521385Z","caller":"traceutil/trace.go:171","msg":"trace[1817916932] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"102.592877ms","start":"2026-04-16T22:54:33.418768Z","end":"2026-04-16T22:54:33.521361Z","steps":["trace[1817916932] 'process raft request' (duration: 102.414612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:36.253044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.300102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:54:36.253129Z","caller":"traceutil/trace.go:171","msg":"trace[723807520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"403.438125ms","start":"2026-04-16T22:54:35.849671Z","end":"2026-04-16T22:54:36.253109Z","steps":["trace[723807520] 'range keys from in-memory index tree' (duration: 403.22875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:54:36.253171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:54:35.849649Z","time spent":"403.512787ms","remote":"127.0.0.1:39672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T22:55:27.598365Z","caller":"traceutil/trace.go:171","msg":"trace[1154206806] transaction","detail":"{read_only:false; response_revision:1657; number_of_response:1; }","duration":"176.733091ms","start":"2026-04-16T22:55:27.421606Z","end":"2026-04-16T22:55:27.598339Z","steps":["trace[1154206806] 'process raft request' (duration: 176.483005ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:27.599215Z","caller":"traceutil/trace.go:171","msg":"trace[413805193] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"175.184194ms","start":"2026-04-16T22:55:27.424006Z","end":"2026-04-16T22:55:27.59919Z","steps":["trace[413805193] 'process raft request' (duration: 174.9991ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:27.758299Z","caller":"traceutil/trace.go:171","msg":"trace[90699060] transaction","detail":"{read_only:false; response_revision:1659; number_of_response:1; }","duration":"152.918094ms","start":"2026-04-16T22:55:27.60511Z","end":"2026-04-16T22:55:27.758028Z","steps":["trace[90699060] 'process raft request' (duration: 78.519792ms)","trace[90699060] 'compare' (duration: 74.224908ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:55:27.760042Z","caller":"traceutil/trace.go:171","msg":"trace[1994383596] transaction","detail":"{read_only:false; response_revision:1660; number_of_response:1; }","duration":"153.978389ms","start":"2026-04-16T22:55:27.606046Z","end":"2026-04-16T22:55:27.760024Z","steps":["trace[1994383596] 'process raft request' (duration: 153.833646ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:55:27.760213Z","caller":"traceutil/trace.go:171","msg":"trace[834163736] transaction","detail":"{read_only:false; response_revision:1661; number_of_response:1; }","duration":"151.450869ms","start":"2026-04-16T22:55:27.608727Z","end":"2026-04-16T22:55:27.760178Z","steps":["trace[834163736] 'process raft request' (duration: 151.248254ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:15.050739Z","caller":"traceutil/trace.go:171","msg":"trace[733935143] linearizableReadLoop","detail":"{readStateIndex:2292; appliedIndex:2291; }","duration":"117.617997ms","start":"2026-04-16T22:57:14.933095Z","end":"2026-04-16T22:57:15.050713Z","steps":["trace[733935143] 'read index received' (duration: 74.629976ms)","trace[733935143] 'applied index is now lower than readState.Index' (duration: 42.987131ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T22:57:15.050956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.845382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-16T22:57:15.050938Z","caller":"traceutil/trace.go:171","msg":"trace[1801978286] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"138.401954ms","start":"2026-04-16T22:57:14.912486Z","end":"2026-04-16T22:57:15.050888Z","steps":["trace[1801978286] 'process raft request' (duration: 95.28845ms)","trace[1801978286] 'compare' (duration: 42.770716ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:15.050999Z","caller":"traceutil/trace.go:171","msg":"trace[1648389122] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2202; }","duration":"117.937334ms","start":"2026-04-16T22:57:14.93305Z","end":"2026-04-16T22:57:15.050987Z","steps":["trace[1648389122] 'agreement among raft nodes before linearized reading' (duration: 117.76566ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:30.024967Z","caller":"traceutil/trace.go:171","msg":"trace[920596582] linearizableReadLoop","detail":"{readStateIndex:2369; appliedIndex:2368; }","duration":"347.872838ms","start":"2026-04-16T22:57:29.677066Z","end":"2026-04-16T22:57:30.024939Z","steps":["trace[920596582] 'read index received' (duration: 347.661073ms)","trace[920596582] 'applied index is now lower than readState.Index' (duration: 210.595µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:30.025103Z","caller":"traceutil/trace.go:171","msg":"trace[4059247] transaction","detail":"{read_only:false; response_revision:2276; number_of_response:1; }","duration":"387.420505ms","start":"2026-04-16T22:57:29.637667Z","end":"2026-04-16T22:57:30.025088Z","steps":["trace[4059247] 'process raft request' (duration: 387.114678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:30.025267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.367971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T22:57:30.025314Z","caller":"traceutil/trace.go:171","msg":"trace[2079247962] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2276; }","duration":"342.434043ms","start":"2026-04-16T22:57:29.682867Z","end":"2026-04-16T22:57:30.025301Z","steps":["trace[2079247962] 'agreement among raft nodes before linearized reading' (duration: 342.345441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:30.025317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:57:29.637643Z","time spent":"387.492876ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T22:57:30.025345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:57:29.682844Z","time spent":"342.490955ms","remote":"127.0.0.1:34686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T22:57:30.025699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.429373ms","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-16T22:57:30.025764Z","caller":"traceutil/trace.go:171","msg":"trace[2090946430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2276; }","duration":"297.544866ms","start":"2026-04-16T22:57:29.728205Z","end":"2026-04-16T22:57:30.02575Z","steps":["trace[2090946430] 'agreement among raft nodes before linearized reading' (duration: 297.32022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:30.0257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.654298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-16T22:57:30.025831Z","caller":"traceutil/trace.go:171","msg":"trace[1538880392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2276; }","duration":"245.817591ms","start":"2026-04-16T22:57:29.779999Z","end":"2026-04-16T22:57:30.025816Z","steps":["trace[1538880392] 'agreement among raft nodes before linearized reading' (duration: 245.492264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:30.025819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.966352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T22:57:30.025879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.471338ms","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-16T22:57:30.025926Z","caller":"traceutil/trace.go:171","msg":"trace[60497956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2276; }","duration":"246.543979ms","start":"2026-04-16T22:57:29.779369Z","end":"2026-04-16T22:57:30.025913Z","steps":["trace[60497956] 'agreement among raft nodes before linearized reading' (duration: 246.362094ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:30.025929Z","caller":"traceutil/trace.go:171","msg":"trace[168184305] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2276; }","duration":"133.123746ms","start":"2026-04-16T22:57:29.89278Z","end":"2026-04-16T22:57:30.025904Z","steps":["trace[168184305] 'agreement among raft nodes before linearized reading' (duration: 132.921151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:30.025691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.620416ms","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-16T22:57:30.026058Z","caller":"traceutil/trace.go:171","msg":"trace[394264631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2276; }","duration":"349.019665ms","start":"2026-04-16T22:57:29.677029Z","end":"2026-04-16T22:57:30.026049Z","steps":["trace[394264631] 'agreement among raft nodes before linearized reading' (duration: 348.565484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:30.026093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T22:57:29.677009Z","time spent":"349.073907ms","remote":"127.0.0.1:39932","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-16T22:57:56.295023Z","caller":"traceutil/trace.go:171","msg":"trace[1852861413] transaction","detail":"{read_only:false; response_revision:2431; number_of_response:1; }","duration":"141.203513ms","start":"2026-04-16T22:57:56.153794Z","end":"2026-04-16T22:57:56.294998Z","steps":["trace[1852861413] 'process raft request' (duration: 141.044039ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:56.295989Z","caller":"traceutil/trace.go:171","msg":"trace[137451209] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"141.568332ms","start":"2026-04-16T22:57:56.154391Z","end":"2026-04-16T22:57:56.29596Z","steps":["trace[137451209] 'process raft request' (duration: 141.374546ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T22:57:56.296357Z","caller":"traceutil/trace.go:171","msg":"trace[1653889544] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"140.058305ms","start":"2026-04-16T22:57:56.156269Z","end":"2026-04-16T22:57:56.296328Z","steps":["trace[1653889544] 'process raft request' (duration: 139.639355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:56.296977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.876925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-16T22:57:56.296991Z","caller":"traceutil/trace.go:171","msg":"trace[854178957] linearizableReadLoop","detail":"{readStateIndex:2531; appliedIndex:2529; }","duration":"140.892746ms","start":"2026-04-16T22:57:56.15608Z","end":"2026-04-16T22:57:56.296973Z","steps":["trace[854178957] 'read index received' (duration: 138.752083ms)","trace[854178957] 'applied index is now lower than readState.Index' (duration: 1.318493ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T22:57:56.297073Z","caller":"traceutil/trace.go:171","msg":"trace[1838890733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2433; }","duration":"141.018798ms","start":"2026-04-16T22:57:56.156032Z","end":"2026-04-16T22:57:56.29705Z","steps":["trace[1838890733] 'agreement among raft nodes before linearized reading' (duration: 140.594318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T22:57:56.297308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.730493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-16T22:57:56.297344Z","caller":"traceutil/trace.go:171","msg":"trace[426516561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2433; }","duration":"138.798325ms","start":"2026-04-16T22:57:56.158535Z","end":"2026-04-16T22:57:56.297333Z","steps":["trace[426516561] 'agreement among raft nodes before linearized reading' (duration: 138.653032ms)"],"step_count":1}