{"level":"warn","ts":"2026-02-17T19:21:50.066492Z","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-17T19:21:50.066594Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-17T19:21:50.066658Z","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-17T19:21:50.066667Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-17T19:21:50.06673Z","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-17T19:21:50.067415Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-02-17T19:21:50.067481Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-17T19:21:50.07702Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.34511ms"} {"level":"info","ts":"2026-02-17T19:21:50.109004Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-02-17T19:21:50.109076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T19:21:50.109094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-02-17T19:21:50.109104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T19:21:50.10911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-02-17T19:21:50.109138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-02-17T19:21:50.125234Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T19:21:50.132212Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T19:21:50.135814Z","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-17T19:21:50.141134Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T19:21:50.151901Z","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-17T19:21:50.151975Z","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-17T19:21:50.151982Z","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-17T19:21:50.153109Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T19:21:50.155977Z","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-17T19:21:50.156222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-02-17T19:21:50.15757Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-02-17T19:21:50.15765Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-17T19:21:50.157679Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-02-17T19:21:50.157915Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T19:21:50.15794Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T19:21:50.51924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T19:21:50.519278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T19:21:50.519297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-02-17T19:21:50.51931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-02-17T19:21:50.519316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-17T19:21:50.519323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-02-17T19:21:50.51933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-02-17T19:21:50.549945Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:21:50.552993Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T19:21:50.553265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:21:50.553642Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:21:50.553888Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T19:21:50.553902Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T19:21:50.581189Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T19:21:50.556067Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-02-17T19:21:50.582896Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:21:50.583Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:21:50.583034Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-17T19:21:56.238672Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555030860,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:21:56.328314Z","caller":"traceutil/trace.go:171","msg":"trace[188263670] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"680.245895ms","start":"2026-02-17T19:21:55.648034Z","end":"2026-02-17T19:21:56.32828Z","steps":["trace[188263670] 'process raft request' (duration: 680.089172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:56.32917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:55.648023Z","time spent":"680.446291ms","remote":"127.0.0.1:47848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:21:56.334746Z","caller":"traceutil/trace.go:171","msg":"trace[346851632] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"604.636595ms","start":"2026-02-17T19:21:55.730098Z","end":"2026-02-17T19:21:56.334734Z","steps":["trace[346851632] 'process raft request' (duration: 604.380279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:56.334873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:55.730078Z","time spent":"604.703346ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:21:56.335072Z","caller":"traceutil/trace.go:171","msg":"trace[1638594276] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:271; }","duration":"596.405662ms","start":"2026-02-17T19:21:55.738637Z","end":"2026-02-17T19:21:56.335043Z","steps":["trace[1638594276] 'read index received' (duration: 589.404177ms)","trace[1638594276] 'applied index is now lower than readState.Index' (duration: 7.000875ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:21:56.335131Z","caller":"traceutil/trace.go:171","msg":"trace[1413839352] transaction","detail":"{read_only:false; number_of_response:0; response_revision:267; }","duration":"486.478912ms","start":"2026-02-17T19:21:55.848627Z","end":"2026-02-17T19:21:56.335106Z","steps":["trace[1413839352] 'process raft request' (duration: 486.021171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:56.33516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:55.848608Z","time spent":"486.538434ms","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:21:56.335316Z","caller":"traceutil/trace.go:171","msg":"trace[853247102] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"265.873775ms","start":"2026-02-17T19:21:56.069436Z","end":"2026-02-17T19:21:56.33531Z","steps":["trace[853247102] 'process raft request' (duration: 265.24336ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:56.335701Z","caller":"traceutil/trace.go:171","msg":"trace[557095124] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"262.455944ms","start":"2026-02-17T19:21:56.073237Z","end":"2026-02-17T19:21:56.335693Z","steps":["trace[557095124] 'process raft request' (duration: 261.668255ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:56.336505Z","caller":"traceutil/trace.go:171","msg":"trace[1677627787] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"263.315775ms","start":"2026-02-17T19:21:56.073167Z","end":"2026-02-17T19:21:56.336483Z","steps":["trace[1677627787] 'process raft request' (duration: 261.668346ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:21:56.336642Z","caller":"traceutil/trace.go:171","msg":"trace[250696346] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"263.303614ms","start":"2026-02-17T19:21:56.073328Z","end":"2026-02-17T19:21:56.336632Z","steps":["trace[250696346] 'process raft request' (duration: 261.693486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:56.336856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.407113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:56.336885Z","caller":"traceutil/trace.go:171","msg":"trace[1801176070] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:272; }","duration":"588.477675ms","start":"2026-02-17T19:21:55.748401Z","end":"2026-02-17T19:21:56.336879Z","steps":["trace[1801176070] 'agreement among raft nodes before linearized reading' (duration: 588.389933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:56.336916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:55.748386Z","time spent":"588.524086ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-17T19:21:56.337026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.381408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-02-17T19:21:56.337138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.742333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:21:56.337175Z","caller":"traceutil/trace.go:171","msg":"trace[571030841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:272; }","duration":"333.814125ms","start":"2026-02-17T19:21:56.003355Z","end":"2026-02-17T19:21:56.337169Z","steps":["trace[571030841] 'agreement among raft nodes before linearized reading' (duration: 333.766724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:56.337201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:56.003334Z","time spent":"333.862596ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T19:21:56.337163Z","caller":"traceutil/trace.go:171","msg":"trace[108226138] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:272; }","duration":"598.550431ms","start":"2026-02-17T19:21:55.738603Z","end":"2026-02-17T19:21:56.337153Z","steps":["trace[108226138] 'agreement among raft nodes before linearized reading' (duration: 598.247304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:21:56.337386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:21:55.73859Z","time spent":"598.785228ms","remote":"127.0.0.1:48120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":686,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-02-17T19:21:56.33509Z","caller":"traceutil/trace.go:171","msg":"trace[510163321] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"265.392304ms","start":"2026-02-17T19:21:56.069693Z","end":"2026-02-17T19:21:56.335086Z","steps":["trace[510163321] 'process raft request' (duration: 265.066846ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:01.568869Z","caller":"traceutil/trace.go:171","msg":"trace[372852882] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"172.77583ms","start":"2026-02-17T19:22:01.396077Z","end":"2026-02-17T19:22:01.568853Z","steps":["trace[372852882] 'process raft request' (duration: 148.709543ms)","trace[372852882] 'compare' (duration: 24.001686ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:01.572332Z","caller":"traceutil/trace.go:171","msg":"trace[2113605265] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:367; }","duration":"159.279853ms","start":"2026-02-17T19:22:01.413039Z","end":"2026-02-17T19:22:01.572319Z","steps":["trace[2113605265] 'read index received' (duration: 131.754615ms)","trace[2113605265] 'applied index is now lower than readState.Index' (duration: 27.524408ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:01.573593Z","caller":"traceutil/trace.go:171","msg":"trace[1448759052] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"177.000981ms","start":"2026-02-17T19:22:01.39658Z","end":"2026-02-17T19:22:01.573581Z","steps":["trace[1448759052] 'process raft request' (duration: 175.410853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:01.573878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.839139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-17T19:22:01.573915Z","caller":"traceutil/trace.go:171","msg":"trace[1242694739] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:360; }","duration":"160.907001ms","start":"2026-02-17T19:22:01.412987Z","end":"2026-02-17T19:22:01.573895Z","steps":["trace[1242694739] 'agreement among raft nodes before linearized reading' (duration: 160.782548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:01.574429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.166719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-17T19:22:01.574721Z","caller":"traceutil/trace.go:171","msg":"trace[35508363] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:360; }","duration":"111.313043ms","start":"2026-02-17T19:22:01.463202Z","end":"2026-02-17T19:22:01.574515Z","steps":["trace[35508363] 'agreement among raft nodes before linearized reading' (duration: 110.941544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:02.29603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.743144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:22:02.296102Z","caller":"traceutil/trace.go:171","msg":"trace[1294385366] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"699.648074ms","start":"2026-02-17T19:22:01.59644Z","end":"2026-02-17T19:22:02.296088Z","steps":["trace[1294385366] 'process raft request' (duration: 466.609893ms)","trace[1294385366] 'compare' (duration: 232.313474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:02.296139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:01.596427Z","time spent":"699.695215ms","remote":"127.0.0.1:48090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:22:02.296699Z","caller":"traceutil/trace.go:171","msg":"trace[1538919992] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:369; }","duration":"684.015935ms","start":"2026-02-17T19:22:01.612675Z","end":"2026-02-17T19:22:02.296691Z","steps":["trace[1538919992] 'read index received' (duration: 450.877712ms)","trace[1538919992] 'applied index is now lower than readState.Index' (duration: 233.137633ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:02.296744Z","caller":"traceutil/trace.go:171","msg":"trace[1429154691] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"690.389815ms","start":"2026-02-17T19:22:01.606349Z","end":"2026-02-17T19:22:02.296739Z","steps":["trace[1429154691] 'process raft request' (duration: 690.217671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:02.296775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:01.606339Z","time spent":"690.417706ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:22:02.296888Z","caller":"traceutil/trace.go:171","msg":"trace[1809761958] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"690.458847ms","start":"2026-02-17T19:22:01.606423Z","end":"2026-02-17T19:22:02.296882Z","steps":["trace[1809761958] 'process raft request' (duration: 690.180381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:02.296915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:01.606419Z","time spent":"690.482338ms","remote":"127.0.0.1:48026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:22:02.297041Z","caller":"traceutil/trace.go:171","msg":"trace[295151790] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"690.559519ms","start":"2026-02-17T19:22:01.606476Z","end":"2026-02-17T19:22:02.297035Z","steps":["trace[295151790] 'process raft request' (duration: 690.153719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:02.298387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:01.606451Z","time spent":"690.607061ms","remote":"127.0.0.1:47924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4711,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:22:02.298553Z","caller":"traceutil/trace.go:171","msg":"trace[372190712] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"691.975082ms","start":"2026-02-17T19:22:01.60657Z","end":"2026-02-17T19:22:02.298545Z","steps":["trace[372190712] 'process raft request' (duration: 690.084278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:02.298585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:01.606563Z","time spent":"692.006323ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:22:02.298688Z","caller":"traceutil/trace.go:171","msg":"trace[1817659022] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"690.769665ms","start":"2026-02-17T19:22:01.607912Z","end":"2026-02-17T19:22:02.298681Z","steps":["trace[1817659022] 'process raft request' (duration: 688.759577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:02.298714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:01.607895Z","time spent":"690.807075ms","remote":"127.0.0.1:47906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T19:22:02.298799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.121374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-17T19:22:02.298823Z","caller":"traceutil/trace.go:171","msg":"trace[1032503291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:366; }","duration":"686.157146ms","start":"2026-02-17T19:22:01.612655Z","end":"2026-02-17T19:22:02.298812Z","steps":["trace[1032503291] 'agreement among raft nodes before linearized reading' (duration: 686.118735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:02.298841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:01.612648Z","time spent":"686.189136ms","remote":"127.0.0.1:47950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-17T19:22:02.298928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.601406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-17T19:22:02.298945Z","caller":"traceutil/trace.go:171","msg":"trace[1149719230] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:366; }","duration":"495.682318ms","start":"2026-02-17T19:22:01.803257Z","end":"2026-02-17T19:22:02.298939Z","steps":["trace[1149719230] 'agreement among raft nodes before linearized reading' (duration: 495.652387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:02.298963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:01.803242Z","time spent":"495.717149ms","remote":"127.0.0.1:47924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4278,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-17T19:22:13.079222Z","caller":"traceutil/trace.go:171","msg":"trace[974713632] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"592.459728ms","start":"2026-02-17T19:22:12.486748Z","end":"2026-02-17T19:22:13.079208Z","steps":["trace[974713632] 'process raft request' (duration: 592.389296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:13.079308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:12.48674Z","time spent":"592.522169ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:22:13.079509Z","caller":"traceutil/trace.go:171","msg":"trace[1464918179] linearizableReadLoop","detail":"{readStateIndex:471; appliedIndex:471; }","duration":"456.909754ms","start":"2026-02-17T19:22:12.622594Z","end":"2026-02-17T19:22:13.079503Z","steps":["trace[1464918179] 'read index received' (duration: 456.907654ms)","trace[1464918179] 'applied index is now lower than readState.Index' (duration: 1.81µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:13.079543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.948736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:13.07956Z","caller":"traceutil/trace.go:171","msg":"trace[917507987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"456.965386ms","start":"2026-02-17T19:22:12.622589Z","end":"2026-02-17T19:22:13.079554Z","steps":["trace[917507987] 'agreement among raft nodes before linearized reading' (duration: 456.939365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:13.079573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:12.622533Z","time spent":"457.037467ms","remote":"127.0.0.1:47788","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-17T19:22:13.338034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.57041ms","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-17T19:22:13.338121Z","caller":"traceutil/trace.go:171","msg":"trace[1743837661] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:457; }","duration":"179.666633ms","start":"2026-02-17T19:22:13.158441Z","end":"2026-02-17T19:22:13.338108Z","steps":["trace[1743837661] 'range keys from in-memory index tree' (duration: 179.56018ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:13.338791Z","caller":"traceutil/trace.go:171","msg":"trace[915977038] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:471; }","duration":"259.255149ms","start":"2026-02-17T19:22:13.07952Z","end":"2026-02-17T19:22:13.338775Z","steps":["trace[915977038] 'read index received' (duration: 258.306996ms)","trace[915977038] 'applied index is now lower than readState.Index' (duration: 947.022µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:13.338909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.865663ms","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-17T19:22:13.338939Z","caller":"traceutil/trace.go:171","msg":"trace[298747706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:458; }","duration":"560.925755ms","start":"2026-02-17T19:22:12.778006Z","end":"2026-02-17T19:22:13.338932Z","steps":["trace[298747706] 'agreement among raft nodes before linearized reading' (duration: 560.875024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:13.338961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:12.777991Z","time spent":"560.964966ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:22:13.339083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.468243ms","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-17T19:22:13.339103Z","caller":"traceutil/trace.go:171","msg":"trace[1659687090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:458; }","duration":"346.514874ms","start":"2026-02-17T19:22:12.992582Z","end":"2026-02-17T19:22:13.339097Z","steps":["trace[1659687090] 'agreement among raft nodes before linearized reading' (duration: 346.480563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:13.339122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:12.992569Z","time spent":"346.546935ms","remote":"127.0.0.1:48022","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-17T19:22:13.339219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.57716ms","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-17T19:22:13.339241Z","caller":"traceutil/trace.go:171","msg":"trace[2025297857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:458; }","duration":"555.62011ms","start":"2026-02-17T19:22:12.783612Z","end":"2026-02-17T19:22:13.339232Z","steps":["trace[2025297857] 'agreement among raft nodes before linearized reading' (duration: 555.577769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:13.339259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:12.783605Z","time spent":"555.649591ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:22:13.339499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.9043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:13.339522Z","caller":"traceutil/trace.go:171","msg":"trace[666403245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"258.944831ms","start":"2026-02-17T19:22:13.080571Z","end":"2026-02-17T19:22:13.339516Z","steps":["trace[666403245] 'agreement among raft nodes before linearized reading' (duration: 258.9114ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:13.341649Z","caller":"traceutil/trace.go:171","msg":"trace[1828160401] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"297.904169ms","start":"2026-02-17T19:22:13.040956Z","end":"2026-02-17T19:22:13.338861Z","steps":["trace[1828160401] 'process raft request' (duration: 296.887225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:13.341723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:13.040935Z","time spent":"300.744946ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:22:21.46082Z","caller":"traceutil/trace.go:171","msg":"trace[154494761] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"348.852639ms","start":"2026-02-17T19:22:21.111951Z","end":"2026-02-17T19:22:21.460804Z","steps":["trace[154494761] 'process raft request' (duration: 348.631143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:21.460924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:21.111933Z","time spent":"348.939521ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":488,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:22:27.258001Z","caller":"traceutil/trace.go:171","msg":"trace[490169672] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"113.099335ms","start":"2026-02-17T19:22:27.144878Z","end":"2026-02-17T19:22:27.257977Z","steps":["trace[490169672] 'process raft request' (duration: 112.91872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:29.781868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.71606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:22:29.782008Z","caller":"traceutil/trace.go:171","msg":"trace[84176188] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"292.734896ms","start":"2026-02-17T19:22:29.489258Z","end":"2026-02-17T19:22:29.781993Z","steps":["trace[84176188] 'read index received' (duration: 59.441µs)","trace[84176188] 'applied index is now lower than readState.Index' (duration: 292.674185ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:29.78211Z","caller":"traceutil/trace.go:171","msg":"trace[186368674] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"408.76074ms","start":"2026-02-17T19:22:29.37334Z","end":"2026-02-17T19:22:29.782101Z","steps":["trace[186368674] 'compare' (duration: 400.603198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:29.782181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:29.373323Z","time spent":"408.832792ms","remote":"127.0.0.1:47832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T19:22:29.782638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.604166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-17T19:22:29.78265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.380282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T19:22:29.782682Z","caller":"traceutil/trace.go:171","msg":"trace[1717718640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"276.651888ms","start":"2026-02-17T19:22:29.506017Z","end":"2026-02-17T19:22:29.782669Z","steps":["trace[1717718640] 'agreement among raft nodes before linearized reading' (duration: 276.490854ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:29.782688Z","caller":"traceutil/trace.go:171","msg":"trace[1950604480] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"293.479454ms","start":"2026-02-17T19:22:29.489198Z","end":"2026-02-17T19:22:29.782677Z","steps":["trace[1950604480] 'agreement among raft nodes before linearized reading' (duration: 293.229399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:29.782872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.399248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:29.7829Z","caller":"traceutil/trace.go:171","msg":"trace[1754180164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"157.446419ms","start":"2026-02-17T19:22:29.625445Z","end":"2026-02-17T19:22:29.782891Z","steps":["trace[1754180164] 'agreement among raft nodes before linearized reading' (duration: 157.404108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:29.783076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.407829ms","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-17T19:22:29.783108Z","caller":"traceutil/trace.go:171","msg":"trace[307471740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"275.48196ms","start":"2026-02-17T19:22:29.507617Z","end":"2026-02-17T19:22:29.783099Z","steps":["trace[307471740] 'agreement among raft nodes before linearized reading' (duration: 275.342837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:29.783269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.256468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T19:22:29.783298Z","caller":"traceutil/trace.go:171","msg":"trace[1788950305] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:532; }","duration":"200.286349ms","start":"2026-02-17T19:22:29.583003Z","end":"2026-02-17T19:22:29.783289Z","steps":["trace[1788950305] 'agreement among raft nodes before linearized reading' (duration: 200.233388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:29.78336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.628965ms","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-17T19:22:29.78341Z","caller":"traceutil/trace.go:171","msg":"trace[1917307432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"270.691198ms","start":"2026-02-17T19:22:29.51271Z","end":"2026-02-17T19:22:29.783401Z","steps":["trace[1917307432] 'agreement among raft nodes before linearized reading' (duration: 270.568745ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:29.960719Z","caller":"traceutil/trace.go:171","msg":"trace[1159585063] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"169.374931ms","start":"2026-02-17T19:22:29.791324Z","end":"2026-02-17T19:22:29.960699Z","steps":["trace[1159585063] 'process raft request' (duration: 159.078348ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:29.961177Z","caller":"traceutil/trace.go:171","msg":"trace[496145547] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"169.371381ms","start":"2026-02-17T19:22:29.791794Z","end":"2026-02-17T19:22:29.961166Z","steps":["trace[496145547] 'process raft request' (duration: 168.695695ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:29.963528Z","caller":"traceutil/trace.go:171","msg":"trace[1181111676] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"165.686673ms","start":"2026-02-17T19:22:29.797833Z","end":"2026-02-17T19:22:29.96352Z","steps":["trace[1181111676] 'process raft request' (duration: 163.286137ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:29.963599Z","caller":"traceutil/trace.go:171","msg":"trace[63088784] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"170.703672ms","start":"2026-02-17T19:22:29.792886Z","end":"2026-02-17T19:22:29.96359Z","steps":["trace[63088784] 'read index received' (duration: 157.515121ms)","trace[63088784] 'applied index is now lower than readState.Index' (duration: 13.187991ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:29.963679Z","caller":"traceutil/trace.go:171","msg":"trace[736609635] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"165.771686ms","start":"2026-02-17T19:22:29.7979Z","end":"2026-02-17T19:22:29.963672Z","steps":["trace[736609635] 'process raft request' (duration: 165.584972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:29.963881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.982049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-17T19:22:29.963904Z","caller":"traceutil/trace.go:171","msg":"trace[185847758] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:536; }","duration":"171.028999ms","start":"2026-02-17T19:22:29.792869Z","end":"2026-02-17T19:22:29.963898Z","steps":["trace[185847758] 'agreement among raft nodes before linearized reading' (duration: 170.954808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:33.836312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.153866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:33.836376Z","caller":"traceutil/trace.go:171","msg":"trace[1423224412] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:559; }","duration":"717.248399ms","start":"2026-02-17T19:22:33.119113Z","end":"2026-02-17T19:22:33.836361Z","steps":["trace[1423224412] 'range keys from in-memory index tree' (duration: 717.122286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:33.836408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:33.119107Z","time spent":"717.29407ms","remote":"127.0.0.1:47808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-17T19:22:33.836588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.484832ms","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-17T19:22:33.836688Z","caller":"traceutil/trace.go:171","msg":"trace[1258798692] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:559; }","duration":"677.595084ms","start":"2026-02-17T19:22:33.159078Z","end":"2026-02-17T19:22:33.836673Z","steps":["trace[1258798692] 'range keys from in-memory index tree' (duration: 677.474412ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:33.83671Z","caller":"traceutil/trace.go:171","msg":"trace[193507562] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"714.522514ms","start":"2026-02-17T19:22:33.122177Z","end":"2026-02-17T19:22:33.8367Z","steps":["trace[193507562] 'process raft request' (duration: 674.599774ms)","trace[193507562] 'compare' (duration: 38.896086ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:33.836807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:33.122168Z","time spent":"714.562105ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:22:33.839956Z","caller":"traceutil/trace.go:171","msg":"trace[23244936] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"412.454048ms","start":"2026-02-17T19:22:33.427488Z","end":"2026-02-17T19:22:33.839942Z","steps":["trace[23244936] 'process raft request' (duration: 412.241892ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:33.839994Z","caller":"traceutil/trace.go:171","msg":"trace[1604179214] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:577; }","duration":"216.029659ms","start":"2026-02-17T19:22:33.623939Z","end":"2026-02-17T19:22:33.839969Z","steps":["trace[1604179214] 'read index received' (duration: 172.850552ms)","trace[1604179214] 'applied index is now lower than readState.Index' (duration: 43.177417ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:33.840011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:33.427468Z","time spent":"412.518119ms","remote":"127.0.0.1:47808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:22:33.840096Z","caller":"traceutil/trace.go:171","msg":"trace[1344081356] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"595.210663ms","start":"2026-02-17T19:22:33.244877Z","end":"2026-02-17T19:22:33.840087Z","steps":["trace[1344081356] 'process raft request' (duration: 594.735522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:33.840129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:33.244851Z","time spent":"595.262825ms","remote":"127.0.0.1:47808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:22:33.840339Z","caller":"traceutil/trace.go:171","msg":"trace[880218495] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"182.238884ms","start":"2026-02-17T19:22:33.658092Z","end":"2026-02-17T19:22:33.840331Z","steps":["trace[880218495] 'process raft request' (duration: 181.719941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:33.840554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.612083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:22:33.84058Z","caller":"traceutil/trace.go:171","msg":"trace[1698448456] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:563; }","duration":"216.664534ms","start":"2026-02-17T19:22:33.623877Z","end":"2026-02-17T19:22:33.840572Z","steps":["trace[1698448456] 'agreement among raft nodes before linearized reading' (duration: 216.623463ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:36.289733Z","caller":"traceutil/trace.go:171","msg":"trace[1222574270] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"237.214319ms","start":"2026-02-17T19:22:36.052496Z","end":"2026-02-17T19:22:36.28971Z","steps":["trace[1222574270] 'process raft request' (duration: 237.032024ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:36.290022Z","caller":"traceutil/trace.go:171","msg":"trace[1351082198] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:630; }","duration":"237.438065ms","start":"2026-02-17T19:22:36.052575Z","end":"2026-02-17T19:22:36.290013Z","steps":["trace[1351082198] 'read index received' (duration: 237.433905ms)","trace[1351082198] 'applied index is now lower than readState.Index' (duration: 3.47µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:36.290234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.997443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:502"} {"level":"info","ts":"2026-02-17T19:22:36.290265Z","caller":"traceutil/trace.go:171","msg":"trace[1299569503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"234.051574ms","start":"2026-02-17T19:22:36.056204Z","end":"2026-02-17T19:22:36.290255Z","steps":["trace[1299569503] 'agreement among raft nodes before linearized reading' (duration: 233.889621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:36.290545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.962697ms","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-17T19:22:36.290579Z","caller":"traceutil/trace.go:171","msg":"trace[668466495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"238.021288ms","start":"2026-02-17T19:22:36.052548Z","end":"2026-02-17T19:22:36.290569Z","steps":["trace[668466495] 'agreement among raft nodes before linearized reading' (duration: 237.925186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:36.290895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.215521ms","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-17T19:22:36.290925Z","caller":"traceutil/trace.go:171","msg":"trace[239067417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"221.274063ms","start":"2026-02-17T19:22:36.069643Z","end":"2026-02-17T19:22:36.290917Z","steps":["trace[239067417] 'agreement among raft nodes before linearized reading' (duration: 221.191831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:36.291074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.211518ms","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-17T19:22:36.2911Z","caller":"traceutil/trace.go:171","msg":"trace[386876534] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"234.245749ms","start":"2026-02-17T19:22:36.056847Z","end":"2026-02-17T19:22:36.291092Z","steps":["trace[386876534] 'agreement among raft nodes before linearized reading' (duration: 234.175817ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:41.994138Z","caller":"traceutil/trace.go:171","msg":"trace[1246299075] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"114.240131ms","start":"2026-02-17T19:22:41.87988Z","end":"2026-02-17T19:22:41.99412Z","steps":["trace[1246299075] 'read index received' (duration: 114.042387ms)","trace[1246299075] 'applied index is now lower than readState.Index' (duration: 197.014µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:41.994453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.559629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T19:22:41.994484Z","caller":"traceutil/trace.go:171","msg":"trace[1065358979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:657; }","duration":"114.624951ms","start":"2026-02-17T19:22:41.879843Z","end":"2026-02-17T19:22:41.994468Z","steps":["trace[1065358979] 'agreement among raft nodes before linearized reading' (duration: 114.345384ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:41.994759Z","caller":"traceutil/trace.go:171","msg":"trace[1384652332] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"202.685235ms","start":"2026-02-17T19:22:41.792066Z","end":"2026-02-17T19:22:41.994751Z","steps":["trace[1384652332] 'process raft request' (duration: 201.906457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:43.139137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.433319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:22:43.139213Z","caller":"traceutil/trace.go:171","msg":"trace[86091141] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"1.139471964s","start":"2026-02-17T19:22:41.99973Z","end":"2026-02-17T19:22:43.139202Z","steps":["trace[86091141] 'process raft request' (duration: 776.951193ms)","trace[86091141] 'compare' (duration: 362.157853ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:43.139245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:41.999715Z","time spent":"1.139514946s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:22:43.197347Z","caller":"traceutil/trace.go:171","msg":"trace[953061967] linearizableReadLoop","detail":"{readStateIndex:685; appliedIndex:681; }","duration":"860.36561ms","start":"2026-02-17T19:22:42.336958Z","end":"2026-02-17T19:22:43.197323Z","steps":["trace[953061967] 'read index received' (duration: 439.621348ms)","trace[953061967] 'applied index is now lower than readState.Index' (duration: 420.743522ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:43.197555Z","caller":"traceutil/trace.go:171","msg":"trace[1524126729] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"1.19063252s","start":"2026-02-17T19:22:42.006915Z","end":"2026-02-17T19:22:43.197548Z","steps":["trace[1524126729] 'process raft request' (duration: 1.190248891s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:43.197621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:42.006899Z","time spent":"1.190681612s","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:22:43.197733Z","caller":"traceutil/trace.go:171","msg":"trace[1370248387] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.18428136s","start":"2026-02-17T19:22:42.013445Z","end":"2026-02-17T19:22:43.197727Z","steps":["trace[1370248387] 'process raft request' (duration: 1.183824699s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:43.197757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:42.013429Z","time spent":"1.184316911s","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2262,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:22:43.197827Z","caller":"traceutil/trace.go:171","msg":"trace[1893635236] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.18086612s","start":"2026-02-17T19:22:42.016956Z","end":"2026-02-17T19:22:43.197822Z","steps":["trace[1893635236] 'process raft request' (duration: 1.180342408s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:43.197848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:42.016949Z","time spent":"1.180889201s","remote":"127.0.0.1:59002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2260,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T19:22:43.198086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.121578ms","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-17T19:22:43.198108Z","caller":"traceutil/trace.go:171","msg":"trace[1770176324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"861.173399ms","start":"2026-02-17T19:22:42.336929Z","end":"2026-02-17T19:22:43.198102Z","steps":["trace[1770176324] 'agreement among raft nodes before linearized reading' (duration: 861.058406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:43.198125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:42.336903Z","time spent":"861.21855ms","remote":"127.0.0.1:48022","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-17T19:22:43.198256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.081117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T19:22:43.198271Z","caller":"traceutil/trace.go:171","msg":"trace[664342344] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"861.120378ms","start":"2026-02-17T19:22:42.337146Z","end":"2026-02-17T19:22:43.198267Z","steps":["trace[664342344] 'agreement among raft nodes before linearized reading' (duration: 861.070917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:43.198285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:42.337132Z","time spent":"861.149268ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:22:43.198471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.82841ms","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-17T19:22:43.198487Z","caller":"traceutil/trace.go:171","msg":"trace[1069204091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"829.871692ms","start":"2026-02-17T19:22:42.368611Z","end":"2026-02-17T19:22:43.198483Z","steps":["trace[1069204091] 'agreement among raft nodes before linearized reading' (duration: 829.818201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:43.198501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:42.368568Z","time spent":"829.902332ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:22:43.198594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.632239ms","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-17T19:22:43.198621Z","caller":"traceutil/trace.go:171","msg":"trace[1478997286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"830.682841ms","start":"2026-02-17T19:22:42.367933Z","end":"2026-02-17T19:22:43.198616Z","steps":["trace[1478997286] 'agreement among raft nodes before linearized reading' (duration: 830.63051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:43.198642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:42.367903Z","time spent":"830.735802ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T19:22:47.463066Z","caller":"traceutil/trace.go:171","msg":"trace[2035820380] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"154.845752ms","start":"2026-02-17T19:22:47.308203Z","end":"2026-02-17T19:22:47.463048Z","steps":["trace[2035820380] 'process raft request' (duration: 154.465943ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:47.463949Z","caller":"traceutil/trace.go:171","msg":"trace[553545368] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"155.311803ms","start":"2026-02-17T19:22:47.308627Z","end":"2026-02-17T19:22:47.463939Z","steps":["trace[553545368] 'process raft request' (duration: 154.146205ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:47.464172Z","caller":"traceutil/trace.go:171","msg":"trace[2125500858] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"155.133458ms","start":"2026-02-17T19:22:47.309031Z","end":"2026-02-17T19:22:47.464164Z","steps":["trace[2125500858] 'process raft request' (duration: 153.976991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:50.14471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.978473ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-17T19:22:50.144786Z","caller":"traceutil/trace.go:171","msg":"trace[1554580263] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"836.462143ms","start":"2026-02-17T19:22:49.308311Z","end":"2026-02-17T19:22:50.144773Z","steps":["trace[1554580263] 'read index received' (duration: 58.358337ms)","trace[1554580263] 'applied index is now lower than readState.Index' (duration: 778.102786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:22:50.145353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.029718ms","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-17T19:22:50.145382Z","caller":"traceutil/trace.go:171","msg":"trace[467761977] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"837.199732ms","start":"2026-02-17T19:22:49.308175Z","end":"2026-02-17T19:22:50.145375Z","steps":["trace[467761977] 'agreement among raft nodes before linearized reading' (duration: 837.046788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:50.145408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:49.308156Z","time spent":"837.245182ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:22:50.145605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.026877ms","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-17T19:22:50.145632Z","caller":"traceutil/trace.go:171","msg":"trace[1286465910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:715; }","duration":"813.080408ms","start":"2026-02-17T19:22:49.332545Z","end":"2026-02-17T19:22:50.145626Z","steps":["trace[1286465910] 'agreement among raft nodes before linearized reading' (duration: 813.008066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:50.145652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:49.332532Z","time spent":"813.116059ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:22:50.145796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.172962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-02-17T19:22:50.145822Z","caller":"traceutil/trace.go:171","msg":"trace[2132027166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"678.206822ms","start":"2026-02-17T19:22:49.467609Z","end":"2026-02-17T19:22:50.145816Z","steps":["trace[2132027166] 'agreement among raft nodes before linearized reading' (duration: 678.151091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:50.145846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:49.467604Z","time spent":"678.235453ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":525,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:22:50.145945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.827887ms","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-17T19:22:50.145966Z","caller":"traceutil/trace.go:171","msg":"trace[874954913] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:715; }","duration":"678.848658ms","start":"2026-02-17T19:22:49.467113Z","end":"2026-02-17T19:22:50.145962Z","steps":["trace[874954913] 'agreement among raft nodes before linearized reading' (duration: 678.798037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:50.145986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:49.4671Z","time spent":"678.882539ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:22:50.146081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.987061ms","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-17T19:22:50.146103Z","caller":"traceutil/trace.go:171","msg":"trace[1003439786] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:715; }","duration":"679.031602ms","start":"2026-02-17T19:22:49.467066Z","end":"2026-02-17T19:22:50.146097Z","steps":["trace[1003439786] 'agreement among raft nodes before linearized reading' (duration: 678.984171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:22:50.146119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:22:49.467053Z","time spent":"679.062203ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T19:22:50.319556Z","caller":"traceutil/trace.go:171","msg":"trace[844276589] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"167.518562ms","start":"2026-02-17T19:22:50.152021Z","end":"2026-02-17T19:22:50.31954Z","steps":["trace[844276589] 'process raft request' (duration: 167.402899ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:50.396273Z","caller":"traceutil/trace.go:171","msg":"trace[231160730] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"240.60483ms","start":"2026-02-17T19:22:50.155652Z","end":"2026-02-17T19:22:50.396256Z","steps":["trace[231160730] 'process raft request' (duration: 240.582329ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:50.39655Z","caller":"traceutil/trace.go:171","msg":"trace[711457520] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"244.341879ms","start":"2026-02-17T19:22:50.152202Z","end":"2026-02-17T19:22:50.396544Z","steps":["trace[711457520] 'process raft request' (duration: 243.902068ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:50.39663Z","caller":"traceutil/trace.go:171","msg":"trace[1424688737] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"244.39141ms","start":"2026-02-17T19:22:50.152233Z","end":"2026-02-17T19:22:50.396625Z","steps":["trace[1424688737] 'process raft request' (duration: 243.957449ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:50.396695Z","caller":"traceutil/trace.go:171","msg":"trace[1627700547] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"244.431801ms","start":"2026-02-17T19:22:50.152258Z","end":"2026-02-17T19:22:50.396689Z","steps":["trace[1627700547] 'process raft request' (duration: 243.95881ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:51.724031Z","caller":"traceutil/trace.go:171","msg":"trace[894427032] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"214.891168ms","start":"2026-02-17T19:22:51.509123Z","end":"2026-02-17T19:22:51.724014Z","steps":["trace[894427032] 'process raft request' (duration: 214.733355ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:52.436227Z","caller":"traceutil/trace.go:171","msg":"trace[368891086] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"110.292402ms","start":"2026-02-17T19:22:52.325914Z","end":"2026-02-17T19:22:52.436206Z","steps":["trace[368891086] 'process raft request' (duration: 109.860162ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:52.582786Z","caller":"traceutil/trace.go:171","msg":"trace[1527417254] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"137.284744ms","start":"2026-02-17T19:22:52.445482Z","end":"2026-02-17T19:22:52.582767Z","steps":["trace[1527417254] 'process raft request' (duration: 71.358356ms)","trace[1527417254] 'compare' (duration: 65.843676ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:22:52.585647Z","caller":"traceutil/trace.go:171","msg":"trace[1116051165] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"135.01697ms","start":"2026-02-17T19:22:52.450621Z","end":"2026-02-17T19:22:52.585638Z","steps":["trace[1116051165] 'process raft request' (duration: 134.971829ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:52.585847Z","caller":"traceutil/trace.go:171","msg":"trace[1740302957] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"136.998487ms","start":"2026-02-17T19:22:52.448842Z","end":"2026-02-17T19:22:52.585841Z","steps":["trace[1740302957] 'process raft request' (duration: 136.621668ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:52.585939Z","caller":"traceutil/trace.go:171","msg":"trace[1298258764] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"137.018078ms","start":"2026-02-17T19:22:52.448914Z","end":"2026-02-17T19:22:52.585932Z","steps":["trace[1298258764] 'process raft request' (duration: 136.649939ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:22:56.590171Z","caller":"traceutil/trace.go:171","msg":"trace[24848553] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"138.313687ms","start":"2026-02-17T19:22:56.451834Z","end":"2026-02-17T19:22:56.590148Z","steps":["trace[24848553] 'process raft request' (duration: 138.184794ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:07.866372Z","caller":"traceutil/trace.go:171","msg":"trace[1428445861] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"152.847574ms","start":"2026-02-17T19:23:07.713506Z","end":"2026-02-17T19:23:07.866354Z","steps":["trace[1428445861] 'process raft request' (duration: 152.746301ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:10.792566Z","caller":"traceutil/trace.go:171","msg":"trace[959236796] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"107.195179ms","start":"2026-02-17T19:23:10.685349Z","end":"2026-02-17T19:23:10.792544Z","steps":["trace[959236796] 'process raft request' (duration: 15.398067ms)","trace[959236796] 'compare' (duration: 91.425773ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:10.968583Z","caller":"traceutil/trace.go:171","msg":"trace[673555231] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"167.972292ms","start":"2026-02-17T19:23:10.800583Z","end":"2026-02-17T19:23:10.968555Z","steps":["trace[673555231] 'process raft request' (duration: 167.89733ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:10.969085Z","caller":"traceutil/trace.go:171","msg":"trace[1182437749] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"168.526235ms","start":"2026-02-17T19:23:10.800543Z","end":"2026-02-17T19:23:10.969069Z","steps":["trace[1182437749] 'process raft request' (duration: 167.265435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:13.26569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555031951,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:23:13.319641Z","caller":"traceutil/trace.go:171","msg":"trace[1794658311] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"554.781659ms","start":"2026-02-17T19:23:12.764842Z","end":"2026-02-17T19:23:13.319623Z","steps":["trace[1794658311] 'read index received' (duration: 554.643366ms)","trace[1794658311] 'applied index is now lower than readState.Index' (duration: 137.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:13.320052Z","caller":"traceutil/trace.go:171","msg":"trace[1565946840] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"625.398588ms","start":"2026-02-17T19:23:12.694645Z","end":"2026-02-17T19:23:13.320044Z","steps":["trace[1565946840] 'process raft request' (duration: 624.889376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:13.323867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:12.694631Z","time spent":"629.183178ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:13.32088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.43885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T19:23:13.324118Z","caller":"traceutil/trace.go:171","msg":"trace[1129436247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:784; }","duration":"526.235791ms","start":"2026-02-17T19:23:12.797868Z","end":"2026-02-17T19:23:13.324104Z","steps":["trace[1129436247] 'agreement among raft nodes before linearized reading' (duration: 522.352658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:13.324159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:12.797857Z","time spent":"526.287902ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:23:13.320952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.105431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:23:13.3243Z","caller":"traceutil/trace.go:171","msg":"trace[1269176950] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:784; }","duration":"559.490861ms","start":"2026-02-17T19:23:12.7648Z","end":"2026-02-17T19:23:13.324291Z","steps":["trace[1269176950] 'agreement among raft nodes before linearized reading' (duration: 556.120301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:13.32433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:12.764781Z","time spent":"559.539312ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:13.321013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.487574ms","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-17T19:23:13.324504Z","caller":"traceutil/trace.go:171","msg":"trace[199092360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"349.013007ms","start":"2026-02-17T19:23:12.975483Z","end":"2026-02-17T19:23:13.324496Z","steps":["trace[199092360] 'agreement among raft nodes before linearized reading' (duration: 345.484093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:13.324527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:12.975461Z","time spent":"349.059378ms","remote":"127.0.0.1:48022","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-17T19:23:13.321065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.527528ms","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-17T19:23:13.324752Z","caller":"traceutil/trace.go:171","msg":"trace[959653751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"350.250347ms","start":"2026-02-17T19:23:12.974494Z","end":"2026-02-17T19:23:13.324744Z","steps":["trace[959653751] 'agreement among raft nodes before linearized reading' (duration: 346.531599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:13.324776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:12.974472Z","time spent":"350.297727ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:23:13.321102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.062784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T19:23:13.324828Z","caller":"traceutil/trace.go:171","msg":"trace[53824631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"478.809743ms","start":"2026-02-17T19:23:12.846014Z","end":"2026-02-17T19:23:13.324823Z","steps":["trace[53824631] 'agreement among raft nodes before linearized reading' (duration: 475.061104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:13.324844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:12.846003Z","time spent":"478.837654ms","remote":"127.0.0.1:48022","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-17T19:23:14.124026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555031954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:23:14.334297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.475131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:14.334399Z","caller":"traceutil/trace.go:171","msg":"trace[848908664] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"1.005412092s","start":"2026-02-17T19:23:13.328952Z","end":"2026-02-17T19:23:14.334364Z","steps":["trace[848908664] 'process raft request' (duration: 861.802417ms)","trace[848908664] 'compare' (duration: 143.39861ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:14.334455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:13.328937Z","time spent":"1.005491924s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:15.072185Z","caller":"traceutil/trace.go:171","msg":"trace[531834582] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:815; }","duration":"1.448687558s","start":"2026-02-17T19:23:13.623477Z","end":"2026-02-17T19:23:15.072164Z","steps":["trace[531834582] 'read index received' (duration: 567.345307ms)","trace[531834582] 'applied index is now lower than readState.Index' (duration: 881.341421ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:15.072461Z","caller":"traceutil/trace.go:171","msg":"trace[590521038] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"1.74278063s","start":"2026-02-17T19:23:13.329667Z","end":"2026-02-17T19:23:15.072448Z","steps":["trace[590521038] 'process raft request' (duration: 1.742317919s)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:15.072533Z","caller":"traceutil/trace.go:171","msg":"trace[1223429941] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"1.736398578s","start":"2026-02-17T19:23:13.336106Z","end":"2026-02-17T19:23:15.072504Z","steps":["trace[1223429941] 'process raft request' (duration: 1.736023519s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:15.072583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:13.329642Z","time spent":"1.742845982s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:15.072661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:13.336092Z","time spent":"1.736501492s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:15.072766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449285453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:15.072797Z","caller":"traceutil/trace.go:171","msg":"trace[452714483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"1.449373735s","start":"2026-02-17T19:23:13.623413Z","end":"2026-02-17T19:23:15.072787Z","steps":["trace[452714483] 'agreement among raft nodes before linearized reading' (duration: 1.449326734s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:15.072823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:13.623399Z","time spent":"1.449419166s","remote":"127.0.0.1:47788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:23:15.072827Z","caller":"traceutil/trace.go:171","msg":"trace[81465880] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"1.741991022s","start":"2026-02-17T19:23:13.330828Z","end":"2026-02-17T19:23:15.072819Z","steps":["trace[81465880] 'process raft request' (duration: 1.741266095s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:15.072864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:13.33082Z","time spent":"1.742023682s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:15.225865Z","caller":"traceutil/trace.go:171","msg":"trace[956643999] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"153.598622ms","start":"2026-02-17T19:23:15.072241Z","end":"2026-02-17T19:23:15.22584Z","steps":["trace[956643999] 'read index received' (duration: 119.687776ms)","trace[956643999] 'applied index is now lower than readState.Index' (duration: 33.909616ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:15.225942Z","caller":"traceutil/trace.go:171","msg":"trace[1117646398] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"432.553723ms","start":"2026-02-17T19:23:14.793351Z","end":"2026-02-17T19:23:15.225905Z","steps":["trace[1117646398] 'process raft request' (duration: 398.635757ms)","trace[1117646398] 'compare' (duration: 33.566048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:15.226066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.098848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-17T19:23:15.226066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:14.793339Z","time spent":"432.658815ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:15.226098Z","caller":"traceutil/trace.go:171","msg":"trace[1509282485] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:789; }","duration":"468.150469ms","start":"2026-02-17T19:23:14.757937Z","end":"2026-02-17T19:23:15.226088Z","steps":["trace[1509282485] 'agreement among raft nodes before linearized reading' (duration: 468.020996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:15.226134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:14.757897Z","time spent":"468.225321ms","remote":"127.0.0.1:48220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:15.226367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090280458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T19:23:15.226396Z","caller":"traceutil/trace.go:171","msg":"trace[472080526] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:789; }","duration":"1.09034782s","start":"2026-02-17T19:23:14.136038Z","end":"2026-02-17T19:23:15.226386Z","steps":["trace[472080526] 'agreement among raft nodes before linearized reading' (duration: 1.090251827s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:15.226422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:14.136016Z","time spent":"1.090399671s","remote":"127.0.0.1:47798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-02-17T19:23:15.226509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.202801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:15.226551Z","caller":"traceutil/trace.go:171","msg":"trace[694759079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:789; }","duration":"150.272832ms","start":"2026-02-17T19:23:15.076267Z","end":"2026-02-17T19:23:15.22654Z","steps":["trace[694759079] 'agreement among raft nodes before linearized reading' (duration: 150.208421ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:26.568807Z","caller":"traceutil/trace.go:171","msg":"trace[1490966565] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"185.198843ms","start":"2026-02-17T19:23:26.38359Z","end":"2026-02-17T19:23:26.568789Z","steps":["trace[1490966565] 'process raft request' (duration: 185.05698ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:27.561547Z","caller":"traceutil/trace.go:171","msg":"trace[1271506694] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"423.269002ms","start":"2026-02-17T19:23:27.138262Z","end":"2026-02-17T19:23:27.561531Z","steps":["trace[1271506694] 'read index received' (duration: 423.142399ms)","trace[1271506694] 'applied index is now lower than readState.Index' (duration: 126.083µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:27.561703Z","caller":"traceutil/trace.go:171","msg":"trace[156067288] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"428.605659ms","start":"2026-02-17T19:23:27.133091Z","end":"2026-02-17T19:23:27.561697Z","steps":["trace[156067288] 'process raft request' (duration: 428.352613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:27.561763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:27.133083Z","time spent":"428.63553ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:27.561898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.633811ms","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-17T19:23:27.561915Z","caller":"traceutil/trace.go:171","msg":"trace[1315501343] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:873; }","duration":"423.649871ms","start":"2026-02-17T19:23:27.13826Z","end":"2026-02-17T19:23:27.56191Z","steps":["trace[1315501343] 'agreement among raft nodes before linearized reading' (duration: 423.58648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:27.561928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:27.138243Z","time spent":"423.682232ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:23:27.56202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.195278ms","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-17T19:23:27.562032Z","caller":"traceutil/trace.go:171","msg":"trace[1397602431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:873; }","duration":"417.220128ms","start":"2026-02-17T19:23:27.144808Z","end":"2026-02-17T19:23:27.562028Z","steps":["trace[1397602431] 'agreement among raft nodes before linearized reading' (duration: 417.182677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:27.562043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:27.144802Z","time spent":"417.23809ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:23:27.562167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.474838ms","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-17T19:23:27.562181Z","caller":"traceutil/trace.go:171","msg":"trace[624560739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:873; }","duration":"161.511ms","start":"2026-02-17T19:23:27.400666Z","end":"2026-02-17T19:23:27.562177Z","steps":["trace[624560739] 'agreement among raft nodes before linearized reading' (duration: 161.461249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:27.562246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.262832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:23:27.562259Z","caller":"traceutil/trace.go:171","msg":"trace[476809596] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:873; }","duration":"248.297673ms","start":"2026-02-17T19:23:27.313958Z","end":"2026-02-17T19:23:27.562255Z","steps":["trace[476809596] 'agreement among raft nodes before linearized reading' (duration: 248.272422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:28.122703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555032154,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:23:28.749099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.120287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:28.749171Z","caller":"traceutil/trace.go:171","msg":"trace[1037543572] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"1.182027581s","start":"2026-02-17T19:23:27.567133Z","end":"2026-02-17T19:23:28.74916Z","steps":["trace[1037543572] 'process raft request' (duration: 748.794451ms)","trace[1037543572] 'compare' (duration: 432.888721ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:28.749209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:27.567108Z","time spent":"1.182081761s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:29.622704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000266982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-17T19:23:29.622758Z","caller":"traceutil/trace.go:171","msg":"trace[407115960] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000357453s","start":"2026-02-17T19:23:27.622387Z","end":"2026-02-17T19:23:29.622745Z","steps":["trace[407115960] 'agreement among raft nodes before linearized reading' (duration: 2.000287281s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.622787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:27.622377Z","time spent":"2.000404834s","remote":"127.0.0.1:47788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T19:23:29.933292Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.617490092s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:23:29.933574Z","caller":"traceutil/trace.go:171","msg":"trace[1919473073] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:909; }","duration":"2.311146011s","start":"2026-02-17T19:23:27.622413Z","end":"2026-02-17T19:23:29.933559Z","steps":["trace[1919473073] 'read index received' (duration: 693.521137ms)","trace[1919473073] 'applied index is now lower than readState.Index' (duration: 1.617624384s)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:29.933665Z","caller":"traceutil/trace.go:171","msg":"trace[1367812865] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"2.352636247s","start":"2026-02-17T19:23:27.581021Z","end":"2026-02-17T19:23:29.933657Z","steps":["trace[1367812865] 'process raft request' (duration: 2.352492704s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.933734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:27.581005Z","time spent":"2.352675119s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:29.933816Z","caller":"traceutil/trace.go:171","msg":"trace[881783230] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"1.621364234s","start":"2026-02-17T19:23:28.312448Z","end":"2026-02-17T19:23:29.933812Z","steps":["trace[881783230] 'process raft request' (duration: 1.621091188s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.933839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:28.312432Z","time spent":"1.621393574s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:29.933596Z","caller":"traceutil/trace.go:171","msg":"trace[331765010] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"2.363761592s","start":"2026-02-17T19:23:27.569815Z","end":"2026-02-17T19:23:29.933577Z","steps":["trace[331765010] 'process raft request' (duration: 2.363604979s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.933889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:27.569806Z","time spent":"2.36407109s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:29.934015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.109921517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:23:29.934037Z","caller":"traceutil/trace.go:171","msg":"trace[475108644] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:877; }","duration":"2.110004049s","start":"2026-02-17T19:23:27.824027Z","end":"2026-02-17T19:23:29.934031Z","steps":["trace[475108644] 'agreement among raft nodes before linearized reading' (duration: 2.109951588s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.934056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:27.824007Z","time spent":"2.110044351s","remote":"127.0.0.1:47916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:29.934188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.705436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-17T19:23:29.934193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362125641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T19:23:29.934206Z","caller":"traceutil/trace.go:171","msg":"trace[2095181820] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:877; }","duration":"575.775268ms","start":"2026-02-17T19:23:29.358425Z","end":"2026-02-17T19:23:29.9342Z","steps":["trace[2095181820] 'agreement among raft nodes before linearized reading' (duration: 575.693726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.934223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:29.358412Z","time spent":"575.807019ms","remote":"127.0.0.1:47950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:29.93423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380801095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:29.934243Z","caller":"traceutil/trace.go:171","msg":"trace[1340086667] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:877; }","duration":"1.380843705s","start":"2026-02-17T19:23:28.553395Z","end":"2026-02-17T19:23:29.934239Z","steps":["trace[1340086667] 'agreement among raft nodes before linearized reading' (duration: 1.380820475s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.934255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:28.553376Z","time spent":"1.380875297s","remote":"127.0.0.1:48056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:29.93431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.345352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:29.934325Z","caller":"traceutil/trace.go:171","msg":"trace[58282582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:877; }","duration":"311.433963ms","start":"2026-02-17T19:23:29.622887Z","end":"2026-02-17T19:23:29.934321Z","steps":["trace[58282582] 'agreement among raft nodes before linearized reading' (duration: 311.398322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.93434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:29.62287Z","time spent":"311.467835ms","remote":"127.0.0.1:47792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:23:29.934208Z","caller":"traceutil/trace.go:171","msg":"trace[1843822213] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:877; }","duration":"1.362139501s","start":"2026-02-17T19:23:28.572064Z","end":"2026-02-17T19:23:29.934203Z","steps":["trace[1843822213] 'agreement among raft nodes before linearized reading' (duration: 1.36208052s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.934483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:28.572038Z","time spent":"1.362440458s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:23:29.934493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.7597ms","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-17T19:23:29.934508Z","caller":"traceutil/trace.go:171","msg":"trace[1909985574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"361.802171ms","start":"2026-02-17T19:23:29.572701Z","end":"2026-02-17T19:23:29.934503Z","steps":["trace[1909985574] 'agreement among raft nodes before linearized reading' (duration: 361.73811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:29.934521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:29.572686Z","time spent":"361.832132ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T19:23:30.103033Z","caller":"traceutil/trace.go:171","msg":"trace[1669635132] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:913; }","duration":"161.060148ms","start":"2026-02-17T19:23:29.941952Z","end":"2026-02-17T19:23:30.103012Z","steps":["trace[1669635132] 'read index received' (duration: 96.567105ms)","trace[1669635132] 'applied index is now lower than readState.Index' (duration: 64.492193ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:30.103158Z","caller":"traceutil/trace.go:171","msg":"trace[1111291544] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"164.920241ms","start":"2026-02-17T19:23:29.938228Z","end":"2026-02-17T19:23:30.103148Z","steps":["trace[1111291544] 'process raft request' (duration: 100.357466ms)","trace[1111291544] 'compare' (duration: 64.248337ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:30.1033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.333435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:30.103379Z","caller":"traceutil/trace.go:171","msg":"trace[733526669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"161.415677ms","start":"2026-02-17T19:23:29.941948Z","end":"2026-02-17T19:23:30.103363Z","steps":["trace[733526669] 'agreement among raft nodes before linearized reading' (duration: 161.309454ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:30.103503Z","caller":"traceutil/trace.go:171","msg":"trace[255381227] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"163.611419ms","start":"2026-02-17T19:23:29.939885Z","end":"2026-02-17T19:23:30.103496Z","steps":["trace[255381227] 'process raft request' (duration: 163.087866ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:30.103572Z","caller":"traceutil/trace.go:171","msg":"trace[1599332260] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"164.638364ms","start":"2026-02-17T19:23:29.938929Z","end":"2026-02-17T19:23:30.103568Z","steps":["trace[1599332260] 'process raft request' (duration: 164.007469ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:31.068269Z","caller":"traceutil/trace.go:171","msg":"trace[760415117] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"322.096517ms","start":"2026-02-17T19:23:30.746145Z","end":"2026-02-17T19:23:31.068242Z","steps":["trace[760415117] 'read index received' (duration: 261.448256ms)","trace[760415117] 'applied index is now lower than readState.Index' (duration: 60.644771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:31.068494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.321643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:31.06853Z","caller":"traceutil/trace.go:171","msg":"trace[1745563816] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:880; }","duration":"322.402454ms","start":"2026-02-17T19:23:30.746118Z","end":"2026-02-17T19:23:31.06852Z","steps":["trace[1745563816] 'agreement among raft nodes before linearized reading' (duration: 322.270491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:31.068566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:30.746104Z","time spent":"322.453046ms","remote":"127.0.0.1:47956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:31.068957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.563665ms","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-17T19:23:31.068988Z","caller":"traceutil/trace.go:171","msg":"trace[5012563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"316.604876ms","start":"2026-02-17T19:23:30.752374Z","end":"2026-02-17T19:23:31.068979Z","steps":["trace[5012563] 'agreement among raft nodes before linearized reading' (duration: 316.483963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:31.069021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:30.752369Z","time spent":"316.643278ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T19:23:36.45848Z","caller":"traceutil/trace.go:171","msg":"trace[14069628] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"273.183854ms","start":"2026-02-17T19:23:36.185279Z","end":"2026-02-17T19:23:36.458463Z","steps":["trace[14069628] 'process raft request' (duration: 227.410386ms)","trace[14069628] 'compare' (duration: 45.711387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:36.810774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.713972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:23:36.810821Z","caller":"traceutil/trace.go:171","msg":"trace[318996492] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:897; }","duration":"171.798734ms","start":"2026-02-17T19:23:36.639012Z","end":"2026-02-17T19:23:36.810811Z","steps":["trace[318996492] 'count revisions from in-memory index tree' (duration: 171.656641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:38.606853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.034014ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:38.606947Z","caller":"traceutil/trace.go:171","msg":"trace[508555805] linearizableReadLoop","detail":"{readStateIndex:938; appliedIndex:937; }","duration":"336.006858ms","start":"2026-02-17T19:23:38.270924Z","end":"2026-02-17T19:23:38.606931Z","steps":["trace[508555805] 'read index received' (duration: 37.851µs)","trace[508555805] 'applied index is now lower than readState.Index' (duration: 335.967907ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:38.607136Z","caller":"traceutil/trace.go:171","msg":"trace[1185030093] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"458.970281ms","start":"2026-02-17T19:23:38.148154Z","end":"2026-02-17T19:23:38.607125Z","steps":["trace[1185030093] 'process raft request' (duration: 115.577418ms)","trace[1185030093] 'compare' (duration: 342.86449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:38.607196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:38.14814Z","time spent":"459.026693ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:38.607517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.592942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:503"} {"level":"info","ts":"2026-02-17T19:23:38.607565Z","caller":"traceutil/trace.go:171","msg":"trace[977393361] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"336.668424ms","start":"2026-02-17T19:23:38.270886Z","end":"2026-02-17T19:23:38.607555Z","steps":["trace[977393361] 'agreement among raft nodes before linearized reading' (duration: 336.442899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:38.607593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:38.270823Z","time spent":"336.763836ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":525,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:23:38.607864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.525593ms","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-17T19:23:38.607895Z","caller":"traceutil/trace.go:171","msg":"trace[1002688729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"146.583065ms","start":"2026-02-17T19:23:38.461303Z","end":"2026-02-17T19:23:38.607886Z","steps":["trace[1002688729] 'agreement among raft nodes before linearized reading' (duration: 146.446102ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:39.550898Z","caller":"traceutil/trace.go:171","msg":"trace[7138621] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"343.781692ms","start":"2026-02-17T19:23:39.2071Z","end":"2026-02-17T19:23:39.550881Z","steps":["trace[7138621] 'process raft request' (duration: 343.70739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:39.551014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:39.207075Z","time spent":"343.872283ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:40.770903Z","caller":"traceutil/trace.go:171","msg":"trace[1260224165] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"156.657774ms","start":"2026-02-17T19:23:40.614228Z","end":"2026-02-17T19:23:40.770886Z","steps":["trace[1260224165] 'process raft request' (duration: 156.564672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:41.32303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.715803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:41.323117Z","caller":"traceutil/trace.go:171","msg":"trace[1697995496] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"707.370176ms","start":"2026-02-17T19:23:40.615732Z","end":"2026-02-17T19:23:41.323102Z","steps":["trace[1697995496] 'read index received' (duration: 155.482866ms)","trace[1697995496] 'applied index is now lower than readState.Index' (duration: 551.88622ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:41.323311Z","caller":"traceutil/trace.go:171","msg":"trace[344437365] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"707.641653ms","start":"2026-02-17T19:23:40.615655Z","end":"2026-02-17T19:23:41.323297Z","steps":["trace[344437365] 'process raft request' (duration: 466.588272ms)","trace[344437365] 'compare' (duration: 240.568149ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:41.323368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:40.615648Z","time spent":"707.689224ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:41.324325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.430909ms","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-17T19:23:41.324368Z","caller":"traceutil/trace.go:171","msg":"trace[765036124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"702.48881ms","start":"2026-02-17T19:23:40.621867Z","end":"2026-02-17T19:23:41.324356Z","steps":["trace[765036124] 'agreement among raft nodes before linearized reading' (duration: 702.338677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:41.324405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:40.621858Z","time spent":"702.536921ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:23:41.324573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.832401ms","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-17T19:23:41.324601Z","caller":"traceutil/trace.go:171","msg":"trace[1338113094] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:907; }","duration":"708.869942ms","start":"2026-02-17T19:23:40.615722Z","end":"2026-02-17T19:23:41.324592Z","steps":["trace[1338113094] 'agreement among raft nodes before linearized reading' (duration: 708.820971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:41.324631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:40.615718Z","time spent":"708.905662ms","remote":"127.0.0.1:48180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:41.324763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.242585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:41.324788Z","caller":"traceutil/trace.go:171","msg":"trace[193637251] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"702.274725ms","start":"2026-02-17T19:23:40.622505Z","end":"2026-02-17T19:23:41.32478Z","steps":["trace[193637251] 'agreement among raft nodes before linearized reading' (duration: 702.238724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:41.324811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:40.622501Z","time spent":"702.305596ms","remote":"127.0.0.1:47788","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-17T19:23:42.575896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.755147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:42.57596Z","caller":"traceutil/trace.go:171","msg":"trace[70123307] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"1.022334974s","start":"2026-02-17T19:23:41.553615Z","end":"2026-02-17T19:23:42.57595Z","steps":["trace[70123307] 'read index received' (duration: 499.432423ms)","trace[70123307] 'applied index is now lower than readState.Index' (duration: 522.901851ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:42.576095Z","caller":"traceutil/trace.go:171","msg":"trace[1848501599] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"1.242262612s","start":"2026-02-17T19:23:41.333825Z","end":"2026-02-17T19:23:42.576087Z","steps":["trace[1848501599] 'process raft request' (duration: 719.263839ms)","trace[1848501599] 'compare' (duration: 522.547653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:42.576134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:41.33381Z","time spent":"1.242304243s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:42.576403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022790125s","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-17T19:23:42.576425Z","caller":"traceutil/trace.go:171","msg":"trace[1737620625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"1.022829935s","start":"2026-02-17T19:23:41.553588Z","end":"2026-02-17T19:23:42.576417Z","steps":["trace[1737620625] 'agreement among raft nodes before linearized reading' (duration: 1.022744893s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:42.576446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:41.553562Z","time spent":"1.022878617s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:23:42.576545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.671838ms","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-17T19:23:42.576563Z","caller":"traceutil/trace.go:171","msg":"trace[1938206050] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:908; }","duration":"581.709139ms","start":"2026-02-17T19:23:41.994848Z","end":"2026-02-17T19:23:42.576557Z","steps":["trace[1938206050] 'agreement among raft nodes before linearized reading' (duration: 581.680139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:42.576583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:41.994833Z","time spent":"581.74573ms","remote":"127.0.0.1:48100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:42.576748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.983767ms","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-17T19:23:42.576782Z","caller":"traceutil/trace.go:171","msg":"trace[1184668282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"410.039118ms","start":"2026-02-17T19:23:42.166737Z","end":"2026-02-17T19:23:42.576777Z","steps":["trace[1184668282] 'agreement among raft nodes before linearized reading' (duration: 409.970116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:42.576801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:42.166722Z","time spent":"410.072488ms","remote":"127.0.0.1:48022","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-17T19:23:43.027962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.222556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:43.028022Z","caller":"traceutil/trace.go:171","msg":"trace[417196382] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"435.592665ms","start":"2026-02-17T19:23:42.59242Z","end":"2026-02-17T19:23:43.028012Z","steps":["trace[417196382] 'process raft request' (duration: 175.270506ms)","trace[417196382] 'compare' (duration: 260.014972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:43.028053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:42.592403Z","time spent":"435.636096ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:43.030968Z","caller":"traceutil/trace.go:171","msg":"trace[1320815038] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"437.694765ms","start":"2026-02-17T19:23:42.593257Z","end":"2026-02-17T19:23:43.030951Z","steps":["trace[1320815038] 'process raft request' (duration: 437.314196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:43.031125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:42.593241Z","time spent":"437.808978ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:43.038625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.092665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:43.038692Z","caller":"traceutil/trace.go:171","msg":"trace[513897962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"417.200238ms","start":"2026-02-17T19:23:42.621479Z","end":"2026-02-17T19:23:43.038679Z","steps":["trace[513897962] 'agreement among raft nodes before linearized reading' (duration: 415.279563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:43.038718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:42.621466Z","time spent":"417.247809ms","remote":"127.0.0.1:47788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T19:23:43.039296Z","caller":"traceutil/trace.go:171","msg":"trace[30803464] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:947; }","duration":"409.209258ms","start":"2026-02-17T19:23:42.621511Z","end":"2026-02-17T19:23:43.03072Z","steps":["trace[30803464] 'read index received' (duration: 146.206936ms)","trace[30803464] 'applied index is now lower than readState.Index' (duration: 263.001312ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:43.03952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.936632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T19:23:43.039542Z","caller":"traceutil/trace.go:171","msg":"trace[1105597851] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"265.988744ms","start":"2026-02-17T19:23:42.773547Z","end":"2026-02-17T19:23:43.039536Z","steps":["trace[1105597851] 'agreement among raft nodes before linearized reading' (duration: 265.858141ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:44.109248Z","caller":"traceutil/trace.go:171","msg":"trace[1309212959] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"775.008104ms","start":"2026-02-17T19:23:43.334225Z","end":"2026-02-17T19:23:44.109233Z","steps":["trace[1309212959] 'process raft request' (duration: 774.793319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:44.10938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:43.334201Z","time spent":"775.128057ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:44.497746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:44.140001Z","time spent":"357.739635ms","remote":"127.0.0.1:47798","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-17T19:23:44.733538Z","caller":"traceutil/trace.go:171","msg":"trace[1929461697] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"150.850726ms","start":"2026-02-17T19:23:44.582654Z","end":"2026-02-17T19:23:44.733505Z","steps":["trace[1929461697] 'read index received' (duration: 52.773785ms)","trace[1929461697] 'applied index is now lower than readState.Index' (duration: 98.076031ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:44.73356Z","caller":"traceutil/trace.go:171","msg":"trace[847228105] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"234.72858ms","start":"2026-02-17T19:23:44.498804Z","end":"2026-02-17T19:23:44.733532Z","steps":["trace[847228105] 'process raft request' (duration: 136.629918ms)","trace[847228105] 'compare' (duration: 97.977919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:44.73375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.077291ms","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-17T19:23:44.733774Z","caller":"traceutil/trace.go:171","msg":"trace[1432775571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"151.143993ms","start":"2026-02-17T19:23:44.582623Z","end":"2026-02-17T19:23:44.733767Z","steps":["trace[1432775571] 'agreement among raft nodes before linearized reading' (duration: 151.01793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:44.733892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.966592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:44.733921Z","caller":"traceutil/trace.go:171","msg":"trace[909980449] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"112.003203ms","start":"2026-02-17T19:23:44.621908Z","end":"2026-02-17T19:23:44.733911Z","steps":["trace[909980449] 'agreement among raft nodes before linearized reading' (duration: 111.945131ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:45.584697Z","caller":"traceutil/trace.go:171","msg":"trace[1460398087] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"539.903065ms","start":"2026-02-17T19:23:45.044743Z","end":"2026-02-17T19:23:45.584646Z","steps":["trace[1460398087] 'process raft request' (duration: 538.986894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:45.584947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:45.044733Z","time spent":"540.11989ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:45.775938Z","caller":"traceutil/trace.go:171","msg":"trace[981305416] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"678.720145ms","start":"2026-02-17T19:23:45.097165Z","end":"2026-02-17T19:23:45.775885Z","steps":["trace[981305416] 'read index received' (duration: 487.120411ms)","trace[981305416] 'applied index is now lower than readState.Index' (duration: 191.598434ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:45.77992Z","caller":"traceutil/trace.go:171","msg":"trace[658672495] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"734.03248ms","start":"2026-02-17T19:23:45.045864Z","end":"2026-02-17T19:23:45.779897Z","steps":["trace[658672495] 'process raft request' (duration: 727.691919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:45.780045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:45.045857Z","time spent":"734.123842ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:45.780366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.204672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T19:23:45.78039Z","caller":"traceutil/trace.go:171","msg":"trace[1819616301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"683.255053ms","start":"2026-02-17T19:23:45.097127Z","end":"2026-02-17T19:23:45.780383Z","steps":["trace[1819616301] 'agreement among raft nodes before linearized reading' (duration: 683.156751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:45.780407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:45.097094Z","time spent":"683.308924ms","remote":"127.0.0.1:48022","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-17T19:23:45.780491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.79332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:45.780505Z","caller":"traceutil/trace.go:171","msg":"trace[238899870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:916; }","duration":"154.832851ms","start":"2026-02-17T19:23:45.625668Z","end":"2026-02-17T19:23:45.7805Z","steps":["trace[238899870] 'agreement among raft nodes before linearized reading' (duration: 154.810641ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:46.827195Z","caller":"traceutil/trace.go:171","msg":"trace[1416447705] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"712.670641ms","start":"2026-02-17T19:23:46.1145Z","end":"2026-02-17T19:23:46.82717Z","steps":["trace[1416447705] 'process raft request' (duration: 712.037446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:46.828478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:46.114491Z","time spent":"713.842859ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":488,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:53.358084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555032349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T19:23:53.642858Z","caller":"traceutil/trace.go:171","msg":"trace[558767290] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"785.776829ms","start":"2026-02-17T19:23:52.857039Z","end":"2026-02-17T19:23:53.642816Z","steps":["trace[558767290] 'read index received' (duration: 785.540333ms)","trace[558767290] 'applied index is now lower than readState.Index' (duration: 235.306µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:53.64333Z","caller":"traceutil/trace.go:171","msg":"trace[625810492] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"787.228734ms","start":"2026-02-17T19:23:52.856089Z","end":"2026-02-17T19:23:53.643317Z","steps":["trace[625810492] 'process raft request' (duration: 786.564478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:53.643521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:52.856072Z","time spent":"787.347166ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:53.643979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.931236ms","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-17T19:23:53.644585Z","caller":"traceutil/trace.go:171","msg":"trace[56586149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"786.999218ms","start":"2026-02-17T19:23:52.857015Z","end":"2026-02-17T19:23:53.644015Z","steps":["trace[56586149] 'agreement among raft nodes before linearized reading' (duration: 786.863425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:53.644687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:52.857006Z","time spent":"787.655583ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:23:53.973192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.014415ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:53.973715Z","caller":"traceutil/trace.go:171","msg":"trace[2034947198] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"322.857504ms","start":"2026-02-17T19:23:53.650831Z","end":"2026-02-17T19:23:53.973689Z","steps":["trace[2034947198] 'process raft request' (duration: 111.254995ms)","trace[2034947198] 'compare' (duration: 210.265187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:53.974148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:53.650817Z","time spent":"323.250853ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:53.974278Z","caller":"traceutil/trace.go:171","msg":"trace[1293566225] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"306.133237ms","start":"2026-02-17T19:23:53.667132Z","end":"2026-02-17T19:23:53.973266Z","steps":["trace[1293566225] 'read index received' (duration: 94.946398ms)","trace[1293566225] 'applied index is now lower than readState.Index' (duration: 211.185669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:53.974926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.783506ms","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-17T19:23:53.975024Z","caller":"traceutil/trace.go:171","msg":"trace[2086259680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"307.898088ms","start":"2026-02-17T19:23:53.667109Z","end":"2026-02-17T19:23:53.975007Z","steps":["trace[2086259680] 'agreement among raft nodes before linearized reading' (duration: 307.354075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:53.975949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:53.667099Z","time spent":"308.81926ms","remote":"127.0.0.1:48022","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-17T19:23:54.640116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555032356,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:23:55.119071Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.073881398s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-17T19:23:55.223148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.722256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:55.223293Z","caller":"traceutil/trace.go:171","msg":"trace[70133251] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:997; }","duration":"1.083857395s","start":"2026-02-17T19:23:54.139422Z","end":"2026-02-17T19:23:55.223279Z","steps":["trace[70133251] 'read index received' (duration: 979.961226ms)","trace[70133251] 'applied index is now lower than readState.Index' (duration: 103.895309ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:55.223483Z","caller":"traceutil/trace.go:171","msg":"trace[273042955] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"1.241797809s","start":"2026-02-17T19:23:53.981677Z","end":"2026-02-17T19:23:55.223475Z","steps":["trace[273042955] 'process raft request' (duration: 1.137696004s)","trace[273042955] 'compare' (duration: 103.628764ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:55.22353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:53.981657Z","time spent":"1.241850391s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:55.223587Z","caller":"traceutil/trace.go:171","msg":"trace[1363966290] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"1.241253247s","start":"2026-02-17T19:23:53.982328Z","end":"2026-02-17T19:23:55.223581Z","steps":["trace[1363966290] 'process raft request' (duration: 1.240888138s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:55.223617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:53.982316Z","time spent":"1.241283867s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:55.223988Z","caller":"traceutil/trace.go:171","msg":"trace[1347170455] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"1.240488318s","start":"2026-02-17T19:23:53.983492Z","end":"2026-02-17T19:23:55.22398Z","steps":["trace[1347170455] 'process raft request' (duration: 1.239758921s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:55.224057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:53.983483Z","time spent":"1.240550099s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:55.224234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.084814618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.72\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T19:23:55.224267Z","caller":"traceutil/trace.go:171","msg":"trace[604414600] range","detail":"{range_begin:/registry/masterleases/199.204.45.72; range_end:; response_count:1; response_revision:959; }","duration":"1.084863908s","start":"2026-02-17T19:23:54.139387Z","end":"2026-02-17T19:23:55.224251Z","steps":["trace[604414600] 'agreement among raft nodes before linearized reading' (duration: 1.084752696s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:55.224291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:54.139372Z","time spent":"1.0849148s","remote":"127.0.0.1:47798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.72\" "} {"level":"warn","ts":"2026-02-17T19:23:55.224432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023258425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:55.22446Z","caller":"traceutil/trace.go:171","msg":"trace[1495606609] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:959; }","duration":"1.023310556s","start":"2026-02-17T19:23:54.20114Z","end":"2026-02-17T19:23:55.224451Z","steps":["trace[1495606609] 'agreement among raft nodes before linearized reading' (duration: 1.023270725s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:55.224482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:54.201113Z","time spent":"1.023363697s","remote":"127.0.0.1:47978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:55.225065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.126205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:55.225092Z","caller":"traceutil/trace.go:171","msg":"trace[1589541883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"536.183836ms","start":"2026-02-17T19:23:54.688901Z","end":"2026-02-17T19:23:55.225085Z","steps":["trace[1589541883] 'agreement among raft nodes before linearized reading' (duration: 536.133485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:55.225299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:54.688883Z","time spent":"536.36069ms","remote":"127.0.0.1:47788","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-17T19:23:55.225481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.609459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:55.225611Z","caller":"traceutil/trace.go:171","msg":"trace[1249603421] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:959; }","duration":"627.672081ms","start":"2026-02-17T19:23:54.597828Z","end":"2026-02-17T19:23:55.2255Z","steps":["trace[1249603421] 'agreement among raft nodes before linearized reading' (duration: 627.62475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:55.225771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:54.59781Z","time spent":"627.824564ms","remote":"127.0.0.1:59018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:23:56.037119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.480993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:56.037178Z","caller":"traceutil/trace.go:171","msg":"trace[1075713694] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"389.748444ms","start":"2026-02-17T19:23:55.647421Z","end":"2026-02-17T19:23:56.03717Z","steps":["trace[1075713694] 'read index received' (duration: 24.34µs)","trace[1075713694] 'applied index is now lower than readState.Index' (duration: 389.723264ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:56.037224Z","caller":"traceutil/trace.go:171","msg":"trace[1884443959] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"749.24462ms","start":"2026-02-17T19:23:55.287974Z","end":"2026-02-17T19:23:56.037218Z","steps":["trace[1884443959] 'process raft request' (duration: 106.613425ms)","trace[1884443959] 'compare' (duration: 642.314458ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:56.037249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:55.287963Z","time spent":"749.271971ms","remote":"127.0.0.1:47798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:56.037465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.043631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T19:23:56.03748Z","caller":"traceutil/trace.go:171","msg":"trace[1471596376] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"390.081123ms","start":"2026-02-17T19:23:55.647394Z","end":"2026-02-17T19:23:56.037475Z","steps":["trace[1471596376] 'agreement among raft nodes before linearized reading' (duration: 389.95209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:56.037494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:55.647381Z","time spent":"390.110303ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:23:56.037568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.464455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:23:56.03758Z","caller":"traceutil/trace.go:171","msg":"trace[2047552398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"343.497005ms","start":"2026-02-17T19:23:55.694079Z","end":"2026-02-17T19:23:56.037576Z","steps":["trace[2047552398] 'agreement among raft nodes before linearized reading' (duration: 343.479285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:56.03759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:55.694066Z","time spent":"343.521966ms","remote":"127.0.0.1:47788","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-17T19:23:56.552066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.620812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:23:56.552162Z","caller":"traceutil/trace.go:171","msg":"trace[503825699] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"510.359852ms","start":"2026-02-17T19:23:56.041783Z","end":"2026-02-17T19:23:56.552142Z","steps":["trace[503825699] 'read index received' (duration: 210.598526ms)","trace[503825699] 'applied index is now lower than readState.Index' (duration: 299.760096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:56.552363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.574047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-17T19:23:56.552399Z","caller":"traceutil/trace.go:171","msg":"trace[1684104774] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:961; }","duration":"510.620278ms","start":"2026-02-17T19:23:56.041769Z","end":"2026-02-17T19:23:56.552389Z","steps":["trace[1684104774] 'agreement among raft nodes before linearized reading' (duration: 510.424983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:56.55243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:56.041747Z","time spent":"510.675579ms","remote":"127.0.0.1:47798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-17T19:23:56.552652Z","caller":"traceutil/trace.go:171","msg":"trace[1101102311] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"512.586484ms","start":"2026-02-17T19:23:56.040046Z","end":"2026-02-17T19:23:56.552633Z","steps":["trace[1101102311] 'process raft request' (duration: 212.323667ms)","trace[1101102311] 'compare' (duration: 299.51538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:56.585039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:56.040037Z","time spent":"512.655857ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:56.696774Z","caller":"traceutil/trace.go:171","msg":"trace[865680301] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"653.425762ms","start":"2026-02-17T19:23:56.043323Z","end":"2026-02-17T19:23:56.696748Z","steps":["trace[865680301] 'process raft request' (duration: 630.244001ms)","trace[865680301] 'compare' (duration: 23.051388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:23:56.696918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:56.043316Z","time spent":"653.522524ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:23:56.696961Z","caller":"traceutil/trace.go:171","msg":"trace[287410206] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1003; }","duration":"141.098304ms","start":"2026-02-17T19:23:56.555848Z","end":"2026-02-17T19:23:56.696946Z","steps":["trace[287410206] 'read index received' (duration: 117.675627ms)","trace[287410206] 'applied index is now lower than readState.Index' (duration: 23.421797ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:23:56.69709Z","caller":"traceutil/trace.go:171","msg":"trace[1443131602] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"450.844447ms","start":"2026-02-17T19:23:56.246234Z","end":"2026-02-17T19:23:56.697079Z","steps":["trace[1443131602] 'process raft request' (duration: 450.476448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:23:56.697202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:23:56.246215Z","time spent":"450.898988ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:23:56.697444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.592446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-17T19:23:56.697481Z","caller":"traceutil/trace.go:171","msg":"trace[696523864] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:963; }","duration":"141.720269ms","start":"2026-02-17T19:23:56.55575Z","end":"2026-02-17T19:23:56.697471Z","steps":["trace[696523864] 'agreement among raft nodes before linearized reading' (duration: 141.661158ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:23:58.706523Z","caller":"traceutil/trace.go:171","msg":"trace[2071290875] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"111.729536ms","start":"2026-02-17T19:23:58.594779Z","end":"2026-02-17T19:23:58.706508Z","steps":["trace[2071290875] 'process raft request' (duration: 111.444889ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:24:01.437533Z","caller":"traceutil/trace.go:171","msg":"trace[1259881788] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"122.994204ms","start":"2026-02-17T19:24:01.31408Z","end":"2026-02-17T19:24:01.437075Z","steps":["trace[1259881788] 'process raft request' (duration: 122.886051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:04.974234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.171212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:24:04.974321Z","caller":"traceutil/trace.go:171","msg":"trace[677121768] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"243.575101ms","start":"2026-02-17T19:24:04.73073Z","end":"2026-02-17T19:24:04.974305Z","steps":["trace[677121768] 'read index received' (duration: 21.228855ms)","trace[677121768] 'applied index is now lower than readState.Index' (duration: 222.345266ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:24:04.974483Z","caller":"traceutil/trace.go:171","msg":"trace[933418760] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"304.427927ms","start":"2026-02-17T19:24:04.670041Z","end":"2026-02-17T19:24:04.974469Z","steps":["trace[933418760] 'process raft request' (duration: 81.965968ms)","trace[933418760] 'compare' (duration: 221.839564ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:24:04.974536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:04.670028Z","time spent":"304.485489ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T19:24:04.974799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.067712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T19:24:04.974868Z","caller":"traceutil/trace.go:171","msg":"trace[2100819551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"244.131614ms","start":"2026-02-17T19:24:04.730688Z","end":"2026-02-17T19:24:04.97482Z","steps":["trace[2100819551] 'agreement among raft nodes before linearized reading' (duration: 244.006441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:04.975135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.724175ms","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-17T19:24:04.97517Z","caller":"traceutil/trace.go:171","msg":"trace[1177480227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"238.780396ms","start":"2026-02-17T19:24:04.736378Z","end":"2026-02-17T19:24:04.975158Z","steps":["trace[1177480227] 'agreement among raft nodes before linearized reading' (duration: 238.631433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:05.778572Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555032484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:24:06.279746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555032484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:24:06.301976Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.264415807s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:24:06.302345Z","caller":"traceutil/trace.go:171","msg":"trace[771848149] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"1.024548526s","start":"2026-02-17T19:24:05.27776Z","end":"2026-02-17T19:24:06.302308Z","steps":["trace[771848149] 'read index received' (duration: 1.024340521s)","trace[771848149] 'applied index is now lower than readState.Index' (duration: 206.885µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:24:06.302527Z","caller":"traceutil/trace.go:171","msg":"trace[1192687657] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.265053832s","start":"2026-02-17T19:24:05.037458Z","end":"2026-02-17T19:24:06.302512Z","steps":["trace[1192687657] 'process raft request' (duration: 1.264712044s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:06.302653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:05.037433Z","time spent":"1.265145885s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:24:06.3028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.848859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-17T19:24:06.302858Z","caller":"traceutil/trace.go:171","msg":"trace[1262206367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"987.018853ms","start":"2026-02-17T19:24:05.315824Z","end":"2026-02-17T19:24:06.302843Z","steps":["trace[1262206367] 'agreement among raft nodes before linearized reading' (duration: 986.780638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:06.302902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:05.315804Z","time spent":"987.084664ms","remote":"127.0.0.1:48022","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-17T19:24:06.303061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025295573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:24:06.303101Z","caller":"traceutil/trace.go:171","msg":"trace[771346341] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1016; }","duration":"1.025338974s","start":"2026-02-17T19:24:05.277751Z","end":"2026-02-17T19:24:06.30309Z","steps":["trace[771346341] 'agreement among raft nodes before linearized reading' (duration: 1.025236732s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:06.303141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:05.277685Z","time spent":"1.025443387s","remote":"127.0.0.1:48090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:24:06.30341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.106164ms","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-17T19:24:06.303458Z","caller":"traceutil/trace.go:171","msg":"trace[344070135] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"846.183056ms","start":"2026-02-17T19:24:05.457263Z","end":"2026-02-17T19:24:06.303446Z","steps":["trace[344070135] 'agreement among raft nodes before linearized reading' (duration: 846.070723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:06.303502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:05.457247Z","time spent":"846.240888ms","remote":"127.0.0.1:48022","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-17T19:24:06.30341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.400999ms","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-17T19:24:06.303724Z","caller":"traceutil/trace.go:171","msg":"trace[1641140838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"835.738228ms","start":"2026-02-17T19:24:05.467972Z","end":"2026-02-17T19:24:06.30371Z","steps":["trace[1641140838] 'agreement among raft nodes before linearized reading' (duration: 835.345399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:06.303808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:05.46796Z","time spent":"835.83451ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:24:06.303465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.984811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:24:06.30462Z","caller":"traceutil/trace.go:171","msg":"trace[652621607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"680.158639ms","start":"2026-02-17T19:24:05.624445Z","end":"2026-02-17T19:24:06.304603Z","steps":["trace[652621607] 'agreement among raft nodes before linearized reading' (duration: 678.993531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:06.304665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:05.624428Z","time spent":"680.2226ms","remote":"127.0.0.1:47788","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-17T19:24:07.469016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.242642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:24:07.469101Z","caller":"traceutil/trace.go:171","msg":"trace[66451137] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.157104807s","start":"2026-02-17T19:24:06.311982Z","end":"2026-02-17T19:24:07.469087Z","steps":["trace[66451137] 'process raft request' (duration: 770.737952ms)","trace[66451137] 'compare' (duration: 386.025437ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:24:07.469161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:06.311965Z","time spent":"1.157166208s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:24:07.469549Z","caller":"traceutil/trace.go:171","msg":"trace[835482619] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1060; }","duration":"568.445063ms","start":"2026-02-17T19:24:06.901095Z","end":"2026-02-17T19:24:07.46954Z","steps":["trace[835482619] 'read index received' (duration: 181.564666ms)","trace[835482619] 'applied index is now lower than readState.Index' (duration: 386.879837ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:24:07.469667Z","caller":"traceutil/trace.go:171","msg":"trace[525085785] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.157274181s","start":"2026-02-17T19:24:06.312386Z","end":"2026-02-17T19:24:07.46966Z","steps":["trace[525085785] 'process raft request' (duration: 1.157064856s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:07.469752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:06.312377Z","time spent":"1.157313152s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:24:07.469831Z","caller":"traceutil/trace.go:171","msg":"trace[1028541144] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"1.157050996s","start":"2026-02-17T19:24:06.312775Z","end":"2026-02-17T19:24:07.469826Z","steps":["trace[1028541144] 'process raft request' (duration: 1.156720368s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:07.469862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:06.312769Z","time spent":"1.157075616s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:24:07.469947Z","caller":"traceutil/trace.go:171","msg":"trace[1033536613] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"683.232103ms","start":"2026-02-17T19:24:06.786709Z","end":"2026-02-17T19:24:07.469941Z","steps":["trace[1033536613] 'process raft request' (duration: 682.810273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:07.469982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:06.78669Z","time spent":"683.269693ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:24:07.47015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.058438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-17T19:24:07.470179Z","caller":"traceutil/trace.go:171","msg":"trace[1041484609] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1020; }","duration":"569.115259ms","start":"2026-02-17T19:24:06.901057Z","end":"2026-02-17T19:24:07.470172Z","steps":["trace[1041484609] 'agreement among raft nodes before linearized reading' (duration: 569.016477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:07.470202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:06.901042Z","time spent":"569.154711ms","remote":"127.0.0.1:48022","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-17T19:24:07.470324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.973692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T19:24:07.470347Z","caller":"traceutil/trace.go:171","msg":"trace[550744998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1020; }","duration":"451.022472ms","start":"2026-02-17T19:24:07.019319Z","end":"2026-02-17T19:24:07.470342Z","steps":["trace[550744998] 'agreement among raft nodes before linearized reading' (duration: 450.964371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:07.470363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:07.019303Z","time spent":"451.055774ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:24:07.470524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.959155ms","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:534"} {"level":"info","ts":"2026-02-17T19:24:07.470556Z","caller":"traceutil/trace.go:171","msg":"trace[1298538649] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1020; }","duration":"354.023506ms","start":"2026-02-17T19:24:07.116523Z","end":"2026-02-17T19:24:07.470547Z","steps":["trace[1298538649] 'agreement among raft nodes before linearized reading' (duration: 353.948074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:07.470579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:07.116509Z","time spent":"354.063018ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-17T19:24:07.470678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.795385ms","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-17T19:24:07.470715Z","caller":"traceutil/trace.go:171","msg":"trace[709497376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1020; }","duration":"431.862457ms","start":"2026-02-17T19:24:07.038847Z","end":"2026-02-17T19:24:07.470709Z","steps":["trace[709497376] 'agreement among raft nodes before linearized reading' (duration: 431.793145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:24:07.470737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:24:07.038831Z","time spent":"431.901877ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T19:27:04.33867Z","caller":"traceutil/trace.go:171","msg":"trace[1999602371] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"173.667144ms","start":"2026-02-17T19:27:04.16498Z","end":"2026-02-17T19:27:04.338647Z","steps":["trace[1999602371] 'process raft request' (duration: 90.251048ms)","trace[1999602371] 'compare' (duration: 82.969455ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:27:22.913858Z","caller":"traceutil/trace.go:171","msg":"trace[2101116369] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"332.21861ms","start":"2026-02-17T19:27:22.58162Z","end":"2026-02-17T19:27:22.913839Z","steps":["trace[2101116369] 'process raft request' (duration: 332.106048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:22.914215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:22.581607Z","time spent":"332.312682ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:27:22.949615Z","caller":"traceutil/trace.go:171","msg":"trace[371285693] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"325.766337ms","start":"2026-02-17T19:27:22.623826Z","end":"2026-02-17T19:27:22.949592Z","steps":["trace[371285693] 'read index received' (duration: 290.864441ms)","trace[371285693] 'applied index is now lower than readState.Index' (duration: 34.900866ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:27:22.949755Z","caller":"traceutil/trace.go:171","msg":"trace[920901348] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"366.619964ms","start":"2026-02-17T19:27:22.583123Z","end":"2026-02-17T19:27:22.949743Z","steps":["trace[920901348] 'process raft request' (duration: 366.325567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:22.949872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:22.583107Z","time spent":"366.677536ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:27:22.949874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.466446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:27:22.949931Z","caller":"traceutil/trace.go:171","msg":"trace[1632951570] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1859; }","duration":"302.535378ms","start":"2026-02-17T19:27:22.64738Z","end":"2026-02-17T19:27:22.949916Z","steps":["trace[1632951570] 'agreement among raft nodes before linearized reading' (duration: 302.439825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:22.94996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:22.64734Z","time spent":"302.61272ms","remote":"127.0.0.1:58774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:27:22.950119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.297809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:27:22.950142Z","caller":"traceutil/trace.go:171","msg":"trace[1119188437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1859; }","duration":"326.418283ms","start":"2026-02-17T19:27:22.623716Z","end":"2026-02-17T19:27:22.950135Z","steps":["trace[1119188437] 'agreement among raft nodes before linearized reading' (duration: 326.380062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:22.950162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:22.623703Z","time spent":"326.454373ms","remote":"127.0.0.1:47788","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-17T19:27:22.950337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.251571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-17T19:27:22.950364Z","caller":"traceutil/trace.go:171","msg":"trace[1532085293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"223.319493ms","start":"2026-02-17T19:27:22.727038Z","end":"2026-02-17T19:27:22.950357Z","steps":["trace[1532085293] 'agreement among raft nodes before linearized reading' (duration: 223.226491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:22.950369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.363719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-17T19:27:22.95041Z","caller":"traceutil/trace.go:171","msg":"trace[446440457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1859; }","duration":"219.452452ms","start":"2026-02-17T19:27:22.730944Z","end":"2026-02-17T19:27:22.950397Z","steps":["trace[446440457] 'agreement among raft nodes before linearized reading' (duration: 219.310739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:22.950652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.663209ms","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-17T19:27:22.950674Z","caller":"traceutil/trace.go:171","msg":"trace[822254388] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1859; }","duration":"157.72867ms","start":"2026-02-17T19:27:22.792939Z","end":"2026-02-17T19:27:22.950667Z","steps":["trace[822254388] 'agreement among raft nodes before linearized reading' (duration: 157.668909ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:27:23.170367Z","caller":"traceutil/trace.go:171","msg":"trace[1386189361] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"210.693945ms","start":"2026-02-17T19:27:22.959653Z","end":"2026-02-17T19:27:23.170346Z","steps":["trace[1386189361] 'process raft request' (duration: 179.744003ms)","trace[1386189361] 'compare' (duration: 30.821089ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:27:23.178096Z","caller":"traceutil/trace.go:171","msg":"trace[2074221057] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"215.166621ms","start":"2026-02-17T19:27:22.962912Z","end":"2026-02-17T19:27:23.178078Z","steps":["trace[2074221057] 'process raft request' (duration: 215.13514ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:27:23.178521Z","caller":"traceutil/trace.go:171","msg":"trace[73708508] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"219.276838ms","start":"2026-02-17T19:27:22.959238Z","end":"2026-02-17T19:27:23.178515Z","steps":["trace[73708508] 'process raft request' (duration: 218.683304ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:27:23.178911Z","caller":"traceutil/trace.go:171","msg":"trace[37475735] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"218.360437ms","start":"2026-02-17T19:27:22.960545Z","end":"2026-02-17T19:27:23.178905Z","steps":["trace[37475735] 'process raft request' (duration: 217.462085ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:27:23.179279Z","caller":"traceutil/trace.go:171","msg":"trace[261051619] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"218.634443ms","start":"2026-02-17T19:27:22.960638Z","end":"2026-02-17T19:27:23.179272Z","steps":["trace[261051619] 'process raft request' (duration: 217.387294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:28.690874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555034758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:27:29.191964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680217927555034758,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T19:27:29.427634Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.240360654s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T19:27:29.427974Z","caller":"traceutil/trace.go:171","msg":"trace[2100434067] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"1.240761994s","start":"2026-02-17T19:27:28.187199Z","end":"2026-02-17T19:27:29.427961Z","steps":["trace[2100434067] 'process raft request' (duration: 1.240687032s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:29.42819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:28.187191Z","time spent":"1.240951078s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:27:30.768378Z","caller":"traceutil/trace.go:171","msg":"trace[1556937021] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"2.578130754s","start":"2026-02-17T19:27:28.190223Z","end":"2026-02-17T19:27:30.768354Z","steps":["trace[1556937021] 'read index received' (duration: 1.237772083s)","trace[1556937021] 'applied index is now lower than readState.Index' (duration: 1.340357811s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:27:30.768607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.578333099s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-02-17T19:27:30.768682Z","caller":"traceutil/trace.go:171","msg":"trace[1099923861] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:1889; }","duration":"2.578460412s","start":"2026-02-17T19:27:28.190183Z","end":"2026-02-17T19:27:30.768643Z","steps":["trace[1099923861] 'agreement among raft nodes before linearized reading' (duration: 2.578264307s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.768727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:28.190167Z","time spent":"2.578550634s","remote":"127.0.0.1:48090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":862,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"info","ts":"2026-02-17T19:27:30.769141Z","caller":"traceutil/trace.go:171","msg":"trace[1492083836] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"2.578752109s","start":"2026-02-17T19:27:28.190377Z","end":"2026-02-17T19:27:30.769129Z","steps":["trace[1492083836] 'process raft request' (duration: 2.577833937s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.769308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:28.190364Z","time spent":"2.57881312s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:27:30.779884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.56528866s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:1 size:2027"} {"level":"info","ts":"2026-02-17T19:27:30.779923Z","caller":"traceutil/trace.go:171","msg":"trace[1582690579] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:1; response_revision:1889; }","duration":"2.565356452s","start":"2026-02-17T19:27:28.214557Z","end":"2026-02-17T19:27:30.779914Z","steps":["trace[1582690579] 'agreement among raft nodes before linearized reading' (duration: 2.56528395s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.779942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:28.214548Z","time spent":"2.565390072s","remote":"127.0.0.1:48090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":2049,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-02-17T19:27:30.780081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.351965ms","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:510"} {"level":"info","ts":"2026-02-17T19:27:30.780094Z","caller":"traceutil/trace.go:171","msg":"trace[1094770028] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1889; }","duration":"967.365135ms","start":"2026-02-17T19:27:29.812725Z","end":"2026-02-17T19:27:30.78009Z","steps":["trace[1094770028] 'agreement among raft nodes before linearized reading' (duration: 967.307134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.780107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:29.812672Z","time spent":"967.432506ms","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-17T19:27:30.780169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155074037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:27:30.78018Z","caller":"traceutil/trace.go:171","msg":"trace[44247052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1889; }","duration":"1.155121098s","start":"2026-02-17T19:27:29.625055Z","end":"2026-02-17T19:27:30.780176Z","steps":["trace[44247052] 'agreement among raft nodes before linearized reading' (duration: 1.155102778s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.780191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:29.625035Z","time spent":"1.155153478s","remote":"127.0.0.1:47788","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-17T19:27:30.780316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343588767s","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-17T19:27:30.78033Z","caller":"traceutil/trace.go:171","msg":"trace[1924659592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1889; }","duration":"1.343603258s","start":"2026-02-17T19:27:29.436723Z","end":"2026-02-17T19:27:30.780326Z","steps":["trace[1924659592] 'agreement among raft nodes before linearized reading' (duration: 1.343528316s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.780345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:29.43671Z","time spent":"1.343631778s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:27:30.780426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35256709s","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-17T19:27:30.780438Z","caller":"traceutil/trace.go:171","msg":"trace[1910028886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1889; }","duration":"1.352604811s","start":"2026-02-17T19:27:29.427829Z","end":"2026-02-17T19:27:30.780434Z","steps":["trace[1910028886] 'agreement among raft nodes before linearized reading' (duration: 1.35256754s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.780449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:29.427815Z","time spent":"1.352631671s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:27:30.780526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.577211905s","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:532"} {"level":"info","ts":"2026-02-17T19:27:30.780538Z","caller":"traceutil/trace.go:171","msg":"trace[320310248] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1889; }","duration":"1.577240766s","start":"2026-02-17T19:27:29.203294Z","end":"2026-02-17T19:27:30.780534Z","steps":["trace[320310248] 'agreement among raft nodes before linearized reading' (duration: 1.577202915s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.780551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:29.203284Z","time spent":"1.577264236s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-17T19:27:30.780617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.813953536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-17T19:27:30.780629Z","caller":"traceutil/trace.go:171","msg":"trace[384843765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1889; }","duration":"1.813976047s","start":"2026-02-17T19:27:28.966649Z","end":"2026-02-17T19:27:30.780625Z","steps":["trace[384843765] 'agreement among raft nodes before linearized reading' (duration: 1.813943086s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.78064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:28.966644Z","time spent":"1.813993707s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T19:27:30.780709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847448179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-17T19:27:30.78072Z","caller":"traceutil/trace.go:171","msg":"trace[1900693304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1889; }","duration":"1.847494179s","start":"2026-02-17T19:27:28.933223Z","end":"2026-02-17T19:27:30.780717Z","steps":["trace[1900693304] 'agreement among raft nodes before linearized reading' (duration: 1.847459898s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.780731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:28.933207Z","time spent":"1.84752202s","remote":"127.0.0.1:48022","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-17T19:27:30.780797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.186827016s","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-17T19:27:30.780808Z","caller":"traceutil/trace.go:171","msg":"trace[795229612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1889; }","duration":"2.186838267s","start":"2026-02-17T19:27:28.593967Z","end":"2026-02-17T19:27:30.780805Z","steps":["trace[795229612] 'agreement among raft nodes before linearized reading' (duration: 2.186806666s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:27:30.780819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:27:28.593951Z","time spent":"2.186865597s","remote":"127.0.0.1:48022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}