{"level":"warn","ts":"2026-02-25T19:23:22.969033Z","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-25T19:23:22.969156Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.128: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.128:2380","--initial-cluster=instance=https://199.19.213.128:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.128:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.128: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-25T19:23:22.969276Z","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-25T19:23:22.969349Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-02-25T19:23:22.969416Z","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-25T19:23:22.970183Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"]} {"level":"info","ts":"2026-02-25T19:23:22.970424Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.128: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-25T19:23:22.974658Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.999525ms"} {"level":"info","ts":"2026-02-25T19:23:22.98202Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a463a6aa9b462de2","cluster-id":"cd3d14b8ed85e2f2"} {"level":"info","ts":"2026-02-25T19:23:22.982109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:23:22.982142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 0"} {"level":"info","ts":"2026-02-25T19:23:22.982171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a463a6aa9b462de2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:23:22.982183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became follower at term 1"} {"level":"info","ts":"2026-02-25T19:23:22.982242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"warn","ts":"2026-02-25T19:23:22.991469Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:23:22.992793Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:23:22.993905Z","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-25T19:23:22.996203Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a463a6aa9b462de2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:23:22.998352Z","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-25T19:23:22.998522Z","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-25T19:23:22.998561Z","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-25T19:23:23.00195Z","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-25T19:23:23.002349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 switched to configuration voters=(11845494696594255330)"} {"level":"info","ts":"2026-02-25T19:23:23.00237Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a463a6aa9b462de2","initial-advertise-peer-urls":["https://199.19.213.128:2380"],"listen-peer-urls":["https://199.19.213.128:2380"],"advertise-client-urls":["https://199.19.213.128:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.128:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:23:23.002434Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:23:23.00253Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-02-25T19:23:23.002605Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a463a6aa9b462de2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:23:23.002566Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.128:2380"} {"level":"info","ts":"2026-02-25T19:23:23.002765Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","added-peer-id":"a463a6aa9b462de2","added-peer-peer-urls":["https://199.19.213.128:2380"]} {"level":"info","ts":"2026-02-25T19:23:23.883068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:23:23.883258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:23:23.883342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgPreVoteResp from a463a6aa9b462de2 at term 1"} {"level":"info","ts":"2026-02-25T19:23:23.883422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:23:23.883487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 received MsgVoteResp from a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-02-25T19:23:23.883554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a463a6aa9b462de2 became leader at term 2"} {"level":"info","ts":"2026-02-25T19:23:23.88361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a463a6aa9b462de2 elected leader a463a6aa9b462de2 at term 2"} {"level":"info","ts":"2026-02-25T19:23:23.884772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:23:23.884778Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a463a6aa9b462de2","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.128:2379]}","request-path":"/0/members/a463a6aa9b462de2/attributes","cluster-id":"cd3d14b8ed85e2f2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:23:23.884808Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:23:23.884823Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:23:23.885192Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:23:23.885229Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:23:23.885842Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cd3d14b8ed85e2f2","local-member-id":"a463a6aa9b462de2","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:23:23.88599Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:23:23.886089Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:23:23.886836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:23:23.892285Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.128:2379"} {"level":"info","ts":"2026-02-25T19:23:32.101796Z","caller":"traceutil/trace.go:171","msg":"trace[40456960] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"185.18374ms","start":"2026-02-25T19:23:31.916588Z","end":"2026-02-25T19:23:32.101772Z","steps":["trace[40456960] 'read index received' (duration: 184.963618ms)","trace[40456960] 'applied index is now lower than readState.Index' (duration: 219.242µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:23:32.101879Z","caller":"traceutil/trace.go:171","msg":"trace[1654279735] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"203.924666ms","start":"2026-02-25T19:23:31.897917Z","end":"2026-02-25T19:23:32.101842Z","steps":["trace[1654279735] 'process raft request' (duration: 203.704055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:23:32.1022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.067527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T19:23:32.102323Z","caller":"traceutil/trace.go:171","msg":"trace[979852217] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:339; }","duration":"136.315899ms","start":"2026-02-25T19:23:31.965988Z","end":"2026-02-25T19:23:32.102304Z","steps":["trace[979852217] 'agreement among raft nodes before linearized reading' (duration: 135.980486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:23:32.102636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.989036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-25T19:23:32.102892Z","caller":"traceutil/trace.go:171","msg":"trace[1414207730] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:339; }","duration":"186.284149ms","start":"2026-02-25T19:23:31.916543Z","end":"2026-02-25T19:23:32.102827Z","steps":["trace[1414207730] 'agreement among raft nodes before linearized reading' (duration: 185.383131ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:23:40.264418Z","caller":"traceutil/trace.go:171","msg":"trace[484094860] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"126.475378ms","start":"2026-02-25T19:23:40.137911Z","end":"2026-02-25T19:23:40.264386Z","steps":["trace[484094860] 'process raft request' (duration: 126.328937ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:23:41.206238Z","caller":"traceutil/trace.go:171","msg":"trace[3452629] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"199.893453ms","start":"2026-02-25T19:23:41.006312Z","end":"2026-02-25T19:23:41.206205Z","steps":["trace[3452629] 'process raft request' (duration: 199.687641ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:23:41.397844Z","caller":"traceutil/trace.go:171","msg":"trace[1507072119] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"119.559588ms","start":"2026-02-25T19:23:41.278263Z","end":"2026-02-25T19:23:41.397823Z","steps":["trace[1507072119] 'read index received' (duration: 42.41253ms)","trace[1507072119] 'applied index is now lower than readState.Index' (duration: 77.146068ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:23:41.397962Z","caller":"traceutil/trace.go:171","msg":"trace[961138133] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"182.24674ms","start":"2026-02-25T19:23:41.215704Z","end":"2026-02-25T19:23:41.397951Z","steps":["trace[961138133] 'process raft request' (duration: 104.986011ms)","trace[961138133] 'compare' (duration: 76.999156ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:23:41.398339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.061691ms","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-25T19:23:41.398411Z","caller":"traceutil/trace.go:171","msg":"trace[1487254098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"120.177272ms","start":"2026-02-25T19:23:41.278215Z","end":"2026-02-25T19:23:41.398393Z","steps":["trace[1487254098] 'agreement among raft nodes before linearized reading' (duration: 120.072441ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:23:49.927435Z","caller":"traceutil/trace.go:171","msg":"trace[2082448450] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"205.457036ms","start":"2026-02-25T19:23:49.72195Z","end":"2026-02-25T19:23:49.927407Z","steps":["trace[2082448450] 'process raft request' (duration: 205.29015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:23:56.268004Z","caller":"traceutil/trace.go:171","msg":"trace[1665203797] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"316.483622ms","start":"2026-02-25T19:23:55.951489Z","end":"2026-02-25T19:23:56.267973Z","steps":["trace[1665203797] 'process raft request' (duration: 316.300936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:23:56.268812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:23:55.951451Z","time spent":"316.647178ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:23:56.269201Z","caller":"traceutil/trace.go:171","msg":"trace[1630225041] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"258.982204ms","start":"2026-02-25T19:23:56.010191Z","end":"2026-02-25T19:23:56.269173Z","steps":["trace[1630225041] 'process raft request' (duration: 258.234489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:05.45304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.9999928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:24:05.453181Z","caller":"traceutil/trace.go:171","msg":"trace[1880173741] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000186296s","start":"2026-02-25T19:24:03.452972Z","end":"2026-02-25T19:24:05.453158Z","steps":["trace[1880173741] 'agreement among raft nodes before linearized reading' (duration: 2.000020981s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:05.45323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:03.452951Z","time spent":"2.000269529s","remote":"127.0.0.1:57218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:24:05 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:24:05.542206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.262361208s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T19:24:05.542396Z","caller":"traceutil/trace.go:171","msg":"trace[170707047] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"3.236835657s","start":"2026-02-25T19:24:02.305532Z","end":"2026-02-25T19:24:05.542368Z","steps":["trace[170707047] 'read index received' (duration: 37.311µs)","trace[170707047] 'applied index is now lower than readState.Index' (duration: 3.236796386s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:24:05.542596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.237035102s","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-25T19:24:05.542693Z","caller":"traceutil/trace.go:171","msg":"trace[1525654694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"3.237175457s","start":"2026-02-25T19:24:02.305494Z","end":"2026-02-25T19:24:05.54267Z","steps":["trace[1525654694] 'agreement among raft nodes before linearized reading' (duration: 3.23696296s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:05.542728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:02.305475Z","time spent":"3.237247088s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:24:05.542926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.182317536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T19:24:05.543003Z","caller":"traceutil/trace.go:171","msg":"trace[852545919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"3.18243741s","start":"2026-02-25T19:24:02.360544Z","end":"2026-02-25T19:24:05.542981Z","steps":["trace[852545919] 'agreement among raft nodes before linearized reading' (duration: 3.182284515s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:05.543012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.032554873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:24:05.543062Z","caller":"traceutil/trace.go:171","msg":"trace[1726599115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"2.032618955s","start":"2026-02-25T19:24:03.510428Z","end":"2026-02-25T19:24:05.543047Z","steps":["trace[1726599115] 'agreement among raft nodes before linearized reading' (duration: 2.032550013s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:05.543116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:03.510418Z","time spent":"2.032686067s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:24:05.543074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.030967265s","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-25T19:24:05.543234Z","caller":"traceutil/trace.go:171","msg":"trace[663000869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"2.031257313s","start":"2026-02-25T19:24:03.511953Z","end":"2026-02-25T19:24:05.54321Z","steps":["trace[663000869] 'agreement among raft nodes before linearized reading' (duration: 2.030950324s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:05.543312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:03.511932Z","time spent":"2.031365567s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:24:05.543061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:02.360527Z","time spent":"3.182521222s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:24:05.542931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367108504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-02-25T19:24:05.543509Z","caller":"traceutil/trace.go:171","msg":"trace[2108698655] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:526; }","duration":"1.367708303s","start":"2026-02-25T19:24:04.17577Z","end":"2026-02-25T19:24:05.543479Z","steps":["trace[2108698655] 'agreement among raft nodes before linearized reading' (duration: 1.367102725s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:05.543571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:04.175751Z","time spent":"1.367804595s","remote":"127.0.0.1:57398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4399,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T19:24:06.051027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306377245601819264,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:24:06.390031Z","caller":"traceutil/trace.go:171","msg":"trace[1808675338] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"842.880779ms","start":"2026-02-25T19:24:05.547089Z","end":"2026-02-25T19:24:06.389969Z","steps":["trace[1808675338] 'process raft request' (duration: 842.528729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:06.390306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:05.547055Z","time spent":"843.111456ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:24:07.55117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000673958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:24:07.551262Z","caller":"traceutil/trace.go:171","msg":"trace[206168089] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000843103s","start":"2026-02-25T19:24:05.550399Z","end":"2026-02-25T19:24:07.551242Z","steps":["trace[206168089] 'agreement among raft nodes before linearized reading' (duration: 2.000716719s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:07.551309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:05.550383Z","time spent":"2.000915885s","remote":"127.0.0.1:57218","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-25T19:24:08.636326Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.246691433s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:24:08.637182Z","caller":"traceutil/trace.go:171","msg":"trace[1371238523] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"3.089617512s","start":"2026-02-25T19:24:05.547509Z","end":"2026-02-25T19:24:08.637126Z","steps":["trace[1371238523] 'process raft request' (duration: 3.089322923s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.637322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:05.547485Z","time spent":"3.089773037s","remote":"127.0.0.1:57298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:24:08.637337Z","caller":"traceutil/trace.go:171","msg":"trace[931698920] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:546; }","duration":"3.086848278s","start":"2026-02-25T19:24:05.550457Z","end":"2026-02-25T19:24:08.637305Z","steps":["trace[931698920] 'read index received' (duration: 839.28403ms)","trace[931698920] 'applied index is now lower than readState.Index' (duration: 2.247563278s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:08.637611Z","caller":"traceutil/trace.go:171","msg":"trace[11081001] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"3.08990192s","start":"2026-02-25T19:24:05.547696Z","end":"2026-02-25T19:24:08.637598Z","steps":["trace[11081001] 'process raft request' (duration: 3.089357724s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.637724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:05.547688Z","time spent":"3.089963682s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:08.637817Z","caller":"traceutil/trace.go:171","msg":"trace[812318513] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"3.085617512s","start":"2026-02-25T19:24:05.552181Z","end":"2026-02-25T19:24:08.637798Z","steps":["trace[812318513] 'process raft request' (duration: 3.085088526s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.637917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:05.552167Z","time spent":"3.085695063s","remote":"127.0.0.1:57398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:08.637936Z","caller":"traceutil/trace.go:171","msg":"trace[176979644] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"3.08956963s","start":"2026-02-25T19:24:05.548338Z","end":"2026-02-25T19:24:08.637908Z","steps":["trace[176979644] 'process raft request' (duration: 3.08890015s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.638074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:05.548328Z","time spent":"3.089668262s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:08.638057Z","caller":"traceutil/trace.go:171","msg":"trace[979194292] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"3.090184078s","start":"2026-02-25T19:24:05.547757Z","end":"2026-02-25T19:24:08.637941Z","steps":["trace[979194292] 'process raft request' (duration: 3.089378954s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.638689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:05.54775Z","time spent":"3.090775356s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:24:08.648661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.805404061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:24:08.648718Z","caller":"traceutil/trace.go:171","msg":"trace[1355257200] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:1; response_revision:533; }","duration":"1.805509394s","start":"2026-02-25T19:24:06.843196Z","end":"2026-02-25T19:24:08.648705Z","steps":["trace[1355257200] 'agreement among raft nodes before linearized reading' (duration: 1.805405351s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.648746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:06.843174Z","time spent":"1.805567396s","remote":"127.0.0.1:57260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.128\" "} {"level":"info","ts":"2026-02-25T19:24:08.648985Z","caller":"traceutil/trace.go:171","msg":"trace[674525275] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"2.211146578s","start":"2026-02-25T19:24:06.437833Z","end":"2026-02-25T19:24:08.648979Z","steps":["trace[674525275] 'process raft request' (duration: 2.210684934s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.64904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:06.437808Z","time spent":"2.211194949s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:24:08.649043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097372757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:24:08.649089Z","caller":"traceutil/trace.go:171","msg":"trace[1222458510] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"1.09746917s","start":"2026-02-25T19:24:07.551603Z","end":"2026-02-25T19:24:08.649072Z","steps":["trace[1222458510] 'agreement among raft nodes before linearized reading' (duration: 1.097380758s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.649084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.998262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T19:24:08.649121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:07.551582Z","time spent":"1.097532682s","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:24:08.649131Z","caller":"traceutil/trace.go:171","msg":"trace[2022853918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:533; }","duration":"254.092026ms","start":"2026-02-25T19:24:08.395029Z","end":"2026-02-25T19:24:08.649121Z","steps":["trace[2022853918] 'agreement among raft nodes before linearized reading' (duration: 254.001883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.649103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.668367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:24:08.649189Z","caller":"traceutil/trace.go:171","msg":"trace[393824578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"377.80311ms","start":"2026-02-25T19:24:08.271378Z","end":"2026-02-25T19:24:08.649181Z","steps":["trace[393824578] 'agreement among raft nodes before linearized reading' (duration: 377.666436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:08.649242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:08.271339Z","time spent":"377.897073ms","remote":"127.0.0.1:57218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:24:11.053305Z","caller":"traceutil/trace.go:171","msg":"trace[1509478489] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"402.35159ms","start":"2026-02-25T19:24:10.65089Z","end":"2026-02-25T19:24:11.053242Z","steps":["trace[1509478489] 'process raft request' (duration: 402.165435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:11.05344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:10.650876Z","time spent":"402.492464ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:11.054562Z","caller":"traceutil/trace.go:171","msg":"trace[1439006148] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"403.645447ms","start":"2026-02-25T19:24:10.650886Z","end":"2026-02-25T19:24:11.054532Z","steps":["trace[1439006148] 'process raft request' (duration: 403.440051ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:11.054679Z","caller":"traceutil/trace.go:171","msg":"trace[2054083466] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:558; }","duration":"356.202812ms","start":"2026-02-25T19:24:10.698447Z","end":"2026-02-25T19:24:11.05465Z","steps":["trace[2054083466] 'read index received' (duration: 354.607727ms)","trace[2054083466] 'applied index is now lower than readState.Index' (duration: 1.593665ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:24:11.054732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:10.65087Z","time spent":"403.76851ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:24:11.054839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.375767ms","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-25T19:24:11.054885Z","caller":"traceutil/trace.go:171","msg":"trace[227108107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"356.436428ms","start":"2026-02-25T19:24:10.698434Z","end":"2026-02-25T19:24:11.054871Z","steps":["trace[227108107] 'agreement among raft nodes before linearized reading' (duration: 356.304035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:11.054926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:10.698375Z","time spent":"356.539532ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:24:11.054963Z","caller":"traceutil/trace.go:171","msg":"trace[1557993071] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"403.935424ms","start":"2026-02-25T19:24:10.65101Z","end":"2026-02-25T19:24:11.054946Z","steps":["trace[1557993071] 'process raft request' (duration: 403.447201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:11.055048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:10.651005Z","time spent":"403.996496ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:13.180668Z","caller":"traceutil/trace.go:171","msg":"trace[1220102352] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"120.216144ms","start":"2026-02-25T19:24:13.060432Z","end":"2026-02-25T19:24:13.180649Z","steps":["trace[1220102352] 'process raft request' (duration: 119.866035ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:13.180929Z","caller":"traceutil/trace.go:171","msg":"trace[645585770] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"119.659739ms","start":"2026-02-25T19:24:13.061263Z","end":"2026-02-25T19:24:13.180922Z","steps":["trace[645585770] 'process raft request' (duration: 119.33605ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:19.176306Z","caller":"traceutil/trace.go:171","msg":"trace[1706411919] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"297.000515ms","start":"2026-02-25T19:24:18.879281Z","end":"2026-02-25T19:24:19.176281Z","steps":["trace[1706411919] 'process raft request' (duration: 296.838201ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:19.187159Z","caller":"traceutil/trace.go:171","msg":"trace[1492339654] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"166.20047ms","start":"2026-02-25T19:24:19.02094Z","end":"2026-02-25T19:24:19.187141Z","steps":["trace[1492339654] 'process raft request' (duration: 165.720758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:20.419089Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156754057s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:24:21.450247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999876496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:24:21.450318Z","caller":"traceutil/trace.go:171","msg":"trace[1689561611] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999998799s","start":"2026-02-25T19:24:19.450305Z","end":"2026-02-25T19:24:21.450304Z","steps":["trace[1689561611] 'agreement among raft nodes before linearized reading' (duration: 1.999902027s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:21.45035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:19.450286Z","time spent":"2.0000578s","remote":"127.0.0.1:57218","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-25T19:24:21.863805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444426078s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:24:21.864017Z","caller":"traceutil/trace.go:171","msg":"trace[755759129] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"2.008635624s","start":"2026-02-25T19:24:19.855363Z","end":"2026-02-25T19:24:21.863999Z","steps":["trace[755759129] 'process raft request' (duration: 2.008546362s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:21.864098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:19.855331Z","time spent":"2.008726807s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:21.86457Z","caller":"traceutil/trace.go:171","msg":"trace[2102642149] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"2.608278019s","start":"2026-02-25T19:24:19.256264Z","end":"2026-02-25T19:24:21.864542Z","steps":["trace[2102642149] 'read index received' (duration: 6.326945ms)","trace[2102642149] 'applied index is now lower than readState.Index' (duration: 2.601947494s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:21.864613Z","caller":"traceutil/trace.go:171","msg":"trace[1452019329] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"2.650648688s","start":"2026-02-25T19:24:19.21393Z","end":"2026-02-25T19:24:21.864578Z","steps":["trace[1452019329] 'process raft request' (duration: 1.205354978s)","trace[1452019329] 'compare' (duration: 1.443619317s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:24:21.864813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.565904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:24:21.864842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.665938ms","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-25T19:24:21.864869Z","caller":"traceutil/trace.go:171","msg":"trace[1962185403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"413.664736ms","start":"2026-02-25T19:24:21.451189Z","end":"2026-02-25T19:24:21.864853Z","steps":["trace[1962185403] 'agreement among raft nodes before linearized reading' (duration: 413.558774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:21.864846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.791068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T19:24:21.864882Z","caller":"traceutil/trace.go:171","msg":"trace[1123128545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"598.748401ms","start":"2026-02-25T19:24:21.26612Z","end":"2026-02-25T19:24:21.864869Z","steps":["trace[1123128545] 'agreement among raft nodes before linearized reading' (duration: 598.649568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:21.864895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:21.451136Z","time spent":"413.754478ms","remote":"127.0.0.1:57212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:24:21.864881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.608603928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:24:21.864903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:19.213916Z","time spent":"2.650766592s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:21.864928Z","caller":"traceutil/trace.go:171","msg":"trace[452230509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"650.915311ms","start":"2026-02-25T19:24:21.213995Z","end":"2026-02-25T19:24:21.86491Z","steps":["trace[452230509] 'agreement among raft nodes before linearized reading' (duration: 650.710016ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:21.864939Z","caller":"traceutil/trace.go:171","msg":"trace[708649606] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"2.60869098s","start":"2026-02-25T19:24:19.256234Z","end":"2026-02-25T19:24:21.864925Z","steps":["trace[708649606] 'agreement among raft nodes before linearized reading' (duration: 2.608523286s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:21.86498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:19.256219Z","time spent":"2.608753441s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:24:21.86498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:21.213979Z","time spent":"650.992073ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:24:21.864928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:21.266101Z","time spent":"598.819243ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:24:22.178528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.740817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:24:22.178758Z","caller":"traceutil/trace.go:171","msg":"trace[90696081] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"309.736286ms","start":"2026-02-25T19:24:21.869002Z","end":"2026-02-25T19:24:22.178739Z","steps":["trace[90696081] 'process raft request' (duration: 309.636713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:22.178835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:21.868996Z","time spent":"309.803918ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:22.178897Z","caller":"traceutil/trace.go:171","msg":"trace[1174593110] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"309.596712ms","start":"2026-02-25T19:24:21.869267Z","end":"2026-02-25T19:24:22.178864Z","steps":["trace[1174593110] 'read index received' (duration: 147.386313ms)","trace[1174593110] 'applied index is now lower than readState.Index' (duration: 162.208559ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:22.178893Z","caller":"traceutil/trace.go:171","msg":"trace[467258369] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"310.64402ms","start":"2026-02-25T19:24:21.868051Z","end":"2026-02-25T19:24:22.178695Z","steps":["trace[467258369] 'process raft request' (duration: 148.610326ms)","trace[467258369] 'compare' (duration: 161.629214ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:22.179002Z","caller":"traceutil/trace.go:171","msg":"trace[539731032] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"308.710049ms","start":"2026-02-25T19:24:21.870281Z","end":"2026-02-25T19:24:22.178991Z","steps":["trace[539731032] 'process raft request' (duration: 308.412482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:22.179134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:21.87026Z","time spent":"308.772631ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:24:22.17913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.841337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:24:22.179183Z","caller":"traceutil/trace.go:171","msg":"trace[1282669222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"307.908799ms","start":"2026-02-25T19:24:21.871259Z","end":"2026-02-25T19:24:22.179168Z","steps":["trace[1282669222] 'agreement among raft nodes before linearized reading' (duration: 307.764095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:22.179171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.883419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189793c354d2917b\" ","response":"range_response_count:1 size:774"} {"level":"warn","ts":"2026-02-25T19:24:22.179226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:21.87124Z","time spent":"307.97407ms","remote":"127.0.0.1:57218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:24:22.179237Z","caller":"traceutil/trace.go:171","msg":"trace[140230739] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189793c354d2917b; range_end:; response_count:1; response_revision:588; }","duration":"309.983412ms","start":"2026-02-25T19:24:21.869236Z","end":"2026-02-25T19:24:22.179219Z","steps":["trace[140230739] 'agreement among raft nodes before linearized reading' (duration: 309.770117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:22.179257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:21.868037Z","time spent":"311.118602ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:24:22.179279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:21.869221Z","time spent":"310.048933ms","remote":"127.0.0.1:57298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189793c354d2917b\" "} {"level":"info","ts":"2026-02-25T19:24:24.576296Z","caller":"traceutil/trace.go:171","msg":"trace[1739705232] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:641; }","duration":"393.00222ms","start":"2026-02-25T19:24:24.18327Z","end":"2026-02-25T19:24:24.576273Z","steps":["trace[1739705232] 'read index received' (duration: 377.425831ms)","trace[1739705232] 'applied index is now lower than readState.Index' (duration: 15.575459ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:24.576372Z","caller":"traceutil/trace.go:171","msg":"trace[1910064400] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"219.120546ms","start":"2026-02-25T19:24:24.357242Z","end":"2026-02-25T19:24:24.576363Z","steps":["trace[1910064400] 'process raft request' (duration: 218.89407ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:24.576298Z","caller":"traceutil/trace.go:171","msg":"trace[748508064] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"418.435495ms","start":"2026-02-25T19:24:24.157831Z","end":"2026-02-25T19:24:24.576267Z","steps":["trace[748508064] 'process raft request' (duration: 418.185869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:24.576558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:24.157805Z","time spent":"418.665711ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:24:24.576627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.337639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T19:24:24.576642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.273547ms","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-25T19:24:24.5767Z","caller":"traceutil/trace.go:171","msg":"trace[657975525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"393.451302ms","start":"2026-02-25T19:24:24.183229Z","end":"2026-02-25T19:24:24.576681Z","steps":["trace[657975525] 'agreement among raft nodes before linearized reading' (duration: 393.261117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:24.57676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:24.183216Z","time spent":"393.526623ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:24:24.576781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.431371ms","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-25T19:24:24.576707Z","caller":"traceutil/trace.go:171","msg":"trace[1449317526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"393.39881ms","start":"2026-02-25T19:24:24.183295Z","end":"2026-02-25T19:24:24.576694Z","steps":["trace[1449317526] 'agreement among raft nodes before linearized reading' (duration: 393.223106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:24.576704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.06781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:24:24.576977Z","caller":"traceutil/trace.go:171","msg":"trace[1651164250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"124.360528ms","start":"2026-02-25T19:24:24.452603Z","end":"2026-02-25T19:24:24.576963Z","steps":["trace[1651164250] 'agreement among raft nodes before linearized reading' (duration: 124.06994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:24.576926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:24.183273Z","time spent":"393.582165ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:24:24.576809Z","caller":"traceutil/trace.go:171","msg":"trace[689003879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"393.506473ms","start":"2026-02-25T19:24:24.183295Z","end":"2026-02-25T19:24:24.576802Z","steps":["trace[689003879] 'agreement among raft nodes before linearized reading' (duration: 393.430361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:24.577137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:24.183272Z","time spent":"393.846042ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T19:24:31.195308Z","caller":"traceutil/trace.go:171","msg":"trace[989828849] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"226.247218ms","start":"2026-02-25T19:24:30.969037Z","end":"2026-02-25T19:24:31.195284Z","steps":["trace[989828849] 'process raft request' (duration: 226.121855ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:36.122338Z","caller":"traceutil/trace.go:171","msg":"trace[1565921282] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"146.139541ms","start":"2026-02-25T19:24:35.976164Z","end":"2026-02-25T19:24:36.122304Z","steps":["trace[1565921282] 'process raft request' (duration: 127.563693ms)","trace[1565921282] 'compare' (duration: 18.419995ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:36.422151Z","caller":"traceutil/trace.go:171","msg":"trace[792653444] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"438.137387ms","start":"2026-02-25T19:24:35.983983Z","end":"2026-02-25T19:24:36.422121Z","steps":["trace[792653444] 'process raft request' (duration: 416.576551ms)","trace[792653444] 'compare' (duration: 21.446643ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:24:36.422374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:35.98397Z","time spent":"438.256219ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:24:36.578655Z","caller":"traceutil/trace.go:171","msg":"trace[799356312] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"149.655366ms","start":"2026-02-25T19:24:36.428968Z","end":"2026-02-25T19:24:36.578623Z","steps":["trace[799356312] 'process raft request' (duration: 135.492217ms)","trace[799356312] 'compare' (duration: 13.981865ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:36.722054Z","caller":"traceutil/trace.go:171","msg":"trace[968299751] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"135.798065ms","start":"2026-02-25T19:24:36.586208Z","end":"2026-02-25T19:24:36.722006Z","steps":["trace[968299751] 'process raft request' (duration: 106.384503ms)","trace[968299751] 'compare' (duration: 29.157236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:24:36.977399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.689604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.128\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T19:24:36.977492Z","caller":"traceutil/trace.go:171","msg":"trace[1333801926] range","detail":"{range_begin:/registry/masterleases/199.19.213.128; range_end:; response_count:1; response_revision:771; }","duration":"132.796597ms","start":"2026-02-25T19:24:36.844671Z","end":"2026-02-25T19:24:36.977467Z","steps":["trace[1333801926] 'range keys from in-memory index tree' (duration: 132.49986ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:37.209376Z","caller":"traceutil/trace.go:171","msg":"trace[2013820686] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"137.785203ms","start":"2026-02-25T19:24:37.071568Z","end":"2026-02-25T19:24:37.209354Z","steps":["trace[2013820686] 'process raft request' (duration: 120.729462ms)","trace[2013820686] 'compare' (duration: 16.923668ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:38.0243Z","caller":"traceutil/trace.go:171","msg":"trace[1674745584] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"177.645827ms","start":"2026-02-25T19:24:37.846634Z","end":"2026-02-25T19:24:38.02428Z","steps":["trace[1674745584] 'process raft request' (duration: 177.139186ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:38.886811Z","caller":"traceutil/trace.go:171","msg":"trace[1397252548] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"107.841515ms","start":"2026-02-25T19:24:38.778947Z","end":"2026-02-25T19:24:38.886789Z","steps":["trace[1397252548] 'process raft request' (duration: 28.594482ms)","trace[1397252548] 'compare' (duration: 79.059468ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:38.886998Z","caller":"traceutil/trace.go:171","msg":"trace[1121422657] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"107.233691ms","start":"2026-02-25T19:24:38.77972Z","end":"2026-02-25T19:24:38.886953Z","steps":["trace[1121422657] 'process raft request' (duration: 107.018036ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:40.302592Z","caller":"traceutil/trace.go:171","msg":"trace[304486615] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"185.181378ms","start":"2026-02-25T19:24:40.11739Z","end":"2026-02-25T19:24:40.302571Z","steps":["trace[304486615] 'process raft request' (duration: 110.271469ms)","trace[304486615] 'compare' (duration: 74.789507ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:24:42.04269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.216748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:24:42.042745Z","caller":"traceutil/trace.go:171","msg":"trace[908756651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"589.305209ms","start":"2026-02-25T19:24:41.453428Z","end":"2026-02-25T19:24:42.042734Z","steps":["trace[908756651] 'range keys from in-memory index tree' (duration: 588.812289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:42.042766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:41.453415Z","time spent":"589.34652ms","remote":"127.0.0.1:57218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:24:42.621217Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3306377245601819771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:24:43.105624Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.001591873s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:24:44.048541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.439865ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T19:24:44.049029Z","caller":"traceutil/trace.go:171","msg":"trace[1782721219] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:813; }","duration":"1.93105236s","start":"2026-02-25T19:24:42.117947Z","end":"2026-02-25T19:24:44.048999Z","steps":["trace[1782721219] 'read index received' (duration: 988.285788ms)","trace[1782721219] 'applied index is now lower than readState.Index' (duration: 942.765472ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:44.049215Z","caller":"traceutil/trace.go:171","msg":"trace[993605860] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"1.758445175s","start":"2026-02-25T19:24:42.290758Z","end":"2026-02-25T19:24:44.049203Z","steps":["trace[993605860] 'process raft request' (duration: 1.758094257s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:44.049388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:42.290732Z","time spent":"1.758520726s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:24:44.049386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742166796s","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-25T19:24:44.049464Z","caller":"traceutil/trace.go:171","msg":"trace[1042233067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:785; }","duration":"1.742290619s","start":"2026-02-25T19:24:42.307151Z","end":"2026-02-25T19:24:44.049441Z","steps":["trace[1042233067] 'agreement among raft nodes before linearized reading' (duration: 1.742068514s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:44.049452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.60399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:24:44.049516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:42.307111Z","time spent":"1.742396651s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:24:44.049526Z","caller":"traceutil/trace.go:171","msg":"trace[1493046398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"595.713052ms","start":"2026-02-25T19:24:43.453796Z","end":"2026-02-25T19:24:44.049509Z","steps":["trace[1493046398] 'agreement among raft nodes before linearized reading' (duration: 595.60576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:44.049548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.963978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"warn","ts":"2026-02-25T19:24:44.049563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:43.453781Z","time spent":"595.774273ms","remote":"127.0.0.1:57218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:24:44.04961Z","caller":"traceutil/trace.go:171","msg":"trace[2062759782] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:785; }","duration":"523.054739ms","start":"2026-02-25T19:24:43.52654Z","end":"2026-02-25T19:24:44.049594Z","steps":["trace[2062759782] 'agreement among raft nodes before linearized reading' (duration: 522.943437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:44.049648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:43.526524Z","time spent":"523.117261ms","remote":"127.0.0.1:57338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":1108,"request content":"key:\"/registry/configmaps/kube-system/kubeadm-config\" "} {"level":"warn","ts":"2026-02-25T19:24:44.049736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206022019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T19:24:44.049764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121625962s","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-25T19:24:44.049825Z","caller":"traceutil/trace.go:171","msg":"trace[93354019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:785; }","duration":"1.121723655s","start":"2026-02-25T19:24:42.928086Z","end":"2026-02-25T19:24:44.049809Z","steps":["trace[93354019] 'agreement among raft nodes before linearized reading' (duration: 1.121571211s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:44.049864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:42.928067Z","time spent":"1.121789477s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:24:44.049871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04952948s","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-25T19:24:44.049775Z","caller":"traceutil/trace.go:171","msg":"trace[898802404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:785; }","duration":"1.206086981s","start":"2026-02-25T19:24:42.843678Z","end":"2026-02-25T19:24:44.049765Z","steps":["trace[898802404] 'agreement among raft nodes before linearized reading' (duration: 1.205993309s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:44.04991Z","caller":"traceutil/trace.go:171","msg":"trace[423803056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"1.049590841s","start":"2026-02-25T19:24:43.000309Z","end":"2026-02-25T19:24:44.0499Z","steps":["trace[423803056] 'agreement among raft nodes before linearized reading' (duration: 1.049508059s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:44.049927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:42.843663Z","time spent":"1.206253414s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:24:44.049943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:43.000296Z","time spent":"1.049640322s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:24:44.049969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.931982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T19:24:44.050033Z","caller":"traceutil/trace.go:171","msg":"trace[1178662404] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:785; }","duration":"1.932124103s","start":"2026-02-25T19:24:42.117894Z","end":"2026-02-25T19:24:44.050019Z","steps":["trace[1178662404] 'agreement among raft nodes before linearized reading' (duration: 1.931642743s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:44.050069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:24:42.117875Z","time spent":"1.932187904s","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:24:44.236093Z","caller":"traceutil/trace.go:171","msg":"trace[243569175] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"180.763828ms","start":"2026-02-25T19:24:44.055297Z","end":"2026-02-25T19:24:44.236061Z","steps":["trace[243569175] 'process raft request' (duration: 164.409544ms)","trace[243569175] 'compare' (duration: 16.13953ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:44.236198Z","caller":"traceutil/trace.go:171","msg":"trace[2068989835] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"180.535973ms","start":"2026-02-25T19:24:44.055625Z","end":"2026-02-25T19:24:44.236161Z","steps":["trace[2068989835] 'process raft request' (duration: 180.35408ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:44.236725Z","caller":"traceutil/trace.go:171","msg":"trace[782771363] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"181.023003ms","start":"2026-02-25T19:24:44.055691Z","end":"2026-02-25T19:24:44.236714Z","steps":["trace[782771363] 'process raft request' (duration: 180.902141ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:44.236696Z","caller":"traceutil/trace.go:171","msg":"trace[535668647] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"180.147685ms","start":"2026-02-25T19:24:44.056537Z","end":"2026-02-25T19:24:44.236685Z","steps":["trace[535668647] 'process raft request' (duration: 180.108015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:45.184736Z","caller":"traceutil/trace.go:171","msg":"trace[604945038] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"163.818994ms","start":"2026-02-25T19:24:45.020891Z","end":"2026-02-25T19:24:45.18471Z","steps":["trace[604945038] 'process raft request' (duration: 163.677471ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:46.517969Z","caller":"traceutil/trace.go:171","msg":"trace[1512106738] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"271.657983ms","start":"2026-02-25T19:24:46.246281Z","end":"2026-02-25T19:24:46.517939Z","steps":["trace[1512106738] 'process raft request' (duration: 232.88954ms)","trace[1512106738] 'compare' (duration: 38.58847ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:46.518184Z","caller":"traceutil/trace.go:171","msg":"trace[1333607290] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"271.819997ms","start":"2026-02-25T19:24:46.246347Z","end":"2026-02-25T19:24:46.518167Z","steps":["trace[1333607290] 'process raft request' (duration: 271.538701ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:46.51835Z","caller":"traceutil/trace.go:171","msg":"trace[1682737729] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"271.302627ms","start":"2026-02-25T19:24:46.247034Z","end":"2026-02-25T19:24:46.518336Z","steps":["trace[1682737729] 'process raft request' (duration: 271.067492ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:54.696973Z","caller":"traceutil/trace.go:171","msg":"trace[1944927810] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"138.79869ms","start":"2026-02-25T19:24:54.558136Z","end":"2026-02-25T19:24:54.696935Z","steps":["trace[1944927810] 'process raft request' (duration: 92.922014ms)","trace[1944927810] 'compare' (duration: 45.730203ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:54.877817Z","caller":"traceutil/trace.go:171","msg":"trace[543978199] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"177.005686ms","start":"2026-02-25T19:24:54.700782Z","end":"2026-02-25T19:24:54.877788Z","steps":["trace[543978199] 'process raft request' (duration: 152.748072ms)","trace[543978199] 'compare' (duration: 24.149572ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:56.852428Z","caller":"traceutil/trace.go:171","msg":"trace[1433916221] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"119.929798ms","start":"2026-02-25T19:24:56.73247Z","end":"2026-02-25T19:24:56.852399Z","steps":["trace[1433916221] 'process raft request' (duration: 65.001441ms)","trace[1433916221] 'compare' (duration: 54.774184ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:57.073737Z","caller":"traceutil/trace.go:171","msg":"trace[803893248] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"122.006569ms","start":"2026-02-25T19:24:56.951709Z","end":"2026-02-25T19:24:57.073715Z","steps":["trace[803893248] 'read index received' (duration: 988.779µs)","trace[803893248] 'applied index is now lower than readState.Index' (duration: 121.0168ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:24:57.074018Z","caller":"traceutil/trace.go:171","msg":"trace[1744753117] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"126.579396ms","start":"2026-02-25T19:24:56.94742Z","end":"2026-02-25T19:24:57.073999Z","steps":["trace[1744753117] 'process raft request' (duration: 126.180829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:24:57.074378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.643521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T19:24:57.074406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.744364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-25T19:24:57.074437Z","caller":"traceutil/trace.go:171","msg":"trace[176139072] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:829; }","duration":"120.794835ms","start":"2026-02-25T19:24:56.953633Z","end":"2026-02-25T19:24:57.074428Z","steps":["trace[176139072] 'agreement among raft nodes before linearized reading' (duration: 120.733114ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:57.074441Z","caller":"traceutil/trace.go:171","msg":"trace[1571810406] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:829; }","duration":"122.722762ms","start":"2026-02-25T19:24:56.951703Z","end":"2026-02-25T19:24:57.074425Z","steps":["trace[1571810406] 'agreement among raft nodes before linearized reading' (duration: 122.58813ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:24:59.883014Z","caller":"traceutil/trace.go:171","msg":"trace[2061467214] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"145.309627ms","start":"2026-02-25T19:24:59.737679Z","end":"2026-02-25T19:24:59.882989Z","steps":["trace[2061467214] 'process raft request' (duration: 145.089683ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:01.3278Z","caller":"traceutil/trace.go:171","msg":"trace[1578345957] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"131.491413ms","start":"2026-02-25T19:25:01.196284Z","end":"2026-02-25T19:25:01.327776Z","steps":["trace[1578345957] 'process raft request' (duration: 131.328669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:02.200001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.420218ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-25T19:25:03.403324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.14248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T19:25:03.403409Z","caller":"traceutil/trace.go:171","msg":"trace[186191520] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:845; }","duration":"283.281982ms","start":"2026-02-25T19:25:03.120109Z","end":"2026-02-25T19:25:03.40339Z","steps":["trace[186191520] 'range keys from in-memory index tree' (duration: 282.936186ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:03.596383Z","caller":"traceutil/trace.go:171","msg":"trace[1830637767] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:880; }","duration":"143.749915ms","start":"2026-02-25T19:25:03.452615Z","end":"2026-02-25T19:25:03.596365Z","steps":["trace[1830637767] 'read index received' (duration: 143.612372ms)","trace[1830637767] 'applied index is now lower than readState.Index' (duration: 136.583µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:03.596448Z","caller":"traceutil/trace.go:171","msg":"trace[1953147001] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"189.775458ms","start":"2026-02-25T19:25:03.406649Z","end":"2026-02-25T19:25:03.596424Z","steps":["trace[1953147001] 'process raft request' (duration: 189.599885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:03.59648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.854747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:25:03.596516Z","caller":"traceutil/trace.go:171","msg":"trace[850481070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"143.942479ms","start":"2026-02-25T19:25:03.452562Z","end":"2026-02-25T19:25:03.596505Z","steps":["trace[850481070] 'agreement among raft nodes before linearized reading' (duration: 143.865147ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:05.737396Z","caller":"traceutil/trace.go:171","msg":"trace[1261224747] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"133.614447ms","start":"2026-02-25T19:25:05.603759Z","end":"2026-02-25T19:25:05.737373Z","steps":["trace[1261224747] 'process raft request' (duration: 133.495105ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:06.916665Z","caller":"traceutil/trace.go:171","msg":"trace[280468626] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"108.456959ms","start":"2026-02-25T19:25:06.808166Z","end":"2026-02-25T19:25:06.916623Z","steps":["trace[280468626] 'process raft request' (duration: 108.223084ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:07.914821Z","caller":"traceutil/trace.go:171","msg":"trace[206476351] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"170.207458ms","start":"2026-02-25T19:25:07.744594Z","end":"2026-02-25T19:25:07.914801Z","steps":["trace[206476351] 'process raft request' (duration: 170.073106ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:12.073318Z","caller":"traceutil/trace.go:171","msg":"trace[875820799] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"132.63146ms","start":"2026-02-25T19:25:11.940661Z","end":"2026-02-25T19:25:12.073292Z","steps":["trace[875820799] 'process raft request' (duration: 132.500038ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:14.668709Z","caller":"traceutil/trace.go:171","msg":"trace[788937746] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"215.349268ms","start":"2026-02-25T19:25:14.453331Z","end":"2026-02-25T19:25:14.66868Z","steps":["trace[788937746] 'read index received' (duration: 215.181525ms)","trace[788937746] 'applied index is now lower than readState.Index' (duration: 163.183µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:25:14.668784Z","caller":"traceutil/trace.go:171","msg":"trace[497430675] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"318.075793ms","start":"2026-02-25T19:25:14.350671Z","end":"2026-02-25T19:25:14.668747Z","steps":["trace[497430675] 'process raft request' (duration: 317.856509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:14.66897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.630513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:25:14.668972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:14.350655Z","time spent":"318.226945ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:14.669025Z","caller":"traceutil/trace.go:171","msg":"trace[2103325731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:875; }","duration":"215.718914ms","start":"2026-02-25T19:25:14.45329Z","end":"2026-02-25T19:25:14.669009Z","steps":["trace[2103325731] 'agreement among raft nodes before linearized reading' (duration: 215.549471ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:14.77528Z","caller":"traceutil/trace.go:171","msg":"trace[1146153350] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"101.211859ms","start":"2026-02-25T19:25:14.674032Z","end":"2026-02-25T19:25:14.775244Z","steps":["trace[1146153350] 'process raft request' (duration: 65.060271ms)","trace[1146153350] 'compare' (duration: 36.025936ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:15.505584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.186723ms","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-25T19:25:15.505671Z","caller":"traceutil/trace.go:171","msg":"trace[1233709511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:878; }","duration":"192.319515ms","start":"2026-02-25T19:25:15.313332Z","end":"2026-02-25T19:25:15.505651Z","steps":["trace[1233709511] 'range keys from in-memory index tree' (duration: 192.01288ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:20.916015Z","caller":"traceutil/trace.go:171","msg":"trace[1082400430] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"113.110134ms","start":"2026-02-25T19:25:20.802857Z","end":"2026-02-25T19:25:20.915967Z","steps":["trace[1082400430] 'process raft request' (duration: 112.945942ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:23.076439Z","caller":"traceutil/trace.go:171","msg":"trace[1473462169] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"119.728305ms","start":"2026-02-25T19:25:22.956675Z","end":"2026-02-25T19:25:23.076404Z","steps":["trace[1473462169] 'process raft request' (duration: 112.931595ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:25.387857Z","caller":"traceutil/trace.go:171","msg":"trace[62340163] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"106.04526ms","start":"2026-02-25T19:25:25.281794Z","end":"2026-02-25T19:25:25.38784Z","steps":["trace[62340163] 'process raft request' (duration: 105.937788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:49.975857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.009734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:25:49.975972Z","caller":"traceutil/trace.go:171","msg":"trace[1171431333] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1011; }","duration":"309.203606ms","start":"2026-02-25T19:25:49.666746Z","end":"2026-02-25T19:25:49.975949Z","steps":["trace[1171431333] 'count revisions from in-memory index tree' (duration: 308.892652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:49.976025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:49.666719Z","time spent":"309.293768ms","remote":"127.0.0.1:50728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:25:50.136925Z","caller":"traceutil/trace.go:171","msg":"trace[1061344786] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"150.818606ms","start":"2026-02-25T19:25:49.986067Z","end":"2026-02-25T19:25:50.136886Z","steps":["trace[1061344786] 'process raft request' (duration: 150.600023ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:25:51.296275Z","caller":"traceutil/trace.go:171","msg":"trace[335989166] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"862.580331ms","start":"2026-02-25T19:25:50.433673Z","end":"2026-02-25T19:25:51.296254Z","steps":["trace[335989166] 'process raft request' (duration: 862.444749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:51.296527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:25:50.433653Z","time spent":"862.703374ms","remote":"127.0.0.1:57488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:25:51.296324Z","caller":"traceutil/trace.go:171","msg":"trace[1245857855] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1058; }","duration":"210.222359ms","start":"2026-02-25T19:25:51.086051Z","end":"2026-02-25T19:25:51.296273Z","steps":["trace[1245857855] 'read index received' (duration: 210.197628ms)","trace[1245857855] 'applied index is now lower than readState.Index' (duration: 19.85µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:25:51.296718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.635414ms","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-25T19:25:51.296825Z","caller":"traceutil/trace.go:171","msg":"trace[631957745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"210.788506ms","start":"2026-02-25T19:25:51.086008Z","end":"2026-02-25T19:25:51.296797Z","steps":["trace[631957745] 'agreement among raft nodes before linearized reading' (duration: 210.492962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:25:51.364734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.669565ms","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-25T19:25:51.364803Z","caller":"traceutil/trace.go:171","msg":"trace[495615679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"143.791537ms","start":"2026-02-25T19:25:51.220996Z","end":"2026-02-25T19:25:51.364787Z","steps":["trace[495615679] 'agreement among raft nodes before linearized reading' (duration: 143.548984ms)"],"step_count":1}