{"level":"warn","ts":"2026-03-03T02:52:31.607456Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T02:52:31.607549Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57: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-03-03T02:52:31.607621Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-03T02:52:31.607638Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-03-03T02:52:31.607661Z","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-03-03T02:52:31.608441Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-03-03T02:52:31.60853Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57: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-03-03T02:52:31.612635Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.855014ms"} {"level":"info","ts":"2026-03-03T02:52:31.619356Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-03-03T02:52:31.619427Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T02:52:31.619449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-03-03T02:52:31.619459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T02:52:31.619471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-03-03T02:52:31.61951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-03-03T02:52:31.625416Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T02:52:31.626723Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T02:52:31.627842Z","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-03-03T02:52:31.630694Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T02:52:31.630894Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T02:52:31.631088Z","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-03-03T02:52:31.631156Z","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-03-03T02:52:31.631166Z","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-03-03T02:52:31.631791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-03-03T02:52:31.631926Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-03-03T02:52:31.636319Z","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-03-03T02:52:31.636668Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T02:52:31.636729Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T02:52:31.637232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-03-03T02:52:31.637265Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-03-03T02:52:31.720244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T02:52:31.720334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T02:52:31.720385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-03-03T02:52:31.720412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-03-03T02:52:31.720464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-03-03T02:52:31.720498Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-03-03T02:52:31.72052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-03-03T02:52:31.721434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T02:52:31.721495Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T02:52:31.721748Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T02:52:31.722001Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T02:52:31.722042Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T02:52:31.723813Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-03-03T02:52:31.723938Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T02:52:31.725875Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T02:52:31.728371Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T02:52:31.728463Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T02:52:31.728486Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T02:52:36.029694Z","caller":"traceutil/trace.go:171","msg":"trace[979855600] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"178.181676ms","start":"2026-03-03T02:52:35.851488Z","end":"2026-03-03T02:52:36.029669Z","steps":["trace[979855600] 'process raft request' (duration: 136.527333ms)","trace[979855600] 'compare' (duration: 41.573391ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:52:41.350806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.801161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T02:52:41.35092Z","caller":"traceutil/trace.go:171","msg":"trace[1651955040] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"174.395394ms","start":"2026-03-03T02:52:41.176511Z","end":"2026-03-03T02:52:41.350907Z","steps":["trace[1651955040] 'read index received' (duration: 47.145773ms)","trace[1651955040] 'applied index is now lower than readState.Index' (duration: 127.248271ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:52:41.351024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.511146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-03T02:52:41.350976Z","caller":"traceutil/trace.go:171","msg":"trace[2139940931] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"219.260918ms","start":"2026-03-03T02:52:41.131662Z","end":"2026-03-03T02:52:41.350923Z","steps":["trace[2139940931] 'process raft request' (duration: 92.008118ms)","trace[2139940931] 'compare' (duration: 126.683888ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:52:41.351062Z","caller":"traceutil/trace.go:171","msg":"trace[1348292145] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:374; }","duration":"174.594108ms","start":"2026-03-03T02:52:41.17646Z","end":"2026-03-03T02:52:41.351055Z","steps":["trace[1348292145] 'agreement among raft nodes before linearized reading' (duration: 174.479986ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:47.639126Z","caller":"traceutil/trace.go:171","msg":"trace[2016850931] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"445.08859ms","start":"2026-03-03T02:52:47.193986Z","end":"2026-03-03T02:52:47.639074Z","steps":["trace[2016850931] 'read index received' (duration: 444.6706ms)","trace[2016850931] 'applied index is now lower than readState.Index' (duration: 416.35µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:52:47.63958Z","caller":"traceutil/trace.go:171","msg":"trace[1617108988] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"885.269422ms","start":"2026-03-03T02:52:46.754273Z","end":"2026-03-03T02:52:47.639543Z","steps":["trace[1617108988] 'process raft request' (duration: 884.447864ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:47.640676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:46.754246Z","time spent":"885.400265ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:52:47.644232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.388031ms","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-03-03T02:52:47.644273Z","caller":"traceutil/trace.go:171","msg":"trace[1423239155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"208.471973ms","start":"2026-03-03T02:52:47.435789Z","end":"2026-03-03T02:52:47.644261Z","steps":["trace[1423239155] 'agreement among raft nodes before linearized reading' (duration: 203.915205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:47.64427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.434593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-03T02:52:47.644291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.958761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-03-03T02:52:47.644331Z","caller":"traceutil/trace.go:171","msg":"trace[968982450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:411; }","duration":"208.543366ms","start":"2026-03-03T02:52:47.435772Z","end":"2026-03-03T02:52:47.644315Z","steps":["trace[968982450] 'agreement among raft nodes before linearized reading' (duration: 205.824487ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:47.644371Z","caller":"traceutil/trace.go:171","msg":"trace[170648700] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:411; }","duration":"407.091195ms","start":"2026-03-03T02:52:47.237261Z","end":"2026-03-03T02:52:47.644352Z","steps":["trace[170648700] 'agreement among raft nodes before linearized reading' (duration: 402.137187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:47.644412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:47.237239Z","time spent":"407.164086ms","remote":"127.0.0.1:34810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4391,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-03T02:52:47.644424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.369394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:52:47.644531Z","caller":"traceutil/trace.go:171","msg":"trace[1579909568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"450.547367ms","start":"2026-03-03T02:52:47.193954Z","end":"2026-03-03T02:52:47.644501Z","steps":["trace[1579909568] 'agreement among raft nodes before linearized reading' (duration: 445.421206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:47.644601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:47.193941Z","time spent":"450.64716ms","remote":"127.0.0.1:34646","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-03-03T02:52:48.065604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.203019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T02:52:48.065709Z","caller":"traceutil/trace.go:171","msg":"trace[571563678] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"418.234527ms","start":"2026-03-03T02:52:47.647456Z","end":"2026-03-03T02:52:48.06569Z","steps":["trace[571563678] 'process raft request' (duration: 120.847873ms)","trace[571563678] 'compare' (duration: 296.908303ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:52:48.065753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:47.647445Z","time spent":"418.287577ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:52:48.392645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101459387942555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T02:52:48.893582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101459387942555,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T02:52:48.933122Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.164746347s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T02:52:48.933655Z","caller":"traceutil/trace.go:171","msg":"trace[2057701257] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"1.283018983s","start":"2026-03-03T02:52:47.650606Z","end":"2026-03-03T02:52:48.933625Z","steps":["trace[2057701257] 'process raft request' (duration: 1.282780288s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:48.933796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:47.650588Z","time spent":"1.283131616s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:52:48.933959Z","caller":"traceutil/trace.go:171","msg":"trace[116411682] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.283232208s","start":"2026-03-03T02:52:47.650704Z","end":"2026-03-03T02:52:48.933936Z","steps":["trace[116411682] 'process raft request' (duration: 1.28285961s)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:48.934047Z","caller":"traceutil/trace.go:171","msg":"trace[493432701] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:424; }","duration":"1.042389491s","start":"2026-03-03T02:52:47.891633Z","end":"2026-03-03T02:52:48.934022Z","steps":["trace[493432701] 'read index received' (duration: 1.041618504s)","trace[493432701] 'applied index is now lower than readState.Index' (duration: 769.767µs)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:52:48.934097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:47.650686Z","time spent":"1.28332488s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:52:48.934169Z","caller":"traceutil/trace.go:171","msg":"trace[4883670] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.280259463s","start":"2026-03-03T02:52:47.653886Z","end":"2026-03-03T02:52:48.934145Z","steps":["trace[4883670] 'process raft request' (duration: 1.279950557s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:48.934284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:47.653877Z","time spent":"1.280352885s","remote":"127.0.0.1:34810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4360,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:52:48.934292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042565765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-03T02:52:48.9343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.991706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-03-03T02:52:48.934346Z","caller":"traceutil/trace.go:171","msg":"trace[1471406389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"1.042742178s","start":"2026-03-03T02:52:47.891591Z","end":"2026-03-03T02:52:48.934334Z","steps":["trace[1471406389] 'agreement among raft nodes before linearized reading' (duration: 1.042565475s)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:52:48.934363Z","caller":"traceutil/trace.go:171","msg":"trace[1692486858] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:415; }","duration":"650.086678ms","start":"2026-03-03T02:52:48.284263Z","end":"2026-03-03T02:52:48.934349Z","steps":["trace[1692486858] 'agreement among raft nodes before linearized reading' (duration: 649.935524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:48.934392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:47.891571Z","time spent":"1.04281051s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T02:52:48.934399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:48.284244Z","time spent":"650.14832ms","remote":"127.0.0.1:34822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6984,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-03T02:52:49.856679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.159614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T02:52:49.856895Z","caller":"traceutil/trace.go:171","msg":"trace[927943316] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"663.258033ms","start":"2026-03-03T02:52:49.193587Z","end":"2026-03-03T02:52:49.856845Z","steps":["trace[927943316] 'read index received' (duration: 338.865404ms)","trace[927943316] 'applied index is now lower than readState.Index' (duration: 324.391709ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:52:49.856959Z","caller":"traceutil/trace.go:171","msg":"trace[1870268564] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"917.222534ms","start":"2026-03-03T02:52:48.939711Z","end":"2026-03-03T02:52:49.856933Z","steps":["trace[1870268564] 'process raft request' (duration: 592.724933ms)","trace[1870268564] 'compare' (duration: 324.011801ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:52:49.856985Z","caller":"traceutil/trace.go:171","msg":"trace[841199344] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"909.028077ms","start":"2026-03-03T02:52:48.947912Z","end":"2026-03-03T02:52:49.85694Z","steps":["trace[841199344] 'process raft request' (duration: 908.866553ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:49.857044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.453067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:52:49.857087Z","caller":"traceutil/trace.go:171","msg":"trace[1022840567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:417; }","duration":"663.522269ms","start":"2026-03-03T02:52:49.193555Z","end":"2026-03-03T02:52:49.857077Z","steps":["trace[1022840567] 'agreement among raft nodes before linearized reading' (duration: 663.419726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:49.857102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:48.939673Z","time spent":"917.353007ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:52:49.857127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:49.193541Z","time spent":"663.578259ms","remote":"127.0.0.1:34646","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-03-03T02:52:49.857132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:48.947904Z","time spent":"909.144509ms","remote":"127.0.0.1:34822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:52:49.857326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.074887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-03-03T02:52:49.857428Z","caller":"traceutil/trace.go:171","msg":"trace[1515528261] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:417; }","duration":"321.206881ms","start":"2026-03-03T02:52:49.536198Z","end":"2026-03-03T02:52:49.857404Z","steps":["trace[1515528261] 'agreement among raft nodes before linearized reading' (duration: 321.048526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:49.857495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:49.536175Z","time spent":"321.305743ms","remote":"127.0.0.1:34810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-03T02:52:54.262991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.516923ms","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-03-03T02:52:54.263094Z","caller":"traceutil/trace.go:171","msg":"trace[1519313456] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:456; }","duration":"174.731617ms","start":"2026-03-03T02:52:54.088342Z","end":"2026-03-03T02:52:54.263074Z","steps":["trace[1519313456] 'range keys from in-memory index tree' (duration: 174.42094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:54.263175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.224868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T02:52:54.263249Z","caller":"traceutil/trace.go:171","msg":"trace[376649812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:456; }","duration":"394.338332ms","start":"2026-03-03T02:52:53.86889Z","end":"2026-03-03T02:52:54.263229Z","steps":["trace[376649812] 'range keys from in-memory index tree' (duration: 394.135976ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:54.263288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:53.868848Z","time spent":"394.432284ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T02:52:54.569375Z","caller":"traceutil/trace.go:171","msg":"trace[870461131] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"301.278858ms","start":"2026-03-03T02:52:54.268068Z","end":"2026-03-03T02:52:54.569347Z","steps":["trace[870461131] 'process raft request' (duration: 301.104593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:54.569545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:54.268044Z","time spent":"301.40089ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:52:54.572793Z","caller":"traceutil/trace.go:171","msg":"trace[661769956] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"304.180102ms","start":"2026-03-03T02:52:54.268594Z","end":"2026-03-03T02:52:54.572774Z","steps":["trace[661769956] 'process raft request' (duration: 304.07676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:52:54.572949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:52:54.268558Z","time spent":"304.295554ms","remote":"127.0.0.1:34872","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-03-03T02:53:04.692249Z","caller":"traceutil/trace.go:171","msg":"trace[1299046201] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"217.542359ms","start":"2026-03-03T02:53:04.474687Z","end":"2026-03-03T02:53:04.692229Z","steps":["trace[1299046201] 'process raft request' (duration: 206.557477ms)","trace[1299046201] 'compare' (duration: 10.843589ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:04.851258Z","caller":"traceutil/trace.go:171","msg":"trace[1070779861] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"159.054683ms","start":"2026-03-03T02:53:04.692185Z","end":"2026-03-03T02:53:04.85124Z","steps":["trace[1070779861] 'read index received' (duration: 159.027093ms)","trace[1070779861] 'applied index is now lower than readState.Index' (duration: 26.97µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:04.851345Z","caller":"traceutil/trace.go:171","msg":"trace[323963336] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"192.479298ms","start":"2026-03-03T02:53:04.658841Z","end":"2026-03-03T02:53:04.85132Z","steps":["trace[323963336] 'process raft request' (duration: 192.303934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:04.851406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.268231ms","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-03-03T02:53:04.851437Z","caller":"traceutil/trace.go:171","msg":"trace[2094751291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"232.342133ms","start":"2026-03-03T02:53:04.619085Z","end":"2026-03-03T02:53:04.851427Z","steps":["trace[2094751291] 'agreement among raft nodes before linearized reading' (duration: 232.245761ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:04.859703Z","caller":"traceutil/trace.go:171","msg":"trace[520542931] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"162.929649ms","start":"2026-03-03T02:53:04.696754Z","end":"2026-03-03T02:53:04.859683Z","steps":["trace[520542931] 'process raft request' (duration: 162.845117ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:05.452412Z","caller":"traceutil/trace.go:171","msg":"trace[1968851171] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"437.620382ms","start":"2026-03-03T02:53:05.014747Z","end":"2026-03-03T02:53:05.452368Z","steps":["trace[1968851171] 'process raft request' (duration: 437.473729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:05.452671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:05.014736Z","time spent":"437.831736ms","remote":"127.0.0.1:34872","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-03-03T02:53:05.88016Z","caller":"traceutil/trace.go:171","msg":"trace[503460948] linearizableReadLoop","detail":"{readStateIndex:563; appliedIndex:560; }","duration":"686.958627ms","start":"2026-03-03T02:53:05.193179Z","end":"2026-03-03T02:53:05.880138Z","steps":["trace[503460948] 'read index received' (duration: 259.072539ms)","trace[503460948] 'applied index is now lower than readState.Index' (duration: 427.885198ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:05.880439Z","caller":"traceutil/trace.go:171","msg":"trace[1775677508] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"865.429018ms","start":"2026-03-03T02:53:05.014999Z","end":"2026-03-03T02:53:05.880428Z","steps":["trace[1775677508] 'process raft request' (duration: 864.826735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:05.880534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:05.014985Z","time spent":"865.481099ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:53:05.880742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.572881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:53:05.880751Z","caller":"traceutil/trace.go:171","msg":"trace[678390515] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"621.819987ms","start":"2026-03-03T02:53:05.258894Z","end":"2026-03-03T02:53:05.880714Z","steps":["trace[678390515] 'process raft request' (duration: 621.214264ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:05.880769Z","caller":"traceutil/trace.go:171","msg":"trace[1822389856] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"809.317145ms","start":"2026-03-03T02:53:05.07142Z","end":"2026-03-03T02:53:05.880737Z","steps":["trace[1822389856] 'process raft request' (duration: 808.62438ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:05.88078Z","caller":"traceutil/trace.go:171","msg":"trace[1000821162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"687.651773ms","start":"2026-03-03T02:53:05.193118Z","end":"2026-03-03T02:53:05.88077Z","steps":["trace[1000821162] 'agreement among raft nodes before linearized reading' (duration: 687.597832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:05.880901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:05.071384Z","time spent":"809.445338ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T02:53:05.880922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:05.1931Z","time spent":"687.810556ms","remote":"127.0.0.1:34656","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-03-03T02:53:05.881227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:05.258876Z","time spent":"621.94754ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T02:53:05.889314Z","caller":"traceutil/trace.go:171","msg":"trace[435617788] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"429.323009ms","start":"2026-03-03T02:53:05.459957Z","end":"2026-03-03T02:53:05.88928Z","steps":["trace[435617788] 'process raft request' (duration: 429.125925ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:05.889355Z","caller":"traceutil/trace.go:171","msg":"trace[973867974] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"227.556517ms","start":"2026-03-03T02:53:05.661785Z","end":"2026-03-03T02:53:05.889342Z","steps":["trace[973867974] 'process raft request' (duration: 227.443455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:05.889426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:05.459936Z","time spent":"429.436242ms","remote":"127.0.0.1:34700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T02:53:10.221514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.981207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-03T02:53:10.221586Z","caller":"traceutil/trace.go:171","msg":"trace[114031342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:575; }","duration":"121.10499ms","start":"2026-03-03T02:53:10.100467Z","end":"2026-03-03T02:53:10.221572Z","steps":["trace[114031342] 'range keys from in-memory index tree' (duration: 120.808103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:10.221854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.221148ms","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-03-03T02:53:10.221884Z","caller":"traceutil/trace.go:171","msg":"trace[196618666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"323.28368ms","start":"2026-03-03T02:53:09.898591Z","end":"2026-03-03T02:53:10.221875Z","steps":["trace[196618666] 'range keys from in-memory index tree' (duration: 323.075516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:10.221904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:09.898574Z","time spent":"323.323511ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T02:53:13.223068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.364734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T02:53:13.2232Z","caller":"traceutil/trace.go:171","msg":"trace[1906531769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"339.515267ms","start":"2026-03-03T02:53:12.883646Z","end":"2026-03-03T02:53:13.223162Z","steps":["trace[1906531769] 'range keys from in-memory index tree' (duration: 339.16234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:13.223192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.015447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5ftdh\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-03-03T02:53:13.223243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:12.883593Z","time spent":"339.64091ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T02:53:13.223276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.540152ms","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-03-03T02:53:13.22327Z","caller":"traceutil/trace.go:171","msg":"trace[953789462] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5ftdh; range_end:; response_count:1; response_revision:585; }","duration":"375.15011ms","start":"2026-03-03T02:53:12.8481Z","end":"2026-03-03T02:53:13.223251Z","steps":["trace[953789462] 'range keys from in-memory index tree' (duration: 374.838503ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:13.223336Z","caller":"traceutil/trace.go:171","msg":"trace[754408737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"331.622483ms","start":"2026-03-03T02:53:12.8917Z","end":"2026-03-03T02:53:13.223322Z","steps":["trace[754408737] 'range keys from in-memory index tree' (duration: 331.43705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:13.223399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:12.848082Z","time spent":"375.307403ms","remote":"127.0.0.1:34822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-5ftdh\" "} {"level":"warn","ts":"2026-03-03T02:53:13.223443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:12.89169Z","time spent":"331.743196ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T02:53:14.315552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.3968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:53:14.315697Z","caller":"traceutil/trace.go:171","msg":"trace[1134074751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"123.580324ms","start":"2026-03-03T02:53:14.192071Z","end":"2026-03-03T02:53:14.315651Z","steps":["trace[1134074751] 'range keys from in-memory index tree' (duration: 123.337639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:15.371608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.737491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-03-03T02:53:15.371918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.163716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T02:53:15.372002Z","caller":"traceutil/trace.go:171","msg":"trace[421421656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"108.300429ms","start":"2026-03-03T02:53:15.263682Z","end":"2026-03-03T02:53:15.371983Z","steps":["trace[421421656] 'range keys from in-memory index tree' (duration: 108.002151ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:15.371906Z","caller":"traceutil/trace.go:171","msg":"trace[724843310] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:592; }","duration":"589.092079ms","start":"2026-03-03T02:53:14.782794Z","end":"2026-03-03T02:53:15.371886Z","steps":["trace[724843310] 'range keys from in-memory index tree' (duration: 588.535206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:15.372309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:14.782773Z","time spent":"589.520199ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-03-03T02:53:16.048558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101459387942935,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T02:53:16.126299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:15.374156Z","time spent":"752.13835ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-03T02:53:16.142523Z","caller":"traceutil/trace.go:171","msg":"trace[1549280325] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:610; }","duration":"594.460287ms","start":"2026-03-03T02:53:15.548041Z","end":"2026-03-03T02:53:16.142502Z","steps":["trace[1549280325] 'read index received' (duration: 578.212111ms)","trace[1549280325] 'applied index is now lower than readState.Index' (duration: 16.247336ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:16.142584Z","caller":"traceutil/trace.go:171","msg":"trace[970015172] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"764.260516ms","start":"2026-03-03T02:53:15.378309Z","end":"2026-03-03T02:53:16.14257Z","steps":["trace[970015172] 'process raft request' (duration: 764.150913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:16.142682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:15.378283Z","time spent":"764.339508ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:53:16.142725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.665332ms","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-03-03T02:53:16.142778Z","caller":"traceutil/trace.go:171","msg":"trace[1322958738] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"594.794765ms","start":"2026-03-03T02:53:15.547972Z","end":"2026-03-03T02:53:16.142767Z","steps":["trace[1322958738] 'agreement among raft nodes before linearized reading' (duration: 594.60534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:16.142829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:15.547941Z","time spent":"594.879897ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T02:53:16.142678Z","caller":"traceutil/trace.go:171","msg":"trace[1872996080] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"765.495353ms","start":"2026-03-03T02:53:15.377056Z","end":"2026-03-03T02:53:16.142552Z","steps":["trace[1872996080] 'process raft request' (duration: 765.262848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:16.143017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:15.377034Z","time spent":"765.898802ms","remote":"127.0.0.1:34872","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-03-03T02:53:26.433126Z","caller":"traceutil/trace.go:171","msg":"trace[648104466] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"241.846621ms","start":"2026-03-03T02:53:26.19125Z","end":"2026-03-03T02:53:26.433097Z","steps":["trace[648104466] 'process raft request' (duration: 241.740779ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:26.452647Z","caller":"traceutil/trace.go:171","msg":"trace[109034461] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"257.084357ms","start":"2026-03-03T02:53:26.19554Z","end":"2026-03-03T02:53:26.452624Z","steps":["trace[109034461] 'read index received' (duration: 237.494006ms)","trace[109034461] 'applied index is now lower than readState.Index' (duration: 19.589511ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:26.452718Z","caller":"traceutil/trace.go:171","msg":"trace[636890179] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"261.457833ms","start":"2026-03-03T02:53:26.19122Z","end":"2026-03-03T02:53:26.452678Z","steps":["trace[636890179] 'process raft request' (duration: 258.726633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:26.452805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.248471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:53:26.452845Z","caller":"traceutil/trace.go:171","msg":"trace[1915684435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"257.322282ms","start":"2026-03-03T02:53:26.195512Z","end":"2026-03-03T02:53:26.452834Z","steps":["trace[1915684435] 'agreement among raft nodes before linearized reading' (duration: 257.24013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:26.45301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.560495ms","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-03-03T02:53:26.453084Z","caller":"traceutil/trace.go:171","msg":"trace[1287773030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"220.665767ms","start":"2026-03-03T02:53:26.2324Z","end":"2026-03-03T02:53:26.453065Z","steps":["trace[1287773030] 'agreement among raft nodes before linearized reading' (duration: 220.446832ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:32.98717Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101459387943175,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T02:53:33.085309Z","caller":"traceutil/trace.go:171","msg":"trace[1906787338] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"598.917634ms","start":"2026-03-03T02:53:32.486356Z","end":"2026-03-03T02:53:33.085274Z","steps":["trace[1906787338] 'read index received' (duration: 598.604807ms)","trace[1906787338] 'applied index is now lower than readState.Index' (duration: 311.677µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:33.085506Z","caller":"traceutil/trace.go:171","msg":"trace[1144690304] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"629.14092ms","start":"2026-03-03T02:53:32.45631Z","end":"2026-03-03T02:53:33.08545Z","steps":["trace[1144690304] 'process raft request' (duration: 628.75691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:33.085681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.628383ms","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-03-03T02:53:33.085724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.316894ms","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-03-03T02:53:33.085793Z","caller":"traceutil/trace.go:171","msg":"trace[690774335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"599.496988ms","start":"2026-03-03T02:53:32.486283Z","end":"2026-03-03T02:53:33.08578Z","steps":["trace[690774335] 'agreement among raft nodes before linearized reading' (duration: 599.159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:33.085866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:32.486254Z","time spent":"599.60274ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T02:53:33.085998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.444773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T02:53:33.086034Z","caller":"traceutil/trace.go:171","msg":"trace[933789376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:727; }","duration":"597.508645ms","start":"2026-03-03T02:53:32.488516Z","end":"2026-03-03T02:53:33.086025Z","steps":["trace[933789376] 'agreement among raft nodes before linearized reading' (duration: 597.25375ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:33.08605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.491584ms","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-03-03T02:53:33.086099Z","caller":"traceutil/trace.go:171","msg":"trace[1173778441] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"597.587686ms","start":"2026-03-03T02:53:32.488499Z","end":"2026-03-03T02:53:33.086087Z","steps":["trace[1173778441] 'agreement among raft nodes before linearized reading' (duration: 597.368852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:33.086133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:32.48848Z","time spent":"597.646358ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T02:53:33.086242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:32.4885Z","time spent":"597.73287ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-03T02:53:33.085799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:32.456293Z","time spent":"629.371114ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:53:33.085726Z","caller":"traceutil/trace.go:171","msg":"trace[1193518854] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:727; }","duration":"325.677494ms","start":"2026-03-03T02:53:32.76003Z","end":"2026-03-03T02:53:33.085707Z","steps":["trace[1193518854] 'agreement among raft nodes before linearized reading' (duration: 325.601863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:33.086357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:32.760024Z","time spent":"326.320348ms","remote":"127.0.0.1:34860","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-03-03T02:53:33.085603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.191079ms","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-03-03T02:53:33.086517Z","caller":"traceutil/trace.go:171","msg":"trace[264119573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"599.15033ms","start":"2026-03-03T02:53:32.487356Z","end":"2026-03-03T02:53:33.086506Z","steps":["trace[264119573] 'agreement among raft nodes before linearized reading' (duration: 598.132598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:33.08657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:32.487346Z","time spent":"599.215832ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T02:53:34.238964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.447755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T02:53:34.239157Z","caller":"traceutil/trace.go:171","msg":"trace[1270120045] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"1.146976931s","start":"2026-03-03T02:53:33.092165Z","end":"2026-03-03T02:53:34.239142Z","steps":["trace[1270120045] 'process raft request' (duration: 1.146894649s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:34.239227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:33.092141Z","time spent":"1.147056423s","remote":"127.0.0.1:34872","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-03-03T02:53:34.239331Z","caller":"traceutil/trace.go:171","msg":"trace[2094370591] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.147180156s","start":"2026-03-03T02:53:33.092141Z","end":"2026-03-03T02:53:34.239321Z","steps":["trace[2094370591] 'process raft request' (duration: 700.302652ms)","trace[2094370591] 'compare' (duration: 446.335352ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:53:34.239376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:33.092115Z","time spent":"1.147240538s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:53:34.239623Z","caller":"traceutil/trace.go:171","msg":"trace[1292538330] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.147370801s","start":"2026-03-03T02:53:33.092219Z","end":"2026-03-03T02:53:34.23959Z","steps":["trace[1292538330] 'process raft request' (duration: 1.146892519s)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:34.239809Z","caller":"traceutil/trace.go:171","msg":"trace[927554730] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:751; }","duration":"593.617138ms","start":"2026-03-03T02:53:33.646181Z","end":"2026-03-03T02:53:34.239798Z","steps":["trace[927554730] 'read index received' (duration: 146.276723ms)","trace[927554730] 'applied index is now lower than readState.Index' (duration: 447.339565ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:53:34.239861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:33.092188Z","time spent":"1.147557745s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:53:34.239932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.746871ms","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":"info","ts":"2026-03-03T02:53:34.239967Z","caller":"traceutil/trace.go:171","msg":"trace[145417244] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:731; }","duration":"593.820053ms","start":"2026-03-03T02:53:33.646139Z","end":"2026-03-03T02:53:34.239959Z","steps":["trace[145417244] 'agreement among raft nodes before linearized reading' (duration: 593.749381ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:34.239962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.120574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T02:53:34.239992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:33.646117Z","time spent":"593.867753ms","remote":"127.0.0.1:34868","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":"info","ts":"2026-03-03T02:53:34.240004Z","caller":"traceutil/trace.go:171","msg":"trace[1549876559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"220.199846ms","start":"2026-03-03T02:53:34.019791Z","end":"2026-03-03T02:53:34.239991Z","steps":["trace[1549876559] 'agreement among raft nodes before linearized reading' (duration: 220.137195ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:34.240152Z","caller":"traceutil/trace.go:171","msg":"trace[746670587] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"1.146528803s","start":"2026-03-03T02:53:33.093363Z","end":"2026-03-03T02:53:34.239892Z","steps":["trace[746670587] 'process raft request' (duration: 1.146112553s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:34.240307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:33.093338Z","time spent":"1.146896121s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:53:34.789086Z","caller":"traceutil/trace.go:171","msg":"trace[553854310] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"149.319341ms","start":"2026-03-03T02:53:34.639724Z","end":"2026-03-03T02:53:34.789043Z","steps":["trace[553854310] 'process raft request' (duration: 148.885742ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:35.457597Z","caller":"traceutil/trace.go:171","msg":"trace[745008425] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"338.048345ms","start":"2026-03-03T02:53:35.119524Z","end":"2026-03-03T02:53:35.457572Z","steps":["trace[745008425] 'read index received' (duration: 337.80884ms)","trace[745008425] 'applied index is now lower than readState.Index' (duration: 238.475µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:35.457678Z","caller":"traceutil/trace.go:171","msg":"trace[699895922] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"359.874935ms","start":"2026-03-03T02:53:35.097769Z","end":"2026-03-03T02:53:35.457644Z","steps":["trace[699895922] 'process raft request' (duration: 359.65064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:35.457809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.348418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T02:53:35.457895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.30308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T02:53:35.457928Z","caller":"traceutil/trace.go:171","msg":"trace[1453972093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"265.565792ms","start":"2026-03-03T02:53:35.192351Z","end":"2026-03-03T02:53:35.457917Z","steps":["trace[1453972093] 'agreement among raft nodes before linearized reading' (duration: 265.359648ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:35.457963Z","caller":"traceutil/trace.go:171","msg":"trace[1091761351] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:734; }","duration":"338.447603ms","start":"2026-03-03T02:53:35.119499Z","end":"2026-03-03T02:53:35.457947Z","steps":["trace[1091761351] 'agreement among raft nodes before linearized reading' (duration: 338.198498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:35.458011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:35.119488Z","time spent":"338.507704ms","remote":"127.0.0.1:35054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-03-03T02:53:35.458177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:35.097758Z","time spent":"360.161131ms","remote":"127.0.0.1:34872","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-03-03T02:53:36.357579Z","caller":"traceutil/trace.go:171","msg":"trace[1402008799] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"109.70496ms","start":"2026-03-03T02:53:36.247855Z","end":"2026-03-03T02:53:36.35756Z","steps":["trace[1402008799] 'process raft request' (duration: 109.600898ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:36.395175Z","caller":"traceutil/trace.go:171","msg":"trace[944649356] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"146.712442ms","start":"2026-03-03T02:53:36.248442Z","end":"2026-03-03T02:53:36.395155Z","steps":["trace[944649356] 'process raft request' (duration: 146.441466ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:36.395182Z","caller":"traceutil/trace.go:171","msg":"trace[2039450365] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"145.110588ms","start":"2026-03-03T02:53:36.250041Z","end":"2026-03-03T02:53:36.395152Z","steps":["trace[2039450365] 'process raft request' (duration: 145.053007ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:36.395303Z","caller":"traceutil/trace.go:171","msg":"trace[376064882] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"146.693212ms","start":"2026-03-03T02:53:36.2486Z","end":"2026-03-03T02:53:36.395293Z","steps":["trace[376064882] 'process raft request' (duration: 146.442296ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:37.803368Z","caller":"traceutil/trace.go:171","msg":"trace[926327913] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"326.272836ms","start":"2026-03-03T02:53:37.477076Z","end":"2026-03-03T02:53:37.803349Z","steps":["trace[926327913] 'process raft request' (duration: 326.156624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:37.803572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:37.477049Z","time spent":"326.44431ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:53:37.921489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:37.553128Z","time spent":"368.354552ms","remote":"127.0.0.1:34720","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-03T02:53:38.084226Z","caller":"traceutil/trace.go:171","msg":"trace[1966512553] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"161.411376ms","start":"2026-03-03T02:53:37.922792Z","end":"2026-03-03T02:53:38.084203Z","steps":["trace[1966512553] 'process raft request' (duration: 154.230727ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:38.358807Z","caller":"traceutil/trace.go:171","msg":"trace[1032685980] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"162.835757ms","start":"2026-03-03T02:53:38.195952Z","end":"2026-03-03T02:53:38.358788Z","steps":["trace[1032685980] 'process raft request' (duration: 162.645483ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:56.784669Z","caller":"traceutil/trace.go:171","msg":"trace[318657305] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"139.141455ms","start":"2026-03-03T02:53:56.645505Z","end":"2026-03-03T02:53:56.784647Z","steps":["trace[318657305] 'read index received' (duration: 138.855218ms)","trace[318657305] 'applied index is now lower than readState.Index' (duration: 284.556µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:56.784903Z","caller":"traceutil/trace.go:171","msg":"trace[2094372647] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"202.035917ms","start":"2026-03-03T02:53:56.582809Z","end":"2026-03-03T02:53:56.784845Z","steps":["trace[2094372647] 'process raft request' (duration: 201.603907ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:56.785147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.502544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-03T02:53:56.785189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.648867ms","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-03-03T02:53:56.785271Z","caller":"traceutil/trace.go:171","msg":"trace[851682950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"139.7831ms","start":"2026-03-03T02:53:56.645468Z","end":"2026-03-03T02:53:56.785252Z","steps":["trace[851682950] 'agreement among raft nodes before linearized reading' (duration: 139.530204ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:56.785264Z","caller":"traceutil/trace.go:171","msg":"trace[1179747923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"139.637866ms","start":"2026-03-03T02:53:56.6456Z","end":"2026-03-03T02:53:56.785238Z","steps":["trace[1179747923] 'agreement among raft nodes before linearized reading' (duration: 139.36143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:56.785573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.421721ms","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-03-03T02:53:56.785657Z","caller":"traceutil/trace.go:171","msg":"trace[181409100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"128.539773ms","start":"2026-03-03T02:53:56.657104Z","end":"2026-03-03T02:53:56.785644Z","steps":["trace[181409100] 'agreement among raft nodes before linearized reading' (duration: 128.279497ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:57.509577Z","caller":"traceutil/trace.go:171","msg":"trace[1603066022] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"719.948605ms","start":"2026-03-03T02:53:56.789599Z","end":"2026-03-03T02:53:57.509548Z","steps":["trace[1603066022] 'process raft request' (duration: 719.794792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:57.509756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:56.789577Z","time spent":"720.091927ms","remote":"127.0.0.1:34872","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-03-03T02:53:57.533254Z","caller":"traceutil/trace.go:171","msg":"trace[1759668058] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:828; }","duration":"340.232964ms","start":"2026-03-03T02:53:57.193005Z","end":"2026-03-03T02:53:57.533238Z","steps":["trace[1759668058] 'read index received' (duration: 316.473571ms)","trace[1759668058] 'applied index is now lower than readState.Index' (duration: 23.758233ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:57.533285Z","caller":"traceutil/trace.go:171","msg":"trace[416306596] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"743.178026ms","start":"2026-03-03T02:53:56.790054Z","end":"2026-03-03T02:53:57.533232Z","steps":["trace[416306596] 'process raft request' (duration: 742.938061ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:53:57.53337Z","caller":"traceutil/trace.go:171","msg":"trace[843076592] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"743.258718ms","start":"2026-03-03T02:53:56.790102Z","end":"2026-03-03T02:53:57.53336Z","steps":["trace[843076592] 'process raft request' (duration: 743.029993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:57.533441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:56.790078Z","time spent":"743.323658ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:53:57.533331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.329746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:53:57.533513Z","caller":"traceutil/trace.go:171","msg":"trace[1372559730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"340.55361ms","start":"2026-03-03T02:53:57.192941Z","end":"2026-03-03T02:53:57.533495Z","steps":["trace[1372559730] 'agreement among raft nodes before linearized reading' (duration: 340.357946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:57.533601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:57.19292Z","time spent":"340.665503ms","remote":"127.0.0.1:34646","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-03-03T02:53:57.533713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:56.790042Z","time spent":"743.346179ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:53:59.134293Z","caller":"traceutil/trace.go:171","msg":"trace[537463446] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"200.340422ms","start":"2026-03-03T02:53:58.933923Z","end":"2026-03-03T02:53:59.134263Z","steps":["trace[537463446] 'read index received' (duration: 200.047996ms)","trace[537463446] 'applied index is now lower than readState.Index' (duration: 290.896µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:59.134428Z","caller":"traceutil/trace.go:171","msg":"trace[2140909122] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"341.821466ms","start":"2026-03-03T02:53:58.792562Z","end":"2026-03-03T02:53:59.134383Z","steps":["trace[2140909122] 'process raft request' (duration: 341.506199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:59.134484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.527496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T02:53:59.134573Z","caller":"traceutil/trace.go:171","msg":"trace[1994313168] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:803; }","duration":"200.669409ms","start":"2026-03-03T02:53:58.933883Z","end":"2026-03-03T02:53:59.134553Z","steps":["trace[1994313168] 'agreement among raft nodes before linearized reading' (duration: 200.489735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:59.134632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:58.792532Z","time spent":"341.997509ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:53:59.946777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.062015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:53:59.946876Z","caller":"traceutil/trace.go:171","msg":"trace[1175132198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"752.219079ms","start":"2026-03-03T02:53:59.194635Z","end":"2026-03-03T02:53:59.946854Z","steps":["trace[1175132198] 'range keys from in-memory index tree' (duration: 751.956443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:59.946917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:59.194613Z","time spent":"752.29493ms","remote":"127.0.0.1:34656","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-03-03T02:53:59.947067Z","caller":"traceutil/trace.go:171","msg":"trace[1385689575] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"431.508577ms","start":"2026-03-03T02:53:59.51554Z","end":"2026-03-03T02:53:59.947049Z","steps":["trace[1385689575] 'read index received' (duration: 334.824239ms)","trace[1385689575] 'applied index is now lower than readState.Index' (duration: 96.683278ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:53:59.947127Z","caller":"traceutil/trace.go:171","msg":"trace[274277093] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"587.094346ms","start":"2026-03-03T02:53:59.359993Z","end":"2026-03-03T02:53:59.947087Z","steps":["trace[274277093] 'process raft request' (duration: 490.48237ms)","trace[274277093] 'compare' (duration: 96.409971ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:53:59.947264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.723122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-03T02:53:59.947279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.460963ms","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-03-03T02:53:59.947296Z","caller":"traceutil/trace.go:171","msg":"trace[481312789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"431.793133ms","start":"2026-03-03T02:53:59.515494Z","end":"2026-03-03T02:53:59.947287Z","steps":["trace[481312789] 'agreement among raft nodes before linearized reading' (duration: 431.65358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:59.947325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:59.515478Z","time spent":"431.839624ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-03T02:53:59.947319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:59.359966Z","time spent":"587.242769ms","remote":"127.0.0.1:34872","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-03-03T02:53:59.947311Z","caller":"traceutil/trace.go:171","msg":"trace[52149542] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:804; }","duration":"265.535155ms","start":"2026-03-03T02:53:59.681767Z","end":"2026-03-03T02:53:59.947302Z","steps":["trace[52149542] 'agreement among raft nodes before linearized reading' (duration: 265.468823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:59.947365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.23866ms","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-03-03T02:53:59.947419Z","caller":"traceutil/trace.go:171","msg":"trace[908159966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"410.315671ms","start":"2026-03-03T02:53:59.537086Z","end":"2026-03-03T02:53:59.947402Z","steps":["trace[908159966] 'agreement among raft nodes before linearized reading' (duration: 410.144987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:59.94746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:59.537073Z","time spent":"410.376533ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T02:53:59.947645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.462602ms","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-03-03T02:53:59.947676Z","caller":"traceutil/trace.go:171","msg":"trace[718347154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"409.538665ms","start":"2026-03-03T02:53:59.538127Z","end":"2026-03-03T02:53:59.947666Z","steps":["trace[718347154] 'agreement among raft nodes before linearized reading' (duration: 409.455032ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:53:59.947701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:59.538107Z","time spent":"409.588106ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T02:54:00.476811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.828291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T02:54:00.477264Z","caller":"traceutil/trace.go:171","msg":"trace[1645190676] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"525.011164ms","start":"2026-03-03T02:53:59.952215Z","end":"2026-03-03T02:54:00.477226Z","steps":["trace[1645190676] 'process raft request' (duration: 361.682431ms)","trace[1645190676] 'compare' (duration: 162.724209ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:54:00.477427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:59.952206Z","time spent":"525.141707ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T02:54:00.477686Z","caller":"traceutil/trace.go:171","msg":"trace[482379028] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"523.926769ms","start":"2026-03-03T02:53:59.953722Z","end":"2026-03-03T02:54:00.477649Z","steps":["trace[482379028] 'process raft request' (duration: 523.223803ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:00.477781Z","caller":"traceutil/trace.go:171","msg":"trace[1278860424] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:835; }","duration":"412.869589ms","start":"2026-03-03T02:54:00.064829Z","end":"2026-03-03T02:54:00.477699Z","steps":["trace[1278860424] 'read index received' (duration: 249.152588ms)","trace[1278860424] 'applied index is now lower than readState.Index' (duration: 163.715251ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:54:00.477885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:53:59.95371Z","time spent":"524.070842ms","remote":"127.0.0.1:34872","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-03-03T02:54:00.71068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.636419ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T02:54:00.710927Z","caller":"traceutil/trace.go:171","msg":"trace[2097265833] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"232.962013ms","start":"2026-03-03T02:54:00.477884Z","end":"2026-03-03T02:54:00.710846Z","steps":["trace[2097265833] 'read index received' (duration: 52.341µs)","trace[2097265833] 'applied index is now lower than readState.Index' (duration: 232.908162ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:54:00.710769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.921475ms","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-03-03T02:54:00.71111Z","caller":"traceutil/trace.go:171","msg":"trace[1619916773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:807; }","duration":"646.307034ms","start":"2026-03-03T02:54:00.064781Z","end":"2026-03-03T02:54:00.711088Z","steps":["trace[1619916773] 'agreement among raft nodes before linearized reading' (duration: 413.052413ms)","trace[1619916773] 'range keys from in-memory index tree' (duration: 232.79876ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:54:00.71129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:00.064764Z","time spent":"646.509108ms","remote":"127.0.0.1:34872","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-03-03T02:54:00.711146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.069678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:00.711662Z","caller":"traceutil/trace.go:171","msg":"trace[1414164223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"518.64515ms","start":"2026-03-03T02:54:00.192978Z","end":"2026-03-03T02:54:00.711623Z","steps":["trace[1414164223] 'agreement among raft nodes before linearized reading' (duration: 518.021946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:00.711758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:00.192962Z","time spent":"518.783953ms","remote":"127.0.0.1:34646","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-03-03T02:54:02.604039Z","caller":"traceutil/trace.go:171","msg":"trace[1115396517] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"115.50556ms","start":"2026-03-03T02:54:02.488498Z","end":"2026-03-03T02:54:02.604004Z","steps":["trace[1115396517] 'process raft request' (duration: 115.393648ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:16.295711Z","caller":"traceutil/trace.go:171","msg":"trace[311506737] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"102.12617ms","start":"2026-03-03T02:54:16.193561Z","end":"2026-03-03T02:54:16.295687Z","steps":["trace[311506737] 'read index received' (duration: 101.939146ms)","trace[311506737] 'applied index is now lower than readState.Index' (duration: 185.924µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:16.29584Z","caller":"traceutil/trace.go:171","msg":"trace[41866901] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"216.459314ms","start":"2026-03-03T02:54:16.079373Z","end":"2026-03-03T02:54:16.295832Z","steps":["trace[41866901] 'process raft request' (duration: 216.163847ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:16.296104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.534239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:16.296192Z","caller":"traceutil/trace.go:171","msg":"trace[2002683283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"102.666981ms","start":"2026-03-03T02:54:16.193507Z","end":"2026-03-03T02:54:16.296174Z","steps":["trace[2002683283] 'agreement among raft nodes before linearized reading' (duration: 102.555309ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:20.667248Z","caller":"traceutil/trace.go:171","msg":"trace[1767792898] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"107.130093ms","start":"2026-03-03T02:54:20.560091Z","end":"2026-03-03T02:54:20.667221Z","steps":["trace[1767792898] 'process raft request' (duration: 106.965489ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:24.621915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101459387943662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T02:54:25.094775Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.768171591s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T02:54:25.095154Z","caller":"traceutil/trace.go:171","msg":"trace[194168877] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"973.610573ms","start":"2026-03-03T02:54:24.121519Z","end":"2026-03-03T02:54:25.09513Z","steps":["trace[194168877] 'read index received' (duration: 973.423548ms)","trace[194168877] 'applied index is now lower than readState.Index' (duration: 186.445µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:25.095255Z","caller":"traceutil/trace.go:171","msg":"trace[2003480228] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.768756014s","start":"2026-03-03T02:54:23.326465Z","end":"2026-03-03T02:54:25.095221Z","steps":["trace[2003480228] 'process raft request' (duration: 1.768549769s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.095439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:23.326443Z","time spent":"1.768945167s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:54:25.095667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.7255ms","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-03-03T02:54:25.095741Z","caller":"traceutil/trace.go:171","msg":"trace[1150911797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:924; }","duration":"282.887743ms","start":"2026-03-03T02:54:24.812813Z","end":"2026-03-03T02:54:25.095701Z","steps":["trace[1150911797] 'agreement among raft nodes before linearized reading' (duration: 282.784921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.095433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.641883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:25.095894Z","caller":"traceutil/trace.go:171","msg":"trace[1789911568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"904.196476ms","start":"2026-03-03T02:54:24.19165Z","end":"2026-03-03T02:54:25.095846Z","steps":["trace[1789911568] 'agreement among raft nodes before linearized reading' (duration: 903.731965ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.09556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.789917ms","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-03-03T02:54:25.096085Z","caller":"traceutil/trace.go:171","msg":"trace[594216156] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"273.284578ms","start":"2026-03-03T02:54:24.822737Z","end":"2026-03-03T02:54:25.096021Z","steps":["trace[594216156] 'agreement among raft nodes before linearized reading' (duration: 272.742825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.095567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.225742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-03T02:54:25.096412Z","caller":"traceutil/trace.go:171","msg":"trace[1808936870] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:924; }","duration":"309.083002ms","start":"2026-03-03T02:54:24.787287Z","end":"2026-03-03T02:54:25.096371Z","steps":["trace[1808936870] 'agreement among raft nodes before linearized reading' (duration: 308.200221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.095609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.778528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-03T02:54:25.096571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:24.787264Z","time spent":"309.253995ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.57\" "} {"level":"warn","ts":"2026-03-03T02:54:25.095541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.885226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:25.096759Z","caller":"traceutil/trace.go:171","msg":"trace[2027403033] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:924; }","duration":"285.373639ms","start":"2026-03-03T02:54:24.811371Z","end":"2026-03-03T02:54:25.096744Z","steps":["trace[2027403033] 'agreement among raft nodes before linearized reading' (duration: 284.063179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.095305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.769416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T02:54:25.096928Z","caller":"traceutil/trace.go:171","msg":"trace[1617549406] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:924; }","duration":"975.451224ms","start":"2026-03-03T02:54:24.121457Z","end":"2026-03-03T02:54:25.096908Z","steps":["trace[1617549406] 'agreement among raft nodes before linearized reading' (duration: 973.793046ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.096988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:24.121434Z","time spent":"975.533536ms","remote":"127.0.0.1:34870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-03-03T02:54:25.09664Z","caller":"traceutil/trace.go:171","msg":"trace[1755433018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"743.833941ms","start":"2026-03-03T02:54:24.352793Z","end":"2026-03-03T02:54:25.096627Z","steps":["trace[1755433018] 'agreement among raft nodes before linearized reading' (duration: 742.787258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.097016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.494133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T02:54:25.097052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:24.352757Z","time spent":"744.276131ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T02:54:25.097099Z","caller":"traceutil/trace.go:171","msg":"trace[2076688235] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:924; }","duration":"611.625756ms","start":"2026-03-03T02:54:24.485451Z","end":"2026-03-03T02:54:25.097077Z","steps":["trace[2076688235] 'agreement among raft nodes before linearized reading' (duration: 610.085031ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.09716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:24.485428Z","time spent":"611.715757ms","remote":"127.0.0.1:35116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-03T02:54:25.096271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:24.191629Z","time spent":"904.433702ms","remote":"127.0.0.1:34646","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-03-03T02:54:25.603443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:25.098529Z","time spent":"504.910812ms","remote":"127.0.0.1:34678","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-03T02:54:25.604576Z","caller":"traceutil/trace.go:171","msg":"trace[890782814] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:960; }","duration":"411.777875ms","start":"2026-03-03T02:54:25.192775Z","end":"2026-03-03T02:54:25.604553Z","steps":["trace[890782814] 'read index received' (duration: 410.592399ms)","trace[890782814] 'applied index is now lower than readState.Index' (duration: 1.184576ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:25.604841Z","caller":"traceutil/trace.go:171","msg":"trace[2078280467] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"501.791143ms","start":"2026-03-03T02:54:25.103043Z","end":"2026-03-03T02:54:25.604834Z","steps":["trace[2078280467] 'process raft request' (duration: 501.19653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.604932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:25.103025Z","time spent":"501.850684ms","remote":"127.0.0.1:34872","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-03-03T02:54:25.605051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.399777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-03T02:54:25.605075Z","caller":"traceutil/trace.go:171","msg":"trace[1071327249] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:928; }","duration":"321.461708ms","start":"2026-03-03T02:54:25.283606Z","end":"2026-03-03T02:54:25.605068Z","steps":["trace[1071327249] 'agreement among raft nodes before linearized reading' (duration: 321.421447ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.605091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:25.283589Z","time spent":"321.496088ms","remote":"127.0.0.1:35100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-03-03T02:54:25.605097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.276916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:25.605209Z","caller":"traceutil/trace.go:171","msg":"trace[2024486366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"412.406558ms","start":"2026-03-03T02:54:25.19277Z","end":"2026-03-03T02:54:25.605177Z","steps":["trace[2024486366] 'agreement among raft nodes before linearized reading' (duration: 412.237675ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:25.605249Z","caller":"traceutil/trace.go:171","msg":"trace[1502193580] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"501.449605ms","start":"2026-03-03T02:54:25.103779Z","end":"2026-03-03T02:54:25.605228Z","steps":["trace[1502193580] 'process raft request' (duration: 500.645557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.605337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:25.103771Z","time spent":"501.506727ms","remote":"127.0.0.1:34872","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-03-03T02:54:25.605434Z","caller":"traceutil/trace.go:171","msg":"trace[698852450] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"501.956317ms","start":"2026-03-03T02:54:25.103471Z","end":"2026-03-03T02:54:25.605428Z","steps":["trace[698852450] 'process raft request' (duration: 500.924483ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:25.605439Z","caller":"traceutil/trace.go:171","msg":"trace[810981638] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"501.336912ms","start":"2026-03-03T02:54:25.104092Z","end":"2026-03-03T02:54:25.605429Z","steps":["trace[810981638] 'process raft request' (duration: 500.433111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:25.605475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:25.104081Z","time spent":"501.375444ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:54:25.605473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:25.103457Z","time spent":"501.996628ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:54:25.60526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:25.192726Z","time spent":"412.524271ms","remote":"127.0.0.1:34646","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-03-03T02:54:25.928104Z","caller":"traceutil/trace.go:171","msg":"trace[355899267] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"108.008542ms","start":"2026-03-03T02:54:25.820076Z","end":"2026-03-03T02:54:25.928084Z","steps":["trace[355899267] 'process raft request' (duration: 107.90736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:38.250781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101459387943766,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T02:54:38.382982Z","caller":"traceutil/trace.go:171","msg":"trace[1593190129] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"632.909943ms","start":"2026-03-03T02:54:37.750044Z","end":"2026-03-03T02:54:38.382954Z","steps":["trace[1593190129] 'read index received' (duration: 632.692118ms)","trace[1593190129] 'applied index is now lower than readState.Index' (duration: 216.845µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:38.383024Z","caller":"traceutil/trace.go:171","msg":"trace[109278961] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"639.478961ms","start":"2026-03-03T02:54:37.743511Z","end":"2026-03-03T02:54:38.382989Z","steps":["trace[109278961] 'process raft request' (duration: 639.292317ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:38.383288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:37.743493Z","time spent":"639.698346ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:54:38.383331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.274841ms","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-03-03T02:54:38.383391Z","caller":"traceutil/trace.go:171","msg":"trace[738635180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:960; }","duration":"633.332972ms","start":"2026-03-03T02:54:37.750039Z","end":"2026-03-03T02:54:38.383372Z","steps":["trace[738635180] 'agreement among raft nodes before linearized reading' (duration: 633.026575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:38.383433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:37.750008Z","time spent":"633.414144ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T02:54:38.383516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.637035ms","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-03-03T02:54:38.383589Z","caller":"traceutil/trace.go:171","msg":"trace[1873928025] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"631.750238ms","start":"2026-03-03T02:54:37.751821Z","end":"2026-03-03T02:54:38.383572Z","steps":["trace[1873928025] 'agreement among raft nodes before linearized reading' (duration: 631.545483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:38.383623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.846974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:38.383686Z","caller":"traceutil/trace.go:171","msg":"trace[528885184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"175.930375ms","start":"2026-03-03T02:54:38.207742Z","end":"2026-03-03T02:54:38.383672Z","steps":["trace[528885184] 'agreement among raft nodes before linearized reading' (duration: 175.804502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:38.383714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.87916ms","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-03-03T02:54:38.383757Z","caller":"traceutil/trace.go:171","msg":"trace[835138135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"627.927481ms","start":"2026-03-03T02:54:37.755809Z","end":"2026-03-03T02:54:38.383736Z","steps":["trace[835138135] 'agreement among raft nodes before linearized reading' (duration: 627.847859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:38.383792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:37.755798Z","time spent":"627.987272ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T02:54:38.383634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:37.751806Z","time spent":"631.819819ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-03T02:54:38.521962Z","caller":"traceutil/trace.go:171","msg":"trace[454433260] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"134.414144ms","start":"2026-03-03T02:54:38.387519Z","end":"2026-03-03T02:54:38.521933Z","steps":["trace[454433260] 'process raft request' (duration: 43.68798ms)","trace[454433260] 'compare' (duration: 90.368256ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:38.522547Z","caller":"traceutil/trace.go:171","msg":"trace[723370908] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"133.551044ms","start":"2026-03-03T02:54:38.388984Z","end":"2026-03-03T02:54:38.522535Z","steps":["trace[723370908] 'process raft request' (duration: 132.692755ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:38.52276Z","caller":"traceutil/trace.go:171","msg":"trace[2079671921] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"128.596213ms","start":"2026-03-03T02:54:38.394135Z","end":"2026-03-03T02:54:38.522732Z","steps":["trace[2079671921] 'process raft request' (duration: 128.325016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:39.352977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.155404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:39.353093Z","caller":"traceutil/trace.go:171","msg":"trace[1692638260] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:963; }","duration":"503.370968ms","start":"2026-03-03T02:54:38.849696Z","end":"2026-03-03T02:54:39.353067Z","steps":["trace[1692638260] 'count revisions from in-memory index tree' (duration: 502.997941ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:39.353157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:38.849676Z","time spent":"503.465131ms","remote":"127.0.0.1:34904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-03-03T02:54:39.353256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.69041ms","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-03-03T02:54:39.353337Z","caller":"traceutil/trace.go:171","msg":"trace[1982487827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"179.831483ms","start":"2026-03-03T02:54:39.173483Z","end":"2026-03-03T02:54:39.353315Z","steps":["trace[1982487827] 'range keys from in-memory index tree' (duration: 179.477725ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:39.45974Z","caller":"traceutil/trace.go:171","msg":"trace[627968408] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"101.786452ms","start":"2026-03-03T02:54:39.357936Z","end":"2026-03-03T02:54:39.459722Z","steps":["trace[627968408] 'process raft request' (duration: 101.621068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:44.117423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322101459387943845,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-03T02:54:44.305558Z","caller":"traceutil/trace.go:171","msg":"trace[1408095356] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"688.451719ms","start":"2026-03-03T02:54:43.617082Z","end":"2026-03-03T02:54:44.305534Z","steps":["trace[1408095356] 'read index received' (duration: 688.382897ms)","trace[1408095356] 'applied index is now lower than readState.Index' (duration: 67.232µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:44.305746Z","caller":"traceutil/trace.go:171","msg":"trace[137449426] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"826.445043ms","start":"2026-03-03T02:54:43.479245Z","end":"2026-03-03T02:54:44.30569Z","steps":["trace[137449426] 'process raft request' (duration: 826.155797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:44.305849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.263366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:44.305936Z","caller":"traceutil/trace.go:171","msg":"trace[561055185] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1002; }","duration":"602.412059ms","start":"2026-03-03T02:54:43.703498Z","end":"2026-03-03T02:54:44.30591Z","steps":["trace[561055185] 'agreement among raft nodes before linearized reading' (duration: 602.248526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:44.305917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.938694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-03T02:54:44.305896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.630193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-03T02:54:44.306001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:43.703473Z","time spent":"602.514991ms","remote":"127.0.0.1:35122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-03T02:54:44.306011Z","caller":"traceutil/trace.go:171","msg":"trace[1557423797] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1002; }","duration":"126.092328ms","start":"2026-03-03T02:54:44.179897Z","end":"2026-03-03T02:54:44.30599Z","steps":["trace[1557423797] 'agreement among raft nodes before linearized reading' (duration: 125.891033ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:44.306049Z","caller":"traceutil/trace.go:171","msg":"trace[1894681520] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1002; }","duration":"689.007521ms","start":"2026-03-03T02:54:43.617026Z","end":"2026-03-03T02:54:44.306033Z","steps":["trace[1894681520] 'agreement among raft nodes before linearized reading' (duration: 688.653093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:44.306069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:43.479213Z","time spent":"826.691598ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:54:44.306169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:43.617Z","time spent":"689.133144ms","remote":"127.0.0.1:34928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-03-03T02:54:44.632981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.5214ms","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-03-03T02:54:44.633058Z","caller":"traceutil/trace.go:171","msg":"trace[1267950022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"189.644123ms","start":"2026-03-03T02:54:44.443392Z","end":"2026-03-03T02:54:44.633036Z","steps":["trace[1267950022] 'range keys from in-memory index tree' (duration: 189.394757ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:46.696725Z","caller":"traceutil/trace.go:171","msg":"trace[553239657] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"381.678349ms","start":"2026-03-03T02:54:46.315025Z","end":"2026-03-03T02:54:46.696703Z","steps":["trace[553239657] 'process raft request' (duration: 381.504745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:46.696886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:46.315008Z","time spent":"381.784792ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T02:54:46.95463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.064878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T02:54:46.954745Z","caller":"traceutil/trace.go:171","msg":"trace[676717330] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"272.019481ms","start":"2026-03-03T02:54:46.682704Z","end":"2026-03-03T02:54:46.954723Z","steps":["trace[676717330] 'read index received' (duration: 13.754428ms)","trace[676717330] 'applied index is now lower than readState.Index' (duration: 258.263613ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:46.954772Z","caller":"traceutil/trace.go:171","msg":"trace[1867784556] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"451.143338ms","start":"2026-03-03T02:54:46.503595Z","end":"2026-03-03T02:54:46.954738Z","steps":["trace[1867784556] 'process raft request' (duration: 253.874144ms)","trace[1867784556] 'compare' (duration: 196.832293ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:54:46.954948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.216519ms","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-03-03T02:54:46.955002Z","caller":"traceutil/trace.go:171","msg":"trace[1011156871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"253.29522ms","start":"2026-03-03T02:54:46.70169Z","end":"2026-03-03T02:54:46.954985Z","steps":["trace[1011156871] 'agreement among raft nodes before linearized reading' (duration: 253.133427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:46.955035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.779844ms","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-03-03T02:54:46.955087Z","caller":"traceutil/trace.go:171","msg":"trace[1979255102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"250.843165ms","start":"2026-03-03T02:54:46.704228Z","end":"2026-03-03T02:54:46.955071Z","steps":["trace[1979255102] 'agreement among raft nodes before linearized reading' (duration: 250.750043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:46.955108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:46.503575Z","time spent":"451.363982ms","remote":"127.0.0.1:34872","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-03-03T02:54:46.954934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.220656ms","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-03-03T02:54:46.955204Z","caller":"traceutil/trace.go:171","msg":"trace[746368277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"272.543042ms","start":"2026-03-03T02:54:46.682653Z","end":"2026-03-03T02:54:46.955196Z","steps":["trace[746368277] 'agreement among raft nodes before linearized reading' (duration: 272.160314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:46.955284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.540846ms","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-03-03T02:54:46.955305Z","caller":"traceutil/trace.go:171","msg":"trace[1294611756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"253.586467ms","start":"2026-03-03T02:54:46.701714Z","end":"2026-03-03T02:54:46.955301Z","steps":["trace[1294611756] 'agreement among raft nodes before linearized reading' (duration: 253.532856ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:47.860767Z","caller":"traceutil/trace.go:171","msg":"trace[1647196009] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"221.67044ms","start":"2026-03-03T02:54:47.639066Z","end":"2026-03-03T02:54:47.860737Z","steps":["trace[1647196009] 'read index received' (duration: 221.455676ms)","trace[1647196009] 'applied index is now lower than readState.Index' (duration: 213.514µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:54:47.861049Z","caller":"traceutil/trace.go:171","msg":"trace[355565495] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"598.515562ms","start":"2026-03-03T02:54:47.262482Z","end":"2026-03-03T02:54:47.860998Z","steps":["trace[355565495] 'process raft request' (duration: 598.128664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:47.861104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.014649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-03T02:54:47.861149Z","caller":"traceutil/trace.go:171","msg":"trace[264884942] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1014; }","duration":"222.12294ms","start":"2026-03-03T02:54:47.639012Z","end":"2026-03-03T02:54:47.861135Z","steps":["trace[264884942] 'agreement among raft nodes before linearized reading' (duration: 221.835334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:47.861209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:47.262464Z","time spent":"598.676405ms","remote":"127.0.0.1:34720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":884,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T02:54:47.976757Z","caller":"traceutil/trace.go:171","msg":"trace[486774546] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"109.846993ms","start":"2026-03-03T02:54:47.866881Z","end":"2026-03-03T02:54:47.976728Z","steps":["trace[486774546] 'process raft request' (duration: 15.504008ms)","trace[486774546] 'compare' (duration: 94.222432ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:54:48.699239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.400341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:48.699298Z","caller":"traceutil/trace.go:171","msg":"trace[343907531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"504.526734ms","start":"2026-03-03T02:54:48.194758Z","end":"2026-03-03T02:54:48.699284Z","steps":["trace[343907531] 'range keys from in-memory index tree' (duration: 504.31518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:48.699325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:48.194736Z","time spent":"504.583455ms","remote":"127.0.0.1:34646","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-03-03T02:54:48.831626Z","caller":"traceutil/trace.go:171","msg":"trace[2089562938] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"127.116682ms","start":"2026-03-03T02:54:48.704489Z","end":"2026-03-03T02:54:48.831606Z","steps":["trace[2089562938] 'process raft request' (duration: 126.881645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:50.302258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252267642s","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-03-03T02:54:50.302342Z","caller":"traceutil/trace.go:171","msg":"trace[352088553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.252399726s","start":"2026-03-03T02:54:49.049923Z","end":"2026-03-03T02:54:50.302323Z","steps":["trace[352088553] 'range keys from in-memory index tree' (duration: 1.252090578s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:50.302388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:49.049897Z","time spent":"1.252481808s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T02:54:50.30243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251894184s","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-03-03T02:54:50.302486Z","caller":"traceutil/trace.go:171","msg":"trace[1044407213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"1.251978435s","start":"2026-03-03T02:54:49.050493Z","end":"2026-03-03T02:54:50.302472Z","steps":["trace[1044407213] 'range keys from in-memory index tree' (duration: 1.251797341s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:50.302512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:49.050486Z","time spent":"1.252020627s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T02:54:50.302614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251205318s","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-03-03T02:54:50.30263Z","caller":"traceutil/trace.go:171","msg":"trace[368050322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"1.25125626s","start":"2026-03-03T02:54:49.051368Z","end":"2026-03-03T02:54:50.302624Z","steps":["trace[368050322] 'range keys from in-memory index tree' (duration: 1.251100067s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:54:50.302657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:54:49.051348Z","time spent":"1.251294501s","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-03T02:54:50.479929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.820371ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-03T02:54:50.480215Z","caller":"traceutil/trace.go:171","msg":"trace[761865942] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"173.250885ms","start":"2026-03-03T02:54:50.306945Z","end":"2026-03-03T02:54:50.480196Z","steps":["trace[761865942] 'process raft request' (duration: 173.078061ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:50.480244Z","caller":"traceutil/trace.go:171","msg":"trace[2088056734] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"285.131494ms","start":"2026-03-03T02:54:50.195085Z","end":"2026-03-03T02:54:50.480216Z","steps":["trace[2088056734] 'read index received' (duration: 105.713µs)","trace[2088056734] 'applied index is now lower than readState.Index' (duration: 285.022971ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T02:54:50.48037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.281387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:54:50.480423Z","caller":"traceutil/trace.go:171","msg":"trace[1094308736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"285.38305ms","start":"2026-03-03T02:54:50.195024Z","end":"2026-03-03T02:54:50.480407Z","steps":["trace[1094308736] 'agreement among raft nodes before linearized reading' (duration: 285.273347ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:50.491399Z","caller":"traceutil/trace.go:171","msg":"trace[1186076426] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"184.202661ms","start":"2026-03-03T02:54:50.307181Z","end":"2026-03-03T02:54:50.491384Z","steps":["trace[1186076426] 'process raft request' (duration: 183.817213ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:50.491528Z","caller":"traceutil/trace.go:171","msg":"trace[2039531841] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"184.250222ms","start":"2026-03-03T02:54:50.307272Z","end":"2026-03-03T02:54:50.491522Z","steps":["trace[2039531841] 'process raft request' (duration: 183.842473ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:54:51.033061Z","caller":"traceutil/trace.go:171","msg":"trace[801439414] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"189.035648ms","start":"2026-03-03T02:54:50.844002Z","end":"2026-03-03T02:54:51.033038Z","steps":["trace[801439414] 'process raft request' (duration: 188.67843ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:55:58.894079Z","caller":"traceutil/trace.go:171","msg":"trace[1727089775] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"110.514126ms","start":"2026-03-03T02:55:58.783538Z","end":"2026-03-03T02:55:58.894052Z","steps":["trace[1727089775] 'process raft request' (duration: 110.368762ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:57:32.394847Z","caller":"traceutil/trace.go:171","msg":"trace[265964297] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"243.704093ms","start":"2026-03-03T02:57:32.151117Z","end":"2026-03-03T02:57:32.394821Z","steps":["trace[265964297] 'process raft request' (duration: 243.436917ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:57:34.589247Z","caller":"traceutil/trace.go:171","msg":"trace[702094184] linearizableReadLoop","detail":"{readStateIndex:1790; appliedIndex:1789; }","duration":"167.581374ms","start":"2026-03-03T02:57:34.42163Z","end":"2026-03-03T02:57:34.589212Z","steps":["trace[702094184] 'read index received' (duration: 167.206266ms)","trace[702094184] 'applied index is now lower than readState.Index' (duration: 373.968µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T02:57:34.589347Z","caller":"traceutil/trace.go:171","msg":"trace[1645702218] transaction","detail":"{read_only:false; response_revision:1715; number_of_response:1; }","duration":"187.282626ms","start":"2026-03-03T02:57:34.402004Z","end":"2026-03-03T02:57:34.589287Z","steps":["trace[1645702218] 'process raft request' (duration: 186.988009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:57:34.589453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.800539ms","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-03-03T02:57:34.589708Z","caller":"traceutil/trace.go:171","msg":"trace[156752203] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1715; }","duration":"168.096485ms","start":"2026-03-03T02:57:34.421586Z","end":"2026-03-03T02:57:34.589683Z","steps":["trace[156752203] 'agreement among raft nodes before linearized reading' (duration: 167.753078ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:57:57.38123Z","caller":"traceutil/trace.go:171","msg":"trace[508385932] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"159.624719ms","start":"2026-03-03T02:57:57.221585Z","end":"2026-03-03T02:57:57.381209Z","steps":["trace[508385932] 'process raft request' (duration: 159.489597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:58:04.353661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.173759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T02:58:04.353756Z","caller":"traceutil/trace.go:171","msg":"trace[1214514668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1864; }","duration":"159.330393ms","start":"2026-03-03T02:58:04.194405Z","end":"2026-03-03T02:58:04.353735Z","steps":["trace[1214514668] 'range keys from in-memory index tree' (duration: 159.099828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:58:04.354002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.138674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-03-03T02:58:04.354036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.37752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-03T02:58:04.354057Z","caller":"traceutil/trace.go:171","msg":"trace[1651817344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1864; }","duration":"546.219057ms","start":"2026-03-03T02:58:03.807824Z","end":"2026-03-03T02:58:04.354043Z","steps":["trace[1651817344] 'range keys from in-memory index tree' (duration: 545.9391ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T02:58:04.354069Z","caller":"traceutil/trace.go:171","msg":"trace[426877323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1864; }","duration":"458.454152ms","start":"2026-03-03T02:58:03.895604Z","end":"2026-03-03T02:58:04.354058Z","steps":["trace[426877323] 'range keys from in-memory index tree' (duration: 458.218487ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T02:58:04.354092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:58:03.807808Z","time spent":"546.275247ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T02:58:04.354103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T02:58:03.895581Z","time spent":"458.507354ms","remote":"127.0.0.1:34872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T02:58:04.354202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.410433ms","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-03-03T02:58:04.354247Z","caller":"traceutil/trace.go:171","msg":"trace[2026172607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1864; }","duration":"298.508045ms","start":"2026-03-03T02:58:04.055728Z","end":"2026-03-03T02:58:04.354236Z","steps":["trace[2026172607] 'range keys from in-memory index tree' (duration: 298.30438ms)"],"step_count":1}