{"level":"warn","ts":"2026-02-26T20:05:55.289177Z","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-26T20:05:55.289285Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.220: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.220:2380","--initial-cluster=instance=https://199.19.213.220:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.220:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.220: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-26T20:05:55.289368Z","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-26T20:05:55.289383Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.220:2380"]} {"level":"info","ts":"2026-02-26T20:05:55.289403Z","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-26T20:05:55.290194Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.220:2379"]} {"level":"info","ts":"2026-02-26T20:05:55.29028Z","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.220:2380"],"listen-peer-urls":["https://199.19.213.220:2380"],"advertise-client-urls":["https://199.19.213.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.220: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-26T20:05:55.299209Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.699344ms"} {"level":"info","ts":"2026-02-26T20:05:55.310997Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab3c4fae41c4ba3","cluster-id":"574139c3944fcfd2"} {"level":"info","ts":"2026-02-26T20:05:55.311069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T20:05:55.311092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became follower at term 0"} {"level":"info","ts":"2026-02-26T20:05:55.311104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab3c4fae41c4ba3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T20:05:55.311116Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became follower at term 1"} {"level":"info","ts":"2026-02-26T20:05:55.311154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 switched to configuration voters=(771176543058414499)"} {"level":"warn","ts":"2026-02-26T20:05:55.318877Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T20:05:55.320455Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T20:05:55.321716Z","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-26T20:05:55.324344Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab3c4fae41c4ba3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T20:05:55.324457Z","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-26T20:05:55.324474Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab3c4fae41c4ba3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T20:05:55.324531Z","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-26T20:05:55.324548Z","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-26T20:05:55.325925Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 switched to configuration voters=(771176543058414499)"} {"level":"info","ts":"2026-02-26T20:05:55.327223Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"574139c3944fcfd2","local-member-id":"ab3c4fae41c4ba3","added-peer-id":"ab3c4fae41c4ba3","added-peer-peer-urls":["https://199.19.213.220:2380"]} {"level":"info","ts":"2026-02-26T20:05:55.332051Z","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-26T20:05:55.332266Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.220:2380"} {"level":"info","ts":"2026-02-26T20:05:55.332308Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.220:2380"} {"level":"info","ts":"2026-02-26T20:05:55.332541Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab3c4fae41c4ba3","initial-advertise-peer-urls":["https://199.19.213.220:2380"],"listen-peer-urls":["https://199.19.213.220:2380"],"advertise-client-urls":["https://199.19.213.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T20:05:55.332596Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T20:05:56.211961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T20:05:56.212032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T20:05:56.212059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 received MsgPreVoteResp from ab3c4fae41c4ba3 at term 1"} {"level":"info","ts":"2026-02-26T20:05:56.212085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became candidate at term 2"} {"level":"info","ts":"2026-02-26T20:05:56.212107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 received MsgVoteResp from ab3c4fae41c4ba3 at term 2"} {"level":"info","ts":"2026-02-26T20:05:56.21213Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab3c4fae41c4ba3 became leader at term 2"} {"level":"info","ts":"2026-02-26T20:05:56.212153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab3c4fae41c4ba3 elected leader ab3c4fae41c4ba3 at term 2"} {"level":"info","ts":"2026-02-26T20:05:56.237319Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:05:56.302068Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T20:05:56.30209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T20:05:56.302036Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab3c4fae41c4ba3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.220:2379]}","request-path":"/0/members/ab3c4fae41c4ba3/attributes","cluster-id":"574139c3944fcfd2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T20:05:56.302935Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T20:05:56.303008Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T20:05:56.304764Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.220:2379"} {"level":"info","ts":"2026-02-26T20:05:56.306216Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T20:05:56.333146Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"574139c3944fcfd2","local-member-id":"ab3c4fae41c4ba3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:05:56.333249Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:05:56.333274Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-26T20:05:58.320474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.756149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-26T20:05:58.320847Z","caller":"traceutil/trace.go:171","msg":"trace[1695589735] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"684.812371ms","start":"2026-02-26T20:05:57.636008Z","end":"2026-02-26T20:05:58.32082Z","steps":["trace[1695589735] 'process raft request' (duration: 567.010247ms)","trace[1695589735] 'compare' (duration: 116.661354ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:05:58.321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.635996Z","time spent":"684.967884ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:05:58.321113Z","caller":"traceutil/trace.go:171","msg":"trace[151053001] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"685.014552ms","start":"2026-02-26T20:05:57.636083Z","end":"2026-02-26T20:05:58.321098Z","steps":["trace[151053001] 'process raft request' (duration: 684.502015ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.321142Z","caller":"traceutil/trace.go:171","msg":"trace[1647092383] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"677.045013ms","start":"2026-02-26T20:05:57.644085Z","end":"2026-02-26T20:05:58.32113Z","steps":["trace[1647092383] 'process raft request' (duration: 676.824393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.321234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.644075Z","time spent":"677.102121ms","remote":"127.0.0.1:41866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:05:58.321022Z","caller":"traceutil/trace.go:171","msg":"trace[1544989266] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"684.477356ms","start":"2026-02-26T20:05:57.636512Z","end":"2026-02-26T20:05:58.320989Z","steps":["trace[1544989266] 'process raft request' (duration: 684.17626ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.321134Z","caller":"traceutil/trace.go:171","msg":"trace[1342904115] linearizableReadLoop","detail":"{readStateIndex:10; appliedIndex:8; }","duration":"684.582372ms","start":"2026-02-26T20:05:57.636532Z","end":"2026-02-26T20:05:58.321115Z","steps":["trace[1342904115] 'read index received' (duration: 22.566749ms)","trace[1342904115] 'applied index is now lower than readState.Index' (duration: 662.014583ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:05:58.321422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.636486Z","time spent":"684.84252ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:05:58.321461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.636061Z","time spent":"685.097589ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:05:58.321081Z","caller":"traceutil/trace.go:171","msg":"trace[450648057] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"684.106054ms","start":"2026-02-26T20:05:57.636955Z","end":"2026-02-26T20:05:58.321061Z","steps":["trace[450648057] 'process raft request' (duration: 683.903363ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.321679Z","caller":"traceutil/trace.go:171","msg":"trace[878354349] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"675.214939ms","start":"2026-02-26T20:05:57.646223Z","end":"2026-02-26T20:05:58.321438Z","steps":["trace[878354349] 'process raft request' (duration: 674.731391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.321812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.731625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T20:05:58.321834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.646212Z","time spent":"675.548593ms","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:05:58.321678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.636942Z","time spent":"684.670028ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:05:58.321927Z","caller":"traceutil/trace.go:171","msg":"trace[1612678501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"685.409605ms","start":"2026-02-26T20:05:57.636499Z","end":"2026-02-26T20:05:58.321909Z","steps":["trace[1612678501] 'agreement among raft nodes before linearized reading' (duration: 684.670298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.322227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.636487Z","time spent":"685.72415ms","remote":"127.0.0.1:41632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:05:58.997725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.14526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-26T20:05:58.997898Z","caller":"traceutil/trace.go:171","msg":"trace[336380724] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"676.524639ms","start":"2026-02-26T20:05:58.321356Z","end":"2026-02-26T20:05:58.997881Z","steps":["trace[336380724] 'read index received' (duration: 289.148368ms)","trace[336380724] 'applied index is now lower than readState.Index' (duration: 387.375301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:05:58.998116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3609131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:05:58.998203Z","caller":"traceutil/trace.go:171","msg":"trace[555443314] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:13; }","duration":"1.361121901s","start":"2026-02-26T20:05:57.637059Z","end":"2026-02-26T20:05:58.998181Z","steps":["trace[555443314] 'agreement among raft nodes before linearized reading' (duration: 1.360863493s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.998204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311397096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T20:05:58.998225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338084994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T20:05:58.998212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186625254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:05:58.998255Z","caller":"traceutil/trace.go:171","msg":"trace[1604492843] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:13; }","duration":"1.311478002s","start":"2026-02-26T20:05:57.686761Z","end":"2026-02-26T20:05:58.998239Z","steps":["trace[1604492843] 'agreement among raft nodes before linearized reading' (duration: 1.311339689s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.998258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.637047Z","time spent":"1.361194507s","remote":"127.0.0.1:41726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-02-26T20:05:58.998271Z","caller":"traceutil/trace.go:171","msg":"trace[1892775644] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"1.338156461s","start":"2026-02-26T20:05:57.660098Z","end":"2026-02-26T20:05:58.998255Z","steps":["trace[1892775644] 'agreement among raft nodes before linearized reading' (duration: 1.338095333s)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.998279Z","caller":"traceutil/trace.go:171","msg":"trace[1367691508] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:13; }","duration":"1.186731959s","start":"2026-02-26T20:05:57.811531Z","end":"2026-02-26T20:05:58.998262Z","steps":["trace[1367691508] 'agreement among raft nodes before linearized reading' (duration: 1.186593936s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.998291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.686751Z","time spent":"1.31153349s","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-26T20:05:58.998315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.660087Z","time spent":"1.338214858s","remote":"127.0.0.1:41988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-26T20:05:58.998328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.81152Z","time spent":"1.186799787s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-26T20:05:58.998495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.491779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-4k6zr\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-26T20:05:58.998668Z","caller":"traceutil/trace.go:171","msg":"trace[2110516619] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"670.567396ms","start":"2026-02-26T20:05:58.328086Z","end":"2026-02-26T20:05:58.998654Z","steps":["trace[2110516619] 'process raft request' (duration: 670.216772ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.998729Z","caller":"traceutil/trace.go:171","msg":"trace[1746756965] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.338444247s","start":"2026-02-26T20:05:57.660266Z","end":"2026-02-26T20:05:58.99871Z","steps":["trace[1746756965] 'process raft request' (duration: 950.232035ms)","trace[1746756965] 'compare' (duration: 387.026605ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:05:58.998737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.293815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:05:58.998792Z","caller":"traceutil/trace.go:171","msg":"trace[1175406954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"372.364102ms","start":"2026-02-26T20:05:58.626415Z","end":"2026-02-26T20:05:58.99878Z","steps":["trace[1175406954] 'agreement among raft nodes before linearized reading' (duration: 372.259667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.998804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.660254Z","time spent":"1.338521774s","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:05:58.998832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.626354Z","time spent":"372.469237ms","remote":"127.0.0.1:41624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:05:58.998834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.105613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-26T20:05:58.998855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.328076Z","time spent":"670.686081ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:05:58.998958Z","caller":"traceutil/trace.go:171","msg":"trace[1848890998] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"464.247547ms","start":"2026-02-26T20:05:58.534695Z","end":"2026-02-26T20:05:58.998943Z","steps":["trace[1848890998] 'agreement among raft nodes before linearized reading' (duration: 464.089344ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.999023Z","caller":"traceutil/trace.go:171","msg":"trace[6058935] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"671.017906ms","start":"2026-02-26T20:05:58.327994Z","end":"2026-02-26T20:05:58.999012Z","steps":["trace[6058935] 'process raft request' (duration: 670.24383ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.999127Z","caller":"traceutil/trace.go:171","msg":"trace[1333735833] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"665.124979ms","start":"2026-02-26T20:05:58.333988Z","end":"2026-02-26T20:05:58.999113Z","steps":["trace[1333735833] 'process raft request' (duration: 664.340664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.999169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.327976Z","time spent":"671.13962ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:05:58.999289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.791301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T20:05:58.998605Z","caller":"traceutil/trace.go:171","msg":"trace[1768212547] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-4k6zr; range_end:; response_count:1; response_revision:18; }","duration":"672.644363ms","start":"2026-02-26T20:05:58.325943Z","end":"2026-02-26T20:05:58.998587Z","steps":["trace[1768212547] 'agreement among raft nodes before linearized reading' (duration: 672.450041ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.999293Z","caller":"traceutil/trace.go:171","msg":"trace[1218766848] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"671.269945ms","start":"2026-02-26T20:05:58.328006Z","end":"2026-02-26T20:05:58.999276Z","steps":["trace[1218766848] 'process raft request' (duration: 670.26213ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:05:58.999334Z","caller":"traceutil/trace.go:171","msg":"trace[54302312] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:18; }","duration":"464.883807ms","start":"2026-02-26T20:05:58.53444Z","end":"2026-02-26T20:05:58.999324Z","steps":["trace[54302312] 'agreement among raft nodes before linearized reading' (duration: 464.82191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.999367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.534416Z","time spent":"464.943845ms","remote":"127.0.0.1:41964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-02-26T20:05:58.99887Z","caller":"traceutil/trace.go:171","msg":"trace[362883898] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"1.337493521s","start":"2026-02-26T20:05:57.660583Z","end":"2026-02-26T20:05:58.998077Z","steps":["trace[362883898] 'process raft request' (duration: 1.337233221s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.999013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.534688Z","time spent":"464.310503ms","remote":"127.0.0.1:41946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-26T20:05:58.999479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.33398Z","time spent":"665.184516ms","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:05:58.999595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:57.660568Z","time spent":"1.338888018s","remote":"127.0.0.1:42042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:05:58.998695Z","caller":"traceutil/trace.go:171","msg":"trace[433965555] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"671.030685ms","start":"2026-02-26T20:05:58.327643Z","end":"2026-02-26T20:05:58.998674Z","steps":["trace[433965555] 'process raft request' (duration: 670.498319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:05:58.999517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.325913Z","time spent":"673.428297ms","remote":"127.0.0.1:41866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":883,"request content":"key:\"/registry/certificatesigningrequests/csr-4k6zr\" "} {"level":"warn","ts":"2026-02-26T20:05:59.00001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.327583Z","time spent":"672.377365ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:05:58.999613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:05:58.327991Z","time spent":"671.35892ms","remote":"127.0.0.1:42112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:05:59.530231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.703897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-26T20:05:59.530383Z","caller":"traceutil/trace.go:171","msg":"trace[1052890042] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"229.491149ms","start":"2026-02-26T20:05:59.300868Z","end":"2026-02-26T20:05:59.530359Z","steps":["trace[1052890042] 'process raft request' (duration: 63.558113ms)","trace[1052890042] 'compare' (duration: 165.516483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:05:59.896666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.338345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:05:59.89685Z","caller":"traceutil/trace.go:171","msg":"trace[1748826937] transaction","detail":"{read_only:false; response_revision:142; number_of_response:1; }","duration":"198.670835ms","start":"2026-02-26T20:05:59.698149Z","end":"2026-02-26T20:05:59.89682Z","steps":["trace[1748826937] 'process raft request' (duration: 54.081861ms)","trace[1748826937] 'compare' (duration: 144.205921ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:05:59.896851Z","caller":"traceutil/trace.go:171","msg":"trace[2056506542] linearizableReadLoop","detail":"{readStateIndex:146; appliedIndex:145; }","duration":"170.45527ms","start":"2026-02-26T20:05:59.72632Z","end":"2026-02-26T20:05:59.896775Z","steps":["trace[2056506542] 'read index received' (duration: 25.940553ms)","trace[2056506542] 'applied index is now lower than readState.Index' (duration: 144.513127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:05:59.897036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.699589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:05:59.897127Z","caller":"traceutil/trace.go:171","msg":"trace[1356733882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:142; }","duration":"170.838575ms","start":"2026-02-26T20:05:59.726263Z","end":"2026-02-26T20:05:59.897102Z","steps":["trace[1356733882] 'agreement among raft nodes before linearized reading' (duration: 170.644082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:00.18952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.735574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:06:00.189631Z","caller":"traceutil/trace.go:171","msg":"trace[1217140957] linearizableReadLoop","detail":"{readStateIndex:147; appliedIndex:146; }","duration":"163.024939ms","start":"2026-02-26T20:06:00.026587Z","end":"2026-02-26T20:06:00.189612Z","steps":["trace[1217140957] 'read index received' (duration: 34.339µs)","trace[1217140957] 'applied index is now lower than readState.Index' (duration: 162.98952ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:00.189681Z","caller":"traceutil/trace.go:171","msg":"trace[595328558] transaction","detail":"{read_only:false; response_revision:143; number_of_response:1; }","duration":"286.400098ms","start":"2026-02-26T20:05:59.903238Z","end":"2026-02-26T20:06:00.189638Z","steps":["trace[595328558] 'process raft request' (duration: 103.452843ms)","trace[595328558] 'compare' (duration: 182.60094ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:00.189924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.325337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:00.189983Z","caller":"traceutil/trace.go:171","msg":"trace[1708246164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:143; }","duration":"163.415832ms","start":"2026-02-26T20:06:00.026555Z","end":"2026-02-26T20:06:00.189971Z","steps":["trace[1708246164] 'agreement among raft nodes before linearized reading' (duration: 163.107615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:00.465192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.780662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:06:00.465363Z","caller":"traceutil/trace.go:171","msg":"trace[893585553] transaction","detail":"{read_only:false; response_revision:144; number_of_response:1; }","duration":"269.754756ms","start":"2026-02-26T20:06:00.195581Z","end":"2026-02-26T20:06:00.465336Z","steps":["trace[893585553] 'process raft request' (duration: 101.752553ms)","trace[893585553] 'compare' (duration: 167.622709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:01.180765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.961216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:06:01.181048Z","caller":"traceutil/trace.go:171","msg":"trace[1704484251] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"114.064016ms","start":"2026-02-26T20:06:01.066959Z","end":"2026-02-26T20:06:01.181023Z","steps":["trace[1704484251] 'process raft request' (duration: 113.983799ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:01.181072Z","caller":"traceutil/trace.go:171","msg":"trace[623445054] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"114.463969ms","start":"2026-02-26T20:06:01.066573Z","end":"2026-02-26T20:06:01.181037Z","steps":["trace[623445054] 'process raft request' (duration: 12.111609ms)","trace[623445054] 'compare' (duration: 101.754024ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:02.562085Z","caller":"traceutil/trace.go:171","msg":"trace[1174937705] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"198.599971ms","start":"2026-02-26T20:06:02.363457Z","end":"2026-02-26T20:06:02.562057Z","steps":["trace[1174937705] 'process raft request' (duration: 134.927461ms)","trace[1174937705] 'compare' (duration: 63.178679ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:02.859865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.002383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-fhe9as\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:02.860032Z","caller":"traceutil/trace.go:171","msg":"trace[1335530299] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-fhe9as; range_end:; response_count:0; response_revision:276; }","duration":"198.180437ms","start":"2026-02-26T20:06:02.66182Z","end":"2026-02-26T20:06:02.86Z","steps":["trace[1335530299] 'range keys from in-memory index tree' (duration: 197.935786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:02.859952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.349746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-26T20:06:02.86021Z","caller":"traceutil/trace.go:171","msg":"trace[1848107943] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:276; }","duration":"196.646726ms","start":"2026-02-26T20:06:02.66354Z","end":"2026-02-26T20:06:02.860187Z","steps":["trace[1848107943] 'range keys from in-memory index tree' (duration: 196.24914ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:03.203224Z","caller":"traceutil/trace.go:171","msg":"trace[744074363] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:289; }","duration":"239.717036ms","start":"2026-02-26T20:06:02.963486Z","end":"2026-02-26T20:06:03.203203Z","steps":["trace[744074363] 'read index received' (duration: 239.569641ms)","trace[744074363] 'applied index is now lower than readState.Index' (duration: 146.665µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:03.203259Z","caller":"traceutil/trace.go:171","msg":"trace[217841416] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"269.285891ms","start":"2026-02-26T20:06:02.93396Z","end":"2026-02-26T20:06:03.203246Z","steps":["trace[217841416] 'process raft request' (duration: 269.138247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:03.203443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.748925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T20:06:03.203512Z","caller":"traceutil/trace.go:171","msg":"trace[345170472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:284; }","duration":"169.794793ms","start":"2026-02-26T20:06:03.033674Z","end":"2026-02-26T20:06:03.203468Z","steps":["trace[345170472] 'agreement among raft nodes before linearized reading' (duration: 169.671178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:03.203992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.925867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-26T20:06:03.204073Z","caller":"traceutil/trace.go:171","msg":"trace[2086257464] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:284; }","duration":"240.604053ms","start":"2026-02-26T20:06:02.963449Z","end":"2026-02-26T20:06:03.204053Z","steps":["trace[2086257464] 'agreement among raft nodes before linearized reading' (duration: 239.842331ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:04.586706Z","caller":"traceutil/trace.go:171","msg":"trace[1607540389] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"472.486209ms","start":"2026-02-26T20:06:04.114202Z","end":"2026-02-26T20:06:04.586688Z","steps":["trace[1607540389] 'process raft request' (duration: 472.371253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:04.58682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:04.11418Z","time spent":"472.589365ms","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:06:04.698801Z","caller":"traceutil/trace.go:171","msg":"trace[818803760] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"105.449343ms","start":"2026-02-26T20:06:04.593317Z","end":"2026-02-26T20:06:04.698766Z","steps":["trace[818803760] 'process raft request' (duration: 97.156572ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:04.91222Z","caller":"traceutil/trace.go:171","msg":"trace[1314458729] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:332; }","duration":"170.177922ms","start":"2026-02-26T20:06:04.742021Z","end":"2026-02-26T20:06:04.912199Z","steps":["trace[1314458729] 'read index received' (duration: 130.141844ms)","trace[1314458729] 'applied index is now lower than readState.Index' (duration: 40.035038ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:04.91237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.322917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-26T20:06:04.912411Z","caller":"traceutil/trace.go:171","msg":"trace[1544640915] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:324; }","duration":"170.409514ms","start":"2026-02-26T20:06:04.741992Z","end":"2026-02-26T20:06:04.912401Z","steps":["trace[1544640915] 'agreement among raft nodes before linearized reading' (duration: 170.289318ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:04.912455Z","caller":"traceutil/trace.go:171","msg":"trace[314268952] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"209.392459ms","start":"2026-02-26T20:06:04.703034Z","end":"2026-02-26T20:06:04.912427Z","steps":["trace[314268952] 'process raft request' (duration: 169.192357ms)","trace[314268952] 'compare' (duration: 39.785066ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:04.912795Z","caller":"traceutil/trace.go:171","msg":"trace[1755353785] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"181.535157ms","start":"2026-02-26T20:06:04.731246Z","end":"2026-02-26T20:06:04.912781Z","steps":["trace[1755353785] 'process raft request' (duration: 180.89147ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:05.802209Z","caller":"traceutil/trace.go:171","msg":"trace[360884369] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"151.759143ms","start":"2026-02-26T20:06:05.650424Z","end":"2026-02-26T20:06:05.802183Z","steps":["trace[360884369] 'read index received' (duration: 95.754962ms)","trace[360884369] 'applied index is now lower than readState.Index' (duration: 56.002711ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:05.802289Z","caller":"traceutil/trace.go:171","msg":"trace[1262562868] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"187.809846ms","start":"2026-02-26T20:06:05.614444Z","end":"2026-02-26T20:06:05.802254Z","steps":["trace[1262562868] 'process raft request' (duration: 131.795805ms)","trace[1262562868] 'compare' (duration: 55.69258ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:05.803323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.969916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:05.804594Z","caller":"traceutil/trace.go:171","msg":"trace[1679702321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:341; }","duration":"152.968522ms","start":"2026-02-26T20:06:05.650394Z","end":"2026-02-26T20:06:05.803363Z","steps":["trace[1679702321] 'agreement among raft nodes before linearized reading' (duration: 151.908778ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:05.976137Z","caller":"traceutil/trace.go:171","msg":"trace[1200621515] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"161.256006ms","start":"2026-02-26T20:06:05.814867Z","end":"2026-02-26T20:06:05.976123Z","steps":["trace[1200621515] 'process raft request' (duration: 159.858312ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:05.976495Z","caller":"traceutil/trace.go:171","msg":"trace[743787438] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:351; }","duration":"137.236654ms","start":"2026-02-26T20:06:05.83925Z","end":"2026-02-26T20:06:05.976487Z","steps":["trace[743787438] 'read index received' (duration: 135.476412ms)","trace[743787438] 'applied index is now lower than readState.Index' (duration: 1.759672ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:05.976549Z","caller":"traceutil/trace.go:171","msg":"trace[1348815722] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"159.664269ms","start":"2026-02-26T20:06:05.816879Z","end":"2026-02-26T20:06:05.976543Z","steps":["trace[1348815722] 'process raft request' (duration: 159.533424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:05.976652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.383189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-02-26T20:06:05.976709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.121368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-26T20:06:05.976734Z","caller":"traceutil/trace.go:171","msg":"trace[281359041] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:343; }","duration":"137.498406ms","start":"2026-02-26T20:06:05.839216Z","end":"2026-02-26T20:06:05.976715Z","steps":["trace[281359041] 'agreement among raft nodes before linearized reading' (duration: 137.37139ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:05.976733Z","caller":"traceutil/trace.go:171","msg":"trace[361780688] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:343; }","duration":"137.177266ms","start":"2026-02-26T20:06:05.839547Z","end":"2026-02-26T20:06:05.976724Z","steps":["trace[361780688] 'agreement among raft nodes before linearized reading' (duration: 137.013892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:05.976928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.53416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-26T20:06:05.976958Z","caller":"traceutil/trace.go:171","msg":"trace[1658434309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:343; }","duration":"107.602708ms","start":"2026-02-26T20:06:05.869346Z","end":"2026-02-26T20:06:05.976948Z","steps":["trace[1658434309] 'agreement among raft nodes before linearized reading' (duration: 107.554369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:05.976921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.920155ms","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-26T20:06:05.977019Z","caller":"traceutil/trace.go:171","msg":"trace[258868598] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:343; }","duration":"118.064991ms","start":"2026-02-26T20:06:05.858936Z","end":"2026-02-26T20:06:05.977001Z","steps":["trace[258868598] 'agreement among raft nodes before linearized reading' (duration: 117.874697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:05.977187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.596728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-26T20:06:05.977265Z","caller":"traceutil/trace.go:171","msg":"trace[126935655] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:343; }","duration":"104.788791ms","start":"2026-02-26T20:06:05.872455Z","end":"2026-02-26T20:06:05.977244Z","steps":["trace[126935655] 'agreement among raft nodes before linearized reading' (duration: 104.299967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:06.32784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.205143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-02-26T20:06:06.327899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.646843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-26T20:06:06.327935Z","caller":"traceutil/trace.go:171","msg":"trace[640182476] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:343; }","duration":"114.696461ms","start":"2026-02-26T20:06:06.213227Z","end":"2026-02-26T20:06:06.327923Z","steps":["trace[640182476] 'range keys from in-memory index tree' (duration: 114.204556ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:06.327912Z","caller":"traceutil/trace.go:171","msg":"trace[1584725728] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:343; }","duration":"165.30491ms","start":"2026-02-26T20:06:06.162591Z","end":"2026-02-26T20:06:06.327896Z","steps":["trace[1584725728] 'range keys from in-memory index tree' (duration: 164.812895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:06.327858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.714587ms","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-26T20:06:06.328147Z","caller":"traceutil/trace.go:171","msg":"trace[176344653] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:343; }","duration":"213.993108ms","start":"2026-02-26T20:06:06.114097Z","end":"2026-02-26T20:06:06.32809Z","steps":["trace[176344653] 'range keys from in-memory index tree' (duration: 213.288181ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:06.678275Z","caller":"traceutil/trace.go:171","msg":"trace[704595685] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"158.167985ms","start":"2026-02-26T20:06:06.520086Z","end":"2026-02-26T20:06:06.678254Z","steps":["trace[704595685] 'process raft request' (duration: 158.122866ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:06.678315Z","caller":"traceutil/trace.go:171","msg":"trace[771330788] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:367; }","duration":"165.038128ms","start":"2026-02-26T20:06:06.513246Z","end":"2026-02-26T20:06:06.678284Z","steps":["trace[771330788] 'read index received' (duration: 2.422564ms)","trace[771330788] 'applied index is now lower than readState.Index' (duration: 162.612944ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:06.678284Z","caller":"traceutil/trace.go:171","msg":"trace[1076042868] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"168.932354ms","start":"2026-02-26T20:06:06.509327Z","end":"2026-02-26T20:06:06.678259Z","steps":["trace[1076042868] 'process raft request' (duration: 158.620571ms)","trace[1076042868] 'compare' (duration: 10.154438ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:06.678625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.355697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-m7g9c\" ","response":"range_response_count:1 size:3439"} {"level":"warn","ts":"2026-02-26T20:06:06.678622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.349848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-26T20:06:06.678657Z","caller":"traceutil/trace.go:171","msg":"trace[2062505065] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-m7g9c; range_end:; response_count:1; response_revision:361; }","duration":"159.417764ms","start":"2026-02-26T20:06:06.519232Z","end":"2026-02-26T20:06:06.678649Z","steps":["trace[2062505065] 'agreement among raft nodes before linearized reading' (duration: 159.347677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:06.678765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.126089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-02-26T20:06:06.678786Z","caller":"traceutil/trace.go:171","msg":"trace[1874651075] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:361; }","duration":"157.168488ms","start":"2026-02-26T20:06:06.521613Z","end":"2026-02-26T20:06:06.678781Z","steps":["trace[1874651075] 'agreement among raft nodes before linearized reading' (duration: 157.126718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:06.678772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.093559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-26T20:06:06.67883Z","caller":"traceutil/trace.go:171","msg":"trace[933372086] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:361; }","duration":"115.201816ms","start":"2026-02-26T20:06:06.563615Z","end":"2026-02-26T20:06:06.678817Z","steps":["trace[933372086] 'agreement among raft nodes before linearized reading' (duration: 115.065839ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:06.678673Z","caller":"traceutil/trace.go:171","msg":"trace[409536327] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:361; }","duration":"165.450045ms","start":"2026-02-26T20:06:06.513208Z","end":"2026-02-26T20:06:06.678658Z","steps":["trace[409536327] 'agreement among raft nodes before linearized reading' (duration: 165.232372ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:07.039369Z","caller":"traceutil/trace.go:171","msg":"trace[1912800173] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"350.831884ms","start":"2026-02-26T20:06:06.6885Z","end":"2026-02-26T20:06:07.039331Z","steps":["trace[1912800173] 'process raft request' (duration: 350.584022ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:07.039415Z","caller":"traceutil/trace.go:171","msg":"trace[709119678] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"350.774637ms","start":"2026-02-26T20:06:06.68861Z","end":"2026-02-26T20:06:07.039385Z","steps":["trace[709119678] 'process raft request' (duration: 350.628031ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:07.039459Z","caller":"traceutil/trace.go:171","msg":"trace[1193308647] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"350.624161ms","start":"2026-02-26T20:06:06.688814Z","end":"2026-02-26T20:06:07.039438Z","steps":["trace[1193308647] 'process raft request' (duration: 350.476406ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:07.039516Z","caller":"traceutil/trace.go:171","msg":"trace[36088536] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"350.218114ms","start":"2026-02-26T20:06:06.689277Z","end":"2026-02-26T20:06:07.039495Z","steps":["trace[36088536] 'process raft request' (duration: 350.075089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.039536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.688488Z","time spent":"350.98057ms","remote":"127.0.0.1:42074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:06:07.039567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.688808Z","time spent":"350.699819ms","remote":"127.0.0.1:41946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:07.039591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.511651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T20:06:07.039617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.689267Z","time spent":"350.301042ms","remote":"127.0.0.1:41946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:06:07.039649Z","caller":"traceutil/trace.go:171","msg":"trace[1661995458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:368; }","duration":"328.597378ms","start":"2026-02-26T20:06:06.711037Z","end":"2026-02-26T20:06:07.039635Z","steps":["trace[1661995458] 'agreement among raft nodes before linearized reading' (duration: 328.515051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.039688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.711024Z","time spent":"328.654257ms","remote":"127.0.0.1:41624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T20:06:07.039522Z","caller":"traceutil/trace.go:171","msg":"trace[1305200207] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:371; }","duration":"328.416154ms","start":"2026-02-26T20:06:06.711071Z","end":"2026-02-26T20:06:07.039487Z","steps":["trace[1305200207] 'read index received' (duration: 58.873727ms)","trace[1305200207] 'applied index is now lower than readState.Index' (duration: 269.541207ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:07.039715Z","caller":"traceutil/trace.go:171","msg":"trace[1522306408] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"350.270893ms","start":"2026-02-26T20:06:06.689425Z","end":"2026-02-26T20:06:07.039696Z","steps":["trace[1522306408] 'process raft request' (duration: 349.970732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.039726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.688593Z","time spent":"350.888153ms","remote":"127.0.0.1:41796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:06:07.039823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.132079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-02-26T20:06:07.039868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.15574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-02-26T20:06:07.03988Z","caller":"traceutil/trace.go:171","msg":"trace[774474782] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:368; }","duration":"325.199246ms","start":"2026-02-26T20:06:06.714665Z","end":"2026-02-26T20:06:07.039864Z","steps":["trace[774474782] 'agreement among raft nodes before linearized reading' (duration: 325.104459ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:07.039885Z","caller":"traceutil/trace.go:171","msg":"trace[505074688] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"348.274026ms","start":"2026-02-26T20:06:06.691597Z","end":"2026-02-26T20:06:07.039871Z","steps":["trace[505074688] 'process raft request' (duration: 347.839379ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:07.039916Z","caller":"traceutil/trace.go:171","msg":"trace[387213413] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:368; }","duration":"263.237038ms","start":"2026-02-26T20:06:06.776667Z","end":"2026-02-26T20:06:07.039904Z","steps":["trace[387213413] 'agreement among raft nodes before linearized reading' (duration: 263.15557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.039899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.736001ms","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-26T20:06:07.039925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.714659Z","time spent":"325.255565ms","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-02-26T20:06:07.039953Z","caller":"traceutil/trace.go:171","msg":"trace[190999192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"123.823149ms","start":"2026-02-26T20:06:06.916121Z","end":"2026-02-26T20:06:07.039944Z","steps":["trace[190999192] 'agreement among raft nodes before linearized reading' (duration: 123.747791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.039969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.691589Z","time spent":"348.329955ms","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4451,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:07.039835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.689417Z","time spent":"350.362611ms","remote":"127.0.0.1:41946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:07.040094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.831217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-26T20:06:07.040139Z","caller":"traceutil/trace.go:171","msg":"trace[727633226] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:368; }","duration":"300.892727ms","start":"2026-02-26T20:06:06.739235Z","end":"2026-02-26T20:06:07.040128Z","steps":["trace[727633226] 'agreement among raft nodes before linearized reading' (duration: 300.813979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.04015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.855853ms","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-26T20:06:07.040175Z","caller":"traceutil/trace.go:171","msg":"trace[2061846573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:368; }","duration":"297.890562ms","start":"2026-02-26T20:06:06.742277Z","end":"2026-02-26T20:06:07.040168Z","steps":["trace[2061846573] 'agreement among raft nodes before linearized reading' (duration: 297.840764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.040172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:06.739226Z","time spent":"300.939295ms","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":207,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-02-26T20:06:07.397203Z","caller":"traceutil/trace.go:171","msg":"trace[2146330131] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"349.591063ms","start":"2026-02-26T20:06:07.047596Z","end":"2026-02-26T20:06:07.397187Z","steps":["trace[2146330131] 'process raft request' (duration: 281.512453ms)","trace[2146330131] 'compare' (duration: 67.915444ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:07.397295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:07.047579Z","time spent":"349.666491ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:06:07.397404Z","caller":"traceutil/trace.go:171","msg":"trace[765736647] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"349.610392ms","start":"2026-02-26T20:06:07.047759Z","end":"2026-02-26T20:06:07.39737Z","steps":["trace[765736647] 'process raft request' (duration: 349.398988ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:07.397821Z","caller":"traceutil/trace.go:171","msg":"trace[1316272749] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:379; }","duration":"349.091087ms","start":"2026-02-26T20:06:07.048703Z","end":"2026-02-26T20:06:07.397794Z","steps":["trace[1316272749] 'read index received' (duration: 62.635824ms)","trace[1316272749] 'applied index is now lower than readState.Index' (duration: 286.453863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:07.397965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:07.047753Z","time spent":"350.131336ms","remote":"127.0.0.1:41712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T20:06:07.398113Z","caller":"traceutil/trace.go:171","msg":"trace[549167065] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"348.595932ms","start":"2026-02-26T20:06:07.049509Z","end":"2026-02-26T20:06:07.398105Z","steps":["trace[549167065] 'process raft request' (duration: 348.041398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.39816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:07.049487Z","time spent":"348.647061ms","remote":"127.0.0.1:42054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:06:07.397965Z","caller":"traceutil/trace.go:171","msg":"trace[353379994] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"350.087338ms","start":"2026-02-26T20:06:07.047866Z","end":"2026-02-26T20:06:07.397954Z","steps":["trace[353379994] 'process raft request' (duration: 349.488886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.398261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.823372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-02-26T20:06:07.398297Z","caller":"traceutil/trace.go:171","msg":"trace[2001207286] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:375; }","duration":"209.900099ms","start":"2026-02-26T20:06:07.188388Z","end":"2026-02-26T20:06:07.398288Z","steps":["trace[2001207286] 'agreement among raft nodes before linearized reading' (duration: 209.835141ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:07.398175Z","caller":"traceutil/trace.go:171","msg":"trace[563339839] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"348.520414ms","start":"2026-02-26T20:06:07.049572Z","end":"2026-02-26T20:06:07.398092Z","steps":["trace[563339839] 'process raft request' (duration: 348.171075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.398437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.685099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-26T20:06:07.398514Z","caller":"traceutil/trace.go:171","msg":"trace[1777569069] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:375; }","duration":"349.819615ms","start":"2026-02-26T20:06:07.048679Z","end":"2026-02-26T20:06:07.398499Z","steps":["trace[1777569069] 'agreement among raft nodes before linearized reading' (duration: 349.64628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.398581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:07.048673Z","time spent":"349.898772ms","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-26T20:06:07.398605Z","caller":"traceutil/trace.go:171","msg":"trace[2078838992] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"348.515775ms","start":"2026-02-26T20:06:07.049525Z","end":"2026-02-26T20:06:07.39804Z","steps":["trace[2078838992] 'process raft request' (duration: 348.167685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.398339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:07.047861Z","time spent":"350.383718ms","remote":"127.0.0.1:41946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:07.398682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:07.049561Z","time spent":"348.909093ms","remote":"127.0.0.1:41816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T20:06:07.398447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.795897ms","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-26T20:06:07.398916Z","caller":"traceutil/trace.go:171","msg":"trace[1069296127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"154.276435ms","start":"2026-02-26T20:06:07.24462Z","end":"2026-02-26T20:06:07.398897Z","steps":["trace[1069296127] 'agreement among raft nodes before linearized reading' (duration: 153.755919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:07.398716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:07.049487Z","time spent":"349.163055ms","remote":"127.0.0.1:41946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:08.155958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.671283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-02-26T20:06:08.156247Z","caller":"traceutil/trace.go:171","msg":"trace[569615032] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:396; }","duration":"153.030043ms","start":"2026-02-26T20:06:08.003187Z","end":"2026-02-26T20:06:08.156217Z","steps":["trace[569615032] 'range keys from in-memory index tree' (duration: 152.529257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:08.155958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.515726ms","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-26T20:06:08.156491Z","caller":"traceutil/trace.go:171","msg":"trace[1877015892] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:396; }","duration":"175.133168ms","start":"2026-02-26T20:06:07.981339Z","end":"2026-02-26T20:06:08.156472Z","steps":["trace[1877015892] 'range keys from in-memory index tree' (duration: 174.307902ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:08.273197Z","caller":"traceutil/trace.go:171","msg":"trace[2112170541] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"111.071233ms","start":"2026-02-26T20:06:08.162097Z","end":"2026-02-26T20:06:08.273168Z","steps":["trace[2112170541] 'process raft request' (duration: 110.897458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:08.487882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.010141ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:06:08.488178Z","caller":"traceutil/trace.go:171","msg":"trace[772364567] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"297.458761ms","start":"2026-02-26T20:06:08.190687Z","end":"2026-02-26T20:06:08.488146Z","steps":["trace[772364567] 'process raft request' (duration: 195.109539ms)","trace[772364567] 'compare' (duration: 101.868006ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:08.953927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.982529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T20:06:08.953979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.175301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-m7g9c\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-02-26T20:06:08.954003Z","caller":"traceutil/trace.go:171","msg":"trace[66330685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"243.114935ms","start":"2026-02-26T20:06:08.71087Z","end":"2026-02-26T20:06:08.953985Z","steps":["trace[66330685] 'range keys from in-memory index tree' (duration: 242.842093ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:08.954041Z","caller":"traceutil/trace.go:171","msg":"trace[1890785957] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-m7g9c; range_end:; response_count:1; response_revision:399; }","duration":"298.272468ms","start":"2026-02-26T20:06:08.655748Z","end":"2026-02-26T20:06:08.954021Z","steps":["trace[1890785957] 'range keys from in-memory index tree' (duration: 298.075084ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:09.378486Z","caller":"traceutil/trace.go:171","msg":"trace[1864685741] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"259.244357ms","start":"2026-02-26T20:06:09.119206Z","end":"2026-02-26T20:06:09.37845Z","steps":["trace[1864685741] 'process raft request' (duration: 243.495115ms)","trace[1864685741] 'compare' (duration: 15.46692ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:09.611669Z","caller":"traceutil/trace.go:171","msg":"trace[48879146] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"148.323897ms","start":"2026-02-26T20:06:09.463311Z","end":"2026-02-26T20:06:09.611635Z","steps":["trace[48879146] 'process raft request' (duration: 140.910388ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:11.42354Z","caller":"traceutil/trace.go:171","msg":"trace[1257631399] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"120.783743ms","start":"2026-02-26T20:06:11.302724Z","end":"2026-02-26T20:06:11.423507Z","steps":["trace[1257631399] 'process raft request' (duration: 120.251216ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:11.550365Z","caller":"traceutil/trace.go:171","msg":"trace[1778233834] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"119.554764ms","start":"2026-02-26T20:06:11.430775Z","end":"2026-02-26T20:06:11.55033Z","steps":["trace[1778233834] 'read index received' (duration: 41.266511ms)","trace[1778233834] 'applied index is now lower than readState.Index' (duration: 78.286563ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:11.550788Z","caller":"traceutil/trace.go:171","msg":"trace[952821024] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"120.181198ms","start":"2026-02-26T20:06:11.430587Z","end":"2026-02-26T20:06:11.550769Z","steps":["trace[952821024] 'process raft request' (duration: 119.549554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:11.55105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.706078ms","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-26T20:06:11.551137Z","caller":"traceutil/trace.go:171","msg":"trace[1727164567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"117.880314ms","start":"2026-02-26T20:06:11.433217Z","end":"2026-02-26T20:06:11.551097Z","steps":["trace[1727164567] 'agreement among raft nodes before linearized reading' (duration: 117.680859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:11.551246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.440641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-02-26T20:06:11.553111Z","caller":"traceutil/trace.go:171","msg":"trace[630541939] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:408; }","duration":"122.386885ms","start":"2026-02-26T20:06:11.430709Z","end":"2026-02-26T20:06:11.553096Z","steps":["trace[630541939] 'agreement among raft nodes before linearized reading' (duration: 120.386623ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:11.725794Z","caller":"traceutil/trace.go:171","msg":"trace[814772178] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:420; }","duration":"109.964886ms","start":"2026-02-26T20:06:11.615793Z","end":"2026-02-26T20:06:11.725758Z","steps":["trace[814772178] 'read index received' (duration: 3.384878ms)","trace[814772178] 'applied index is now lower than readState.Index' (duration: 106.565768ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:11.726026Z","caller":"traceutil/trace.go:171","msg":"trace[1869189216] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"166.674191ms","start":"2026-02-26T20:06:11.559337Z","end":"2026-02-26T20:06:11.726011Z","steps":["trace[1869189216] 'process raft request' (duration: 166.324469ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:11.726119Z","caller":"traceutil/trace.go:171","msg":"trace[667071499] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"169.265719ms","start":"2026-02-26T20:06:11.556824Z","end":"2026-02-26T20:06:11.72609Z","steps":["trace[667071499] 'process raft request' (duration: 110.782255ms)","trace[667071499] 'compare' (duration: 57.868129ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:11.726258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.448024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T20:06:11.726274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.917338ms","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-26T20:06:11.728289Z","caller":"traceutil/trace.go:171","msg":"trace[1702140224] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:411; }","duration":"106.982718ms","start":"2026-02-26T20:06:11.621295Z","end":"2026-02-26T20:06:11.728278Z","steps":["trace[1702140224] 'agreement among raft nodes before linearized reading' (duration: 104.935347ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:11.729325Z","caller":"traceutil/trace.go:171","msg":"trace[1443713381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"111.160918ms","start":"2026-02-26T20:06:11.615747Z","end":"2026-02-26T20:06:11.726908Z","steps":["trace[1443713381] 'agreement among raft nodes before linearized reading' (duration: 110.427545ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:11.950878Z","caller":"traceutil/trace.go:171","msg":"trace[1802110875] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"211.139955ms","start":"2026-02-26T20:06:11.739702Z","end":"2026-02-26T20:06:11.950842Z","steps":["trace[1802110875] 'process raft request' (duration: 146.884342ms)","trace[1802110875] 'compare' (duration: 63.138469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:12.177005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.679087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:06:12.177193Z","caller":"traceutil/trace.go:171","msg":"trace[1963465052] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"214.015051ms","start":"2026-02-26T20:06:11.963077Z","end":"2026-02-26T20:06:12.177092Z","steps":["trace[1963465052] 'process raft request' (duration: 111.170368ms)","trace[1963465052] 'compare' (duration: 102.586219ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:12.542068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.1326ms","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-26T20:06:12.542256Z","caller":"traceutil/trace.go:171","msg":"trace[1507998836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"237.375665ms","start":"2026-02-26T20:06:12.304826Z","end":"2026-02-26T20:06:12.542201Z","steps":["trace[1507998836] 'range keys from in-memory index tree' (duration: 236.954774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:12.542485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.179166ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:12.542743Z","caller":"traceutil/trace.go:171","msg":"trace[722882786] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:414; }","duration":"174.387681ms","start":"2026-02-26T20:06:12.368254Z","end":"2026-02-26T20:06:12.542642Z","steps":["trace[722882786] 'range keys from in-memory index tree' (duration: 174.150037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:13.041388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.739436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:13.041688Z","caller":"traceutil/trace.go:171","msg":"trace[1177104889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"328.041589ms","start":"2026-02-26T20:06:12.713573Z","end":"2026-02-26T20:06:13.041615Z","steps":["trace[1177104889] 'range keys from in-memory index tree' (duration: 327.653408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:13.041838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:12.713544Z","time spent":"328.279365ms","remote":"127.0.0.1:41624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T20:06:13.678825Z","caller":"traceutil/trace.go:171","msg":"trace[219260830] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"119.128657ms","start":"2026-02-26T20:06:13.559678Z","end":"2026-02-26T20:06:13.678806Z","steps":["trace[219260830] 'process raft request' (duration: 118.923072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:14.154657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.199586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T20:06:14.154759Z","caller":"traceutil/trace.go:171","msg":"trace[125065400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"392.346852ms","start":"2026-02-26T20:06:13.76239Z","end":"2026-02-26T20:06:14.154737Z","steps":["trace[125065400] 'range keys from in-memory index tree' (duration: 392.105218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:14.154811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:13.762376Z","time spent":"392.424641ms","remote":"127.0.0.1:41868","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-26T20:06:14.678677Z","caller":"traceutil/trace.go:171","msg":"trace[1044566527] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"103.846697ms","start":"2026-02-26T20:06:14.574801Z","end":"2026-02-26T20:06:14.678648Z","steps":["trace[1044566527] 'read index received' (duration: 103.577783ms)","trace[1044566527] 'applied index is now lower than readState.Index' (duration: 268.004µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:14.678846Z","caller":"traceutil/trace.go:171","msg":"trace[1537764937] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"519.364605ms","start":"2026-02-26T20:06:14.159441Z","end":"2026-02-26T20:06:14.678806Z","steps":["trace[1537764937] 'process raft request' (duration: 519.012532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:14.679059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:14.159418Z","time spent":"519.527211ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:14.678881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.052373ms","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-26T20:06:14.679207Z","caller":"traceutil/trace.go:171","msg":"trace[1234673288] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"104.432776ms","start":"2026-02-26T20:06:14.574749Z","end":"2026-02-26T20:06:14.679182Z","steps":["trace[1234673288] 'agreement among raft nodes before linearized reading' (duration: 104.014884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:18.209081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:06:18.710067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:06:19.21026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:06:19.708339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000180654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-26T20:06:19.708455Z","caller":"traceutil/trace.go:171","msg":"trace[1137437213] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000339192s","start":"2026-02-26T20:06:17.708098Z","end":"2026-02-26T20:06:19.708437Z","steps":["trace[1137437213] 'agreement among raft nodes before linearized reading' (duration: 2.000208994s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:19.708507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:17.708082Z","time spent":"2.00041166s","remote":"127.0.0.1:41624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/26 20:06:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-26T20:06:19.710926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682023,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:06:19.868434Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.17148878s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T20:06:19.869021Z","caller":"traceutil/trace.go:171","msg":"trace[997696200] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"2.172127351s","start":"2026-02-26T20:06:17.696849Z","end":"2026-02-26T20:06:19.868976Z","steps":["trace[997696200] 'process raft request' (duration: 2.171855835s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:19.869309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:17.696834Z","time spent":"2.172321649s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:20.098303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.988827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:06:20.098453Z","caller":"traceutil/trace.go:171","msg":"trace[2133068831] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:438; }","duration":"2.39029105s","start":"2026-02-26T20:06:17.708136Z","end":"2026-02-26T20:06:20.098427Z","steps":["trace[2133068831] 'read index received' (duration: 2.160516607s)","trace[2133068831] 'applied index is now lower than readState.Index' (duration: 229.772433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:20.098715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301753983s","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-26T20:06:20.098795Z","caller":"traceutil/trace.go:171","msg":"trace[327146382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"1.301864102s","start":"2026-02-26T20:06:18.796905Z","end":"2026-02-26T20:06:20.098769Z","steps":["trace[327146382] 'agreement among raft nodes before linearized reading' (duration: 1.301736354s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:20.098835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:18.796888Z","time spent":"1.301938211s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T20:06:20.098759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.335424403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T20:06:20.098945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.905089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:20.098832Z","caller":"traceutil/trace.go:171","msg":"trace[970836891] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"394.456178ms","start":"2026-02-26T20:06:19.704309Z","end":"2026-02-26T20:06:20.098765Z","steps":["trace[970836891] 'process raft request' (duration: 236.894388ms)","trace[970836891] 'compare' (duration: 156.7472ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:20.099015Z","caller":"traceutil/trace.go:171","msg":"trace[1688052371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"2.335704559s","start":"2026-02-26T20:06:17.763281Z","end":"2026-02-26T20:06:20.098986Z","steps":["trace[1688052371] 'agreement among raft nodes before linearized reading' (duration: 2.335356354s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:20.099155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:17.763273Z","time spent":"2.335866917s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T20:06:20.098878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377277736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T20:06:20.099349Z","caller":"traceutil/trace.go:171","msg":"trace[890975719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"1.37779064s","start":"2026-02-26T20:06:18.721539Z","end":"2026-02-26T20:06:20.09933Z","steps":["trace[890975719] 'agreement among raft nodes before linearized reading' (duration: 1.377106708s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:20.099407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:18.721473Z","time spent":"1.377917258s","remote":"127.0.0.1:41868","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-26T20:06:20.09908Z","caller":"traceutil/trace.go:171","msg":"trace[1850608266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"390.110877ms","start":"2026-02-26T20:06:19.708942Z","end":"2026-02-26T20:06:20.099053Z","steps":["trace[1850608266] 'agreement among raft nodes before linearized reading' (duration: 389.793121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:20.099781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:19.70892Z","time spent":"390.851879ms","remote":"127.0.0.1:41632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:06:20.100265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:19.704281Z","time spent":"394.901443ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:06:30.12436Z","caller":"traceutil/trace.go:171","msg":"trace[848606637] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"203.732934ms","start":"2026-02-26T20:06:29.920612Z","end":"2026-02-26T20:06:30.124345Z","steps":["trace[848606637] 'process raft request' (duration: 203.606845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:36.761923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682227,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T20:06:36.964828Z","caller":"traceutil/trace.go:171","msg":"trace[1316772393] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"703.104141ms","start":"2026-02-26T20:06:36.261701Z","end":"2026-02-26T20:06:36.964805Z","steps":["trace[1316772393] 'read index received' (duration: 702.89108ms)","trace[1316772393] 'applied index is now lower than readState.Index' (duration: 212.091µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:36.964866Z","caller":"traceutil/trace.go:171","msg":"trace[1198368937] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"818.620785ms","start":"2026-02-26T20:06:36.146219Z","end":"2026-02-26T20:06:36.96484Z","steps":["trace[1198368937] 'process raft request' (duration: 818.403233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:36.965026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:36.146193Z","time spent":"818.738325ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:36.965067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.347702ms","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-26T20:06:36.965157Z","caller":"traceutil/trace.go:171","msg":"trace[523465528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"703.485364ms","start":"2026-02-26T20:06:36.261649Z","end":"2026-02-26T20:06:36.965135Z","steps":["trace[523465528] 'agreement among raft nodes before linearized reading' (duration: 703.293693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:36.9652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:36.261632Z","time spent":"703.559364ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T20:06:37.016114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.55212ms","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-26T20:06:37.016172Z","caller":"traceutil/trace.go:171","msg":"trace[903360473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"736.64765ms","start":"2026-02-26T20:06:36.27951Z","end":"2026-02-26T20:06:37.016158Z","steps":["trace[903360473] 'agreement among raft nodes before linearized reading' (duration: 736.421639ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:37.016211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:36.279492Z","time spent":"736.711701ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T20:06:37.01656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.623219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T20:06:37.01653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.78478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:37.016592Z","caller":"traceutil/trace.go:171","msg":"trace[1700145637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"732.65203ms","start":"2026-02-26T20:06:36.283929Z","end":"2026-02-26T20:06:37.016581Z","steps":["trace[1700145637] 'agreement among raft nodes before linearized reading' (duration: 732.569128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:37.016622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:36.283903Z","time spent":"732.71359ms","remote":"127.0.0.1:41868","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-26T20:06:37.016636Z","caller":"traceutil/trace.go:171","msg":"trace[1949168864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"305.953772ms","start":"2026-02-26T20:06:36.710658Z","end":"2026-02-26T20:06:37.016612Z","steps":["trace[1949168864] 'agreement among raft nodes before linearized reading' (duration: 305.74949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:37.016693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:36.710637Z","time spent":"306.047242ms","remote":"127.0.0.1:41624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T20:06:37.151965Z","caller":"traceutil/trace.go:171","msg":"trace[1404414725] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"126.32081ms","start":"2026-02-26T20:06:37.025621Z","end":"2026-02-26T20:06:37.151942Z","steps":["trace[1404414725] 'process raft request' (duration: 126.194659ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:41.184776Z","caller":"traceutil/trace.go:171","msg":"trace[1751378693] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"158.200527ms","start":"2026-02-26T20:06:41.02655Z","end":"2026-02-26T20:06:41.18475Z","steps":["trace[1751378693] 'read index received' (duration: 158.042026ms)","trace[1751378693] 'applied index is now lower than readState.Index' (duration: 157.211µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:41.184837Z","caller":"traceutil/trace.go:171","msg":"trace[2131783466] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"181.241434ms","start":"2026-02-26T20:06:41.003565Z","end":"2026-02-26T20:06:41.184807Z","steps":["trace[2131783466] 'process raft request' (duration: 181.028812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:41.18504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.475021ms","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-26T20:06:41.185085Z","caller":"traceutil/trace.go:171","msg":"trace[1488876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"158.575312ms","start":"2026-02-26T20:06:41.026495Z","end":"2026-02-26T20:06:41.18507Z","steps":["trace[1488876] 'agreement among raft nodes before linearized reading' (duration: 158.42201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:41.18513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.168712ms","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-26T20:06:41.185196Z","caller":"traceutil/trace.go:171","msg":"trace[1982457217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"123.262923ms","start":"2026-02-26T20:06:41.061914Z","end":"2026-02-26T20:06:41.185177Z","steps":["trace[1982457217] 'agreement among raft nodes before linearized reading' (duration: 123.083771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:41.185312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.726278ms","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-26T20:06:41.185352Z","caller":"traceutil/trace.go:171","msg":"trace[964455693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"122.794879ms","start":"2026-02-26T20:06:41.062546Z","end":"2026-02-26T20:06:41.185341Z","steps":["trace[964455693] 'agreement among raft nodes before linearized reading' (duration: 122.633127ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:44.632478Z","caller":"traceutil/trace.go:171","msg":"trace[1413202252] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"336.836993ms","start":"2026-02-26T20:06:44.295612Z","end":"2026-02-26T20:06:44.632449Z","steps":["trace[1413202252] 'process raft request' (duration: 336.707041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:44.632632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:44.295592Z","time spent":"336.953644ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:06:46.915958Z","caller":"traceutil/trace.go:171","msg":"trace[1938690005] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"205.505881ms","start":"2026-02-26T20:06:46.710429Z","end":"2026-02-26T20:06:46.915935Z","steps":["trace[1938690005] 'read index received' (duration: 205.320528ms)","trace[1938690005] 'applied index is now lower than readState.Index' (duration: 184.523µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:46.916094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.653193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:46.916086Z","caller":"traceutil/trace.go:171","msg":"trace[859398628] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.12230147s","start":"2026-02-26T20:06:45.793757Z","end":"2026-02-26T20:06:46.916058Z","steps":["trace[859398628] 'process raft request' (duration: 1.122022316s)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:46.916127Z","caller":"traceutil/trace.go:171","msg":"trace[1788684404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"205.723573ms","start":"2026-02-26T20:06:46.710392Z","end":"2026-02-26T20:06:46.916116Z","steps":["trace[1788684404] 'agreement among raft nodes before linearized reading' (duration: 205.631822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:46.916247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:45.793742Z","time spent":"1.122422161s","remote":"127.0.0.1:41810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:48.458459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110094361s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-26T20:06:48.458555Z","caller":"traceutil/trace.go:171","msg":"trace[1401363725] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:597; }","duration":"1.54066596s","start":"2026-02-26T20:06:46.917877Z","end":"2026-02-26T20:06:48.458543Z","steps":["trace[1401363725] 'read index received' (duration: 430.440228ms)","trace[1401363725] 'applied index is now lower than readState.Index' (duration: 1.110224832s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:06:48.458762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251162688s","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-26T20:06:48.459177Z","caller":"traceutil/trace.go:171","msg":"trace[614089429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"1.251234509s","start":"2026-02-26T20:06:47.207546Z","end":"2026-02-26T20:06:48.458781Z","steps":["trace[614089429] 'agreement among raft nodes before linearized reading' (duration: 1.251128698s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:48.459214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:47.207525Z","time spent":"1.251679916s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T20:06:48.459407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.22324622s","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-26T20:06:48.459438Z","caller":"traceutil/trace.go:171","msg":"trace[1481743571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"1.223315061s","start":"2026-02-26T20:06:47.236116Z","end":"2026-02-26T20:06:48.459432Z","steps":["trace[1481743571] 'agreement among raft nodes before linearized reading' (duration: 1.2232497s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:48.459453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:47.236095Z","time spent":"1.223353501s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T20:06:48.459575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223574315s","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-26T20:06:48.459595Z","caller":"traceutil/trace.go:171","msg":"trace[2089335662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"1.223617716s","start":"2026-02-26T20:06:47.235972Z","end":"2026-02-26T20:06:48.459589Z","steps":["trace[2089335662] 'agreement among raft nodes before linearized reading' (duration: 1.223565195s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:48.459573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223171709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T20:06:48.459598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541695696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:48.459793Z","caller":"traceutil/trace.go:171","msg":"trace[863629985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"1.54192699s","start":"2026-02-26T20:06:46.91784Z","end":"2026-02-26T20:06:48.459767Z","steps":["trace[863629985] 'agreement among raft nodes before linearized reading' (duration: 1.541694427s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:48.459891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:46.917823Z","time spent":"1.542017791s","remote":"127.0.0.1:41632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T20:06:48.459671Z","caller":"traceutil/trace.go:171","msg":"trace[45728095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"1.223308651s","start":"2026-02-26T20:06:47.236339Z","end":"2026-02-26T20:06:48.459648Z","steps":["trace[45728095] 'agreement among raft nodes before linearized reading' (duration: 1.222420848s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:48.460543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:47.236322Z","time spent":"1.224214166s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T20:06:48.459615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:47.235962Z","time spent":"1.223645296s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T20:06:48.648923Z","caller":"traceutil/trace.go:171","msg":"trace[1993864542] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:598; }","duration":"178.669721ms","start":"2026-02-26T20:06:48.470226Z","end":"2026-02-26T20:06:48.648895Z","steps":["trace[1993864542] 'read index received' (duration: 68.869344ms)","trace[1993864542] 'applied index is now lower than readState.Index' (duration: 109.799007ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:48.64899Z","caller":"traceutil/trace.go:171","msg":"trace[246702719] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"182.741025ms","start":"2026-02-26T20:06:48.466211Z","end":"2026-02-26T20:06:48.648952Z","steps":["trace[246702719] 'process raft request' (duration: 182.566892ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:48.649047Z","caller":"traceutil/trace.go:171","msg":"trace[436845587] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"182.911687ms","start":"2026-02-26T20:06:48.466092Z","end":"2026-02-26T20:06:48.649004Z","steps":["trace[436845587] 'process raft request' (duration: 103.966715ms)","trace[436845587] 'compare' (duration: 78.527645ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:48.64918Z","caller":"traceutil/trace.go:171","msg":"trace[2102185066] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"182.651323ms","start":"2026-02-26T20:06:48.466504Z","end":"2026-02-26T20:06:48.649156Z","steps":["trace[2102185066] 'process raft request' (duration: 182.337828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:48.649206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.813293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:48.649427Z","caller":"traceutil/trace.go:171","msg":"trace[1878759049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"179.214509ms","start":"2026-02-26T20:06:48.470195Z","end":"2026-02-26T20:06:48.64941Z","steps":["trace[1878759049] 'agreement among raft nodes before linearized reading' (duration: 178.807213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:51.153961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682343,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:06:51.654185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682343,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:06:52.155278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682343,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:06:52.655726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978682343,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:06:52.687249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.143316044s","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-26T20:06:52.687316Z","caller":"traceutil/trace.go:171","msg":"trace[321334453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"2.143380376s","start":"2026-02-26T20:06:50.543911Z","end":"2026-02-26T20:06:52.687291Z","steps":["trace[321334453] 'range keys from in-memory index tree' (duration: 2.143151081s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.687354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:50.543852Z","time spent":"2.143493769s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T20:06:52.687641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.542515ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:52.687726Z","caller":"traceutil/trace.go:171","msg":"trace[1694195321] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:582; }","duration":"318.629616ms","start":"2026-02-26T20:06:52.369083Z","end":"2026-02-26T20:06:52.687713Z","steps":["trace[1694195321] 'range keys from in-memory index tree' (duration: 318.531795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.687849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.974372379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:52.687907Z","caller":"traceutil/trace.go:171","msg":"trace[2099853395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"2.97446783s","start":"2026-02-26T20:06:49.713424Z","end":"2026-02-26T20:06:52.687892Z","steps":["trace[2099853395] 'range keys from in-memory index tree' (duration: 2.974285616s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.687936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:49.713399Z","time spent":"2.97453082s","remote":"127.0.0.1:41624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:06:52.845868Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.210555798s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T20:06:52.84623Z","caller":"traceutil/trace.go:171","msg":"trace[941039464] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"2.19322756s","start":"2026-02-26T20:06:50.652984Z","end":"2026-02-26T20:06:52.846211Z","steps":["trace[941039464] 'read index received' (duration: 2.193038116s)","trace[941039464] 'applied index is now lower than readState.Index' (duration: 188.834µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:52.846286Z","caller":"traceutil/trace.go:171","msg":"trace[1675338488] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"2.211077858s","start":"2026-02-26T20:06:50.635201Z","end":"2026-02-26T20:06:52.846279Z","steps":["trace[1675338488] 'process raft request' (duration: 2.210831963s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.846361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:50.635177Z","time spent":"2.211125258s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:06:52.846465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.193478544s","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-26T20:06:52.846507Z","caller":"traceutil/trace.go:171","msg":"trace[174925426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:583; }","duration":"2.193556115s","start":"2026-02-26T20:06:50.652943Z","end":"2026-02-26T20:06:52.846499Z","steps":["trace[174925426] 'agreement among raft nodes before linearized reading' (duration: 2.193439333s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.846555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:50.652923Z","time spent":"2.193625897s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T20:06:52.885734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171867574s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:06:52.88579Z","caller":"traceutil/trace.go:171","msg":"trace[1953972710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"1.171968597s","start":"2026-02-26T20:06:51.71381Z","end":"2026-02-26T20:06:52.885778Z","steps":["trace[1953972710] 'agreement among raft nodes before linearized reading' (duration: 1.171862115s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.885775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.23259287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T20:06:52.88582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:51.71379Z","time spent":"1.172023677s","remote":"127.0.0.1:41632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T20:06:52.885812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455242824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.220\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T20:06:52.885853Z","caller":"traceutil/trace.go:171","msg":"trace[511164537] range","detail":"{range_begin:/registry/masterleases/199.19.213.220; range_end:; response_count:1; response_revision:584; }","duration":"1.455347876s","start":"2026-02-26T20:06:51.430494Z","end":"2026-02-26T20:06:52.885842Z","steps":["trace[511164537] 'agreement among raft nodes before linearized reading' (duration: 1.455191603s)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:52.885854Z","caller":"traceutil/trace.go:171","msg":"trace[651753832] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"2.232718472s","start":"2026-02-26T20:06:50.653113Z","end":"2026-02-26T20:06:52.885832Z","steps":["trace[651753832] 'agreement among raft nodes before linearized reading' (duration: 2.232482547s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.885875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:51.430475Z","time spent":"1.455396106s","remote":"127.0.0.1:41654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.220\" "} {"level":"warn","ts":"2026-02-26T20:06:52.885909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:50.65309Z","time spent":"2.232808024s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T20:06:52.886025Z","caller":"traceutil/trace.go:171","msg":"trace[652274308] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"194.353217ms","start":"2026-02-26T20:06:52.691664Z","end":"2026-02-26T20:06:52.886017Z","steps":["trace[652274308] 'process raft request' (duration: 193.799187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.886158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.232999808s","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-26T20:06:52.886187Z","caller":"traceutil/trace.go:171","msg":"trace[1256904216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"2.233030978s","start":"2026-02-26T20:06:50.653149Z","end":"2026-02-26T20:06:52.88618Z","steps":["trace[1256904216] 'agreement among raft nodes before linearized reading' (duration: 2.232961797s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:52.886205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:06:50.653091Z","time spent":"2.23311036s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T20:06:55.01759Z","caller":"traceutil/trace.go:171","msg":"trace[1129075492] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"126.911606ms","start":"2026-02-26T20:06:54.890631Z","end":"2026-02-26T20:06:55.017542Z","steps":["trace[1129075492] 'read index received' (duration: 126.795803ms)","trace[1129075492] 'applied index is now lower than readState.Index' (duration: 114.963µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:06:55.01785Z","caller":"traceutil/trace.go:171","msg":"trace[1719654972] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"189.997255ms","start":"2026-02-26T20:06:54.827843Z","end":"2026-02-26T20:06:55.01784Z","steps":["trace[1719654972] 'process raft request' (duration: 189.29668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:55.018086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.446128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T20:06:55.018116Z","caller":"traceutil/trace.go:171","msg":"trace[1687277269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"127.522229ms","start":"2026-02-26T20:06:54.890585Z","end":"2026-02-26T20:06:55.018107Z","steps":["trace[1687277269] 'agreement among raft nodes before linearized reading' (duration: 127.407536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:55.021784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.778131ms","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-26T20:06:55.021875Z","caller":"traceutil/trace.go:171","msg":"trace[98062268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"108.902014ms","start":"2026-02-26T20:06:54.91295Z","end":"2026-02-26T20:06:55.021852Z","steps":["trace[98062268] 'agreement among raft nodes before linearized reading' (duration: 108.689669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:55.021894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.069777ms","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-26T20:06:55.021925Z","caller":"traceutil/trace.go:171","msg":"trace[914141894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"108.130088ms","start":"2026-02-26T20:06:54.913787Z","end":"2026-02-26T20:06:55.021917Z","steps":["trace[914141894] 'agreement among raft nodes before linearized reading' (duration: 108.005896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:06:55.022034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.703419ms","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-26T20:06:55.02206Z","caller":"traceutil/trace.go:171","msg":"trace[214688420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"108.762411ms","start":"2026-02-26T20:06:54.913289Z","end":"2026-02-26T20:06:55.022052Z","steps":["trace[214688420] 'agreement among raft nodes before linearized reading' (duration: 108.691599ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:59.295197Z","caller":"traceutil/trace.go:171","msg":"trace[1825604296] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"250.096864ms","start":"2026-02-26T20:06:59.045065Z","end":"2026-02-26T20:06:59.295162Z","steps":["trace[1825604296] 'process raft request' (duration: 247.032751ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:59.30326Z","caller":"traceutil/trace.go:171","msg":"trace[1877073282] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"255.905793ms","start":"2026-02-26T20:06:59.047316Z","end":"2026-02-26T20:06:59.303222Z","steps":["trace[1877073282] 'process raft request' (duration: 255.458913ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:59.303744Z","caller":"traceutil/trace.go:171","msg":"trace[213199609] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"208.719831ms","start":"2026-02-26T20:06:59.095006Z","end":"2026-02-26T20:06:59.303726Z","steps":["trace[213199609] 'process raft request' (duration: 207.913672ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:59.304102Z","caller":"traceutil/trace.go:171","msg":"trace[497313731] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"256.687311ms","start":"2026-02-26T20:06:59.047388Z","end":"2026-02-26T20:06:59.304076Z","steps":["trace[497313731] 'process raft request' (duration: 255.413021ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:06:59.304142Z","caller":"traceutil/trace.go:171","msg":"trace[82644874] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"258.956945ms","start":"2026-02-26T20:06:59.045156Z","end":"2026-02-26T20:06:59.304113Z","steps":["trace[82644874] 'process raft request' (duration: 257.519601ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:07:03.464349Z","caller":"traceutil/trace.go:171","msg":"trace[541303194] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"127.395259ms","start":"2026-02-26T20:07:03.336926Z","end":"2026-02-26T20:07:03.464322Z","steps":["trace[541303194] 'process raft request' (duration: 127.178922ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:07:07.63776Z","caller":"traceutil/trace.go:171","msg":"trace[142304612] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"161.202722ms","start":"2026-02-26T20:07:07.476522Z","end":"2026-02-26T20:07:07.637725Z","steps":["trace[142304612] 'read index received' (duration: 86.039268ms)","trace[142304612] 'applied index is now lower than readState.Index' (duration: 75.162194ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:07:07.637837Z","caller":"traceutil/trace.go:171","msg":"trace[2140343627] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"202.850022ms","start":"2026-02-26T20:07:07.434966Z","end":"2026-02-26T20:07:07.637816Z","steps":["trace[2140343627] 'process raft request' (duration: 127.607795ms)","trace[2140343627] 'compare' (duration: 75.037951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:07:07.638102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.524061ms","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-26T20:07:07.638167Z","caller":"traceutil/trace.go:171","msg":"trace[892642843] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"161.682005ms","start":"2026-02-26T20:07:07.476469Z","end":"2026-02-26T20:07:07.638151Z","steps":["trace[892642843] 'agreement among raft nodes before linearized reading' (duration: 161.387007ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:07:13.80146Z","caller":"traceutil/trace.go:171","msg":"trace[1579181693] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"134.449807ms","start":"2026-02-26T20:07:13.666987Z","end":"2026-02-26T20:07:13.801437Z","steps":["trace[1579181693] 'process raft request' (duration: 134.310011ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:07:13.809609Z","caller":"traceutil/trace.go:171","msg":"trace[2099633648] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"140.695657ms","start":"2026-02-26T20:07:13.668889Z","end":"2026-02-26T20:07:13.809584Z","steps":["trace[2099633648] 'process raft request' (duration: 140.572243ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:07:31.564251Z","caller":"traceutil/trace.go:171","msg":"trace[1087305831] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"106.542335ms","start":"2026-02-26T20:07:31.457684Z","end":"2026-02-26T20:07:31.564226Z","steps":["trace[1087305831] 'read index received' (duration: 51.821824ms)","trace[1087305831] 'applied index is now lower than readState.Index' (duration: 54.719481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:07:31.564498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.802632ms","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-26T20:07:31.564537Z","caller":"traceutil/trace.go:171","msg":"trace[3893219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:841; }","duration":"100.872495ms","start":"2026-02-26T20:07:31.463655Z","end":"2026-02-26T20:07:31.564528Z","steps":["trace[3893219] 'agreement among raft nodes before linearized reading' (duration: 100.708338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:07:31.564829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.125798ms","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-26T20:07:31.564911Z","caller":"traceutil/trace.go:171","msg":"trace[1764900611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:841; }","duration":"107.244183ms","start":"2026-02-26T20:07:31.457653Z","end":"2026-02-26T20:07:31.564897Z","steps":["trace[1764900611] 'agreement among raft nodes before linearized reading' (duration: 106.67842ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:07:31.565279Z","caller":"traceutil/trace.go:171","msg":"trace[751901993] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"122.21048ms","start":"2026-02-26T20:07:31.442054Z","end":"2026-02-26T20:07:31.564265Z","steps":["trace[751901993] 'process raft request' (duration: 67.534231ms)","trace[751901993] 'compare' (duration: 54.527623ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:07:32.256601Z","caller":"traceutil/trace.go:171","msg":"trace[692993563] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"374.298054ms","start":"2026-02-26T20:07:31.882278Z","end":"2026-02-26T20:07:32.256576Z","steps":["trace[692993563] 'process raft request' (duration: 374.157989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:07:32.256769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:07:31.882264Z","time spent":"374.42265ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:07:32.260675Z","caller":"traceutil/trace.go:171","msg":"trace[159527879] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:876; }","duration":"183.585386ms","start":"2026-02-26T20:07:32.077067Z","end":"2026-02-26T20:07:32.260652Z","steps":["trace[159527879] 'read index received' (duration: 179.460026ms)","trace[159527879] 'applied index is now lower than readState.Index' (duration: 4.12447ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:07:32.261237Z","caller":"traceutil/trace.go:171","msg":"trace[1888222114] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"377.691235ms","start":"2026-02-26T20:07:31.883028Z","end":"2026-02-26T20:07:32.260719Z","steps":["trace[1888222114] 'process raft request' (duration: 377.504337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:07:32.261336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.260673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T20:07:32.261432Z","caller":"traceutil/trace.go:171","msg":"trace[1782075486] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:846; }","duration":"184.358848ms","start":"2026-02-26T20:07:32.077024Z","end":"2026-02-26T20:07:32.261383Z","steps":["trace[1782075486] 'agreement among raft nodes before linearized reading' (duration: 184.156749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:07:32.261466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:07:31.883008Z","time spent":"378.337211ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:07:40.480853Z","caller":"traceutil/trace.go:171","msg":"trace[242082242] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"126.546656ms","start":"2026-02-26T20:07:40.354281Z","end":"2026-02-26T20:07:40.480828Z","steps":["trace[242082242] 'read index received' (duration: 126.245993ms)","trace[242082242] 'applied index is now lower than readState.Index' (duration: 299.623µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:07:40.481108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.811647ms","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-26T20:07:40.481183Z","caller":"traceutil/trace.go:171","msg":"trace[5526568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:868; }","duration":"126.906861ms","start":"2026-02-26T20:07:40.354265Z","end":"2026-02-26T20:07:40.481172Z","steps":["trace[5526568] 'agreement among raft nodes before linearized reading' (duration: 126.660361ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:07:40.481345Z","caller":"traceutil/trace.go:171","msg":"trace[513667276] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"128.102704ms","start":"2026-02-26T20:07:40.353193Z","end":"2026-02-26T20:07:40.481296Z","steps":["trace[513667276] 'process raft request' (duration: 127.422384ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:07:43.798686Z","caller":"traceutil/trace.go:171","msg":"trace[960255023] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"113.77297ms","start":"2026-02-26T20:07:43.684885Z","end":"2026-02-26T20:07:43.798658Z","steps":["trace[960255023] 'read index received' (duration: 96.140177ms)","trace[960255023] 'applied index is now lower than readState.Index' (duration: 17.631593ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:07:43.798786Z","caller":"traceutil/trace.go:171","msg":"trace[551937630] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"124.893571ms","start":"2026-02-26T20:07:43.673869Z","end":"2026-02-26T20:07:43.798762Z","steps":["trace[551937630] 'process raft request' (duration: 107.163083ms)","trace[551937630] 'compare' (duration: 17.435645ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:07:43.798947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.049014ms","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-26T20:07:43.798987Z","caller":"traceutil/trace.go:171","msg":"trace[1870181225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"114.123598ms","start":"2026-02-26T20:07:43.68485Z","end":"2026-02-26T20:07:43.798974Z","steps":["trace[1870181225] 'agreement among raft nodes before linearized reading' (duration: 113.957289ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:08:02.726604Z","caller":"traceutil/trace.go:171","msg":"trace[8284132] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"111.8732ms","start":"2026-02-26T20:08:02.614713Z","end":"2026-02-26T20:08:02.726586Z","steps":["trace[8284132] 'process raft request' (duration: 111.439288ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:08:03.277762Z","caller":"traceutil/trace.go:171","msg":"trace[1748157455] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"192.484969ms","start":"2026-02-26T20:08:03.085246Z","end":"2026-02-26T20:08:03.277731Z","steps":["trace[1748157455] 'read index received' (duration: 192.224936ms)","trace[1748157455] 'applied index is now lower than readState.Index' (duration: 255.803µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:08:03.277819Z","caller":"traceutil/trace.go:171","msg":"trace[1555377648] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"198.40679ms","start":"2026-02-26T20:08:03.079383Z","end":"2026-02-26T20:08:03.27779Z","steps":["trace[1555377648] 'process raft request' (duration: 198.163808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:03.277916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.638907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:08:03.277958Z","caller":"traceutil/trace.go:171","msg":"trace[1841142037] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:947; }","duration":"192.720251ms","start":"2026-02-26T20:08:03.085226Z","end":"2026-02-26T20:08:03.277946Z","steps":["trace[1841142037] 'agreement among raft nodes before linearized reading' (duration: 192.621426ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:08:04.022155Z","caller":"traceutil/trace.go:171","msg":"trace[1488940274] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"258.196857ms","start":"2026-02-26T20:08:03.763931Z","end":"2026-02-26T20:08:04.022127Z","steps":["trace[1488940274] 'process raft request' (duration: 257.987837ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:08:04.079841Z","caller":"traceutil/trace.go:171","msg":"trace[1010801844] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:986; }","duration":"192.461195ms","start":"2026-02-26T20:08:03.887353Z","end":"2026-02-26T20:08:04.079814Z","steps":["trace[1010801844] 'read index received' (duration: 134.565403ms)","trace[1010801844] 'applied index is now lower than readState.Index' (duration: 57.894922ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:08:04.080149Z","caller":"traceutil/trace.go:171","msg":"trace[1000625329] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"260.1512ms","start":"2026-02-26T20:08:03.819983Z","end":"2026-02-26T20:08:04.080135Z","steps":["trace[1000625329] 'process raft request' (duration: 259.669346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:04.080437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.066557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T20:08:04.080468Z","caller":"traceutil/trace.go:171","msg":"trace[1633700679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"193.14371ms","start":"2026-02-26T20:08:03.887315Z","end":"2026-02-26T20:08:04.080459Z","steps":["trace[1633700679] 'agreement among raft nodes before linearized reading' (duration: 193.014063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:04.080514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.597833ms","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-26T20:08:04.08054Z","caller":"traceutil/trace.go:171","msg":"trace[1540958857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:949; }","duration":"153.638705ms","start":"2026-02-26T20:08:03.926893Z","end":"2026-02-26T20:08:04.080532Z","steps":["trace[1540958857] 'agreement among raft nodes before linearized reading' (duration: 153.559571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:04.080609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.326034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:08:04.080627Z","caller":"traceutil/trace.go:171","msg":"trace[1634267887] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:949; }","duration":"121.403738ms","start":"2026-02-26T20:08:03.959216Z","end":"2026-02-26T20:08:04.08062Z","steps":["trace[1634267887] 'agreement among raft nodes before linearized reading' (duration: 121.373296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:04.50278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.277417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T20:08:04.50292Z","caller":"traceutil/trace.go:171","msg":"trace[542653851] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"418.089371ms","start":"2026-02-26T20:08:04.084812Z","end":"2026-02-26T20:08:04.502902Z","steps":["trace[542653851] 'process raft request' (duration: 33.610194ms)","trace[542653851] 'compare' (duration: 384.202863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:08:04.502968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:08:04.08479Z","time spent":"418.156255ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:08:04.503071Z","caller":"traceutil/trace.go:171","msg":"trace[744933509] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:988; }","duration":"301.825573ms","start":"2026-02-26T20:08:04.201221Z","end":"2026-02-26T20:08:04.503046Z","steps":["trace[744933509] 'read index received' (duration: 301.096316ms)","trace[744933509] 'applied index is now lower than readState.Index' (duration: 728.167µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T20:08:04.503254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.017493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T20:08:04.50329Z","caller":"traceutil/trace.go:171","msg":"trace[531462764] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:951; }","duration":"302.112907ms","start":"2026-02-26T20:08:04.201166Z","end":"2026-02-26T20:08:04.503279Z","steps":["trace[531462764] 'agreement among raft nodes before linearized reading' (duration: 301.96637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:04.503322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:08:04.201149Z","time spent":"302.16418ms","remote":"127.0.0.1:41712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":114,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-26T20:08:04.503316Z","caller":"traceutil/trace.go:171","msg":"trace[920364590] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"418.297992ms","start":"2026-02-26T20:08:04.084956Z","end":"2026-02-26T20:08:04.503254Z","steps":["trace[920364590] 'process raft request' (duration: 417.912073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:04.503402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:08:04.084948Z","time spent":"418.412748ms","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T20:08:04.987045Z","caller":"traceutil/trace.go:171","msg":"trace[1150312412] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"210.542587ms","start":"2026-02-26T20:08:04.776477Z","end":"2026-02-26T20:08:04.987019Z","steps":["trace[1150312412] 'process raft request' (duration: 210.481263ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:08:04.987253Z","caller":"traceutil/trace.go:171","msg":"trace[1152456947] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"211.874305ms","start":"2026-02-26T20:08:04.775367Z","end":"2026-02-26T20:08:04.987241Z","steps":["trace[1152456947] 'process raft request' (duration: 136.438818ms)","trace[1152456947] 'compare' (duration: 74.91074ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:08:05.194987Z","caller":"traceutil/trace.go:171","msg":"trace[901492503] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"103.774656ms","start":"2026-02-26T20:08:05.091186Z","end":"2026-02-26T20:08:05.194961Z","steps":["trace[901492503] 'process raft request' (duration: 103.614678ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:08:13.133623Z","caller":"traceutil/trace.go:171","msg":"trace[1228284646] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"108.430698ms","start":"2026-02-26T20:08:13.025155Z","end":"2026-02-26T20:08:13.133586Z","steps":["trace[1228284646] 'process raft request' (duration: 102.837192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:16.950225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.640076ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-26T20:08:18.211149Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5450372165978683357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T20:08:18.279491Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125779904s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T20:08:18.279922Z","caller":"traceutil/trace.go:171","msg":"trace[2136560048] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"569.090593ms","start":"2026-02-26T20:08:17.710812Z","end":"2026-02-26T20:08:18.279903Z","steps":["trace[2136560048] 'read index received' (duration: 568.972436ms)","trace[2136560048] 'applied index is now lower than readState.Index' (duration: 117.467µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:08:18.280061Z","caller":"traceutil/trace.go:171","msg":"trace[451980234] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.126416348s","start":"2026-02-26T20:08:17.1536Z","end":"2026-02-26T20:08:18.280017Z","steps":["trace[451980234] 'process raft request' (duration: 1.126130163s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:18.280282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:08:17.153585Z","time spent":"1.126564646s","remote":"127.0.0.1:41868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T20:08:18.280343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.271426ms","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-26T20:08:18.280399Z","caller":"traceutil/trace.go:171","msg":"trace[1112982314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"197.3541ms","start":"2026-02-26T20:08:18.083027Z","end":"2026-02-26T20:08:18.280381Z","steps":["trace[1112982314] 'agreement among raft nodes before linearized reading' (duration: 197.140269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:18.280787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.973769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T20:08:18.280854Z","caller":"traceutil/trace.go:171","msg":"trace[1656774009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"570.077245ms","start":"2026-02-26T20:08:17.710759Z","end":"2026-02-26T20:08:18.280837Z","steps":["trace[1656774009] 'agreement among raft nodes before linearized reading' (duration: 569.971669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:18.280999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T20:08:17.71074Z","time spent":"570.181691ms","remote":"127.0.0.1:41624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T20:08:32.468624Z","caller":"traceutil/trace.go:171","msg":"trace[613118013] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"124.563521ms","start":"2026-02-26T20:08:32.344036Z","end":"2026-02-26T20:08:32.4686Z","steps":["trace[613118013] 'read index received' (duration: 124.340907ms)","trace[613118013] 'applied index is now lower than readState.Index' (duration: 221.594µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T20:08:32.468684Z","caller":"traceutil/trace.go:171","msg":"trace[1259171543] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"135.00757ms","start":"2026-02-26T20:08:32.333643Z","end":"2026-02-26T20:08:32.46865Z","steps":["trace[1259171543] 'process raft request' (duration: 134.761336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:32.468819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.761749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"warn","ts":"2026-02-26T20:08:32.468848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.791963ms","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-26T20:08:32.468924Z","caller":"traceutil/trace.go:171","msg":"trace[774105767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"124.928671ms","start":"2026-02-26T20:08:32.343982Z","end":"2026-02-26T20:08:32.46891Z","steps":["trace[774105767] 'agreement among raft nodes before linearized reading' (duration: 124.717309ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T20:08:32.468864Z","caller":"traceutil/trace.go:171","msg":"trace[295976514] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:1052; }","duration":"122.884876ms","start":"2026-02-26T20:08:32.345967Z","end":"2026-02-26T20:08:32.468852Z","steps":["trace[295976514] 'agreement among raft nodes before linearized reading' (duration: 122.77407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T20:08:32.469256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.591609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-02-26T20:08:32.469319Z","caller":"traceutil/trace.go:171","msg":"trace[1077215223] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:1052; }","duration":"122.670983ms","start":"2026-02-26T20:08:32.346632Z","end":"2026-02-26T20:08:32.469303Z","steps":["trace[1077215223] 'agreement among raft nodes before linearized reading' (duration: 122.148234ms)"],"step_count":1}