{"level":"warn","ts":"2026-02-10T14:57:09.566216Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T14:57:09.566491Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-10T14:57:09.566632Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T14:57:09.566688Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-02-10T14:57:09.566755Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-10T14:57:09.567612Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-02-10T14:57:09.567818Z","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.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-10T14:57:09.573419Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.288128ms"} {"level":"info","ts":"2026-02-10T14:57:09.594853Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-02-10T14:57:09.595006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T14:57:09.595068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-02-10T14:57:09.59512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T14:57:09.595169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-02-10T14:57:09.595238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-02-10T14:57:09.608163Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T14:57:09.609662Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T14:57:09.611558Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-10T14:57:09.613255Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T14:57:09.618017Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-10T14:57:09.618477Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T14:57:09.61862Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T14:57:09.618852Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T14:57:09.621323Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T14:57:09.625171Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T14:57:09.62169Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-02-10T14:57:09.625027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-02-10T14:57:09.625334Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T14:57:09.625412Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-02-10T14:57:09.629673Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-02-10T14:57:10.39599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T14:57:10.396028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T14:57:10.396054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-02-10T14:57:10.396065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-02-10T14:57:10.396074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-02-10T14:57:10.396082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-02-10T14:57:10.396089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-02-10T14:57:10.407316Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T14:57:10.407452Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T14:57:10.407765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:57:10.408027Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T14:57:10.41299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T14:57:10.413043Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T14:57:10.41424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T14:57:10.426035Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-02-10T14:57:10.426196Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:57:10.426333Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:57:10.426445Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T14:57:13.01917Z","caller":"traceutil/trace.go:171","msg":"trace[480698503] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"140.047667ms","start":"2026-02-10T14:57:12.879099Z","end":"2026-02-10T14:57:13.019147Z","steps":["trace[480698503] 'process raft request' (duration: 136.252675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:15.792111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999856355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-10T14:57:15.792201Z","caller":"traceutil/trace.go:171","msg":"trace[1831762056] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000003196s","start":"2026-02-10T14:57:13.79218Z","end":"2026-02-10T14:57:15.792184Z","steps":["trace[1831762056] 'agreement among raft nodes before linearized reading' (duration: 1.999891725s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:15.792245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:13.792161Z","time spent":"2.000075457s","remote":"127.0.0.1:50304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/10 14:57:15 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-10T14:57:16.919272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000682526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-10T14:57:16.919663Z","caller":"traceutil/trace.go:171","msg":"trace[1098531522] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00111126s","start":"2026-02-10T14:57:14.918524Z","end":"2026-02-10T14:57:16.919636Z","steps":["trace[1098531522] 'agreement among raft nodes before linearized reading' (duration: 2.000712747s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:16.919931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:14.918506Z","time spent":"2.001406872s","remote":"127.0.0.1:50304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/10 14:57:16 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-10T14:57:17.316861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.383252451s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:57:17.317095Z","caller":"traceutil/trace.go:171","msg":"trace[366142452] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"3.524857214s","start":"2026-02-10T14:57:13.792226Z","end":"2026-02-10T14:57:17.317083Z","steps":["trace[366142452] 'read index received' (duration: 140.935517ms)","trace[366142452] 'applied index is now lower than readState.Index' (duration: 3.383920297s)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:17.317314Z","caller":"traceutil/trace.go:171","msg":"trace[524370133] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"4.013411086s","start":"2026-02-10T14:57:13.303894Z","end":"2026-02-10T14:57:17.317305Z","steps":["trace[524370133] 'process raft request' (duration: 629.334448ms)","trace[524370133] 'compare' (duration: 3.378672073s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:57:17.317497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:13.30386Z","time spent":"4.013615657s","remote":"127.0.0.1:50666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:57:17.662768Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.636799361s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T14:57:17.665229Z","caller":"traceutil/trace.go:171","msg":"trace[1361909343] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"2.637817449s","start":"2026-02-10T14:57:15.025834Z","end":"2026-02-10T14:57:17.663652Z","steps":["trace[1361909343] 'process raft request' (duration: 2.637274644s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.665551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:15.02581Z","time spent":"2.639676134s","remote":"127.0.0.1:50494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:57:17.729939Z","caller":"traceutil/trace.go:171","msg":"trace[852949212] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:218; }","duration":"412.740648ms","start":"2026-02-10T14:57:17.317182Z","end":"2026-02-10T14:57:17.729923Z","steps":["trace[852949212] 'read index received' (duration: 345.935244ms)","trace[852949212] 'applied index is now lower than readState.Index' (duration: 66.804514ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:17.730006Z","caller":"traceutil/trace.go:171","msg":"trace[1955870475] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"1.292278892s","start":"2026-02-10T14:57:16.437722Z","end":"2026-02-10T14:57:17.730001Z","steps":["trace[1955870475] 'process raft request' (duration: 1.29212454s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.730164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:16.437698Z","time spent":"1.292352023s","remote":"127.0.0.1:50494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5476,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:57:17.730487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.868709062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T14:57:17.730643Z","caller":"traceutil/trace.go:171","msg":"trace[1543438971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:214; }","duration":"3.868890104s","start":"2026-02-10T14:57:13.861733Z","end":"2026-02-10T14:57:17.730623Z","steps":["trace[1543438971] 'agreement among raft nodes before linearized reading' (duration: 3.868640222s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.730761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:13.861725Z","time spent":"3.869023275s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T14:57:17.731029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.858212907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:57:17.731137Z","caller":"traceutil/trace.go:171","msg":"trace[1285254547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:214; }","duration":"1.858306077s","start":"2026-02-10T14:57:15.872788Z","end":"2026-02-10T14:57:17.731094Z","steps":["trace[1285254547] 'agreement among raft nodes before linearized reading' (duration: 1.858214447s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.731226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:15.872775Z","time spent":"1.858440638s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T14:57:17.731939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.064695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:57:17.731971Z","caller":"traceutil/trace.go:171","msg":"trace[1699596133] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:0; response_revision:214; }","duration":"412.504117ms","start":"2026-02-10T14:57:17.319459Z","end":"2026-02-10T14:57:17.731963Z","steps":["trace[1699596133] 'agreement among raft nodes before linearized reading' (duration: 411.077235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.732052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:17.319448Z","time spent":"412.596079ms","remote":"127.0.0.1:50666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" "} {"level":"warn","ts":"2026-02-10T14:57:17.732264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.80033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:57:17.732289Z","caller":"traceutil/trace.go:171","msg":"trace[1716601402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:214; }","duration":"313.87126ms","start":"2026-02-10T14:57:17.418408Z","end":"2026-02-10T14:57:17.73228Z","steps":["trace[1716601402] 'agreement among raft nodes before linearized reading' (duration: 313.839499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.732128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.939459852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:57:17.732427Z","caller":"traceutil/trace.go:171","msg":"trace[862168654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:214; }","duration":"1.939827955s","start":"2026-02-10T14:57:15.792559Z","end":"2026-02-10T14:57:17.732387Z","steps":["trace[862168654] 'agreement among raft nodes before linearized reading' (duration: 1.939494002s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.732521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:15.792537Z","time spent":"1.939949015s","remote":"127.0.0.1:50308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:57:17.732742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.958370294s","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-10T14:57:17.732858Z","caller":"traceutil/trace.go:171","msg":"trace[1583529229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:214; }","duration":"2.958502505s","start":"2026-02-10T14:57:14.774337Z","end":"2026-02-10T14:57:17.732839Z","steps":["trace[1583529229] 'agreement among raft nodes before linearized reading' (duration: 2.958379744s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.733964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:14.774319Z","time spent":"2.959630444s","remote":"127.0.0.1:50552","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":"warn","ts":"2026-02-10T14:57:17.730507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.869785711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T14:57:17.734533Z","caller":"traceutil/trace.go:171","msg":"trace[151822834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:214; }","duration":"3.873839154s","start":"2026-02-10T14:57:13.860686Z","end":"2026-02-10T14:57:17.734525Z","steps":["trace[151822834] 'agreement among raft nodes before linearized reading' (duration: 3.86978466s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:17.734651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:13.860663Z","time spent":"3.873978546s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T14:57:17.732356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:17.418386Z","time spent":"313.964201ms","remote":"127.0.0.1:50304","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-10T14:57:24.059529Z","caller":"traceutil/trace.go:171","msg":"trace[100894785] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"116.469271ms","start":"2026-02-10T14:57:23.94305Z","end":"2026-02-10T14:57:24.059519Z","steps":["trace[100894785] 'process raft request' (duration: 115.952617ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.060356Z","caller":"traceutil/trace.go:171","msg":"trace[461154252] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"118.903191ms","start":"2026-02-10T14:57:23.941421Z","end":"2026-02-10T14:57:24.060324Z","steps":["trace[461154252] 'process raft request' (duration: 117.411938ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.060804Z","caller":"traceutil/trace.go:171","msg":"trace[263717608] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"117.791151ms","start":"2026-02-10T14:57:23.943Z","end":"2026-02-10T14:57:24.060791Z","steps":["trace[263717608] 'process raft request' (duration: 115.891666ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.059454Z","caller":"traceutil/trace.go:171","msg":"trace[2023638236] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"125.583522ms","start":"2026-02-10T14:57:23.933849Z","end":"2026-02-10T14:57:24.059433Z","steps":["trace[2023638236] 'process raft request' (duration: 124.835316ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.285945Z","caller":"traceutil/trace.go:171","msg":"trace[58505668] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"208.977497ms","start":"2026-02-10T14:57:24.076942Z","end":"2026-02-10T14:57:24.28592Z","steps":["trace[58505668] 'process raft request' (duration: 152.20937ms)","trace[58505668] 'compare' (duration: 56.462675ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:24.288888Z","caller":"traceutil/trace.go:171","msg":"trace[934509003] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"210.912172ms","start":"2026-02-10T14:57:24.077957Z","end":"2026-02-10T14:57:24.288869Z","steps":["trace[934509003] 'process raft request' (duration: 207.915449ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.291077Z","caller":"traceutil/trace.go:171","msg":"trace[1951987688] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"212.994769ms","start":"2026-02-10T14:57:24.07807Z","end":"2026-02-10T14:57:24.291064Z","steps":["trace[1951987688] 'process raft request' (duration: 210.438968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:24.512835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.501013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:57:24.512972Z","caller":"traceutil/trace.go:171","msg":"trace[384927894] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"206.592626ms","start":"2026-02-10T14:57:24.306366Z","end":"2026-02-10T14:57:24.512959Z","steps":["trace[384927894] 'process raft request' (duration: 87.911992ms)","trace[384927894] 'compare' (duration: 118.339372ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:24.513263Z","caller":"traceutil/trace.go:171","msg":"trace[1609568460] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"206.687288ms","start":"2026-02-10T14:57:24.30657Z","end":"2026-02-10T14:57:24.513257Z","steps":["trace[1609568460] 'process raft request' (duration: 206.345965ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.513448Z","caller":"traceutil/trace.go:171","msg":"trace[356334785] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"163.81383ms","start":"2026-02-10T14:57:24.349629Z","end":"2026-02-10T14:57:24.513443Z","steps":["trace[356334785] 'process raft request' (duration: 163.79116ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.513483Z","caller":"traceutil/trace.go:171","msg":"trace[974335338] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"205.810901ms","start":"2026-02-10T14:57:24.307668Z","end":"2026-02-10T14:57:24.513479Z","steps":["trace[974335338] 'process raft request' (duration: 205.273457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:24.77559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.654685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:57:24.775836Z","caller":"traceutil/trace.go:171","msg":"trace[1814373755] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"215.220586ms","start":"2026-02-10T14:57:24.560601Z","end":"2026-02-10T14:57:24.775821Z","steps":["trace[1814373755] 'process raft request' (duration: 215.174036ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.77626Z","caller":"traceutil/trace.go:171","msg":"trace[2139339465] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"230.639777ms","start":"2026-02-10T14:57:24.545607Z","end":"2026-02-10T14:57:24.776247Z","steps":["trace[2139339465] 'process raft request' (duration: 73.120976ms)","trace[2139339465] 'compare' (duration: 156.555164ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:24.776396Z","caller":"traceutil/trace.go:171","msg":"trace[243033093] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"221.019162ms","start":"2026-02-10T14:57:24.555368Z","end":"2026-02-10T14:57:24.776387Z","steps":["trace[243033093] 'process raft request' (duration: 220.323707ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.776641Z","caller":"traceutil/trace.go:171","msg":"trace[1937186586] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"216.379885ms","start":"2026-02-10T14:57:24.560234Z","end":"2026-02-10T14:57:24.776614Z","steps":["trace[1937186586] 'process raft request' (duration: 215.507408ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:24.937467Z","caller":"traceutil/trace.go:171","msg":"trace[1319695336] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"147.749344ms","start":"2026-02-10T14:57:24.78967Z","end":"2026-02-10T14:57:24.937419Z","steps":["trace[1319695336] 'process raft request' (duration: 115.060226ms)","trace[1319695336] 'compare' (duration: 32.509707ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:29.631593Z","caller":"traceutil/trace.go:171","msg":"trace[1664748523] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"121.165976ms","start":"2026-02-10T14:57:29.51041Z","end":"2026-02-10T14:57:29.631576Z","steps":["trace[1664748523] 'process raft request' (duration: 121.076935ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:30.01235Z","caller":"traceutil/trace.go:171","msg":"trace[95191761] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"104.016443ms","start":"2026-02-10T14:57:29.908315Z","end":"2026-02-10T14:57:30.012331Z","steps":["trace[95191761] 'process raft request' (duration: 58.600307ms)","trace[95191761] 'compare' (duration: 45.310975ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:30.034657Z","caller":"traceutil/trace.go:171","msg":"trace[346791117] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"126.128672ms","start":"2026-02-10T14:57:29.90851Z","end":"2026-02-10T14:57:30.034638Z","steps":["trace[346791117] 'process raft request' (duration: 125.998621ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:30.034921Z","caller":"traceutil/trace.go:171","msg":"trace[873373994] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"126.338014ms","start":"2026-02-10T14:57:29.908576Z","end":"2026-02-10T14:57:30.034914Z","steps":["trace[873373994] 'process raft request' (duration: 126.031851ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:30.035166Z","caller":"traceutil/trace.go:171","msg":"trace[1560259098] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"124.782452ms","start":"2026-02-10T14:57:29.910366Z","end":"2026-02-10T14:57:30.035148Z","steps":["trace[1560259098] 'process raft request' (duration: 124.435669ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:31.525959Z","caller":"traceutil/trace.go:171","msg":"trace[41731001] transaction","detail":"{read_only:false; number_of_response:1; response_revision:445; }","duration":"107.18ms","start":"2026-02-10T14:57:31.418758Z","end":"2026-02-10T14:57:31.525938Z","steps":["trace[41731001] 'process raft request' (duration: 41.728375ms)","trace[41731001] 'compare' (duration: 65.324314ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:31.526279Z","caller":"traceutil/trace.go:171","msg":"trace[1417634260] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"103.807884ms","start":"2026-02-10T14:57:31.422464Z","end":"2026-02-10T14:57:31.526272Z","steps":["trace[1417634260] 'process raft request' (duration: 103.412291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:31.756076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.17945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:57:31.756275Z","caller":"traceutil/trace.go:171","msg":"trace[1316505803] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"180.14171ms","start":"2026-02-10T14:57:31.576123Z","end":"2026-02-10T14:57:31.756265Z","steps":["trace[1316505803] 'process raft request' (duration: 180.10369ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:31.756387Z","caller":"traceutil/trace.go:171","msg":"trace[1952095029] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"226.448801ms","start":"2026-02-10T14:57:31.529933Z","end":"2026-02-10T14:57:31.756382Z","steps":["trace[1952095029] 'process raft request' (duration: 110.909898ms)","trace[1952095029] 'compare' (duration: 115.09879ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:31.756493Z","caller":"traceutil/trace.go:171","msg":"trace[1449647784] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"225.524283ms","start":"2026-02-10T14:57:31.530965Z","end":"2026-02-10T14:57:31.756489Z","steps":["trace[1449647784] 'process raft request' (duration: 225.17845ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:31.756624Z","caller":"traceutil/trace.go:171","msg":"trace[662118280] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"224.368675ms","start":"2026-02-10T14:57:31.53225Z","end":"2026-02-10T14:57:31.756619Z","steps":["trace[662118280] 'process raft request' (duration: 223.921261ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:31.756685Z","caller":"traceutil/trace.go:171","msg":"trace[1939131461] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"181.120078ms","start":"2026-02-10T14:57:31.575561Z","end":"2026-02-10T14:57:31.756681Z","steps":["trace[1939131461] 'process raft request' (duration: 180.649575ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:40.171927Z","caller":"traceutil/trace.go:171","msg":"trace[387004731] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"113.52355ms","start":"2026-02-10T14:57:40.058387Z","end":"2026-02-10T14:57:40.17191Z","steps":["trace[387004731] 'process raft request' (duration: 113.388359ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:43.880745Z","caller":"traceutil/trace.go:171","msg":"trace[1511722318] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"207.809695ms","start":"2026-02-10T14:57:43.672911Z","end":"2026-02-10T14:57:43.880721Z","steps":["trace[1511722318] 'process raft request' (duration: 207.599803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:45.990933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000555411s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-10T14:57:45.991043Z","caller":"traceutil/trace.go:171","msg":"trace[688139485] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000719842s","start":"2026-02-10T14:57:43.990304Z","end":"2026-02-10T14:57:45.991024Z","steps":["trace[688139485] 'agreement among raft nodes before linearized reading' (duration: 2.00058788s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:45.991122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:43.990226Z","time spent":"2.000885023s","remote":"127.0.0.1:50308","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-10T14:57:46.506238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.791154ms","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-10T14:57:46.506598Z","caller":"traceutil/trace.go:171","msg":"trace[1701525224] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:491; }","duration":"964.156793ms","start":"2026-02-10T14:57:45.542421Z","end":"2026-02-10T14:57:46.506578Z","steps":["trace[1701525224] 'range keys from in-memory index tree' (duration: 963.779354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:46.507863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.323119617s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:57:46.508293Z","caller":"traceutil/trace.go:171","msg":"trace[759742053] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"2.595061826s","start":"2026-02-10T14:57:43.913213Z","end":"2026-02-10T14:57:46.508274Z","steps":["trace[759742053] 'process raft request' (duration: 271.447504ms)","trace[759742053] 'compare' (duration: 2.321462233s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:57:46.508569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:43.913188Z","time spent":"2.595310307s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:57:46.508708Z","caller":"traceutil/trace.go:171","msg":"trace[1022948666] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:511; }","duration":"2.517785729s","start":"2026-02-10T14:57:43.990355Z","end":"2026-02-10T14:57:46.508141Z","steps":["trace[1022948666] 'read index received' (duration: 194.313224ms)","trace[1022948666] 'applied index is now lower than readState.Index' (duration: 2.323471415s)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:46.508861Z","caller":"traceutil/trace.go:171","msg":"trace[1244337395] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"2.595463693s","start":"2026-02-10T14:57:43.913386Z","end":"2026-02-10T14:57:46.50885Z","steps":["trace[1244337395] 'process raft request' (duration: 2.594631682s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:46.509976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:43.913382Z","time spent":"2.596558039s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:57:47.000263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.749364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:57:47.001166Z","caller":"traceutil/trace.go:171","msg":"trace[51795851] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"484.532164ms","start":"2026-02-10T14:57:46.516617Z","end":"2026-02-10T14:57:47.001149Z","steps":["trace[51795851] 'process raft request' (duration: 484.431899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:47.001344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:46.516599Z","time spent":"484.711163ms","remote":"127.0.0.1:50398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6519,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:57:47.001893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.761013895s","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-10T14:57:47.002192Z","caller":"traceutil/trace.go:171","msg":"trace[2049863485] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:495; }","duration":"2.761272315s","start":"2026-02-10T14:57:44.240817Z","end":"2026-02-10T14:57:47.00209Z","steps":["trace[2049863485] 'agreement among raft nodes before linearized reading' (duration: 2.761046004s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:47.002461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:44.240799Z","time spent":"2.761629112s","remote":"127.0.0.1:50552","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-10T14:57:47.001594Z","caller":"traceutil/trace.go:171","msg":"trace[22398584] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"491.753309ms","start":"2026-02-10T14:57:46.509827Z","end":"2026-02-10T14:57:47.00158Z","steps":["trace[22398584] 'read index received' (duration: 115.740602ms)","trace[22398584] 'applied index is now lower than readState.Index' (duration: 376.010977ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:47.001621Z","caller":"traceutil/trace.go:171","msg":"trace[1921332819] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.008891448s","start":"2026-02-10T14:57:45.992635Z","end":"2026-02-10T14:57:47.001527Z","steps":["trace[1921332819] 'process raft request' (duration: 632.820368ms)","trace[1921332819] 'compare' (duration: 374.525853ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:57:47.001732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.763408003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-10T14:57:47.00344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:45.992623Z","time spent":"1.010760381s","remote":"127.0.0.1:50364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-10T14:57:47.001739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.677645996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T14:57:47.00427Z","caller":"traceutil/trace.go:171","msg":"trace[1279348029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"2.68016265s","start":"2026-02-10T14:57:44.324047Z","end":"2026-02-10T14:57:47.00421Z","steps":["trace[1279348029] 'agreement among raft nodes before linearized reading' (duration: 2.677639694s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:47.004395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:44.324032Z","time spent":"2.680347188s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T14:57:47.001776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.681221912s","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-10T14:57:47.004787Z","caller":"traceutil/trace.go:171","msg":"trace[1937291447] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"2.684225822s","start":"2026-02-10T14:57:44.320547Z","end":"2026-02-10T14:57:47.004773Z","steps":["trace[1937291447] 'agreement among raft nodes before linearized reading' (duration: 2.681203931s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:47.004895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:44.32048Z","time spent":"2.684401118s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T14:57:47.001962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004920566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:57:47.005341Z","caller":"traceutil/trace.go:171","msg":"trace[1023837009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"1.008313334s","start":"2026-02-10T14:57:45.997012Z","end":"2026-02-10T14:57:47.005326Z","steps":["trace[1023837009] 'agreement among raft nodes before linearized reading' (duration: 1.004933316s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:47.005509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:45.997003Z","time spent":"1.008453961s","remote":"127.0.0.1:50304","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-10T14:57:47.002734Z","caller":"traceutil/trace.go:171","msg":"trace[366298451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:495; }","duration":"2.764459095s","start":"2026-02-10T14:57:44.238261Z","end":"2026-02-10T14:57:47.00272Z","steps":["trace[366298451] 'agreement among raft nodes before linearized reading' (duration: 2.76335814s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:47.00649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:44.238245Z","time spent":"2.768234872s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-10T14:57:47.289401Z","caller":"traceutil/trace.go:171","msg":"trace[1697848047] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:516; }","duration":"248.475149ms","start":"2026-02-10T14:57:47.040904Z","end":"2026-02-10T14:57:47.289379Z","steps":["trace[1697848047] 'read index received' (duration: 155.880953ms)","trace[1697848047] 'applied index is now lower than readState.Index' (duration: 92.593356ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:57:47.289724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.802655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:57:47.290236Z","caller":"traceutil/trace.go:171","msg":"trace[1657002915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"249.227056ms","start":"2026-02-10T14:57:47.040878Z","end":"2026-02-10T14:57:47.290105Z","steps":["trace[1657002915] 'agreement among raft nodes before linearized reading' (duration: 248.71566ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:47.289549Z","caller":"traceutil/trace.go:171","msg":"trace[886813569] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"277.39594ms","start":"2026-02-10T14:57:47.012131Z","end":"2026-02-10T14:57:47.289527Z","steps":["trace[886813569] 'process raft request' (duration: 277.075454ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:47.289804Z","caller":"traceutil/trace.go:171","msg":"trace[124636324] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"277.622071ms","start":"2026-02-10T14:57:47.012167Z","end":"2026-02-10T14:57:47.289789Z","steps":["trace[124636324] 'process raft request' (duration: 277.110406ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:47.289838Z","caller":"traceutil/trace.go:171","msg":"trace[1543621987] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"276.864614ms","start":"2026-02-10T14:57:47.012966Z","end":"2026-02-10T14:57:47.289831Z","steps":["trace[1543621987] 'process raft request' (duration: 276.351389ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:47.289897Z","caller":"traceutil/trace.go:171","msg":"trace[825962279] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"276.342798ms","start":"2026-02-10T14:57:47.013547Z","end":"2026-02-10T14:57:47.289889Z","steps":["trace[825962279] 'process raft request' (duration: 275.797601ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:47.291136Z","caller":"traceutil/trace.go:171","msg":"trace[1761957186] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"279.042502ms","start":"2026-02-10T14:57:47.012076Z","end":"2026-02-10T14:57:47.291119Z","steps":["trace[1761957186] 'process raft request' (duration: 184.764782ms)","trace[1761957186] 'compare' (duration: 92.166715ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:47.509118Z","caller":"traceutil/trace.go:171","msg":"trace[936134562] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"208.555418ms","start":"2026-02-10T14:57:47.300542Z","end":"2026-02-10T14:57:47.509098Z","steps":["trace[936134562] 'process raft request' (duration: 208.518976ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:47.509531Z","caller":"traceutil/trace.go:171","msg":"trace[1905294471] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"210.152318ms","start":"2026-02-10T14:57:47.299366Z","end":"2026-02-10T14:57:47.509519Z","steps":["trace[1905294471] 'process raft request' (duration: 172.145183ms)","trace[1905294471] 'compare' (duration: 37.413045ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:47.777894Z","caller":"traceutil/trace.go:171","msg":"trace[901772235] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"108.031115ms","start":"2026-02-10T14:57:47.669844Z","end":"2026-02-10T14:57:47.777875Z","steps":["trace[901772235] 'process raft request' (duration: 94.6976ms)","trace[901772235] 'compare' (duration: 13.015169ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:49.947551Z","caller":"traceutil/trace.go:171","msg":"trace[1874421488] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"102.596702ms","start":"2026-02-10T14:57:49.844927Z","end":"2026-02-10T14:57:49.947524Z","steps":["trace[1874421488] 'process raft request' (duration: 102.507668ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:50.345871Z","caller":"traceutil/trace.go:171","msg":"trace[1763229509] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"432.65155ms","start":"2026-02-10T14:57:49.913203Z","end":"2026-02-10T14:57:50.345854Z","steps":["trace[1763229509] 'process raft request' (duration: 431.518725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:50.345987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:49.913172Z","time spent":"432.754555ms","remote":"127.0.0.1:50552","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-10T14:57:53.380726Z","caller":"traceutil/trace.go:171","msg":"trace[1012675222] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"229.582195ms","start":"2026-02-10T14:57:53.151122Z","end":"2026-02-10T14:57:53.380705Z","steps":["trace[1012675222] 'process raft request' (duration: 229.425588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:53.534954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.905738ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-10T14:57:53.535174Z","caller":"traceutil/trace.go:171","msg":"trace[1078913501] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:562; }","duration":"200.824092ms","start":"2026-02-10T14:57:53.334336Z","end":"2026-02-10T14:57:53.53516Z","steps":["trace[1078913501] 'read index received' (duration: 46.268495ms)","trace[1078913501] 'applied index is now lower than readState.Index' (duration: 154.554367ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:53.535237Z","caller":"traceutil/trace.go:171","msg":"trace[888319658] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"151.132478ms","start":"2026-02-10T14:57:53.3841Z","end":"2026-02-10T14:57:53.535232Z","steps":["trace[888319658] 'process raft request' (duration: 150.936559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:53.629819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.47028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T14:57:53.629864Z","caller":"traceutil/trace.go:171","msg":"trace[856870382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"295.553403ms","start":"2026-02-10T14:57:53.334298Z","end":"2026-02-10T14:57:53.629852Z","steps":["trace[856870382] 'agreement among raft nodes before linearized reading' (duration: 200.909875ms)","trace[856870382] 'range keys from in-memory index tree' (duration: 94.534794ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:53.630011Z","caller":"traceutil/trace.go:171","msg":"trace[1229318810] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"239.482133ms","start":"2026-02-10T14:57:53.390523Z","end":"2026-02-10T14:57:53.630005Z","steps":["trace[1229318810] 'process raft request' (duration: 239.362928ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:53.630075Z","caller":"traceutil/trace.go:171","msg":"trace[701002796] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"245.732614ms","start":"2026-02-10T14:57:53.384337Z","end":"2026-02-10T14:57:53.63007Z","steps":["trace[701002796] 'process raft request' (duration: 150.891757ms)","trace[701002796] 'compare' (duration: 94.363135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:57:53.630062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.52698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T14:57:53.630108Z","caller":"traceutil/trace.go:171","msg":"trace[2009557176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"293.585063ms","start":"2026-02-10T14:57:53.336509Z","end":"2026-02-10T14:57:53.630094Z","steps":["trace[2009557176] 'agreement among raft nodes before linearized reading' (duration: 293.453157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:53.630272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.257319ms","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-10T14:57:53.630287Z","caller":"traceutil/trace.go:171","msg":"trace[314007554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"210.294071ms","start":"2026-02-10T14:57:53.419989Z","end":"2026-02-10T14:57:53.630283Z","steps":["trace[314007554] 'agreement among raft nodes before linearized reading' (duration: 210.250479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:53.630342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.306462ms","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-10T14:57:53.630354Z","caller":"traceutil/trace.go:171","msg":"trace[1626355930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"210.329412ms","start":"2026-02-10T14:57:53.42002Z","end":"2026-02-10T14:57:53.63035Z","steps":["trace[1626355930] 'agreement among raft nodes before linearized reading' (duration: 210.295191ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:54.09522Z","caller":"traceutil/trace.go:171","msg":"trace[1386481134] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"459.293236ms","start":"2026-02-10T14:57:53.635897Z","end":"2026-02-10T14:57:54.09519Z","steps":["trace[1386481134] 'process raft request' (duration: 458.191166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:54.095611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:53.635892Z","time spent":"459.646292ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":882,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T14:57:54.096028Z","caller":"traceutil/trace.go:171","msg":"trace[1864621583] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"460.076562ms","start":"2026-02-10T14:57:53.635928Z","end":"2026-02-10T14:57:54.096004Z","steps":["trace[1864621583] 'process raft request' (duration: 458.502199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:54.096271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:53.635925Z","time spent":"460.169476ms","remote":"127.0.0.1:50552","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-10T14:57:54.096497Z","caller":"traceutil/trace.go:171","msg":"trace[1016088924] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:568; }","duration":"105.217677ms","start":"2026-02-10T14:57:53.99126Z","end":"2026-02-10T14:57:54.096477Z","steps":["trace[1016088924] 'read index received' (duration: 102.766415ms)","trace[1016088924] 'applied index is now lower than readState.Index' (duration: 2.449142ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:57:54.096702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.414397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:57:54.096908Z","caller":"traceutil/trace.go:171","msg":"trace[2137588521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"105.699189ms","start":"2026-02-10T14:57:53.991195Z","end":"2026-02-10T14:57:54.096894Z","steps":["trace[2137588521] 'agreement among raft nodes before linearized reading' (duration: 105.435337ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:54.097993Z","caller":"traceutil/trace.go:171","msg":"trace[356441100] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"459.833899ms","start":"2026-02-10T14:57:53.638143Z","end":"2026-02-10T14:57:54.097977Z","steps":["trace[356441100] 'process raft request' (duration: 457.46415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:54.098769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:53.638137Z","time spent":"460.578203ms","remote":"127.0.0.1:50552","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-10T14:57:54.09682Z","caller":"traceutil/trace.go:171","msg":"trace[1638787766] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"459.640091ms","start":"2026-02-10T14:57:53.637171Z","end":"2026-02-10T14:57:54.096811Z","steps":["trace[1638787766] 'process raft request' (duration: 458.370632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:54.098956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:53.637165Z","time spent":"461.768168ms","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:57:54.255029Z","caller":"traceutil/trace.go:171","msg":"trace[1498854633] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"108.952442ms","start":"2026-02-10T14:57:54.146024Z","end":"2026-02-10T14:57:54.254976Z","steps":["trace[1498854633] 'process raft request' (duration: 86.563086ms)","trace[1498854633] 'compare' (duration: 22.216628ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:54.665062Z","caller":"traceutil/trace.go:171","msg":"trace[989844928] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"175.153165ms","start":"2026-02-10T14:57:54.48988Z","end":"2026-02-10T14:57:54.665033Z","steps":["trace[989844928] 'process raft request' (duration: 174.975697ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:54.706733Z","caller":"traceutil/trace.go:171","msg":"trace[881172696] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"215.300535ms","start":"2026-02-10T14:57:54.491414Z","end":"2026-02-10T14:57:54.706714Z","steps":["trace[881172696] 'process raft request' (duration: 215.189419ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:54.848313Z","caller":"traceutil/trace.go:171","msg":"trace[590483343] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:579; }","duration":"170.629728ms","start":"2026-02-10T14:57:54.677638Z","end":"2026-02-10T14:57:54.848268Z","steps":["trace[590483343] 'read index received' (duration: 29.791745ms)","trace[590483343] 'applied index is now lower than readState.Index' (duration: 140.837093ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:54.848414Z","caller":"traceutil/trace.go:171","msg":"trace[1244477558] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"170.926912ms","start":"2026-02-10T14:57:54.677479Z","end":"2026-02-10T14:57:54.848406Z","steps":["trace[1244477558] 'process raft request' (duration: 170.728603ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:54.848475Z","caller":"traceutil/trace.go:171","msg":"trace[1830012827] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"171.086548ms","start":"2026-02-10T14:57:54.677372Z","end":"2026-02-10T14:57:54.848459Z","steps":["trace[1830012827] 'process raft request' (duration: 170.694581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:54.84861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.957253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:57:54.848642Z","caller":"traceutil/trace.go:171","msg":"trace[1985299605] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:559; }","duration":"171.018155ms","start":"2026-02-10T14:57:54.677615Z","end":"2026-02-10T14:57:54.848633Z","steps":["trace[1985299605] 'agreement among raft nodes before linearized reading' (duration: 170.952282ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:57:55.144771Z","caller":"traceutil/trace.go:171","msg":"trace[2099591865] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"140.768533ms","start":"2026-02-10T14:57:55.003983Z","end":"2026-02-10T14:57:55.144752Z","steps":["trace[2099591865] 'process raft request' (duration: 92.09656ms)","trace[2099591865] 'compare' (duration: 48.588729ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:57:58.303179Z","caller":"traceutil/trace.go:171","msg":"trace[937612060] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"177.235096ms","start":"2026-02-10T14:57:58.125915Z","end":"2026-02-10T14:57:58.30315Z","steps":["trace[937612060] 'process raft request' (duration: 176.785846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:58.5867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.775756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:57:58.586791Z","caller":"traceutil/trace.go:171","msg":"trace[2061031850] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"458.445675ms","start":"2026-02-10T14:57:58.128337Z","end":"2026-02-10T14:57:58.586783Z","steps":["trace[2061031850] 'process raft request' (duration: 458.417454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:57:58.586822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:58.12833Z","time spent":"458.478037ms","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:57:58.586985Z","caller":"traceutil/trace.go:171","msg":"trace[567803656] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"458.854114ms","start":"2026-02-10T14:57:58.128126Z","end":"2026-02-10T14:57:58.58698Z","steps":["trace[567803656] 'process raft request' (duration: 262.756144ms)","trace[567803656] 'compare' (duration: 195.67636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:57:58.587009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:58.128115Z","time spent":"458.881344ms","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:58:00.488956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.74888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r66pd\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-10T14:58:00.489052Z","caller":"traceutil/trace.go:171","msg":"trace[1077994331] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r66pd; range_end:; response_count:1; response_revision:579; }","duration":"870.849515ms","start":"2026-02-10T14:57:59.618187Z","end":"2026-02-10T14:58:00.489036Z","steps":["trace[1077994331] 'range keys from in-memory index tree' (duration: 870.634656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:00.48908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:59.618146Z","time spent":"870.927998ms","remote":"127.0.0.1:50494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-r66pd\" "} {"level":"warn","ts":"2026-02-10T14:58:00.489249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.531346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T14:58:00.489273Z","caller":"traceutil/trace.go:171","msg":"trace[1908932367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"768.577548ms","start":"2026-02-10T14:57:59.72069Z","end":"2026-02-10T14:58:00.489267Z","steps":["trace[1908932367] 'range keys from in-memory index tree' (duration: 768.456403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:00.489295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:59.720677Z","time spent":"768.613969ms","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T14:58:00.48948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.151425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:58:00.489505Z","caller":"traceutil/trace.go:171","msg":"trace[802078700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"501.197897ms","start":"2026-02-10T14:57:59.988301Z","end":"2026-02-10T14:58:00.489499Z","steps":["trace[802078700] 'range keys from in-memory index tree' (duration: 501.110514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:00.489521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:57:59.988288Z","time spent":"501.229359ms","remote":"127.0.0.1:50304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:58:00.489726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.814901ms","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-10T14:58:00.489754Z","caller":"traceutil/trace.go:171","msg":"trace[1903415170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"180.866373ms","start":"2026-02-10T14:58:00.308879Z","end":"2026-02-10T14:58:00.489745Z","steps":["trace[1903415170] 'range keys from in-memory index tree' (duration: 180.740868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:00.997784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710898541122281456,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T14:58:01.438405Z","caller":"traceutil/trace.go:171","msg":"trace[768409502] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"940.805792ms","start":"2026-02-10T14:58:00.497404Z","end":"2026-02-10T14:58:01.438209Z","steps":["trace[768409502] 'process raft request' (duration: 940.644525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:01.438576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:00.497388Z","time spent":"941.061413ms","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:58:01.973185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.054459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:58:01.973318Z","caller":"traceutil/trace.go:171","msg":"trace[257789167] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:602; }","duration":"1.47577935s","start":"2026-02-10T14:58:00.497529Z","end":"2026-02-10T14:58:01.973308Z","steps":["trace[257789167] 'read index received' (duration: 940.548821ms)","trace[257789167] 'applied index is now lower than readState.Index' (duration: 535.229589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:58:01.973471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475938497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:58:01.973487Z","caller":"traceutil/trace.go:171","msg":"trace[598443785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"1.475968689s","start":"2026-02-10T14:58:00.497512Z","end":"2026-02-10T14:58:01.973481Z","steps":["trace[598443785] 'agreement among raft nodes before linearized reading' (duration: 1.475939477s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:01.973502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:00.497508Z","time spent":"1.475991009s","remote":"127.0.0.1:50308","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-10T14:58:01.973492Z","caller":"traceutil/trace.go:171","msg":"trace[113603924] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.249000838s","start":"2026-02-10T14:58:00.724465Z","end":"2026-02-10T14:58:01.973466Z","steps":["trace[113603924] 'process raft request' (duration: 1.24882105s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:01.973602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:00.724441Z","time spent":"1.249099542s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:58:01.973637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382577972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-10T14:58:01.973655Z","caller":"traceutil/trace.go:171","msg":"trace[2046928857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:583; }","duration":"1.382631535s","start":"2026-02-10T14:58:00.591017Z","end":"2026-02-10T14:58:01.973649Z","steps":["trace[2046928857] 'agreement among raft nodes before linearized reading' (duration: 1.382572382s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:01.973667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:00.590969Z","time spent":"1.382694977s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":453,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T14:58:01.973757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381766418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-10T14:58:01.973769Z","caller":"traceutil/trace.go:171","msg":"trace[639674034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:583; }","duration":"1.38180764s","start":"2026-02-10T14:58:00.591958Z","end":"2026-02-10T14:58:01.973765Z","steps":["trace[639674034] 'agreement among raft nodes before linearized reading' (duration: 1.381770848s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:01.973781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:00.591943Z","time spent":"1.381834921s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T14:58:01.973778Z","caller":"traceutil/trace.go:171","msg":"trace[1762693827] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.476275683s","start":"2026-02-10T14:58:00.497493Z","end":"2026-02-10T14:58:01.973769Z","steps":["trace[1762693827] 'process raft request' (duration: 1.055586656s)","trace[1762693827] 'compare' (duration: 419.990016ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:58:01.973816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:00.497489Z","time spent":"1.476308044s","remote":"127.0.0.1:50552","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-10T14:58:01.973993Z","caller":"traceutil/trace.go:171","msg":"trace[744903540] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.470647002s","start":"2026-02-10T14:58:00.503338Z","end":"2026-02-10T14:58:01.973985Z","steps":["trace[744903540] 'process raft request' (duration: 1.46990704s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:01.974024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:00.503319Z","time spent":"1.470688924s","remote":"127.0.0.1:50494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T14:58:02.549001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710898541122281462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T14:58:04.021528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000183806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-10T14:58:04.021582Z","caller":"traceutil/trace.go:171","msg":"trace[707206627] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000316231s","start":"2026-02-10T14:58:02.021251Z","end":"2026-02-10T14:58:04.021567Z","steps":["trace[707206627] 'agreement among raft nodes before linearized reading' (duration: 2.000248558s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:04.021612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:02.02123Z","time spent":"2.000376323s","remote":"127.0.0.1:50304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/10 14:58:04 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-10T14:58:05.228668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.302039954s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:58:05.22875Z","caller":"traceutil/trace.go:171","msg":"trace[1690159533] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"3.211292653s","start":"2026-02-10T14:58:02.017444Z","end":"2026-02-10T14:58:05.228737Z","steps":["trace[1690159533] 'process raft request' (duration: 909.122983ms)","trace[1690159533] 'compare' (duration: 2.301213332s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:58:05.228803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:02.017428Z","time spent":"3.211354776s","remote":"127.0.0.1:50552","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-10T14:58:05.231815Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.303859577s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T14:58:05.233329Z","caller":"traceutil/trace.go:171","msg":"trace[1341618901] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"3.215748373s","start":"2026-02-10T14:58:02.017568Z","end":"2026-02-10T14:58:05.233316Z","steps":["trace[1341618901] 'process raft request' (duration: 3.215612317s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:05.233523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:02.017563Z","time spent":"3.21593017s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:58:05.233332Z","caller":"traceutil/trace.go:171","msg":"trace[1944964526] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"3.211989776s","start":"2026-02-10T14:58:02.021324Z","end":"2026-02-10T14:58:05.233314Z","steps":["trace[1944964526] 'read index received' (duration: 905.367996ms)","trace[1944964526] 'applied index is now lower than readState.Index' (duration: 2.306620649s)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:58:05.233426Z","caller":"traceutil/trace.go:171","msg":"trace[1858684276] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"2.4917035s","start":"2026-02-10T14:58:02.741714Z","end":"2026-02-10T14:58:05.233418Z","steps":["trace[1858684276] 'process raft request' (duration: 2.491568904s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:05.233741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:02.741693Z","time spent":"2.491987021s","remote":"127.0.0.1:50552","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-10T14:58:05.282216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839526014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T14:58:05.282685Z","caller":"traceutil/trace.go:171","msg":"trace[1322890096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"1.840027624s","start":"2026-02-10T14:58:03.442636Z","end":"2026-02-10T14:58:05.282664Z","steps":["trace[1322890096] 'agreement among raft nodes before linearized reading' (duration: 1.839407119s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:05.28299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:03.442607Z","time spent":"1.840363637s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T14:58:05.282459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260793445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:58:05.283583Z","caller":"traceutil/trace.go:171","msg":"trace[1948055547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.261928389s","start":"2026-02-10T14:58:04.02164Z","end":"2026-02-10T14:58:05.283569Z","steps":["trace[1948055547] 'agreement among raft nodes before linearized reading' (duration: 1.260784954s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:05.283709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:04.021633Z","time spent":"1.262013863s","remote":"127.0.0.1:50308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:58:05.282551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.265512507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T14:58:05.284073Z","caller":"traceutil/trace.go:171","msg":"trace[745979780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"1.266979006s","start":"2026-02-10T14:58:04.016975Z","end":"2026-02-10T14:58:05.283954Z","steps":["trace[745979780] 'agreement among raft nodes before linearized reading' (duration: 1.265513327s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:05.284171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:04.016948Z","time spent":"1.267210365s","remote":"127.0.0.1:50552","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-10T14:58:05.282599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.700208817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:58:05.284756Z","caller":"traceutil/trace.go:171","msg":"trace[913770271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.702361953s","start":"2026-02-10T14:58:03.582382Z","end":"2026-02-10T14:58:05.284744Z","steps":["trace[913770271] 'agreement among raft nodes before linearized reading' (duration: 1.700195987s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:05.285117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:03.582324Z","time spent":"1.702778992s","remote":"127.0.0.1:50304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:58:07.30059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.740461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:58:07.300631Z","caller":"traceutil/trace.go:171","msg":"trace[1482089111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"311.808244ms","start":"2026-02-10T14:58:06.988813Z","end":"2026-02-10T14:58:07.300622Z","steps":["trace[1482089111] 'range keys from in-memory index tree' (duration: 311.70105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:07.300664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:06.9888Z","time spent":"311.853406ms","remote":"127.0.0.1:50304","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-10T14:58:10.231523Z","caller":"traceutil/trace.go:171","msg":"trace[734544618] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"225.128766ms","start":"2026-02-10T14:58:10.00638Z","end":"2026-02-10T14:58:10.231509Z","steps":["trace[734544618] 'read index received' (duration: 224.392389ms)","trace[734544618] 'applied index is now lower than readState.Index' (duration: 735.807µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:58:10.231674Z","caller":"traceutil/trace.go:171","msg":"trace[737047059] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"238.75779ms","start":"2026-02-10T14:58:09.992911Z","end":"2026-02-10T14:58:10.231668Z","steps":["trace[737047059] 'process raft request' (duration: 237.905938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:10.231802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.410597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:58:10.23182Z","caller":"traceutil/trace.go:171","msg":"trace[1422116059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:657; }","duration":"225.458588ms","start":"2026-02-10T14:58:10.006355Z","end":"2026-02-10T14:58:10.231814Z","steps":["trace[1422116059] 'agreement among raft nodes before linearized reading' (duration: 225.419826ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:10.723288Z","caller":"traceutil/trace.go:171","msg":"trace[1857297062] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"157.423328ms","start":"2026-02-10T14:58:10.565468Z","end":"2026-02-10T14:58:10.722891Z","steps":["trace[1857297062] 'process raft request' (duration: 157.269913ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:20.026428Z","caller":"traceutil/trace.go:171","msg":"trace[1755893450] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"184.099921ms","start":"2026-02-10T14:58:19.842313Z","end":"2026-02-10T14:58:20.026413Z","steps":["trace[1755893450] 'read index received' (duration: 183.978347ms)","trace[1755893450] 'applied index is now lower than readState.Index' (duration: 121.034µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:58:20.02658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.249516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-srcm5\" ","response":"range_response_count:1 size:5001"} {"level":"info","ts":"2026-02-10T14:58:20.026602Z","caller":"traceutil/trace.go:171","msg":"trace[1264160586] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-srcm5; range_end:; response_count:1; response_revision:762; }","duration":"184.305428ms","start":"2026-02-10T14:58:19.842291Z","end":"2026-02-10T14:58:20.026596Z","steps":["trace[1264160586] 'agreement among raft nodes before linearized reading' (duration: 184.184984ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:20.02679Z","caller":"traceutil/trace.go:171","msg":"trace[1393106386] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"188.792104ms","start":"2026-02-10T14:58:19.837993Z","end":"2026-02-10T14:58:20.026785Z","steps":["trace[1393106386] 'process raft request' (duration: 188.335678ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:20.402934Z","caller":"traceutil/trace.go:171","msg":"trace[290015559] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"367.371895ms","start":"2026-02-10T14:58:20.035542Z","end":"2026-02-10T14:58:20.402914Z","steps":["trace[290015559] 'process raft request' (duration: 367.23792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:20.403045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:20.035526Z","time spent":"367.460668ms","remote":"127.0.0.1:50494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4808,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:58:27.601984Z","caller":"traceutil/trace.go:171","msg":"trace[1117481665] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"104.942205ms","start":"2026-02-10T14:58:27.49702Z","end":"2026-02-10T14:58:27.601962Z","steps":["trace[1117481665] 'process raft request' (duration: 104.822231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:46.381228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.772895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T14:58:46.3813Z","caller":"traceutil/trace.go:171","msg":"trace[1761965412] linearizableReadLoop","detail":"{readStateIndex:886; appliedIndex:885; }","duration":"588.809162ms","start":"2026-02-10T14:58:45.792479Z","end":"2026-02-10T14:58:46.381288Z","steps":["trace[1761965412] 'read index received' (duration: 417.931933ms)","trace[1761965412] 'applied index is now lower than readState.Index' (duration: 170.876099ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:58:46.381419Z","caller":"traceutil/trace.go:171","msg":"trace[1375787269] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"592.835659ms","start":"2026-02-10T14:58:45.788577Z","end":"2026-02-10T14:58:46.381413Z","steps":["trace[1375787269] 'process raft request' (duration: 421.822086ms)","trace[1375787269] 'compare' (duration: 170.56827ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:58:46.381453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:45.788563Z","time spent":"592.8704ms","remote":"127.0.0.1:50552","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-10T14:58:46.381556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.078779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:58:46.381572Z","caller":"traceutil/trace.go:171","msg":"trace[1393154379] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:851; }","duration":"589.132921ms","start":"2026-02-10T14:58:45.792433Z","end":"2026-02-10T14:58:46.381565Z","steps":["trace[1393154379] 'agreement among raft nodes before linearized reading' (duration: 589.10282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:46.381586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:45.792425Z","time spent":"589.157351ms","remote":"127.0.0.1:50630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-10T14:58:46.881592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710898541122282033,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T14:58:47.149235Z","caller":"traceutil/trace.go:171","msg":"trace[1489053930] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"767.898833ms","start":"2026-02-10T14:58:46.381313Z","end":"2026-02-10T14:58:47.149212Z","steps":["trace[1489053930] 'read index received' (duration: 724.30448ms)","trace[1489053930] 'applied index is now lower than readState.Index' (duration: 43.593353ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:58:47.149474Z","caller":"traceutil/trace.go:171","msg":"trace[117665963] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"968.818255ms","start":"2026-02-10T14:58:46.180641Z","end":"2026-02-10T14:58:47.149459Z","steps":["trace[117665963] 'process raft request' (duration: 925.038567ms)","trace[117665963] 'compare' (duration: 42.679529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:58:47.14957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:46.180619Z","time spent":"968.889437ms","remote":"127.0.0.1:50552","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-10T14:58:47.149812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.344044705s","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-10T14:58:47.14985Z","caller":"traceutil/trace.go:171","msg":"trace[1123149659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"1.344096377s","start":"2026-02-10T14:58:45.805742Z","end":"2026-02-10T14:58:47.149838Z","steps":["trace[1123149659] 'agreement among raft nodes before linearized reading' (duration: 1.343976574s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:47.149886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:45.805737Z","time spent":"1.344140488s","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T14:58:47.150112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345756951s","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-10T14:58:47.150159Z","caller":"traceutil/trace.go:171","msg":"trace[394982352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:852; }","duration":"1.345803312s","start":"2026-02-10T14:58:45.804343Z","end":"2026-02-10T14:58:47.150147Z","steps":["trace[394982352] 'agreement among raft nodes before linearized reading' (duration: 1.345691669s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:47.150221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:45.804335Z","time spent":"1.345874714s","remote":"127.0.0.1:50552","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-10T14:58:47.150399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.483674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T14:58:47.150436Z","caller":"traceutil/trace.go:171","msg":"trace[1754596582] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:852; }","duration":"760.546356ms","start":"2026-02-10T14:58:46.389878Z","end":"2026-02-10T14:58:47.150424Z","steps":["trace[1754596582] 'agreement among raft nodes before linearized reading' (duration: 760.493315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:47.15047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:46.389865Z","time spent":"760.596657ms","remote":"127.0.0.1:50746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-10T14:58:47.150699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.967078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:58:47.15073Z","caller":"traceutil/trace.go:171","msg":"trace[1628222845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"162.02502ms","start":"2026-02-10T14:58:46.988697Z","end":"2026-02-10T14:58:47.150722Z","steps":["trace[1628222845] 'agreement among raft nodes before linearized reading' (duration: 161.981959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:51.076869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.554824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T14:58:51.076941Z","caller":"traceutil/trace.go:171","msg":"trace[911346969] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:863; }","duration":"451.680897ms","start":"2026-02-10T14:58:50.625243Z","end":"2026-02-10T14:58:51.076924Z","steps":["trace[911346969] 'count revisions from in-memory index tree' (duration: 451.492882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:51.077035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:50.625225Z","time spent":"451.744919ms","remote":"127.0.0.1:50766","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-10T14:58:51.077221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.654731ms","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-10T14:58:51.077256Z","caller":"traceutil/trace.go:171","msg":"trace[384612925] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:863; }","duration":"430.709792ms","start":"2026-02-10T14:58:50.646537Z","end":"2026-02-10T14:58:51.077247Z","steps":["trace[384612925] 'count revisions from in-memory index tree' (duration: 430.61081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:58:51.077288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:58:50.646527Z","time spent":"430.753632ms","remote":"127.0.0.1:50518","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":"info","ts":"2026-02-10T14:58:51.332528Z","caller":"traceutil/trace.go:171","msg":"trace[871727645] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"155.952754ms","start":"2026-02-10T14:58:51.176559Z","end":"2026-02-10T14:58:51.332511Z","steps":["trace[871727645] 'process raft request' (duration: 151.561443ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:55.377438Z","caller":"traceutil/trace.go:171","msg":"trace[134398311] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"104.734979ms","start":"2026-02-10T14:58:55.272678Z","end":"2026-02-10T14:58:55.377413Z","steps":["trace[134398311] 'process raft request' (duration: 104.584305ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:55.932436Z","caller":"traceutil/trace.go:171","msg":"trace[460991424] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"132.487376ms","start":"2026-02-10T14:58:55.799929Z","end":"2026-02-10T14:58:55.932416Z","steps":["trace[460991424] 'process raft request' (duration: 104.877631ms)","trace[460991424] 'compare' (duration: 27.310238ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:58:57.555077Z","caller":"traceutil/trace.go:171","msg":"trace[765593656] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"167.277127ms","start":"2026-02-10T14:58:57.387782Z","end":"2026-02-10T14:58:57.555059Z","steps":["trace[765593656] 'process raft request' (duration: 167.147024ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:57.555498Z","caller":"traceutil/trace.go:171","msg":"trace[1637689473] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:918; }","duration":"129.687244ms","start":"2026-02-10T14:58:57.425802Z","end":"2026-02-10T14:58:57.555489Z","steps":["trace[1637689473] 'read index received' (duration: 129.682944ms)","trace[1637689473] 'applied index is now lower than readState.Index' (duration: 3.83µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:58:57.555618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.786966ms","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-10T14:58:57.555639Z","caller":"traceutil/trace.go:171","msg":"trace[250644248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:881; }","duration":"129.867188ms","start":"2026-02-10T14:58:57.425765Z","end":"2026-02-10T14:58:57.555632Z","steps":["trace[250644248] 'agreement among raft nodes before linearized reading' (duration: 129.764586ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:57.707678Z","caller":"traceutil/trace.go:171","msg":"trace[646726297] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"152.139574ms","start":"2026-02-10T14:58:57.555515Z","end":"2026-02-10T14:58:57.707655Z","steps":["trace[646726297] 'read index received' (duration: 106.369065ms)","trace[646726297] 'applied index is now lower than readState.Index' (duration: 45.769459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:58:57.707866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.161462ms","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-10T14:58:57.707897Z","caller":"traceutil/trace.go:171","msg":"trace[1235551890] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:882; }","duration":"231.233643ms","start":"2026-02-10T14:58:57.476654Z","end":"2026-02-10T14:58:57.707888Z","steps":["trace[1235551890] 'agreement among raft nodes before linearized reading' (duration: 231.083929ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:57.707878Z","caller":"traceutil/trace.go:171","msg":"trace[1135556132] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"190.381951ms","start":"2026-02-10T14:58:57.517475Z","end":"2026-02-10T14:58:57.707857Z","steps":["trace[1135556132] 'process raft request' (duration: 144.340305ms)","trace[1135556132] 'compare' (duration: 45.734399ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:58:57.712643Z","caller":"traceutil/trace.go:171","msg":"trace[960122299] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"153.865225ms","start":"2026-02-10T14:58:57.558765Z","end":"2026-02-10T14:58:57.712631Z","steps":["trace[960122299] 'process raft request' (duration: 153.801374ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:58:58.655858Z","caller":"traceutil/trace.go:171","msg":"trace[812785310] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"131.580703ms","start":"2026-02-10T14:58:58.524255Z","end":"2026-02-10T14:58:58.655836Z","steps":["trace[812785310] 'process raft request' (duration: 131.44058ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:05.718011Z","caller":"traceutil/trace.go:171","msg":"trace[500856703] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"119.053897ms","start":"2026-02-10T14:59:05.598938Z","end":"2026-02-10T14:59:05.717992Z","steps":["trace[500856703] 'process raft request' (duration: 118.918104ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:19.397478Z","caller":"traceutil/trace.go:171","msg":"trace[460197942] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"195.930948ms","start":"2026-02-10T14:59:19.20152Z","end":"2026-02-10T14:59:19.397451Z","steps":["trace[460197942] 'read index received' (duration: 195.689113ms)","trace[460197942] 'applied index is now lower than readState.Index' (duration: 240.565µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:19.397689Z","caller":"traceutil/trace.go:171","msg":"trace[1120210488] transaction","detail":"{read_only:false; number_of_response:1; response_revision:961; }","duration":"202.378489ms","start":"2026-02-10T14:59:19.195293Z","end":"2026-02-10T14:59:19.397671Z","steps":["trace[1120210488] 'process raft request' (duration: 201.990611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:19.397696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.148562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bcb98b49-b9a9-4974-b715-757ce3fc5277\" ","response":"range_response_count:1 size:3824"} {"level":"info","ts":"2026-02-10T14:59:19.397749Z","caller":"traceutil/trace.go:171","msg":"trace[1850461423] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bcb98b49-b9a9-4974-b715-757ce3fc5277; range_end:; response_count:1; response_revision:961; }","duration":"196.243785ms","start":"2026-02-10T14:59:19.201489Z","end":"2026-02-10T14:59:19.397733Z","steps":["trace[1850461423] 'agreement among raft nodes before linearized reading' (duration: 196.066381ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:24.608066Z","caller":"traceutil/trace.go:171","msg":"trace[399520229] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1034; }","duration":"369.62819ms","start":"2026-02-10T14:59:24.238416Z","end":"2026-02-10T14:59:24.608044Z","steps":["trace[399520229] 'read index received' (duration: 331.293001ms)","trace[399520229] 'applied index is now lower than readState.Index' (duration: 38.334159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:24.608228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.803033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:24.60826Z","caller":"traceutil/trace.go:171","msg":"trace[1194338541] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:991; }","duration":"369.888015ms","start":"2026-02-10T14:59:24.238363Z","end":"2026-02-10T14:59:24.608251Z","steps":["trace[1194338541] 'agreement among raft nodes before linearized reading' (duration: 369.780633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:24.608294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:24.238344Z","time spent":"369.941156ms","remote":"127.0.0.1:50616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-10T14:59:24.608568Z","caller":"traceutil/trace.go:171","msg":"trace[1231804372] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"538.121567ms","start":"2026-02-10T14:59:24.070433Z","end":"2026-02-10T14:59:24.608555Z","steps":["trace[1231804372] 'process raft request' (duration: 499.332699ms)","trace[1231804372] 'compare' (duration: 37.809169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:24.608644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:24.070422Z","time spent":"538.172158ms","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T14:59:30.92392Z","caller":"traceutil/trace.go:171","msg":"trace[1729320193] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"102.468238ms","start":"2026-02-10T14:59:30.82142Z","end":"2026-02-10T14:59:30.923888Z","steps":["trace[1729320193] 'process raft request' (duration: 102.344865ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:32.303918Z","caller":"traceutil/trace.go:171","msg":"trace[2039672580] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"380.649858ms","start":"2026-02-10T14:59:31.923249Z","end":"2026-02-10T14:59:32.303899Z","steps":["trace[2039672580] 'read index received' (duration: 380.486235ms)","trace[2039672580] 'applied index is now lower than readState.Index' (duration: 162.913µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T14:59:32.304285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.016685ms","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-10T14:59:32.304399Z","caller":"traceutil/trace.go:171","msg":"trace[1810413239] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"381.171768ms","start":"2026-02-10T14:59:31.923212Z","end":"2026-02-10T14:59:32.304384Z","steps":["trace[1810413239] 'agreement among raft nodes before linearized reading' (duration: 380.907292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:32.304493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:31.923195Z","time spent":"381.289631ms","remote":"127.0.0.1:50552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T14:59:32.304639Z","caller":"traceutil/trace.go:171","msg":"trace[1426219187] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"454.015159ms","start":"2026-02-10T14:59:31.850615Z","end":"2026-02-10T14:59:32.30463Z","steps":["trace[1426219187] 'process raft request' (duration: 453.168102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:32.30474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:31.850601Z","time spent":"454.09823ms","remote":"127.0.0.1:50552","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-10T14:59:32.304881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.427682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:32.304946Z","caller":"traceutil/trace.go:171","msg":"trace[1673203207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"313.527033ms","start":"2026-02-10T14:59:31.991408Z","end":"2026-02-10T14:59:32.304935Z","steps":["trace[1673203207] 'agreement among raft nodes before linearized reading' (duration: 313.443212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:32.305014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:31.991393Z","time spent":"313.615116ms","remote":"127.0.0.1:50304","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T14:59:32.305261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.556322ms","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-10T14:59:32.305326Z","caller":"traceutil/trace.go:171","msg":"trace[677216560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"257.653124ms","start":"2026-02-10T14:59:32.047667Z","end":"2026-02-10T14:59:32.305321Z","steps":["trace[677216560] 'agreement among raft nodes before linearized reading' (duration: 257.544612ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T14:59:32.695949Z","caller":"traceutil/trace.go:171","msg":"trace[1083890884] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"374.501044ms","start":"2026-02-10T14:59:32.321372Z","end":"2026-02-10T14:59:32.695873Z","steps":["trace[1083890884] 'read index received' (duration: 354.959101ms)","trace[1083890884] 'applied index is now lower than readState.Index' (duration: 19.540863ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:32.696212Z","caller":"traceutil/trace.go:171","msg":"trace[283441350] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"378.02925ms","start":"2026-02-10T14:59:32.318169Z","end":"2026-02-10T14:59:32.696199Z","steps":["trace[283441350] 'process raft request' (duration: 358.209052ms)","trace[283441350] 'compare' (duration: 19.248887ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T14:59:32.695987Z","caller":"traceutil/trace.go:171","msg":"trace[496434507] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"373.343623ms","start":"2026-02-10T14:59:32.322628Z","end":"2026-02-10T14:59:32.695971Z","steps":["trace[496434507] 'process raft request' (duration: 373.2193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:32.696535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:32.318159Z","time spent":"378.173802ms","remote":"127.0.0.1:50552","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-10T14:59:32.696571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:32.322619Z","time spent":"373.799721ms","remote":"127.0.0.1:50552","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-10T14:59:32.696758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.380781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T14:59:32.698438Z","caller":"traceutil/trace.go:171","msg":"trace[1501757471] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1018; }","duration":"377.054891ms","start":"2026-02-10T14:59:32.321368Z","end":"2026-02-10T14:59:32.698423Z","steps":["trace[1501757471] 'agreement among raft nodes before linearized reading' (duration: 375.277969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T14:59:32.698606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T14:59:32.32133Z","time spent":"377.262064ms","remote":"127.0.0.1:50468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "}