{"level":"warn","ts":"2026-02-10T14:59:38.380648Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T14:59:38.380741Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.6: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.6:2380","--initial-cluster=instance=https://199.204.45.6:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.6:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.6: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-02-10T14:59:38.380806Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T14:59:38.380814Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-02-10T14:59:38.380834Z","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-02-10T14:59:38.381442Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"]} {"level":"info","ts":"2026-02-10T14:59:38.381515Z","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":2,"max-cpu-available":2,"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.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.6: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-02-10T14:59:38.390051Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.342478ms"} {"level":"info","ts":"2026-02-10T14:59:38.405509Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4ec288cbdba84d36","cluster-id":"9b45faacd38a820f"} {"level":"info","ts":"2026-02-10T14:59:38.405559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T14:59:38.405574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 0"} {"level":"info","ts":"2026-02-10T14:59:38.405582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4ec288cbdba84d36 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T14:59:38.405592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became follower at term 1"} {"level":"info","ts":"2026-02-10T14:59:38.405618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"warn","ts":"2026-02-10T14:59:38.418482Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T14:59:38.425878Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T14:59:38.436261Z","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-02-10T14:59:38.451019Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4ec288cbdba84d36","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T14:59:38.451655Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4ec288cbdba84d36","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T14:59:38.451869Z","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-02-10T14:59:38.451984Z","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-02-10T14:59:38.451992Z","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-02-10T14:59:38.452456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 switched to configuration voters=(5675248889585225014)"} {"level":"info","ts":"2026-02-10T14:59:38.452566Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","added-peer-id":"4ec288cbdba84d36","added-peer-peer-urls":["https://199.204.45.6:2380"]} {"level":"info","ts":"2026-02-10T14:59:38.470911Z","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-02-10T14:59:38.471178Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-02-10T14:59:38.471194Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.6:2380"} {"level":"info","ts":"2026-02-10T14:59:38.471726Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4ec288cbdba84d36","initial-advertise-peer-urls":["https://199.204.45.6:2380"],"listen-peer-urls":["https://199.204.45.6:2380"],"advertise-client-urls":["https://199.204.45.6:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.6:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T14:59:38.471751Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T14:59:38.806526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T14:59:38.806622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T14:59:38.806674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgPreVoteResp from 4ec288cbdba84d36 at term 1"} {"level":"info","ts":"2026-02-10T14:59:38.806712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became candidate at term 2"} {"level":"info","ts":"2026-02-10T14:59:38.806732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 received MsgVoteResp from 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-02-10T14:59:38.806749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4ec288cbdba84d36 became leader at term 2"} {"level":"info","ts":"2026-02-10T14:59:38.806765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4ec288cbdba84d36 elected leader 4ec288cbdba84d36 at term 2"} {"level":"info","ts":"2026-02-10T14:59:38.807746Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4ec288cbdba84d36","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.6:2379]}","request-path":"/0/members/4ec288cbdba84d36/attributes","cluster-id":"9b45faacd38a820f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T14:59:38.808956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T14:59:38.809363Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:38.80951Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T14:59:38.814943Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T14:59:38.814995Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T14:59:38.821022Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.6:2379"} {"level":"info","ts":"2026-02-10T14:59:38.821168Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9b45faacd38a820f","local-member-id":"4ec288cbdba84d36","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:38.822932Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:38.822984Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:59:38.828552Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T14:59:41.006818Z","caller":"traceutil/trace.go:171","msg":"trace[1680216661] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"107.559064ms","start":"2026-02-10T14:59:40.899216Z","end":"2026-02-10T14:59:41.006775Z","steps":["trace[1680216661] 'process raft request' (duration: 107.368969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.501274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999051131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-10T14:59:43.501441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.658027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.501497Z","caller":"traceutil/trace.go:171","msg":"trace[1394950637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"772.75376ms","start":"2026-02-10T14:59:42.72873Z","end":"2026-02-10T14:59:43.501483Z","steps":["trace[1394950637] 'range keys from in-memory index tree' (duration: 772.609526ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:43.501491Z","caller":"traceutil/trace.go:171","msg":"trace[1494691287] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:74; }","duration":"1.999275177s","start":"2026-02-10T14:59:41.502159Z","end":"2026-02-10T14:59:43.501434Z","steps":["trace[1494691287] 'range keys from in-memory index tree' (duration: 1.998984679s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.501524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.728715Z","time spent":"772.805411ms","remote":"127.0.0.1:48094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:43.501545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.502142Z","time spent":"1.99938641s","remote":"127.0.0.1:48532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-02-10T14:59:43.501709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.888706728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.501727Z","caller":"traceutil/trace.go:171","msg":"trace[1452934736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"1.88874557s","start":"2026-02-10T14:59:41.612973Z","end":"2026-02-10T14:59:43.501719Z","steps":["trace[1452934736] 'range keys from in-memory index tree' (duration: 1.888660157s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.501739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.612957Z","time spent":"1.88877863s","remote":"127.0.0.1:48094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:43.501792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.108540023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-10T14:59:43.501812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.506508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T14:59:43.501826Z","caller":"traceutil/trace.go:171","msg":"trace[1529315907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:74; }","duration":"845.536158ms","start":"2026-02-10T14:59:42.656285Z","end":"2026-02-10T14:59:43.501821Z","steps":["trace[1529315907] 'range keys from in-memory index tree' (duration: 845.456676ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:43.501825Z","caller":"traceutil/trace.go:171","msg":"trace[1009574251] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:74; }","duration":"2.108592704s","start":"2026-02-10T14:59:41.39322Z","end":"2026-02-10T14:59:43.501813Z","steps":["trace[1009574251] 'range keys from in-memory index tree' (duration: 2.108479351s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.501839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.656269Z","time spent":"845.56719ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T14:59:43.501853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.393203Z","time spent":"2.108643176s","remote":"127.0.0.1:48284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-02-10T14:59:43.501917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.482549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T14:59:43.501932Z","caller":"traceutil/trace.go:171","msg":"trace[549028404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:74; }","duration":"829.49649ms","start":"2026-02-10T14:59:42.67243Z","end":"2026-02-10T14:59:43.501927Z","steps":["trace[549028404] 'range keys from in-memory index tree' (duration: 829.396767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.501944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:42.672365Z","time spent":"829.576181ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T14:59:43.501975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999900802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.501999Z","caller":"traceutil/trace.go:171","msg":"trace[2133226625] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:74; }","duration":"1.999925672s","start":"2026-02-10T14:59:41.502066Z","end":"2026-02-10T14:59:43.501992Z","steps":["trace[2133226625] 'range keys from in-memory index tree' (duration: 1.999851601s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.502024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:41.50203Z","time spent":"1.999987454s","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-02-10T14:59:43.85451Z","caller":"traceutil/trace.go:171","msg":"trace[2094169823] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"347.527029ms","start":"2026-02-10T14:59:43.506956Z","end":"2026-02-10T14:59:43.854483Z","steps":["trace[2094169823] 'process raft request' (duration: 347.342155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.855029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.506949Z","time spent":"347.648282ms","remote":"127.0.0.1:48344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3431,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:43.855743Z","caller":"traceutil/trace.go:171","msg":"trace[1271082638] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:79; }","duration":"348.518604ms","start":"2026-02-10T14:59:43.507209Z","end":"2026-02-10T14:59:43.855727Z","steps":["trace[1271082638] 'read index received' (duration: 348.218727ms)","trace[1271082638] 'applied index is now lower than readState.Index' (duration: 299.217µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:43.855923Z","caller":"traceutil/trace.go:171","msg":"trace[479655213] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"348.934715ms","start":"2026-02-10T14:59:43.506976Z","end":"2026-02-10T14:59:43.855911Z","steps":["trace[479655213] 'process raft request' (duration: 348.599597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.85604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.50696Z","time spent":"349.037927ms","remote":"127.0.0.1:48532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:59:43.85627Z","caller":"traceutil/trace.go:171","msg":"trace[402556262] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"349.172141ms","start":"2026-02-10T14:59:43.507091Z","end":"2026-02-10T14:59:43.856263Z","steps":["trace[402556262] 'process raft request' (duration: 348.578386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.85644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.507086Z","time spent":"349.200281ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:43.856526Z","caller":"traceutil/trace.go:171","msg":"trace[164955380] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"349.341224ms","start":"2026-02-10T14:59:43.507178Z","end":"2026-02-10T14:59:43.856519Z","steps":["trace[164955380] 'process raft request' (duration: 348.522504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.85656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.507173Z","time spent":"349.366426ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:59:43.856804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.754295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.856827Z","caller":"traceutil/trace.go:171","msg":"trace[233472012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"349.795335ms","start":"2026-02-10T14:59:43.507024Z","end":"2026-02-10T14:59:43.856819Z","steps":["trace[233472012] 'agreement among raft nodes before linearized reading' (duration: 349.760785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.856846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.507017Z","time spent":"349.825617ms","remote":"127.0.0.1:48100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:43.856933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.552685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.856978Z","caller":"traceutil/trace.go:171","msg":"trace[491899617] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:78; }","duration":"348.609156ms","start":"2026-02-10T14:59:43.508363Z","end":"2026-02-10T14:59:43.856972Z","steps":["trace[491899617] 'agreement among raft nodes before linearized reading' (duration: 348.555985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:43.856995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:43.508356Z","time spent":"348.634087ms","remote":"127.0.0.1:48506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-02-10T14:59:43.857161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.223413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T14:59:43.857179Z","caller":"traceutil/trace.go:171","msg":"trace[667253466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:78; }","duration":"127.241413ms","start":"2026-02-10T14:59:43.729932Z","end":"2026-02-10T14:59:43.857173Z","steps":["trace[667253466] 'agreement among raft nodes before linearized reading' (duration: 127.214493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:56.233218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.591841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:59:56.233311Z","caller":"traceutil/trace.go:171","msg":"trace[141545298] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:383; }","duration":"254.22871ms","start":"2026-02-10T14:59:55.979067Z","end":"2026-02-10T14:59:56.233296Z","steps":["trace[141545298] 'read index received' (duration: 52.30041ms)","trace[141545298] 'applied index is now lower than readState.Index' (duration: 201.92716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:56.233386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.316582ms","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-02-10T14:59:56.233412Z","caller":"traceutil/trace.go:171","msg":"trace[748119787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"254.373623ms","start":"2026-02-10T14:59:55.97903Z","end":"2026-02-10T14:59:56.233403Z","steps":["trace[748119787] 'agreement among raft nodes before linearized reading' (duration: 254.307692ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:56.233632Z","caller":"traceutil/trace.go:171","msg":"trace[2116463804] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"308.570551ms","start":"2026-02-10T14:59:55.925049Z","end":"2026-02-10T14:59:56.233619Z","steps":["trace[2116463804] 'process raft request' (duration: 106.362014ms)","trace[2116463804] 'compare' (duration: 201.313255ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:56.233687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:55.925039Z","time spent":"308.617732ms","remote":"127.0.0.1:48428","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-02-10T15:00:10.30196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.016598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T15:00:10.302009Z","caller":"traceutil/trace.go:171","msg":"trace[1293760441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"261.09364ms","start":"2026-02-10T15:00:10.040904Z","end":"2026-02-10T15:00:10.301997Z","steps":["trace[1293760441] 'range keys from in-memory index tree' (duration: 260.972917ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:12.894774Z","caller":"traceutil/trace.go:171","msg":"trace[1908565767] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"522.491758ms","start":"2026-02-10T15:00:12.372267Z","end":"2026-02-10T15:00:12.894758Z","steps":["trace[1908565767] 'process raft request' (duration: 522.395115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:12.894878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:12.37226Z","time spent":"522.56491ms","remote":"127.0.0.1:48428","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-02-10T15:00:12.894962Z","caller":"traceutil/trace.go:171","msg":"trace[354557011] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"522.656913ms","start":"2026-02-10T15:00:12.372302Z","end":"2026-02-10T15:00:12.894958Z","steps":["trace[354557011] 'process raft request' (duration: 522.437137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:12.894985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:12.372298Z","time spent":"522.674673ms","remote":"127.0.0.1:48428","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-02-10T15:00:13.282719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.633466ms","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-02-10T15:00:13.282767Z","caller":"traceutil/trace.go:171","msg":"trace[1131998739] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:480; }","duration":"212.728738ms","start":"2026-02-10T15:00:13.070028Z","end":"2026-02-10T15:00:13.282756Z","steps":["trace[1131998739] 'range keys from in-memory index tree' (duration: 212.484242ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:16.883076Z","caller":"traceutil/trace.go:171","msg":"trace[197095302] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"421.99716ms","start":"2026-02-10T15:00:16.461064Z","end":"2026-02-10T15:00:16.883061Z","steps":["trace[197095302] 'process raft request' (duration: 421.696083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:16.883184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:16.461055Z","time spent":"422.073582ms","remote":"127.0.0.1:48428","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-02-10T15:00:17.870313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994188619,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:00:17.873108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.854339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:00:17.873176Z","caller":"traceutil/trace.go:171","msg":"trace[486972802] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"962.761981ms","start":"2026-02-10T15:00:16.910398Z","end":"2026-02-10T15:00:17.87316Z","steps":["trace[486972802] 'process raft request' (duration: 313.7899ms)","trace[486972802] 'compare' (duration: 648.262754ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:17.873232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:16.91039Z","time spent":"962.813933ms","remote":"127.0.0.1:48428","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-02-10T15:00:17.875176Z","caller":"traceutil/trace.go:171","msg":"trace[1623888697] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:505; }","duration":"505.555206ms","start":"2026-02-10T15:00:17.369609Z","end":"2026-02-10T15:00:17.875164Z","steps":["trace[1623888697] 'read index received' (duration: 505.430563ms)","trace[1623888697] 'applied index is now lower than readState.Index' (duration: 124.063µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:17.875306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.69939ms","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-02-10T15:00:17.875334Z","caller":"traceutil/trace.go:171","msg":"trace[1991161701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"505.762221ms","start":"2026-02-10T15:00:17.369561Z","end":"2026-02-10T15:00:17.875324Z","steps":["trace[1991161701] 'agreement among raft nodes before linearized reading' (duration: 505.648868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:17.875359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:17.369543Z","time spent":"505.809533ms","remote":"127.0.0.1:48428","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-02-10T15:00:17.875541Z","caller":"traceutil/trace.go:171","msg":"trace[1530660118] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"963.357106ms","start":"2026-02-10T15:00:16.912173Z","end":"2026-02-10T15:00:17.87553Z","steps":["trace[1530660118] 'process raft request' (duration: 962.902465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:17.875609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:16.91216Z","time spent":"963.405257ms","remote":"127.0.0.1:48428","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-02-10T15:00:17.875993Z","caller":"traceutil/trace.go:171","msg":"trace[313587840] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"791.008143ms","start":"2026-02-10T15:00:17.084972Z","end":"2026-02-10T15:00:17.875981Z","steps":["trace[313587840] 'process raft request' (duration: 790.158091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:17.876065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:17.084954Z","time spent":"791.077104ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:18.681662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.564831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xhf2t\" ","response":"range_response_count:1 size:16648"} {"level":"info","ts":"2026-02-10T15:00:18.681726Z","caller":"traceutil/trace.go:171","msg":"trace[132265074] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xhf2t; range_end:; response_count:1; response_revision:494; }","duration":"238.683024ms","start":"2026-02-10T15:00:18.443029Z","end":"2026-02-10T15:00:18.681712Z","steps":["trace[132265074] 'range keys from in-memory index tree' (duration: 238.347364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:21.0634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.38525ms","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-02-10T15:00:21.06347Z","caller":"traceutil/trace.go:171","msg":"trace[225899599] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"165.497934ms","start":"2026-02-10T15:00:20.897955Z","end":"2026-02-10T15:00:21.063453Z","steps":["trace[225899599] 'range keys from in-memory index tree' (duration: 165.250587ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:23.898911Z","caller":"traceutil/trace.go:171","msg":"trace[622248430] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"243.16275ms","start":"2026-02-10T15:00:23.655735Z","end":"2026-02-10T15:00:23.898898Z","steps":["trace[622248430] 'process raft request' (duration: 242.974416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:37.835668Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994188972,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:00:38.052282Z","caller":"traceutil/trace.go:171","msg":"trace[526306635] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"717.315942ms","start":"2026-02-10T15:00:37.334946Z","end":"2026-02-10T15:00:38.052261Z","steps":["trace[526306635] 'read index received' (duration: 717.158998ms)","trace[526306635] 'applied index is now lower than readState.Index' (duration: 156.054µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:38.052567Z","caller":"traceutil/trace.go:171","msg":"trace[1958215462] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"923.471509ms","start":"2026-02-10T15:00:37.129087Z","end":"2026-02-10T15:00:38.052558Z","steps":["trace[1958215462] 'process raft request' (duration: 923.070709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:38.052664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:37.129076Z","time spent":"923.518339ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:38.052856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.903097ms","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-02-10T15:00:38.052882Z","caller":"traceutil/trace.go:171","msg":"trace[1281283691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"717.955027ms","start":"2026-02-10T15:00:37.334917Z","end":"2026-02-10T15:00:38.052872Z","steps":["trace[1281283691] 'agreement among raft nodes before linearized reading' (duration: 717.864275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:38.052903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:37.334905Z","time spent":"717.992879ms","remote":"127.0.0.1:48428","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-02-10T15:00:38.940908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.395237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:00:38.941091Z","caller":"traceutil/trace.go:171","msg":"trace[393349888] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"880.476398ms","start":"2026-02-10T15:00:38.060602Z","end":"2026-02-10T15:00:38.941078Z","steps":["trace[393349888] 'process raft request' (duration: 880.435177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:38.941141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:38.060596Z","time spent":"880.523699ms","remote":"127.0.0.1:48428","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-02-10T15:00:38.94136Z","caller":"traceutil/trace.go:171","msg":"trace[421390963] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"881.745929ms","start":"2026-02-10T15:00:38.059605Z","end":"2026-02-10T15:00:38.941351Z","steps":["trace[421390963] 'process raft request' (duration: 881.368099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:38.941441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:38.059587Z","time spent":"881.830261ms","remote":"127.0.0.1:48428","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-02-10T15:00:38.941517Z","caller":"traceutil/trace.go:171","msg":"trace[1192042205] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"882.794044ms","start":"2026-02-10T15:00:38.058714Z","end":"2026-02-10T15:00:38.941508Z","steps":["trace[1192042205] 'process raft request' (duration: 281.349793ms)","trace[1192042205] 'compare' (duration: 599.777771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:38.941553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:38.058695Z","time spent":"882.841465ms","remote":"127.0.0.1:48428","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-02-10T15:00:38.941668Z","caller":"traceutil/trace.go:171","msg":"trace[1623938637] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"881.327109ms","start":"2026-02-10T15:00:38.060321Z","end":"2026-02-10T15:00:38.941649Z","steps":["trace[1623938637] 'process raft request' (duration: 880.695013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:38.941707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:38.060312Z","time spent":"881.37515ms","remote":"127.0.0.1:48428","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-02-10T15:00:38.942161Z","caller":"traceutil/trace.go:171","msg":"trace[205143392] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"598.275914ms","start":"2026-02-10T15:00:38.343877Z","end":"2026-02-10T15:00:38.942153Z","steps":["trace[205143392] 'process raft request' (duration: 598.014198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:38.942211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:38.343857Z","time spent":"598.330326ms","remote":"127.0.0.1:48428","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-02-10T15:00:39.621533Z","caller":"traceutil/trace.go:171","msg":"trace[732410883] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"106.190723ms","start":"2026-02-10T15:00:39.515323Z","end":"2026-02-10T15:00:39.621513Z","steps":["trace[732410883] 'read index received' (duration: 45.838079ms)","trace[732410883] 'applied index is now lower than readState.Index' (duration: 60.351154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:00:39.621627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.281745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:39.621651Z","caller":"traceutil/trace.go:171","msg":"trace[1403063134] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:680; }","duration":"106.351677ms","start":"2026-02-10T15:00:39.515291Z","end":"2026-02-10T15:00:39.621643Z","steps":["trace[1403063134] 'agreement among raft nodes before linearized reading' (duration: 106.289156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:41.280746Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.222131694s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:00:41.280996Z","caller":"traceutil/trace.go:171","msg":"trace[1715060739] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"397.840138ms","start":"2026-02-10T15:00:40.88314Z","end":"2026-02-10T15:00:41.28098Z","steps":["trace[1715060739] 'read index received' (duration: 397.690405ms)","trace[1715060739] 'applied index is now lower than readState.Index' (duration: 149.113µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:41.281145Z","caller":"traceutil/trace.go:171","msg":"trace[1869008083] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.222601755s","start":"2026-02-10T15:00:40.058537Z","end":"2026-02-10T15:00:41.281138Z","steps":["trace[1869008083] 'process raft request' (duration: 1.222345889s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:41.281228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:40.058518Z","time spent":"1.222645506s","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:41.281347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.206357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:00:41.281367Z","caller":"traceutil/trace.go:171","msg":"trace[180446528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"398.251509ms","start":"2026-02-10T15:00:40.883109Z","end":"2026-02-10T15:00:41.281361Z","steps":["trace[180446528] 'agreement among raft nodes before linearized reading' (duration: 398.218648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:41.281383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:40.883095Z","time spent":"398.285239ms","remote":"127.0.0.1:48094","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-02-10T15:00:41.281512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.574494ms","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-02-10T15:00:41.281528Z","caller":"traceutil/trace.go:171","msg":"trace[215156439] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"332.607785ms","start":"2026-02-10T15:00:40.948915Z","end":"2026-02-10T15:00:41.281523Z","steps":["trace[215156439] 'agreement among raft nodes before linearized reading' (duration: 332.542643ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:41.281543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:40.948903Z","time spent":"332.637135ms","remote":"127.0.0.1:48428","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-02-10T15:00:41.281686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.958085ms","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-02-10T15:00:41.281705Z","caller":"traceutil/trace.go:171","msg":"trace[287800434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"330.984885ms","start":"2026-02-10T15:00:40.950716Z","end":"2026-02-10T15:00:41.281701Z","steps":["trace[287800434] 'agreement among raft nodes before linearized reading' (duration: 330.919083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:41.281721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:40.950711Z","time spent":"331.005945ms","remote":"127.0.0.1:48428","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-02-10T15:00:41.281813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.606651ms","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-02-10T15:00:41.281828Z","caller":"traceutil/trace.go:171","msg":"trace[1331338131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"331.635831ms","start":"2026-02-10T15:00:40.950188Z","end":"2026-02-10T15:00:41.281823Z","steps":["trace[1331338131] 'agreement among raft nodes before linearized reading' (duration: 331.59325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:41.281842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:40.95018Z","time spent":"331.658761ms","remote":"127.0.0.1:48428","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-02-10T15:00:41.281929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.756524ms","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-02-10T15:00:41.281944Z","caller":"traceutil/trace.go:171","msg":"trace[1295540904] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"331.779525ms","start":"2026-02-10T15:00:40.95016Z","end":"2026-02-10T15:00:41.281939Z","steps":["trace[1295540904] 'agreement among raft nodes before linearized reading' (duration: 331.738824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:41.281958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:40.950155Z","time spent":"331.800475ms","remote":"127.0.0.1:48428","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-02-10T15:00:55.878554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994189119,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:00:56.084441Z","caller":"traceutil/trace.go:171","msg":"trace[770718230] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:753; }","duration":"708.276882ms","start":"2026-02-10T15:00:55.376147Z","end":"2026-02-10T15:00:56.084424Z","steps":["trace[770718230] 'read index received' (duration: 708.126248ms)","trace[770718230] 'applied index is now lower than readState.Index' (duration: 149.914µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:56.084563Z","caller":"traceutil/trace.go:171","msg":"trace[592158297] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"732.033951ms","start":"2026-02-10T15:00:55.352522Z","end":"2026-02-10T15:00:56.084556Z","steps":["trace[592158297] 'process raft request' (duration: 731.766005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:56.084662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:55.352514Z","time spent":"732.072663ms","remote":"127.0.0.1:48428","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-02-10T15:00:56.084756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.195052ms","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-02-10T15:00:56.084804Z","caller":"traceutil/trace.go:171","msg":"trace[1765643324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:727; }","duration":"695.260784ms","start":"2026-02-10T15:00:55.38953Z","end":"2026-02-10T15:00:56.08479Z","steps":["trace[1765643324] 'agreement among raft nodes before linearized reading' (duration: 695.131381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:56.08483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:55.389523Z","time spent":"695.301495ms","remote":"127.0.0.1:48428","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-02-10T15:00:56.085001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.353066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-10T15:00:56.084996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.286355ms","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-02-10T15:00:56.085023Z","caller":"traceutil/trace.go:171","msg":"trace[1956132344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"677.385468ms","start":"2026-02-10T15:00:55.407632Z","end":"2026-02-10T15:00:56.085017Z","steps":["trace[1956132344] 'agreement among raft nodes before linearized reading' (duration: 677.325406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:56.08504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:55.407622Z","time spent":"677.414648ms","remote":"127.0.0.1:48428","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-02-10T15:00:56.085109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.949388ms","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-02-10T15:00:56.085044Z","caller":"traceutil/trace.go:171","msg":"trace[190248136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"677.366617ms","start":"2026-02-10T15:00:55.407666Z","end":"2026-02-10T15:00:56.085032Z","steps":["trace[190248136] 'agreement among raft nodes before linearized reading' (duration: 677.246734ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:56.085126Z","caller":"traceutil/trace.go:171","msg":"trace[1862140834] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"708.998479ms","start":"2026-02-10T15:00:55.376123Z","end":"2026-02-10T15:00:56.085122Z","steps":["trace[1862140834] 'agreement among raft nodes before linearized reading' (duration: 708.933678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:56.085149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:55.376115Z","time spent":"709.02973ms","remote":"127.0.0.1:48428","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-02-10T15:00:56.085149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:55.407649Z","time spent":"677.49328ms","remote":"127.0.0.1:48428","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-02-10T15:00:58.534451Z","caller":"traceutil/trace.go:171","msg":"trace[125477853] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"437.921918ms","start":"2026-02-10T15:00:58.096506Z","end":"2026-02-10T15:00:58.534428Z","steps":["trace[125477853] 'read index received' (duration: 437.705452ms)","trace[125477853] 'applied index is now lower than readState.Index' (duration: 188.545µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:00:58.534756Z","caller":"traceutil/trace.go:171","msg":"trace[1738965302] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"444.685712ms","start":"2026-02-10T15:00:58.090058Z","end":"2026-02-10T15:00:58.534744Z","steps":["trace[1738965302] 'process raft request' (duration: 444.212551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:58.534873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:58.090049Z","time spent":"444.737263ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:58.535133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.620645ms","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-02-10T15:00:58.535166Z","caller":"traceutil/trace.go:171","msg":"trace[52638619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:732; }","duration":"438.662255ms","start":"2026-02-10T15:00:58.096494Z","end":"2026-02-10T15:00:58.535156Z","steps":["trace[52638619] 'agreement among raft nodes before linearized reading' (duration: 438.551073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:58.535194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:58.096489Z","time spent":"438.698466ms","remote":"127.0.0.1:48428","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-02-10T15:00:58.535368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.704526ms","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-02-10T15:00:58.535396Z","caller":"traceutil/trace.go:171","msg":"trace[841085545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"438.738567ms","start":"2026-02-10T15:00:58.096649Z","end":"2026-02-10T15:00:58.535387Z","steps":["trace[841085545] 'agreement among raft nodes before linearized reading' (duration: 438.663835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:58.535421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:58.096645Z","time spent":"438.770408ms","remote":"127.0.0.1:48428","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-02-10T15:00:58.535601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.418994ms","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-02-10T15:00:58.535635Z","caller":"traceutil/trace.go:171","msg":"trace[539702857] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:732; }","duration":"186.485065ms","start":"2026-02-10T15:00:58.349142Z","end":"2026-02-10T15:00:58.535627Z","steps":["trace[539702857] 'agreement among raft nodes before linearized reading' (duration: 186.433814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:58.535813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.379908ms","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-02-10T15:00:58.535839Z","caller":"traceutil/trace.go:171","msg":"trace[690483698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:732; }","duration":"438.405519ms","start":"2026-02-10T15:00:58.097426Z","end":"2026-02-10T15:00:58.535832Z","steps":["trace[690483698] 'agreement among raft nodes before linearized reading' (duration: 438.333847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:58.535864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:58.097414Z","time spent":"438.44454ms","remote":"127.0.0.1:48428","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-02-10T15:00:58.536008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.069065ms","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-02-10T15:00:58.536034Z","caller":"traceutil/trace.go:171","msg":"trace[1572817248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:732; }","duration":"439.105156ms","start":"2026-02-10T15:00:58.096921Z","end":"2026-02-10T15:00:58.536026Z","steps":["trace[1572817248] 'agreement among raft nodes before linearized reading' (duration: 439.033744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:58.536059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:58.096918Z","time spent":"439.135726ms","remote":"127.0.0.1:48428","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-02-10T15:00:58.835826Z","caller":"traceutil/trace.go:171","msg":"trace[1744766697] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"291.412469ms","start":"2026-02-10T15:00:58.544398Z","end":"2026-02-10T15:00:58.835811Z","steps":["trace[1744766697] 'process raft request' (duration: 291.313406ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:58.84438Z","caller":"traceutil/trace.go:171","msg":"trace[1830743830] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"299.814214ms","start":"2026-02-10T15:00:58.544549Z","end":"2026-02-10T15:00:58.844363Z","steps":["trace[1830743830] 'process raft request' (duration: 299.785193ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:00:58.84484Z","caller":"traceutil/trace.go:171","msg":"trace[113589059] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"300.341207ms","start":"2026-02-10T15:00:58.54449Z","end":"2026-02-10T15:00:58.844831Z","steps":["trace[113589059] 'process raft request' (duration: 299.731052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:58.845046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:58.544485Z","time spent":"300.381928ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:00:58.845259Z","caller":"traceutil/trace.go:171","msg":"trace[1503311909] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"300.730176ms","start":"2026-02-10T15:00:58.544521Z","end":"2026-02-10T15:00:58.845251Z","steps":["trace[1503311909] 'process raft request' (duration: 299.789113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:00:58.845365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:58.544517Z","time spent":"300.765727ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:00:59.047021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.564094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:00:59.047107Z","caller":"traceutil/trace.go:171","msg":"trace[1664394449] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:736; }","duration":"147.657636ms","start":"2026-02-10T15:00:58.899417Z","end":"2026-02-10T15:00:59.047075Z","steps":["trace[1664394449] 'count revisions from in-memory index tree' (duration: 147.528803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:00.367209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.895942ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:01:00.367426Z","caller":"traceutil/trace.go:171","msg":"trace[565689525] linearizableReadLoop","detail":"{readStateIndex:765; appliedIndex:763; }","duration":"484.116116ms","start":"2026-02-10T15:00:59.883295Z","end":"2026-02-10T15:01:00.367411Z","steps":["trace[565689525] 'read index received' (duration: 300.118822ms)","trace[565689525] 'applied index is now lower than readState.Index' (duration: 183.995954ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:00.367513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.209048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:00.367539Z","caller":"traceutil/trace.go:171","msg":"trace[1173170621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"484.28126ms","start":"2026-02-10T15:00:59.883249Z","end":"2026-02-10T15:01:00.36753Z","steps":["trace[1173170621] 'agreement among raft nodes before linearized reading' (duration: 484.218498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:00.367566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:59.88323Z","time spent":"484.329031ms","remote":"127.0.0.1:48094","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-02-10T15:01:00.367605Z","caller":"traceutil/trace.go:171","msg":"trace[167723955] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"719.488706ms","start":"2026-02-10T15:00:59.648104Z","end":"2026-02-10T15:01:00.367593Z","steps":["trace[167723955] 'process raft request' (duration: 719.203699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:00.367678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:00:59.648083Z","time spent":"719.549026ms","remote":"127.0.0.1:48428","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-02-10T15:01:00.900505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.720798ms","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-02-10T15:01:00.900611Z","caller":"traceutil/trace.go:171","msg":"trace[259657425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"357.869221ms","start":"2026-02-10T15:01:00.542713Z","end":"2026-02-10T15:01:00.900583Z","steps":["trace[259657425] 'range keys from in-memory index tree' (duration: 357.512712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:00.900661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:00.542656Z","time spent":"357.993664ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-10T15:01:01.341251Z","caller":"traceutil/trace.go:171","msg":"trace[339688991] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"436.043552ms","start":"2026-02-10T15:01:00.905181Z","end":"2026-02-10T15:01:01.341225Z","steps":["trace[339688991] 'process raft request' (duration: 435.883128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:01.341443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:00.905164Z","time spent":"436.176685ms","remote":"127.0.0.1:48428","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-02-10T15:01:01.726586Z","caller":"traceutil/trace.go:171","msg":"trace[1705278993] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"817.450199ms","start":"2026-02-10T15:01:00.909115Z","end":"2026-02-10T15:01:01.726565Z","steps":["trace[1705278993] 'process raft request' (duration: 817.412218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:01.726776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:00.909085Z","time spent":"817.618793ms","remote":"127.0.0.1:48428","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-02-10T15:01:01.727219Z","caller":"traceutil/trace.go:171","msg":"trace[306911035] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"820.478813ms","start":"2026-02-10T15:01:00.906712Z","end":"2026-02-10T15:01:01.727191Z","steps":["trace[306911035] 'process raft request' (duration: 819.733224ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:01.727253Z","caller":"traceutil/trace.go:171","msg":"trace[1031200311] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"819.770426ms","start":"2026-02-10T15:01:00.907462Z","end":"2026-02-10T15:01:01.727232Z","steps":["trace[1031200311] 'process raft request' (duration: 819.033798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:01.727351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:00.907456Z","time spent":"819.845067ms","remote":"127.0.0.1:48428","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-02-10T15:01:01.727379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:00.906706Z","time spent":"820.583415ms","remote":"127.0.0.1:48428","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-02-10T15:01:01.727846Z","caller":"traceutil/trace.go:171","msg":"trace[554218806] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"821.522138ms","start":"2026-02-10T15:01:00.906307Z","end":"2026-02-10T15:01:01.727829Z","steps":["trace[554218806] 'process raft request' (duration: 819.106109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:01.727996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:00.906258Z","time spent":"821.696172ms","remote":"127.0.0.1:48428","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-02-10T15:01:02.059855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.268226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:02.059913Z","caller":"traceutil/trace.go:171","msg":"trace[1485330441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"176.386138ms","start":"2026-02-10T15:01:01.883513Z","end":"2026-02-10T15:01:02.059899Z","steps":["trace[1485330441] 'range keys from in-memory index tree' (duration: 176.199504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:15.257009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:14.419467Z","time spent":"837.538819ms","remote":"127.0.0.1:48134","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-10T15:01:17.564306Z","caller":"traceutil/trace.go:171","msg":"trace[1839744244] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"156.218187ms","start":"2026-02-10T15:01:17.408072Z","end":"2026-02-10T15:01:17.56429Z","steps":["trace[1839744244] 'process raft request' (duration: 156.109404ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:17.933652Z","caller":"traceutil/trace.go:171","msg":"trace[1925750954] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"119.954379ms","start":"2026-02-10T15:01:17.813677Z","end":"2026-02-10T15:01:17.933631Z","steps":["trace[1925750954] 'process raft request' (duration: 119.846657ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:18.193861Z","caller":"traceutil/trace.go:171","msg":"trace[1678349527] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"256.750782ms","start":"2026-02-10T15:01:17.93709Z","end":"2026-02-10T15:01:18.193841Z","steps":["trace[1678349527] 'process raft request' (duration: 215.146556ms)","trace[1678349527] 'compare' (duration: 41.287968ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:18.199877Z","caller":"traceutil/trace.go:171","msg":"trace[2022306424] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"260.995465ms","start":"2026-02-10T15:01:17.938871Z","end":"2026-02-10T15:01:18.199867Z","steps":["trace[2022306424] 'process raft request' (duration: 260.968585ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:18.200087Z","caller":"traceutil/trace.go:171","msg":"trace[276755225] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"262.744628ms","start":"2026-02-10T15:01:17.937336Z","end":"2026-02-10T15:01:18.20008Z","steps":["trace[276755225] 'process raft request' (duration: 262.451911ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:19.842511Z","caller":"traceutil/trace.go:171","msg":"trace[282358397] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"207.307404ms","start":"2026-02-10T15:01:19.635188Z","end":"2026-02-10T15:01:19.842495Z","steps":["trace[282358397] 'process raft request' (duration: 207.181021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:20.453279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.447854ms","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-02-10T15:01:20.453333Z","caller":"traceutil/trace.go:171","msg":"trace[1671061596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:841; }","duration":"249.541666ms","start":"2026-02-10T15:01:20.20378Z","end":"2026-02-10T15:01:20.453321Z","steps":["trace[1671061596] 'range keys from in-memory index tree' (duration: 249.346341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:20.453484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.687274ms","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-02-10T15:01:20.453576Z","caller":"traceutil/trace.go:171","msg":"trace[169680596] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:841; }","duration":"510.834058ms","start":"2026-02-10T15:01:19.942728Z","end":"2026-02-10T15:01:20.453562Z","steps":["trace[169680596] 'range keys from in-memory index tree' (duration: 510.545551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:20.453603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:19.942715Z","time spent":"510.882099ms","remote":"127.0.0.1:48428","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-02-10T15:01:20.453616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.247636ms","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-02-10T15:01:20.453636Z","caller":"traceutil/trace.go:171","msg":"trace[683956394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:841; }","duration":"255.265896ms","start":"2026-02-10T15:01:20.198362Z","end":"2026-02-10T15:01:20.453628Z","steps":["trace[683956394] 'range keys from in-memory index tree' (duration: 255.04765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:20.453691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.850698ms","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-02-10T15:01:20.453703Z","caller":"traceutil/trace.go:171","msg":"trace[187321284] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:841; }","duration":"250.861598ms","start":"2026-02-10T15:01:20.202836Z","end":"2026-02-10T15:01:20.453698Z","steps":["trace[187321284] 'range keys from in-memory index tree' (duration: 250.780376ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:20.668295Z","caller":"traceutil/trace.go:171","msg":"trace[151150706] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"209.234032ms","start":"2026-02-10T15:01:20.459045Z","end":"2026-02-10T15:01:20.668279Z","steps":["trace[151150706] 'process raft request' (duration: 209.117599ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:20.668476Z","caller":"traceutil/trace.go:171","msg":"trace[1822883899] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"210.141093ms","start":"2026-02-10T15:01:20.458326Z","end":"2026-02-10T15:01:20.668467Z","steps":["trace[1822883899] 'process raft request' (duration: 165.77979ms)","trace[1822883899] 'compare' (duration: 43.798259ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:20.668558Z","caller":"traceutil/trace.go:171","msg":"trace[1075150610] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"204.046485ms","start":"2026-02-10T15:01:20.46449Z","end":"2026-02-10T15:01:20.668536Z","steps":["trace[1075150610] 'process raft request' (duration: 203.758818ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:20.948531Z","caller":"traceutil/trace.go:171","msg":"trace[2006665237] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"115.977333ms","start":"2026-02-10T15:01:20.832536Z","end":"2026-02-10T15:01:20.948514Z","steps":["trace[2006665237] 'process raft request' (duration: 115.712146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:22.819815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.307069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:22.819885Z","caller":"traceutil/trace.go:171","msg":"trace[1051671477] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:847; }","duration":"818.422581ms","start":"2026-02-10T15:01:22.001449Z","end":"2026-02-10T15:01:22.819872Z","steps":["trace[1051671477] 'count revisions from in-memory index tree' (duration: 818.237137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:22.819925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:22.001432Z","time spent":"818.480893ms","remote":"127.0.0.1:48654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:01:22.820164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.030677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:22.820219Z","caller":"traceutil/trace.go:171","msg":"trace[1523333527] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:847; }","duration":"582.133059ms","start":"2026-02-10T15:01:22.238074Z","end":"2026-02-10T15:01:22.820207Z","steps":["trace[1523333527] 'count revisions from in-memory index tree' (duration: 581.934504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:22.820247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:22.238053Z","time spent":"582.187091ms","remote":"127.0.0.1:48692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":87,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:01:22.820325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.735431ms","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-02-10T15:01:22.820348Z","caller":"traceutil/trace.go:171","msg":"trace[1600590324] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:847; }","duration":"283.779452ms","start":"2026-02-10T15:01:22.536562Z","end":"2026-02-10T15:01:22.820341Z","steps":["trace[1600590324] 'range keys from in-memory index tree' (duration: 283.648829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:22.820511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.668037ms","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-02-10T15:01:22.820531Z","caller":"traceutil/trace.go:171","msg":"trace[835934074] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"147.733619ms","start":"2026-02-10T15:01:22.672792Z","end":"2026-02-10T15:01:22.820525Z","steps":["trace[835934074] 'range keys from in-memory index tree' (duration: 147.585155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:22.82075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.561346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T15:01:22.820777Z","caller":"traceutil/trace.go:171","msg":"trace[336119894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:847; }","duration":"145.608757ms","start":"2026-02-10T15:01:22.675162Z","end":"2026-02-10T15:01:22.82077Z","steps":["trace[336119894] 'range keys from in-memory index tree' (duration: 145.445713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:22.826385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.420184ms","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-02-10T15:01:22.826446Z","caller":"traceutil/trace.go:171","msg":"trace[1579544419] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"152.529956ms","start":"2026-02-10T15:01:22.673907Z","end":"2026-02-10T15:01:22.826437Z","steps":["trace[1579544419] 'range keys from in-memory index tree' (duration: 146.587811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:23.617463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.918958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:23.617572Z","caller":"traceutil/trace.go:171","msg":"trace[175659452] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"789.930586ms","start":"2026-02-10T15:01:22.827622Z","end":"2026-02-10T15:01:23.617553Z","steps":["trace[175659452] 'process raft request' (duration: 511.856674ms)","trace[175659452] 'compare' (duration: 277.839516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:23.617626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:22.827605Z","time spent":"789.995317ms","remote":"127.0.0.1:48428","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-02-10T15:01:23.622042Z","caller":"traceutil/trace.go:171","msg":"trace[616754130] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"787.488646ms","start":"2026-02-10T15:01:22.83453Z","end":"2026-02-10T15:01:23.622019Z","steps":["trace[616754130] 'process raft request' (duration: 787.312302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:23.622178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:22.834517Z","time spent":"787.588698ms","remote":"127.0.0.1:48428","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-02-10T15:01:23.622232Z","caller":"traceutil/trace.go:171","msg":"trace[1749493542] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"787.62877ms","start":"2026-02-10T15:01:22.834584Z","end":"2026-02-10T15:01:23.622213Z","steps":["trace[1749493542] 'process raft request' (duration: 787.367933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:23.622553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:22.83458Z","time spent":"787.692411ms","remote":"127.0.0.1:48428","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-02-10T15:01:23.622653Z","caller":"traceutil/trace.go:171","msg":"trace[1351619740] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:882; }","duration":"742.281342ms","start":"2026-02-10T15:01:22.88036Z","end":"2026-02-10T15:01:23.622641Z","steps":["trace[1351619740] 'read index received' (duration: 459.126796ms)","trace[1351619740] 'applied index is now lower than readState.Index' (duration: 283.153846ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:23.622839Z","caller":"traceutil/trace.go:171","msg":"trace[701958113] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"788.221424ms","start":"2026-02-10T15:01:22.834608Z","end":"2026-02-10T15:01:23.622829Z","steps":["trace[701958113] 'process raft request' (duration: 787.551037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:23.622896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:22.834605Z","time spent":"788.257465ms","remote":"127.0.0.1:48428","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-02-10T15:01:23.622974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.359678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:23.623058Z","caller":"traceutil/trace.go:171","msg":"trace[755647295] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:852; }","duration":"289.475401ms","start":"2026-02-10T15:01:23.333569Z","end":"2026-02-10T15:01:23.623044Z","steps":["trace[755647295] 'agreement among raft nodes before linearized reading' (duration: 289.369238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:23.62307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.706982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:23.623108Z","caller":"traceutil/trace.go:171","msg":"trace[985178726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"742.743623ms","start":"2026-02-10T15:01:22.880355Z","end":"2026-02-10T15:01:23.623099Z","steps":["trace[985178726] 'agreement among raft nodes before linearized reading' (duration: 742.690802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:23.623134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:22.88032Z","time spent":"742.809235ms","remote":"127.0.0.1:48100","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-02-10T15:01:29.792698Z","caller":"traceutil/trace.go:171","msg":"trace[1981863098] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"112.334853ms","start":"2026-02-10T15:01:29.680344Z","end":"2026-02-10T15:01:29.792679Z","steps":["trace[1981863098] 'process raft request' (duration: 110.049188ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:29.793367Z","caller":"traceutil/trace.go:171","msg":"trace[1078418947] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"112.381364ms","start":"2026-02-10T15:01:29.680972Z","end":"2026-02-10T15:01:29.793353Z","steps":["trace[1078418947] 'process raft request' (duration: 111.605255ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:29.793437Z","caller":"traceutil/trace.go:171","msg":"trace[1393915802] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"111.452532ms","start":"2026-02-10T15:01:29.681976Z","end":"2026-02-10T15:01:29.793429Z","steps":["trace[1393915802] 'process raft request' (duration: 110.621371ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:31.849243Z","caller":"traceutil/trace.go:171","msg":"trace[1808899004] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"169.515019ms","start":"2026-02-10T15:01:31.679713Z","end":"2026-02-10T15:01:31.849228Z","steps":["trace[1808899004] 'read index received' (duration: 169.391736ms)","trace[1808899004] 'applied index is now lower than readState.Index' (duration: 122.753µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:31.849419Z","caller":"traceutil/trace.go:171","msg":"trace[1026666919] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"715.398182ms","start":"2026-02-10T15:01:31.134015Z","end":"2026-02-10T15:01:31.849413Z","steps":["trace[1026666919] 'process raft request' (duration: 715.126565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:31.849486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:31.134Z","time spent":"715.436722ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:31.849627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.924629ms","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-02-10T15:01:31.849642Z","caller":"traceutil/trace.go:171","msg":"trace[273708369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:870; }","duration":"169.96231ms","start":"2026-02-10T15:01:31.679675Z","end":"2026-02-10T15:01:31.849637Z","steps":["trace[273708369] 'agreement among raft nodes before linearized reading' (duration: 169.900859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:32.443713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.147786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:32.443772Z","caller":"traceutil/trace.go:171","msg":"trace[1799324764] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"590.878102ms","start":"2026-02-10T15:01:31.852883Z","end":"2026-02-10T15:01:32.443761Z","steps":["trace[1799324764] 'process raft request' (duration: 389.637394ms)","trace[1799324764] 'compare' (duration: 200.812248ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:32.443803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:31.85287Z","time spent":"590.918633ms","remote":"127.0.0.1:48428","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-02-10T15:01:32.444074Z","caller":"traceutil/trace.go:171","msg":"trace[526486615] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:906; }","duration":"521.341987ms","start":"2026-02-10T15:01:31.922725Z","end":"2026-02-10T15:01:32.444067Z","steps":["trace[526486615] 'read index received' (duration: 318.695155ms)","trace[526486615] 'applied index is now lower than readState.Index' (duration: 202.646352ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:32.444214Z","caller":"traceutil/trace.go:171","msg":"trace[1785728610] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"590.458252ms","start":"2026-02-10T15:01:31.853751Z","end":"2026-02-10T15:01:32.444209Z","steps":["trace[1785728610] 'process raft request' (duration: 590.217786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:32.444248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:31.853745Z","time spent":"590.481153ms","remote":"127.0.0.1:48428","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-02-10T15:01:32.444343Z","caller":"traceutil/trace.go:171","msg":"trace[1093831670] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"589.93401ms","start":"2026-02-10T15:01:31.854391Z","end":"2026-02-10T15:01:32.444325Z","steps":["trace[1093831670] 'process raft request' (duration: 589.612222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:32.444382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:31.854386Z","time spent":"589.975071ms","remote":"127.0.0.1:48428","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-02-10T15:01:32.444467Z","caller":"traceutil/trace.go:171","msg":"trace[75640313] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"588.385652ms","start":"2026-02-10T15:01:31.856075Z","end":"2026-02-10T15:01:32.444461Z","steps":["trace[75640313] 'process raft request' (duration: 587.950092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:32.444533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:31.856068Z","time spent":"588.435283ms","remote":"127.0.0.1:48428","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-02-10T15:01:32.44463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.916661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:32.444652Z","caller":"traceutil/trace.go:171","msg":"trace[943604880] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:874; }","duration":"521.973362ms","start":"2026-02-10T15:01:31.922673Z","end":"2026-02-10T15:01:32.444646Z","steps":["trace[943604880] 'agreement among raft nodes before linearized reading' (duration: 521.933571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:32.444683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:31.922656Z","time spent":"522.017614ms","remote":"127.0.0.1:48532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:01:32.444842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.392311ms","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-02-10T15:01:32.444866Z","caller":"traceutil/trace.go:171","msg":"trace[1729294622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:874; }","duration":"425.448423ms","start":"2026-02-10T15:01:32.019412Z","end":"2026-02-10T15:01:32.44486Z","steps":["trace[1729294622] 'agreement among raft nodes before linearized reading' (duration: 425.369511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:32.444879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:32.019394Z","time spent":"425.482033ms","remote":"127.0.0.1:48428","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":"info","ts":"2026-02-10T15:01:32.691601Z","caller":"traceutil/trace.go:171","msg":"trace[1418316708] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"241.251737ms","start":"2026-02-10T15:01:32.450326Z","end":"2026-02-10T15:01:32.691577Z","steps":["trace[1418316708] 'process raft request' (duration: 172.408329ms)","trace[1418316708] 'compare' (duration: 68.403148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:33.090203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.527544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:33.090283Z","caller":"traceutil/trace.go:171","msg":"trace[1625676644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"208.646827ms","start":"2026-02-10T15:01:32.881619Z","end":"2026-02-10T15:01:33.090265Z","steps":["trace[1625676644] 'range keys from in-memory index tree' (duration: 208.476942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:35.15491Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994189485,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:01:35.552812Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.080690416s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:01:35.600797Z","caller":"traceutil/trace.go:171","msg":"trace[1640312847] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:913; }","duration":"946.615896ms","start":"2026-02-10T15:01:34.654158Z","end":"2026-02-10T15:01:35.600774Z","steps":["trace[1640312847] 'read index received' (duration: 899.019122ms)","trace[1640312847] 'applied index is now lower than readState.Index' (duration: 47.595794ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:35.600951Z","caller":"traceutil/trace.go:171","msg":"trace[1528993911] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"1.117836147s","start":"2026-02-10T15:01:34.483105Z","end":"2026-02-10T15:01:35.600942Z","steps":["trace[1528993911] 'process raft request' (duration: 1.117584011s)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:01:35.600918Z","caller":"traceutil/trace.go:171","msg":"trace[280161252] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"1.117826656s","start":"2026-02-10T15:01:34.483041Z","end":"2026-02-10T15:01:35.600868Z","steps":["trace[280161252] 'process raft request' (duration: 1.11758683s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:35.601066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:34.4831Z","time spent":"1.117873208s","remote":"127.0.0.1:48428","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-02-10T15:01:35.601121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:34.483024Z","time spent":"1.117974491s","remote":"127.0.0.1:48428","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-02-10T15:01:35.601245Z","caller":"traceutil/trace.go:171","msg":"trace[1193654650] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"1.118098723s","start":"2026-02-10T15:01:34.483139Z","end":"2026-02-10T15:01:35.601238Z","steps":["trace[1193654650] 'process raft request' (duration: 1.117580191s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:35.601304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:34.483135Z","time spent":"1.118127914s","remote":"127.0.0.1:48428","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-02-10T15:01:35.6014Z","caller":"traceutil/trace.go:171","msg":"trace[708502282] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"1.118226626s","start":"2026-02-10T15:01:34.483166Z","end":"2026-02-10T15:01:35.601392Z","steps":["trace[708502282] 'process raft request' (duration: 1.11758025s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:35.601442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:34.483162Z","time spent":"1.118259787s","remote":"127.0.0.1:48428","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-02-10T15:01:35.601588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.424016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:35.601617Z","caller":"traceutil/trace.go:171","msg":"trace[27956877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"947.478327ms","start":"2026-02-10T15:01:34.654132Z","end":"2026-02-10T15:01:35.60161Z","steps":["trace[27956877] 'agreement among raft nodes before linearized reading' (duration: 947.389735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:35.601642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:34.654099Z","time spent":"947.539789ms","remote":"127.0.0.1:48094","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-02-10T15:01:35.601782Z","caller":"traceutil/trace.go:171","msg":"trace[928590352] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"1.129681844s","start":"2026-02-10T15:01:34.472041Z","end":"2026-02-10T15:01:35.601723Z","steps":["trace[928590352] 'process raft request' (duration: 1.081186819s)","trace[928590352] 'compare' (duration: 46.250971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:35.601839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.14368ms","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-02-10T15:01:35.601867Z","caller":"traceutil/trace.go:171","msg":"trace[968211607] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:881; }","duration":"907.1793ms","start":"2026-02-10T15:01:34.694679Z","end":"2026-02-10T15:01:35.601859Z","steps":["trace[968211607] 'agreement among raft nodes before linearized reading' (duration: 907.071928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:35.601887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:34.694674Z","time spent":"907.210111ms","remote":"127.0.0.1:48428","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-02-10T15:01:35.601939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:34.472022Z","time spent":"1.129801417s","remote":"127.0.0.1:48134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:01:35.602209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.16763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:35.602233Z","caller":"traceutil/trace.go:171","msg":"trace[2083769903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"723.20838ms","start":"2026-02-10T15:01:34.879019Z","end":"2026-02-10T15:01:35.602227Z","steps":["trace[2083769903] 'agreement among raft nodes before linearized reading' (duration: 723.17358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:35.60225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:34.879011Z","time spent":"723.235352ms","remote":"127.0.0.1:48100","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-02-10T15:01:36.110507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994189492,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:01:36.470682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.009042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:36.470775Z","caller":"traceutil/trace.go:171","msg":"trace[1166109950] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"860.389646ms","start":"2026-02-10T15:01:35.610366Z","end":"2026-02-10T15:01:36.470755Z","steps":["trace[1166109950] 'read index received' (duration: 704.18201ms)","trace[1166109950] 'applied index is now lower than readState.Index' (duration: 156.206626ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:36.471023Z","caller":"traceutil/trace.go:171","msg":"trace[596303763] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"860.675003ms","start":"2026-02-10T15:01:35.610334Z","end":"2026-02-10T15:01:36.471009Z","steps":["trace[596303763] 'process raft request' (duration: 704.271431ms)","trace[596303763] 'compare' (duration: 155.903769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:36.471096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:35.610312Z","time spent":"860.749975ms","remote":"127.0.0.1:48428","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-02-10T15:01:36.471283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.915949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:420"} {"level":"info","ts":"2026-02-10T15:01:36.47132Z","caller":"traceutil/trace.go:171","msg":"trace[742585571] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:882; }","duration":"861.031162ms","start":"2026-02-10T15:01:35.610279Z","end":"2026-02-10T15:01:36.47131Z","steps":["trace[742585571] 'agreement among raft nodes before linearized reading' (duration: 860.96908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.471352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:35.610258Z","time spent":"861.085843ms","remote":"127.0.0.1:48322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-10T15:01:36.471513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.58418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:36.471549Z","caller":"traceutil/trace.go:171","msg":"trace[235075298] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:882; }","duration":"834.671932ms","start":"2026-02-10T15:01:35.636867Z","end":"2026-02-10T15:01:36.471539Z","steps":["trace[235075298] 'agreement among raft nodes before linearized reading' (duration: 834.618931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.471577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:35.636843Z","time spent":"834.726914ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:01:36.471847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.572511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:36.471886Z","caller":"traceutil/trace.go:171","msg":"trace[1134641341] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:882; }","duration":"388.637053ms","start":"2026-02-10T15:01:36.08324Z","end":"2026-02-10T15:01:36.471877Z","steps":["trace[1134641341] 'agreement among raft nodes before linearized reading' (duration: 388.577941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.471917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:36.083226Z","time spent":"388.682793ms","remote":"127.0.0.1:48476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:01:36.472059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.775605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:36.472091Z","caller":"traceutil/trace.go:171","msg":"trace[205715029] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"591.830866ms","start":"2026-02-10T15:01:35.880252Z","end":"2026-02-10T15:01:36.472083Z","steps":["trace[205715029] 'agreement among raft nodes before linearized reading' (duration: 591.785945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.472117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:35.88023Z","time spent":"591.881237ms","remote":"127.0.0.1:48094","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-02-10T15:01:36.472236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.789538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:36.47227Z","caller":"traceutil/trace.go:171","msg":"trace[392354750] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:882; }","duration":"719.86484ms","start":"2026-02-10T15:01:35.752397Z","end":"2026-02-10T15:01:36.472262Z","steps":["trace[392354750] 'agreement among raft nodes before linearized reading' (duration: 719.819629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.472299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:35.752365Z","time spent":"719.92474ms","remote":"127.0.0.1:48632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:01:36.870953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.5332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:36.870998Z","caller":"traceutil/trace.go:171","msg":"trace[918681733] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:882; }","duration":"294.625183ms","start":"2026-02-10T15:01:36.576362Z","end":"2026-02-10T15:01:36.870987Z","steps":["trace[918681733] 'count revisions from in-memory index tree' (duration: 294.477229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.871118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.797611ms","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-02-10T15:01:36.871177Z","caller":"traceutil/trace.go:171","msg":"trace[1649017119] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:882; }","duration":"184.887143ms","start":"2026-02-10T15:01:36.686273Z","end":"2026-02-10T15:01:36.87116Z","steps":["trace[1649017119] 'count revisions from in-memory index tree' (duration: 184.716219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:36.871239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.308995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:36.871256Z","caller":"traceutil/trace.go:171","msg":"trace[2053203022] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:882; }","duration":"103.348756ms","start":"2026-02-10T15:01:36.767903Z","end":"2026-02-10T15:01:36.871251Z","steps":["trace[2053203022] 'count revisions from in-memory index tree' (duration: 103.262523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:40.324482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.071128ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:01:40.324549Z","caller":"traceutil/trace.go:171","msg":"trace[1061358619] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:932; }","duration":"705.822378ms","start":"2026-02-10T15:01:39.618713Z","end":"2026-02-10T15:01:40.324536Z","steps":["trace[1061358619] 'read index received' (duration: 432.714279ms)","trace[1061358619] 'applied index is now lower than readState.Index' (duration: 273.106999ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:40.324936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.220428ms","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-02-10T15:01:40.324958Z","caller":"traceutil/trace.go:171","msg":"trace[1654904328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"706.28153ms","start":"2026-02-10T15:01:39.61867Z","end":"2026-02-10T15:01:40.324952Z","steps":["trace[1654904328] 'agreement among raft nodes before linearized reading' (duration: 706.165737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:40.324981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:39.618652Z","time spent":"706.32363ms","remote":"127.0.0.1:48428","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-02-10T15:01:40.325137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.278272ms","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-02-10T15:01:40.325156Z","caller":"traceutil/trace.go:171","msg":"trace[592032595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"702.336424ms","start":"2026-02-10T15:01:39.622813Z","end":"2026-02-10T15:01:40.32515Z","steps":["trace[592032595] 'agreement among raft nodes before linearized reading' (duration: 702.281542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:40.325174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:39.622803Z","time spent":"702.366984ms","remote":"127.0.0.1:48428","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-02-10T15:01:40.325329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.842985ms","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-02-10T15:01:40.325347Z","caller":"traceutil/trace.go:171","msg":"trace[1879967901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:895; }","duration":"697.874605ms","start":"2026-02-10T15:01:39.627467Z","end":"2026-02-10T15:01:40.325341Z","steps":["trace[1879967901] 'agreement among raft nodes before linearized reading' (duration: 697.822794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:40.325363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:39.62746Z","time spent":"697.900276ms","remote":"127.0.0.1:48428","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-02-10T15:01:40.325469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.110734ms","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-02-10T15:01:40.325486Z","caller":"traceutil/trace.go:171","msg":"trace[867916158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"701.137005ms","start":"2026-02-10T15:01:39.624344Z","end":"2026-02-10T15:01:40.325481Z","steps":["trace[867916158] 'agreement among raft nodes before linearized reading' (duration: 701.089733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:40.325502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:39.624339Z","time spent":"701.159735ms","remote":"127.0.0.1:48428","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-02-10T15:01:40.990201Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994189532,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:01:41.406329Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.07801286s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:01:41.406599Z","caller":"traceutil/trace.go:171","msg":"trace[1272675697] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.078324567s","start":"2026-02-10T15:01:40.328259Z","end":"2026-02-10T15:01:41.406583Z","steps":["trace[1272675697] 'process raft request' (duration: 1.078206164s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:41.406676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:40.328245Z","time spent":"1.078377189s","remote":"127.0.0.1:48428","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-02-10T15:01:42.004314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.742904ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:42.004426Z","caller":"traceutil/trace.go:171","msg":"trace[1012168813] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"1.674997151s","start":"2026-02-10T15:01:40.329415Z","end":"2026-02-10T15:01:42.004412Z","steps":["trace[1012168813] 'process raft request' (duration: 1.281098213s)","trace[1012168813] 'compare' (duration: 393.430516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:42.004468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:40.329408Z","time spent":"1.675040612s","remote":"127.0.0.1:48428","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-02-10T15:01:42.004579Z","caller":"traceutil/trace.go:171","msg":"trace[543969735] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"1.674385257s","start":"2026-02-10T15:01:40.330188Z","end":"2026-02-10T15:01:42.004573Z","steps":["trace[543969735] 'process raft request' (duration: 1.674192032s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.004624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:40.330182Z","time spent":"1.674410627s","remote":"127.0.0.1:48428","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-02-10T15:01:42.004794Z","caller":"traceutil/trace.go:171","msg":"trace[909278779] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:934; }","duration":"1.518162589s","start":"2026-02-10T15:01:40.486608Z","end":"2026-02-10T15:01:42.00477Z","steps":["trace[909278779] 'read index received' (duration: 920.3978ms)","trace[909278779] 'applied index is now lower than readState.Index' (duration: 597.763759ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:42.004922Z","caller":"traceutil/trace.go:171","msg":"trace[1157287299] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"1.67413219s","start":"2026-02-10T15:01:40.33078Z","end":"2026-02-10T15:01:42.004912Z","steps":["trace[1157287299] 'process raft request' (duration: 1.673769171s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.004957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306259063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:42.004983Z","caller":"traceutil/trace.go:171","msg":"trace[751370844] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:899; }","duration":"1.306306704s","start":"2026-02-10T15:01:40.698669Z","end":"2026-02-10T15:01:42.004975Z","steps":["trace[751370844] 'agreement among raft nodes before linearized reading' (duration: 1.306256223s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.005006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:40.698655Z","time spent":"1.306345965s","remote":"127.0.0.1:38480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:01:42.005017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:40.330775Z","time spent":"1.674173151s","remote":"127.0.0.1:48428","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-02-10T15:01:42.005138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.518534038s","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-02-10T15:01:42.005156Z","caller":"traceutil/trace.go:171","msg":"trace[860031435] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"1.518573529s","start":"2026-02-10T15:01:40.486577Z","end":"2026-02-10T15:01:42.005151Z","steps":["trace[860031435] 'agreement among raft nodes before linearized reading' (duration: 1.518506097s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.005171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:40.486563Z","time spent":"1.5186044s","remote":"127.0.0.1:48428","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-02-10T15:01:42.005219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124819115s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:42.005233Z","caller":"traceutil/trace.go:171","msg":"trace[1119165956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"1.124833625s","start":"2026-02-10T15:01:40.880395Z","end":"2026-02-10T15:01:42.005228Z","steps":["trace[1119165956] 'agreement among raft nodes before linearized reading' (duration: 1.124813455s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:42.005246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:40.880347Z","time spent":"1.124896477s","remote":"127.0.0.1:48094","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-02-10T15:01:43.62086Z","caller":"traceutil/trace.go:171","msg":"trace[1852638403] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"106.246906ms","start":"2026-02-10T15:01:43.514599Z","end":"2026-02-10T15:01:43.620846Z","steps":["trace[1852638403] 'process raft request' (duration: 106.155754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:46.292662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.0919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:01:46.292785Z","caller":"traceutil/trace.go:171","msg":"trace[936894119] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"217.245466ms","start":"2026-02-10T15:01:46.075524Z","end":"2026-02-10T15:01:46.29277Z","steps":["trace[936894119] 'read index received' (duration: 101.915811ms)","trace[936894119] 'applied index is now lower than readState.Index' (duration: 115.328555ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:01:46.292854Z","caller":"traceutil/trace.go:171","msg":"trace[30903194] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"247.285694ms","start":"2026-02-10T15:01:46.045541Z","end":"2026-02-10T15:01:46.292826Z","steps":["trace[30903194] 'process raft request' (duration: 131.956618ms)","trace[30903194] 'compare' (duration: 114.626669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:46.292947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.41532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T15:01:46.292976Z","caller":"traceutil/trace.go:171","msg":"trace[1087371329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"217.477672ms","start":"2026-02-10T15:01:46.075489Z","end":"2026-02-10T15:01:46.292966Z","steps":["trace[1087371329] 'agreement among raft nodes before linearized reading' (duration: 217.356299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:46.293124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.403688ms","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-02-10T15:01:46.293151Z","caller":"traceutil/trace.go:171","msg":"trace[864056525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"214.441298ms","start":"2026-02-10T15:01:46.078702Z","end":"2026-02-10T15:01:46.293143Z","steps":["trace[864056525] 'agreement among raft nodes before linearized reading' (duration: 214.372147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:47.931726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994189605,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:01:48.008666Z","caller":"traceutil/trace.go:171","msg":"trace[666197923] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"579.148199ms","start":"2026-02-10T15:01:47.429501Z","end":"2026-02-10T15:01:48.00865Z","steps":["trace[666197923] 'process raft request' (duration: 579.020886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:48.008855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:47.429489Z","time spent":"579.289683ms","remote":"127.0.0.1:48428","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-02-10T15:01:48.009581Z","caller":"traceutil/trace.go:171","msg":"trace[1445055847] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"578.053383ms","start":"2026-02-10T15:01:47.431513Z","end":"2026-02-10T15:01:48.009566Z","steps":["trace[1445055847] 'read index received' (duration: 576.942996ms)","trace[1445055847] 'applied index is now lower than readState.Index' (duration: 1.109267ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:01:48.009672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.154625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:01:48.009747Z","caller":"traceutil/trace.go:171","msg":"trace[1703044586] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:933; }","duration":"578.259657ms","start":"2026-02-10T15:01:47.431482Z","end":"2026-02-10T15:01:48.009742Z","steps":["trace[1703044586] 'agreement among raft nodes before linearized reading' (duration: 578.153065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:48.009797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:01:47.431469Z","time spent":"578.32238ms","remote":"127.0.0.1:48498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:01:48.009724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.885299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:01:48.009896Z","caller":"traceutil/trace.go:171","msg":"trace[584544572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"125.108054ms","start":"2026-02-10T15:01:47.884783Z","end":"2026-02-10T15:01:48.009891Z","steps":["trace[584544572] 'agreement among raft nodes before linearized reading' (duration: 124.916709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:56.598144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.605595ms","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-02-10T15:01:56.598214Z","caller":"traceutil/trace.go:171","msg":"trace[1564430713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:968; }","duration":"262.721488ms","start":"2026-02-10T15:01:56.335477Z","end":"2026-02-10T15:01:56.598198Z","steps":["trace[1564430713] 'range keys from in-memory index tree' (duration: 262.37814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:56.598826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.361874ms","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-02-10T15:01:56.598863Z","caller":"traceutil/trace.go:171","msg":"trace[1642393122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:968; }","duration":"256.425175ms","start":"2026-02-10T15:01:56.342426Z","end":"2026-02-10T15:01:56.598851Z","steps":["trace[1642393122] 'range keys from in-memory index tree' (duration: 256.246041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:01:56.599058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.83336ms","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-02-10T15:01:56.599085Z","caller":"traceutil/trace.go:171","msg":"trace[431800688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"250.881882ms","start":"2026-02-10T15:01:56.348196Z","end":"2026-02-10T15:01:56.599077Z","steps":["trace[431800688] 'range keys from in-memory index tree' (duration: 250.746968ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:00.034456Z","caller":"traceutil/trace.go:171","msg":"trace[1238052209] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"152.075025ms","start":"2026-02-10T15:01:59.882368Z","end":"2026-02-10T15:02:00.034443Z","steps":["trace[1238052209] 'read index received' (duration: 132.380238ms)","trace[1238052209] 'applied index is now lower than readState.Index' (duration: 19.694167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:00.034537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.155707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:00.034553Z","caller":"traceutil/trace.go:171","msg":"trace[2071999392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"152.209999ms","start":"2026-02-10T15:01:59.882337Z","end":"2026-02-10T15:02:00.034548Z","steps":["trace[2071999392] 'agreement among raft nodes before linearized reading' (duration: 152.162348ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:05.305901Z","caller":"traceutil/trace.go:171","msg":"trace[1646457386] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"111.154363ms","start":"2026-02-10T15:02:05.194723Z","end":"2026-02-10T15:02:05.305877Z","steps":["trace[1646457386] 'process raft request' (duration: 110.980959ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:06.338817Z","caller":"traceutil/trace.go:171","msg":"trace[1061456251] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"100.556968ms","start":"2026-02-10T15:02:06.238244Z","end":"2026-02-10T15:02:06.338801Z","steps":["trace[1061456251] 'process raft request' (duration: 100.471616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:07.719753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.003402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:07.719812Z","caller":"traceutil/trace.go:171","msg":"trace[1173737307] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"914.65521ms","start":"2026-02-10T15:02:06.805147Z","end":"2026-02-10T15:02:07.719802Z","steps":["trace[1173737307] 'read index received' (duration: 397.466035ms)","trace[1173737307] 'applied index is now lower than readState.Index' (duration: 517.188455ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:07.719942Z","caller":"traceutil/trace.go:171","msg":"trace[1024241371] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"958.505824ms","start":"2026-02-10T15:02:06.76143Z","end":"2026-02-10T15:02:07.719935Z","steps":["trace[1024241371] 'process raft request' (duration: 441.263596ms)","trace[1024241371] 'compare' (duration: 516.812447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:07.719972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:06.761415Z","time spent":"958.542454ms","remote":"127.0.0.1:48428","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-02-10T15:02:07.720099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.953479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-10T15:02:07.720114Z","caller":"traceutil/trace.go:171","msg":"trace[172366869] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1032; }","duration":"914.990649ms","start":"2026-02-10T15:02:06.805117Z","end":"2026-02-10T15:02:07.720108Z","steps":["trace[172366869] 'agreement among raft nodes before linearized reading' (duration: 914.932467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:07.720127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:06.8051Z","time spent":"915.02391ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T15:02:07.720208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.50799ms","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-02-10T15:02:07.720221Z","caller":"traceutil/trace.go:171","msg":"trace[438665340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1032; }","duration":"891.591531ms","start":"2026-02-10T15:02:06.828625Z","end":"2026-02-10T15:02:07.720217Z","steps":["trace[438665340] 'agreement among raft nodes before linearized reading' (duration: 891.553561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:07.720239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:06.828595Z","time spent":"891.641443ms","remote":"127.0.0.1:48428","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-02-10T15:02:07.720329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.157409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:02:07.720342Z","caller":"traceutil/trace.go:171","msg":"trace[752715757] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1032; }","duration":"138.171879ms","start":"2026-02-10T15:02:07.582166Z","end":"2026-02-10T15:02:07.720338Z","steps":["trace[752715757] 'agreement among raft nodes before linearized reading' (duration: 138.147819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:07.720427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.994094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:07.720441Z","caller":"traceutil/trace.go:171","msg":"trace[502168096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"836.256731ms","start":"2026-02-10T15:02:06.884181Z","end":"2026-02-10T15:02:07.720438Z","steps":["trace[502168096] 'agreement among raft nodes before linearized reading' (duration: 836.23568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:07.720453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:06.884036Z","time spent":"836.414084ms","remote":"127.0.0.1:48094","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-02-10T15:02:10.087301Z","caller":"traceutil/trace.go:171","msg":"trace[95344555] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"203.681787ms","start":"2026-02-10T15:02:09.883574Z","end":"2026-02-10T15:02:10.087255Z","steps":["trace[95344555] 'read index received' (duration: 171.074297ms)","trace[95344555] 'applied index is now lower than readState.Index' (duration: 32.60491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:10.087433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.831411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:10.087469Z","caller":"traceutil/trace.go:171","msg":"trace[574800296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"203.890553ms","start":"2026-02-10T15:02:09.883569Z","end":"2026-02-10T15:02:10.087459Z","steps":["trace[574800296] 'agreement among raft nodes before linearized reading' (duration: 203.79518ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:02:13.085325Z","caller":"traceutil/trace.go:171","msg":"trace[1433669502] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"308.959239ms","start":"2026-02-10T15:02:12.77634Z","end":"2026-02-10T15:02:13.085299Z","steps":["trace[1433669502] 'read index received' (duration: 308.900488ms)","trace[1433669502] 'applied index is now lower than readState.Index' (duration: 57.521µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:13.085569Z","caller":"traceutil/trace.go:171","msg":"trace[524183703] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"347.660707ms","start":"2026-02-10T15:02:12.73785Z","end":"2026-02-10T15:02:13.08551Z","steps":["trace[524183703] 'process raft request' (duration: 347.187056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.085966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.73784Z","time spent":"347.814961ms","remote":"127.0.0.1:48428","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-02-10T15:02:13.086386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.040455ms","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-02-10T15:02:13.086893Z","caller":"traceutil/trace.go:171","msg":"trace[578815189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1053; }","duration":"310.443145ms","start":"2026-02-10T15:02:12.776292Z","end":"2026-02-10T15:02:13.086735Z","steps":["trace[578815189] 'agreement among raft nodes before linearized reading' (duration: 309.84601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.087046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.776273Z","time spent":"310.755512ms","remote":"127.0.0.1:48428","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-02-10T15:02:13.586277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994189873,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:02:13.750109Z","caller":"traceutil/trace.go:171","msg":"trace[1208022396] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1097; }","duration":"664.61148ms","start":"2026-02-10T15:02:13.085477Z","end":"2026-02-10T15:02:13.750088Z","steps":["trace[1208022396] 'read index received' (duration: 596.5575ms)","trace[1208022396] 'applied index is now lower than readState.Index' (duration: 68.05303ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:13.750362Z","caller":"traceutil/trace.go:171","msg":"trace[1398695448] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"962.404748ms","start":"2026-02-10T15:02:12.787944Z","end":"2026-02-10T15:02:13.750349Z","steps":["trace[1398695448] 'process raft request' (duration: 894.141414ms)","trace[1398695448] 'compare' (duration: 67.861615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:13.7505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.787927Z","time spent":"962.462729ms","remote":"127.0.0.1:48428","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-02-10T15:02:13.750691Z","caller":"traceutil/trace.go:171","msg":"trace[1500845561] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"658.196264ms","start":"2026-02-10T15:02:13.092483Z","end":"2026-02-10T15:02:13.750679Z","steps":["trace[1500845561] 'process raft request' (duration: 657.555439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.750743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.092461Z","time spent":"658.251235ms","remote":"127.0.0.1:48428","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-02-10T15:02:13.75088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.126937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:13.750909Z","caller":"traceutil/trace.go:171","msg":"trace[1393970492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"803.189939ms","start":"2026-02-10T15:02:12.94771Z","end":"2026-02-10T15:02:13.7509Z","steps":["trace[1393970492] 'agreement among raft nodes before linearized reading' (duration: 803.144358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.750934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.947691Z","time spent":"803.238139ms","remote":"127.0.0.1:48094","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-02-10T15:02:13.751517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.027099ms","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-02-10T15:02:13.75155Z","caller":"traceutil/trace.go:171","msg":"trace[606709025] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1055; }","duration":"948.102461ms","start":"2026-02-10T15:02:12.803438Z","end":"2026-02-10T15:02:13.751541Z","steps":["trace[606709025] 'agreement among raft nodes before linearized reading' (duration: 948.002689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.751584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:12.803419Z","time spent":"948.157712ms","remote":"127.0.0.1:48624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:02:13.751818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.200386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-10T15:02:13.751846Z","caller":"traceutil/trace.go:171","msg":"trace[439263145] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1055; }","duration":"557.264667ms","start":"2026-02-10T15:02:13.194572Z","end":"2026-02-10T15:02:13.751836Z","steps":["trace[439263145] 'agreement among raft nodes before linearized reading' (duration: 557.164835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:13.75187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.194542Z","time spent":"557.322039ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T15:02:14.559346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.967103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:02:14.559457Z","caller":"traceutil/trace.go:171","msg":"trace[1375431986] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1099; }","duration":"729.441921ms","start":"2026-02-10T15:02:13.829998Z","end":"2026-02-10T15:02:14.55944Z","steps":["trace[1375431986] 'read index received' (duration: 426.348924ms)","trace[1375431986] 'applied index is now lower than readState.Index' (duration: 303.092427ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:02:14.559664Z","caller":"traceutil/trace.go:171","msg":"trace[766448296] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"800.557675ms","start":"2026-02-10T15:02:13.759098Z","end":"2026-02-10T15:02:14.559656Z","steps":["trace[766448296] 'process raft request' (duration: 497.236843ms)","trace[766448296] 'compare' (duration: 302.678777ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:02:14.559723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.75909Z","time spent":"800.606416ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:14.560286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.833618ms","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-02-10T15:02:14.560316Z","caller":"traceutil/trace.go:171","msg":"trace[1178173612] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"726.882169ms","start":"2026-02-10T15:02:13.833428Z","end":"2026-02-10T15:02:14.56031Z","steps":["trace[1178173612] 'agreement among raft nodes before linearized reading' (duration: 726.424668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:14.560333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.833422Z","time spent":"726.907529ms","remote":"127.0.0.1:48428","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-02-10T15:02:14.560431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.432795ms","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-02-10T15:02:14.560447Z","caller":"traceutil/trace.go:171","msg":"trace[1289284820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1057; }","duration":"730.479116ms","start":"2026-02-10T15:02:13.829964Z","end":"2026-02-10T15:02:14.560443Z","steps":["trace[1289284820] 'agreement among raft nodes before linearized reading' (duration: 729.862821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:14.56046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:13.829948Z","time spent":"730.507937ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T15:02:14.560518Z","caller":"traceutil/trace.go:171","msg":"trace[1680510977] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"400.922258ms","start":"2026-02-10T15:02:14.159591Z","end":"2026-02-10T15:02:14.560513Z","steps":["trace[1680510977] 'process raft request' (duration: 399.820321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:14.560554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:14.159574Z","time spent":"400.964218ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:02:14.561471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.44896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.6\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-02-10T15:02:14.561493Z","caller":"traceutil/trace.go:171","msg":"trace[157360020] range","detail":"{range_begin:/registry/masterleases/199.204.45.6; range_end:; response_count:1; response_revision:1057; }","duration":"139.514741ms","start":"2026-02-10T15:02:14.421972Z","end":"2026-02-10T15:02:14.561487Z","steps":["trace[157360020] 'agreement among raft nodes before linearized reading' (duration: 139.45552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:14.561604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.460428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-10T15:02:14.561617Z","caller":"traceutil/trace.go:171","msg":"trace[780821634] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1057; }","duration":"155.500449ms","start":"2026-02-10T15:02:14.406112Z","end":"2026-02-10T15:02:14.561612Z","steps":["trace[780821634] 'agreement among raft nodes before linearized reading' (duration: 155.464729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:14.559121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.799185ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:02:14.562446Z","caller":"traceutil/trace.go:171","msg":"trace[1354803376] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1055; }","duration":"234.141285ms","start":"2026-02-10T15:02:14.328298Z","end":"2026-02-10T15:02:14.56244Z","steps":["trace[1354803376] 'range keys from in-memory index tree' (duration: 230.785724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:14.563219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.953623ms","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-02-10T15:02:14.56324Z","caller":"traceutil/trace.go:171","msg":"trace[353365255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1057; }","duration":"197.009065ms","start":"2026-02-10T15:02:14.366226Z","end":"2026-02-10T15:02:14.563235Z","steps":["trace[353365255] 'agreement among raft nodes before linearized reading' (duration: 196.956774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:14.563351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.417718ms","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-02-10T15:02:14.563369Z","caller":"traceutil/trace.go:171","msg":"trace[90134205] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1057; }","duration":"377.456659ms","start":"2026-02-10T15:02:14.185908Z","end":"2026-02-10T15:02:14.563365Z","steps":["trace[90134205] 'agreement among raft nodes before linearized reading' (duration: 377.410018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:02:14.563381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:02:14.185898Z","time spent":"377.480049ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-10T15:02:54.025185Z","caller":"traceutil/trace.go:171","msg":"trace[1934924178] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"110.796247ms","start":"2026-02-10T15:02:53.914364Z","end":"2026-02-10T15:02:54.025161Z","steps":["trace[1934924178] 'process raft request' (duration: 110.633723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:50.32569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994190898,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:03:50.826545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5563806222994190898,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:03:51.06743Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.305863419s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:03:51.068377Z","caller":"traceutil/trace.go:171","msg":"trace[818315978] linearizableReadLoop","detail":"{readStateIndex:1513; appliedIndex:1512; }","duration":"1.243684216s","start":"2026-02-10T15:03:49.824682Z","end":"2026-02-10T15:03:51.068366Z","steps":["trace[818315978] 'read index received' (duration: 1.243464491s)","trace[818315978] 'applied index is now lower than readState.Index' (duration: 219.185µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:51.068529Z","caller":"traceutil/trace.go:171","msg":"trace[2050657453] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"1.307000957s","start":"2026-02-10T15:03:49.761516Z","end":"2026-02-10T15:03:51.068517Z","steps":["trace[2050657453] 'process raft request' (duration: 1.306664849s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:51.068612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.761507Z","time spent":"1.307042158s","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:03:51.06955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.244871726s","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-02-10T15:03:51.069573Z","caller":"traceutil/trace.go:171","msg":"trace[1789773011] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1449; }","duration":"1.244922377s","start":"2026-02-10T15:03:49.824644Z","end":"2026-02-10T15:03:51.069566Z","steps":["trace[1789773011] 'agreement among raft nodes before linearized reading' (duration: 1.244063486s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:51.069598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.824629Z","time spent":"1.244964348s","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T15:03:51.069707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185698707s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:03:51.069732Z","caller":"traceutil/trace.go:171","msg":"trace[198018072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1449; }","duration":"1.185751469s","start":"2026-02-10T15:03:49.883974Z","end":"2026-02-10T15:03:51.069726Z","steps":["trace[198018072] 'agreement among raft nodes before linearized reading' (duration: 1.185715148s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:51.069746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.883949Z","time spent":"1.185793999s","remote":"127.0.0.1:48094","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-02-10T15:03:51.070251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.507068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-10T15:03:51.070275Z","caller":"traceutil/trace.go:171","msg":"trace[709767772] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1449; }","duration":"336.555509ms","start":"2026-02-10T15:03:50.733713Z","end":"2026-02-10T15:03:51.070268Z","steps":["trace[709767772] 'agreement among raft nodes before linearized reading' (duration: 336.493677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:51.070291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:50.733699Z","time spent":"336.58722ms","remote":"127.0.0.1:48428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-10T15:03:51.070539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.260983ms","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-02-10T15:03:51.070561Z","caller":"traceutil/trace.go:171","msg":"trace[345679086] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1449; }","duration":"691.323854ms","start":"2026-02-10T15:03:50.379231Z","end":"2026-02-10T15:03:51.070554Z","steps":["trace[345679086] 'agreement among raft nodes before linearized reading' (duration: 691.271543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:51.07058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:50.379219Z","time spent":"691.355005ms","remote":"127.0.0.1:48420","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":"warn","ts":"2026-02-10T15:03:51.070714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.67066ms","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-02-10T15:03:51.070733Z","caller":"traceutil/trace.go:171","msg":"trace[2106674417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1449; }","duration":"831.744072ms","start":"2026-02-10T15:03:50.238983Z","end":"2026-02-10T15:03:51.070727Z","steps":["trace[2106674417] 'agreement among raft nodes before linearized reading' (duration: 831.693071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:51.070749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:50.238965Z","time spent":"831.779623ms","remote":"127.0.0.1:48428","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\" "}