{"level":"warn","ts":"2026-02-18T22:53:52.626813Z","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-18T22:53:52.626894Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.41:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.41:2380","--initial-cluster=instance=https://199.204.45.41:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.41:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.41: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-18T22:53:52.627388Z","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-18T22:53:52.629009Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-18T22:53:52.629041Z","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-18T22:53:52.631382Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"]} {"level":"info","ts":"2026-02-18T22:53:52.632826Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.41: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-18T22:53:52.643373Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.039506ms"} {"level":"info","ts":"2026-02-18T22:53:52.673317Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"17dd22a2661aae79","cluster-id":"c5133f40b27eeb3"} {"level":"info","ts":"2026-02-18T22:53:52.673448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T22:53:52.673484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 0"} {"level":"info","ts":"2026-02-18T22:53:52.6735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 17dd22a2661aae79 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T22:53:52.673509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became follower at term 1"} {"level":"info","ts":"2026-02-18T22:53:52.673545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"warn","ts":"2026-02-18T22:53:52.691342Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T22:53:52.69368Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T22:53:52.695377Z","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-18T22:53:52.697634Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"17dd22a2661aae79","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T22:53:52.704766Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"17dd22a2661aae79","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T22:53:52.711506Z","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-18T22:53:52.711543Z","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-18T22:53:52.711623Z","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-18T22:53:52.714715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 switched to configuration voters=(1719568713618468473)"} {"level":"info","ts":"2026-02-18T22:53:52.714843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","added-peer-id":"17dd22a2661aae79","added-peer-peer-urls":["https://199.204.45.41:2380"]} {"level":"info","ts":"2026-02-18T22:53:52.715308Z","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-18T22:53:52.715615Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"17dd22a2661aae79","initial-advertise-peer-urls":["https://199.204.45.41:2380"],"listen-peer-urls":["https://199.204.45.41:2380"],"advertise-client-urls":["https://199.204.45.41:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.41:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T22:53:52.71564Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T22:53:52.716109Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-18T22:53:52.716124Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.41:2380"} {"level":"info","ts":"2026-02-18T22:53:53.27714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T22:53:53.27718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T22:53:53.277195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgPreVoteResp from 17dd22a2661aae79 at term 1"} {"level":"info","ts":"2026-02-18T22:53:53.277211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became candidate at term 2"} {"level":"info","ts":"2026-02-18T22:53:53.277217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 received MsgVoteResp from 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-18T22:53:53.277235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"17dd22a2661aae79 became leader at term 2"} {"level":"info","ts":"2026-02-18T22:53:53.277247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 17dd22a2661aae79 elected leader 17dd22a2661aae79 at term 2"} {"level":"info","ts":"2026-02-18T22:53:53.285545Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"17dd22a2661aae79","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.41:2379]}","request-path":"/0/members/17dd22a2661aae79/attributes","cluster-id":"c5133f40b27eeb3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T22:53:53.28677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:53:53.286843Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T22:53:53.287091Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:53.287185Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T22:53:53.2872Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T22:53:53.305107Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.41:2379"} {"level":"info","ts":"2026-02-18T22:53:53.305195Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c5133f40b27eeb3","local-member-id":"17dd22a2661aae79","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:53.305245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:53.30526Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T22:53:53.314749Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-18T22:53:56.36554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344304337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:53:56.865917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344304337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:53:57.366861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344304337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:53:57.867685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344304337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:53:57.984473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000469144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-18T22:53:57.984574Z","caller":"traceutil/trace.go:171","msg":"trace[2119733110] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000645258s","start":"2026-02-18T22:53:55.98391Z","end":"2026-02-18T22:53:57.984555Z","steps":["trace[2119733110] 'agreement among raft nodes before linearized reading' (duration: 2.000497905s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:57.984625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:55.983893Z","time spent":"2.00072241s","remote":"127.0.0.1:55840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T22:53:58.368439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344304337,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:53:58.602941Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.73942173s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:53:58.603191Z","caller":"traceutil/trace.go:171","msg":"trace[952921536] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"2.737819679s","start":"2026-02-18T22:53:55.865355Z","end":"2026-02-18T22:53:58.603175Z","steps":["trace[952921536] 'read index received' (duration: 2.737690306s)","trace[952921536] 'applied index is now lower than readState.Index' (duration: 128.713µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:58.603343Z","caller":"traceutil/trace.go:171","msg":"trace[707153664] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"2.739924953s","start":"2026-02-18T22:53:55.863409Z","end":"2026-02-18T22:53:58.603334Z","steps":["trace[707153664] 'process raft request' (duration: 2.739681047s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:58.603689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:55.863393Z","time spent":"2.739998125s","remote":"127.0.0.1:56220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:53:58.603779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44149795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:430"} {"level":"info","ts":"2026-02-18T22:53:58.6038Z","caller":"traceutil/trace.go:171","msg":"trace[916262719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:76; }","duration":"1.441561082s","start":"2026-02-18T22:53:57.162233Z","end":"2026-02-18T22:53:58.603794Z","steps":["trace[916262719] 'agreement among raft nodes before linearized reading' (duration: 1.441445039s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:58.603815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.162209Z","time spent":"1.441602652s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":452,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:53:58.603893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367039868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-18T22:53:58.6039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438682578s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-18T22:53:58.603913Z","caller":"traceutil/trace.go:171","msg":"trace[1145499434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:76; }","duration":"1.43872548s","start":"2026-02-18T22:53:57.165183Z","end":"2026-02-18T22:53:58.603909Z","steps":["trace[1145499434] 'agreement among raft nodes before linearized reading' (duration: 1.438701019s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:58.603924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.165165Z","time spent":"1.43875647s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-18T22:53:58.603916Z","caller":"traceutil/trace.go:171","msg":"trace[337862134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"1.367090969s","start":"2026-02-18T22:53:57.236816Z","end":"2026-02-18T22:53:58.603907Z","steps":["trace[337862134] 'agreement among raft nodes before linearized reading' (duration: 1.367058188s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:58.60395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.236801Z","time spent":"1.36714512s","remote":"127.0.0.1:55840","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-18T22:53:58.604064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.738715282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-18T22:53:58.60407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.391339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T22:53:58.604087Z","caller":"traceutil/trace.go:171","msg":"trace[131219292] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:76; }","duration":"2.738757994s","start":"2026-02-18T22:53:55.865322Z","end":"2026-02-18T22:53:58.60408Z","steps":["trace[131219292] 'agreement among raft nodes before linearized reading' (duration: 2.738725503s)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:53:58.6041Z","caller":"traceutil/trace.go:171","msg":"trace[725535534] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"619.45562ms","start":"2026-02-18T22:53:57.984639Z","end":"2026-02-18T22:53:58.604095Z","steps":["trace[725535534] 'agreement among raft nodes before linearized reading' (duration: 619.418939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:58.604106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:55.86531Z","time spent":"2.738792074s","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/system:aggregate-to-edit\" "} {"level":"warn","ts":"2026-02-18T22:53:58.604112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:57.9846Z","time spent":"619.508542ms","remote":"127.0.0.1:55854","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-18T22:53:59.109063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344304343,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:53:59.270581Z","caller":"traceutil/trace.go:171","msg":"trace[1182439880] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"662.072665ms","start":"2026-02-18T22:53:58.608486Z","end":"2026-02-18T22:53:59.270558Z","steps":["trace[1182439880] 'process raft request' (duration: 656.234707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.270709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:58.608475Z","time spent":"662.171067ms","remote":"127.0.0.1:56220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T22:53:59.273945Z","caller":"traceutil/trace.go:171","msg":"trace[1203467389] linearizableReadLoop","detail":"{readStateIndex:83; appliedIndex:80; }","duration":"665.284466ms","start":"2026-02-18T22:53:58.608643Z","end":"2026-02-18T22:53:59.273927Z","steps":["trace[1203467389] 'read index received' (duration: 656.083973ms)","trace[1203467389] 'applied index is now lower than readState.Index' (duration: 9.199903ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:53:59.274151Z","caller":"traceutil/trace.go:171","msg":"trace[438684945] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"664.401293ms","start":"2026-02-18T22:53:58.609741Z","end":"2026-02-18T22:53:59.274143Z","steps":["trace[438684945] 'process raft request' (duration: 664.097435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.275264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:58.609732Z","time spent":"664.446434ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:59.275418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.554421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T22:53:59.275464Z","caller":"traceutil/trace.go:171","msg":"trace[1571845128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:79; }","duration":"591.632064ms","start":"2026-02-18T22:53:58.683819Z","end":"2026-02-18T22:53:59.275451Z","steps":["trace[1571845128] 'agreement among raft nodes before linearized reading' (duration: 591.510781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.275506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:58.683808Z","time spent":"591.680585ms","remote":"127.0.0.1:55840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T22:53:59.275591Z","caller":"traceutil/trace.go:171","msg":"trace[2059740254] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"664.586577ms","start":"2026-02-18T22:53:58.610981Z","end":"2026-02-18T22:53:59.275567Z","steps":["trace[2059740254] 'process raft request' (duration: 662.913726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.275635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:58.610972Z","time spent":"664.639889ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:53:59.275664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.01604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-18T22:53:59.275702Z","caller":"traceutil/trace.go:171","msg":"trace[2091359840] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:79; }","duration":"667.05578ms","start":"2026-02-18T22:53:58.608627Z","end":"2026-02-18T22:53:59.275682Z","steps":["trace[2091359840] 'agreement among raft nodes before linearized reading' (duration: 667.014389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:53:59.275728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:53:58.608618Z","time spent":"667.103701ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-02-18T22:54:25.465363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.562749ms","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:5"} {"level":"info","ts":"2026-02-18T22:54:25.466626Z","caller":"traceutil/trace.go:171","msg":"trace[1355926690] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:479; }","duration":"128.860352ms","start":"2026-02-18T22:54:25.337745Z","end":"2026-02-18T22:54:25.466605Z","steps":["trace[1355926690] 'range keys from in-memory index tree' (duration: 127.515317ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:27.993637Z","caller":"traceutil/trace.go:171","msg":"trace[1248364780] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"479.384102ms","start":"2026-02-18T22:54:27.514231Z","end":"2026-02-18T22:54:27.993615Z","steps":["trace[1248364780] 'read index received' (duration: 479.150476ms)","trace[1248364780] 'applied index is now lower than readState.Index' (duration: 232.726µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:27.993785Z","caller":"traceutil/trace.go:171","msg":"trace[1482107986] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"752.439538ms","start":"2026-02-18T22:54:27.241334Z","end":"2026-02-18T22:54:27.993773Z","steps":["trace[1482107986] 'process raft request' (duration: 752.068328ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:27.993902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:27.241324Z","time spent":"752.4888ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:27.99397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.249218ms","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-18T22:54:27.99402Z","caller":"traceutil/trace.go:171","msg":"trace[629586907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:518; }","duration":"451.32398ms","start":"2026-02-18T22:54:27.542681Z","end":"2026-02-18T22:54:27.994005Z","steps":["trace[629586907] 'agreement among raft nodes before linearized reading' (duration: 451.244018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:27.994052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:27.542653Z","time spent":"451.391731ms","remote":"127.0.0.1:56108","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-18T22:54:27.994138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.907234ms","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-18T22:54:27.994163Z","caller":"traceutil/trace.go:171","msg":"trace[244426209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"479.965676ms","start":"2026-02-18T22:54:27.514189Z","end":"2026-02-18T22:54:27.994154Z","steps":["trace[244426209] 'agreement among raft nodes before linearized reading' (duration: 479.927505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:27.994186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:27.514168Z","time spent":"480.012807ms","remote":"127.0.0.1:56108","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-18T22:54:28.45385Z","caller":"traceutil/trace.go:171","msg":"trace[1012176772] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"451.126236ms","start":"2026-02-18T22:54:28.002692Z","end":"2026-02-18T22:54:28.453818Z","steps":["trace[1012176772] 'process raft request' (duration: 451.038504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:28.454007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:28.002664Z","time spent":"451.260149ms","remote":"127.0.0.1:56108","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-18T22:54:28.454319Z","caller":"traceutil/trace.go:171","msg":"trace[2037610223] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"454.075099ms","start":"2026-02-18T22:54:28.000223Z","end":"2026-02-18T22:54:28.454298Z","steps":["trace[2037610223] 'process raft request' (duration: 392.388317ms)","trace[2037610223] 'compare' (duration: 61.017226ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:28.454436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:28.000202Z","time spent":"454.169472ms","remote":"127.0.0.1:56108","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-18T22:54:29.592734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344305252,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:54:29.792709Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.277099903s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:54:29.792969Z","caller":"traceutil/trace.go:171","msg":"trace[260821490] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"700.990612ms","start":"2026-02-18T22:54:29.091955Z","end":"2026-02-18T22:54:29.792945Z","steps":["trace[260821490] 'read index received' (duration: 700.846609ms)","trace[260821490] 'applied index is now lower than readState.Index' (duration: 142.883µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:29.793059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.089444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:29.793087Z","caller":"traceutil/trace.go:171","msg":"trace[11520832] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:522; }","duration":"701.164586ms","start":"2026-02-18T22:54:29.091914Z","end":"2026-02-18T22:54:29.793079Z","steps":["trace[11520832] 'agreement among raft nodes before linearized reading' (duration: 701.104735ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:29.79319Z","caller":"traceutil/trace.go:171","msg":"trace[880906458] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.329419021s","start":"2026-02-18T22:54:28.463757Z","end":"2026-02-18T22:54:29.793176Z","steps":["trace[880906458] 'process raft request' (duration: 1.329067192s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:29.793288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:28.463727Z","time spent":"1.329489592s","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:54:29.794137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:29.091895Z","time spent":"702.229604ms","remote":"127.0.0.1:55840","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-18T22:54:32.826318Z","caller":"traceutil/trace.go:171","msg":"trace[1554229025] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"338.81881ms","start":"2026-02-18T22:54:32.487481Z","end":"2026-02-18T22:54:32.8263Z","steps":["trace[1554229025] 'process raft request' (duration: 338.711387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:32.826428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:32.487472Z","time spent":"338.900561ms","remote":"127.0.0.1:56108","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-18T22:54:32.905739Z","caller":"traceutil/trace.go:171","msg":"trace[1595752268] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"381.692491ms","start":"2026-02-18T22:54:32.524029Z","end":"2026-02-18T22:54:32.905721Z","steps":["trace[1595752268] 'read index received' (duration: 302.730977ms)","trace[1595752268] 'applied index is now lower than readState.Index' (duration: 78.960534ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:32.906198Z","caller":"traceutil/trace.go:171","msg":"trace[332765296] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"418.560393ms","start":"2026-02-18T22:54:32.487602Z","end":"2026-02-18T22:54:32.906162Z","steps":["trace[332765296] 'process raft request' (duration: 409.316652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:32.906377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.330597ms","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-18T22:54:32.906459Z","caller":"traceutil/trace.go:171","msg":"trace[950877117] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"382.42638ms","start":"2026-02-18T22:54:32.524002Z","end":"2026-02-18T22:54:32.906429Z","steps":["trace[950877117] 'agreement among raft nodes before linearized reading' (duration: 381.786003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:32.906497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:32.523989Z","time spent":"382.497762ms","remote":"127.0.0.1:56108","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-18T22:54:32.906517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:32.487597Z","time spent":"418.82573ms","remote":"127.0.0.1:56108","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-18T22:54:33.832436Z","caller":"traceutil/trace.go:171","msg":"trace[1899890850] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"109.59873ms","start":"2026-02-18T22:54:33.722507Z","end":"2026-02-18T22:54:33.832106Z","steps":["trace[1899890850] 'process raft request' (duration: 109.421245ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:35.339987Z","caller":"traceutil/trace.go:171","msg":"trace[2051852773] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"508.874681ms","start":"2026-02-18T22:54:34.831088Z","end":"2026-02-18T22:54:35.339963Z","steps":["trace[2051852773] 'process raft request' (duration: 508.734207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:35.34013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:34.831077Z","time spent":"508.981043ms","remote":"127.0.0.1:56108","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-18T22:54:35.340471Z","caller":"traceutil/trace.go:171","msg":"trace[905269004] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:550; }","duration":"427.321162ms","start":"2026-02-18T22:54:34.913139Z","end":"2026-02-18T22:54:35.34046Z","steps":["trace[905269004] 'read index received' (duration: 427.316942ms)","trace[905269004] 'applied index is now lower than readState.Index' (duration: 3.56µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:35.340605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.690285ms","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-18T22:54:35.340632Z","caller":"traceutil/trace.go:171","msg":"trace[1634111218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"416.727157ms","start":"2026-02-18T22:54:34.923897Z","end":"2026-02-18T22:54:35.340624Z","steps":["trace[1634111218] 'agreement among raft nodes before linearized reading' (duration: 416.625494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:35.340658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:34.923881Z","time spent":"416.770827ms","remote":"127.0.0.1:56108","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-18T22:54:35.340877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.737433ms","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-18T22:54:35.340925Z","caller":"traceutil/trace.go:171","msg":"trace[1848057969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"427.783154ms","start":"2026-02-18T22:54:34.913116Z","end":"2026-02-18T22:54:35.340899Z","steps":["trace[1848057969] 'agreement among raft nodes before linearized reading' (duration: 427.702462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:35.340952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:34.913106Z","time spent":"427.839336ms","remote":"127.0.0.1:56108","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-18T22:54:35.341095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.710217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:35.341123Z","caller":"traceutil/trace.go:171","msg":"trace[676287176] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"248.773079ms","start":"2026-02-18T22:54:35.092341Z","end":"2026-02-18T22:54:35.341114Z","steps":["trace[676287176] 'agreement among raft nodes before linearized reading' (duration: 248.716447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:37.135263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.807134ms","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-18T22:54:37.135316Z","caller":"traceutil/trace.go:171","msg":"trace[5726015] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:540; }","duration":"371.894066ms","start":"2026-02-18T22:54:36.76341Z","end":"2026-02-18T22:54:37.135304Z","steps":["trace[5726015] 'range keys from in-memory index tree' (duration: 371.670261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:37.135341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:36.763394Z","time spent":"371.941498ms","remote":"127.0.0.1:55964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-18T22:54:37.443697Z","caller":"traceutil/trace.go:171","msg":"trace[1269956947] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"183.091777ms","start":"2026-02-18T22:54:37.260572Z","end":"2026-02-18T22:54:37.443663Z","steps":["trace[1269956947] 'process raft request' (duration: 182.977784ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:39.754957Z","caller":"traceutil/trace.go:171","msg":"trace[981668679] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"293.520148ms","start":"2026-02-18T22:54:39.461412Z","end":"2026-02-18T22:54:39.754932Z","steps":["trace[981668679] 'read index received' (duration: 225.027846ms)","trace[981668679] 'applied index is now lower than readState.Index' (duration: 68.491302ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:39.755298Z","caller":"traceutil/trace.go:171","msg":"trace[2057326200] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"298.987893ms","start":"2026-02-18T22:54:39.456292Z","end":"2026-02-18T22:54:39.75528Z","steps":["trace[2057326200] 'process raft request' (duration: 230.187544ms)","trace[2057326200] 'compare' (duration: 68.211165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:39.755417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.987129ms","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-18T22:54:39.755648Z","caller":"traceutil/trace.go:171","msg":"trace[635210701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"294.233375ms","start":"2026-02-18T22:54:39.4614Z","end":"2026-02-18T22:54:39.755633Z","steps":["trace[635210701] 'agreement among raft nodes before linearized reading' (duration: 293.754003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:39.756105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.689302ms","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-18T22:54:39.756354Z","caller":"traceutil/trace.go:171","msg":"trace[828993284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"289.955928ms","start":"2026-02-18T22:54:39.466382Z","end":"2026-02-18T22:54:39.756338Z","steps":["trace[828993284] 'agreement among raft nodes before linearized reading' (duration: 289.6107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:39.757883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.352163ms","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-18T22:54:39.75792Z","caller":"traceutil/trace.go:171","msg":"trace[575684249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"290.868141ms","start":"2026-02-18T22:54:39.467042Z","end":"2026-02-18T22:54:39.75791Z","steps":["trace[575684249] 'agreement among raft nodes before linearized reading' (duration: 289.319073ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:39.873903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.288627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:54:39.873978Z","caller":"traceutil/trace.go:171","msg":"trace[55641520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:558; }","duration":"102.38739ms","start":"2026-02-18T22:54:39.771574Z","end":"2026-02-18T22:54:39.873961Z","steps":["trace[55641520] 'agreement among raft nodes before linearized reading' (duration: 102.251396ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:39.874271Z","caller":"traceutil/trace.go:171","msg":"trace[981865645] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"111.890167ms","start":"2026-02-18T22:54:39.762373Z","end":"2026-02-18T22:54:39.874263Z","steps":["trace[981865645] 'process raft request' (duration: 110.559513ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:39.874311Z","caller":"traceutil/trace.go:171","msg":"trace[612284449] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"109.756943ms","start":"2026-02-18T22:54:39.764549Z","end":"2026-02-18T22:54:39.874306Z","steps":["trace[612284449] 'process raft request' (duration: 109.158458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:42.678642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.720038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:42.678851Z","caller":"traceutil/trace.go:171","msg":"trace[1483425365] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"162.580714ms","start":"2026-02-18T22:54:42.516259Z","end":"2026-02-18T22:54:42.67884Z","steps":["trace[1483425365] 'process raft request' (duration: 162.480472ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:42.679042Z","caller":"traceutil/trace.go:171","msg":"trace[516643519] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"259.524548ms","start":"2026-02-18T22:54:42.419508Z","end":"2026-02-18T22:54:42.679033Z","steps":["trace[516643519] 'process raft request' (duration: 115.775055ms)","trace[516643519] 'compare' (duration: 142.624985ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:46.009673Z","caller":"traceutil/trace.go:171","msg":"trace[494203303] transaction","detail":"{read_only:false; number_of_response:0; response_revision:644; }","duration":"124.572384ms","start":"2026-02-18T22:54:45.885079Z","end":"2026-02-18T22:54:46.009651Z","steps":["trace[494203303] 'process raft request' (duration: 78.496753ms)","trace[494203303] 'compare' (duration: 45.547168ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:46.009993Z","caller":"traceutil/trace.go:171","msg":"trace[1340069099] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:662; }","duration":"102.313716ms","start":"2026-02-18T22:54:45.907665Z","end":"2026-02-18T22:54:46.009979Z","steps":["trace[1340069099] 'read index received' (duration: 31.891026ms)","trace[1340069099] 'applied index is now lower than readState.Index' (duration: 70.42088ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:46.013832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.129452ms","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-18T22:54:46.013869Z","caller":"traceutil/trace.go:171","msg":"trace[235979190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"106.220344ms","start":"2026-02-18T22:54:45.907639Z","end":"2026-02-18T22:54:46.013859Z","steps":["trace[235979190] 'agreement among raft nodes before linearized reading' (duration: 103.000914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:46.014752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.014584ms","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-18T22:54:46.014778Z","caller":"traceutil/trace.go:171","msg":"trace[853553620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"107.137197ms","start":"2026-02-18T22:54:45.907633Z","end":"2026-02-18T22:54:46.01477Z","steps":["trace[853553620] 'agreement among raft nodes before linearized reading' (duration: 107.027354ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:47.836174Z","caller":"traceutil/trace.go:171","msg":"trace[1743853809] transaction","detail":"{read_only:false; number_of_response:0; response_revision:654; }","duration":"132.599615ms","start":"2026-02-18T22:54:47.703558Z","end":"2026-02-18T22:54:47.836157Z","steps":["trace[1743853809] 'process raft request' (duration: 91.63058ms)","trace[1743853809] 'compare' (duration: 40.884073ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:47.836642Z","caller":"traceutil/trace.go:171","msg":"trace[761993031] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"126.201915ms","start":"2026-02-18T22:54:47.710434Z","end":"2026-02-18T22:54:47.836636Z","steps":["trace[761993031] 'process raft request' (duration: 125.686382ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:48.089859Z","caller":"traceutil/trace.go:171","msg":"trace[698696295] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"113.699633ms","start":"2026-02-18T22:54:47.976149Z","end":"2026-02-18T22:54:48.089849Z","steps":["trace[698696295] 'process raft request' (duration: 113.61721ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:48.482406Z","caller":"traceutil/trace.go:171","msg":"trace[1986197017] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"130.164264ms","start":"2026-02-18T22:54:48.352203Z","end":"2026-02-18T22:54:48.482367Z","steps":["trace[1986197017] 'process raft request' (duration: 129.60044ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:48.669111Z","caller":"traceutil/trace.go:171","msg":"trace[144928170] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"161.004174ms","start":"2026-02-18T22:54:48.50809Z","end":"2026-02-18T22:54:48.669094Z","steps":["trace[144928170] 'process raft request' (duration: 160.953743ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:48.669409Z","caller":"traceutil/trace.go:171","msg":"trace[1684867327] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"161.363873ms","start":"2026-02-18T22:54:48.50804Z","end":"2026-02-18T22:54:48.669404Z","steps":["trace[1684867327] 'process raft request' (duration: 160.857811ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:48.784235Z","caller":"traceutil/trace.go:171","msg":"trace[1405525656] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"100.598205ms","start":"2026-02-18T22:54:48.683613Z","end":"2026-02-18T22:54:48.784211Z","steps":["trace[1405525656] 'process raft request' (duration: 100.481862ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:50.409963Z","caller":"traceutil/trace.go:171","msg":"trace[1197440371] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"205.643931ms","start":"2026-02-18T22:54:50.204302Z","end":"2026-02-18T22:54:50.409946Z","steps":["trace[1197440371] 'process raft request' (duration: 111.615131ms)","trace[1197440371] 'compare' (duration: 93.738193ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:50.421573Z","caller":"traceutil/trace.go:171","msg":"trace[429659294] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"211.58918ms","start":"2026-02-18T22:54:50.209972Z","end":"2026-02-18T22:54:50.421561Z","steps":["trace[429659294] 'read index received' (duration: 105.896957ms)","trace[429659294] 'applied index is now lower than readState.Index' (duration: 105.691403ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:54:50.421684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.699012ms","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-18T22:54:50.421706Z","caller":"traceutil/trace.go:171","msg":"trace[1786667471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:671; }","duration":"211.743013ms","start":"2026-02-18T22:54:50.209957Z","end":"2026-02-18T22:54:50.4217Z","steps":["trace[1786667471] 'agreement among raft nodes before linearized reading' (duration: 211.650761ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:50.421829Z","caller":"traceutil/trace.go:171","msg":"trace[1505942660] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"215.961218ms","start":"2026-02-18T22:54:50.205862Z","end":"2026-02-18T22:54:50.421823Z","steps":["trace[1505942660] 'process raft request' (duration: 215.653381ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:51.335132Z","caller":"traceutil/trace.go:171","msg":"trace[1453907205] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"232.159474ms","start":"2026-02-18T22:54:51.10295Z","end":"2026-02-18T22:54:51.335109Z","steps":["trace[1453907205] 'process raft request' (duration: 231.99667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:52.193386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.83587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:54:52.194122Z","caller":"traceutil/trace.go:171","msg":"trace[265398823] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"188.064042ms","start":"2026-02-18T22:54:52.006038Z","end":"2026-02-18T22:54:52.194102Z","steps":["trace[265398823] 'process raft request' (duration: 30.440361ms)","trace[265398823] 'compare' (duration: 156.454441ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:52.410244Z","caller":"traceutil/trace.go:171","msg":"trace[118081612] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"134.260326ms","start":"2026-02-18T22:54:52.275956Z","end":"2026-02-18T22:54:52.410217Z","steps":["trace[118081612] 'process raft request' (duration: 86.857721ms)","trace[118081612] 'compare' (duration: 47.279122ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:52.559432Z","caller":"traceutil/trace.go:171","msg":"trace[544372497] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"143.690472ms","start":"2026-02-18T22:54:52.415719Z","end":"2026-02-18T22:54:52.559409Z","steps":["trace[544372497] 'process raft request' (duration: 136.686137ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:52.572985Z","caller":"traceutil/trace.go:171","msg":"trace[491492729] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"147.341763ms","start":"2026-02-18T22:54:52.425627Z","end":"2026-02-18T22:54:52.572969Z","steps":["trace[491492729] 'read index received' (duration: 126.81999ms)","trace[491492729] 'applied index is now lower than readState.Index' (duration: 20.510013ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:52.573223Z","caller":"traceutil/trace.go:171","msg":"trace[36828112] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"154.127743ms","start":"2026-02-18T22:54:52.419084Z","end":"2026-02-18T22:54:52.573212Z","steps":["trace[36828112] 'process raft request' (duration: 153.797895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:52.573435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.789015ms","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-18T22:54:52.573466Z","caller":"traceutil/trace.go:171","msg":"trace[1865792657] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"147.849116ms","start":"2026-02-18T22:54:52.425608Z","end":"2026-02-18T22:54:52.573457Z","steps":["trace[1865792657] 'agreement among raft nodes before linearized reading' (duration: 147.733973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:52.594025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.735342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-8qjpn\" ","response":"range_response_count:1 size:4732"} {"level":"info","ts":"2026-02-18T22:54:52.594467Z","caller":"traceutil/trace.go:171","msg":"trace[1424881622] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-8qjpn; range_end:; response_count:1; response_revision:683; }","duration":"120.206145ms","start":"2026-02-18T22:54:52.474248Z","end":"2026-02-18T22:54:52.594454Z","steps":["trace[1424881622] 'agreement among raft nodes before linearized reading' (duration: 119.676931ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:52.7982Z","caller":"traceutil/trace.go:171","msg":"trace[1429954678] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"195.693202ms","start":"2026-02-18T22:54:52.60246Z","end":"2026-02-18T22:54:52.798153Z","steps":["trace[1429954678] 'process raft request' (duration: 195.58838ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:52.798675Z","caller":"traceutil/trace.go:171","msg":"trace[230583917] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"196.972373ms","start":"2026-02-18T22:54:52.601457Z","end":"2026-02-18T22:54:52.798429Z","steps":["trace[230583917] 'process raft request' (duration: 196.150703ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:54:58.490318Z","caller":"traceutil/trace.go:171","msg":"trace[862289611] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"400.473471ms","start":"2026-02-18T22:54:58.089821Z","end":"2026-02-18T22:54:58.490295Z","steps":["trace[862289611] 'read index received' (duration: 400.272736ms)","trace[862289611] 'applied index is now lower than readState.Index' (duration: 199.595µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:54:58.49046Z","caller":"traceutil/trace.go:171","msg":"trace[1151815985] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"413.874386ms","start":"2026-02-18T22:54:58.076576Z","end":"2026-02-18T22:54:58.490451Z","steps":["trace[1151815985] 'process raft request' (duration: 413.543478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:54:58.490561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.07656Z","time spent":"413.931388ms","remote":"127.0.0.1:56108","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-18T22:55:00.030071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915735548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:00.030185Z","caller":"traceutil/trace.go:171","msg":"trace[263602822] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:726; }","duration":"1.915880592s","start":"2026-02-18T22:54:58.114282Z","end":"2026-02-18T22:55:00.030162Z","steps":["trace[263602822] 'agreement among raft nodes before linearized reading' (duration: 376.402798ms)","trace[263602822] 'count revisions from in-memory index tree' (duration: 1.539278228s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:00.030241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.11425Z","time spent":"1.915974614s","remote":"127.0.0.1:56018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:00.030501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.940672382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:00.030553Z","caller":"traceutil/trace.go:171","msg":"trace[1298393491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"1.940722983s","start":"2026-02-18T22:54:58.089813Z","end":"2026-02-18T22:55:00.030536Z","steps":["trace[1298393491] 'agreement among raft nodes before linearized reading' (duration: 400.891941ms)","trace[1298393491] 'range keys from in-memory index tree' (duration: 1.539767251s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:00.030595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.089758Z","time spent":"1.940825405s","remote":"127.0.0.1:55840","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-18T22:55:00.030886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.766096257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:00.030935Z","caller":"traceutil/trace.go:171","msg":"trace[78660248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"1.766169429s","start":"2026-02-18T22:54:58.264749Z","end":"2026-02-18T22:55:00.030919Z","steps":["trace[78660248] 'agreement among raft nodes before linearized reading' (duration: 225.962708ms)","trace[78660248] 'range keys from in-memory index tree' (duration: 1.540076548s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:00.030979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.264738Z","time spent":"1.766229501s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:00.031874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369035712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:55:00.031925Z","caller":"traceutil/trace.go:171","msg":"trace[2138765384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"1.369108693s","start":"2026-02-18T22:54:58.662803Z","end":"2026-02-18T22:55:00.031912Z","steps":["trace[2138765384] 'range keys from in-memory index tree' (duration: 1.368935929s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.031965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.662793Z","time spent":"1.369160305s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:00.032322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209590207s","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-18T22:55:00.032364Z","caller":"traceutil/trace.go:171","msg":"trace[1086919533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:726; }","duration":"1.209654988s","start":"2026-02-18T22:54:58.822697Z","end":"2026-02-18T22:55:00.032352Z","steps":["trace[1086919533] 'range keys from in-memory index tree' (duration: 1.209465333s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.032401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.822686Z","time spent":"1.20970505s","remote":"127.0.0.1:56108","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-18T22:55:00.033928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437174225s","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-18T22:55:00.033976Z","caller":"traceutil/trace.go:171","msg":"trace[598219683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:726; }","duration":"1.437253868s","start":"2026-02-18T22:54:58.596712Z","end":"2026-02-18T22:55:00.033966Z","steps":["trace[598219683] 'range keys from in-memory index tree' (duration: 1.434656702s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.034004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:54:58.596696Z","time spent":"1.437299508s","remote":"127.0.0.1:56108","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-18T22:55:00.165245Z","caller":"traceutil/trace.go:171","msg":"trace[1481736661] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"126.187595ms","start":"2026-02-18T22:55:00.039031Z","end":"2026-02-18T22:55:00.165218Z","steps":["trace[1481736661] 'process raft request' (duration: 126.061522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.691798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.530553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:00.692558Z","caller":"traceutil/trace.go:171","msg":"trace[1813631134] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"650.779098ms","start":"2026-02-18T22:55:00.041751Z","end":"2026-02-18T22:55:00.69253Z","steps":["trace[1813631134] 'process raft request' (duration: 315.033805ms)","trace[1813631134] 'compare' (duration: 334.435871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:00.69281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:00.041721Z","time spent":"651.009654ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:00.693088Z","caller":"traceutil/trace.go:171","msg":"trace[1428250270] linearizableReadLoop","detail":"{readStateIndex:754; appliedIndex:750; }","duration":"646.694246ms","start":"2026-02-18T22:55:00.04638Z","end":"2026-02-18T22:55:00.693074Z","steps":["trace[1428250270] 'read index received' (duration: 118.708457ms)","trace[1428250270] 'applied index is now lower than readState.Index' (duration: 527.985119ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:00.693164Z","caller":"traceutil/trace.go:171","msg":"trace[2045954818] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"651.292411ms","start":"2026-02-18T22:55:00.041859Z","end":"2026-02-18T22:55:00.693151Z","steps":["trace[2045954818] 'process raft request' (duration: 651.059115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.693235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:00.041847Z","time spent":"651.355373ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:00.69333Z","caller":"traceutil/trace.go:171","msg":"trace[593294067] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"650.274876ms","start":"2026-02-18T22:55:00.043024Z","end":"2026-02-18T22:55:00.693298Z","steps":["trace[593294067] 'process raft request' (duration: 649.998798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.693442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:00.043013Z","time spent":"650.368019ms","remote":"127.0.0.1:56108","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-18T22:55:00.693675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.534138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-18T22:55:00.693846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.461936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:00.693923Z","caller":"traceutil/trace.go:171","msg":"trace[1857689980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"647.552997ms","start":"2026-02-18T22:55:00.046357Z","end":"2026-02-18T22:55:00.69391Z","steps":["trace[1857689980] 'agreement among raft nodes before linearized reading' (duration: 647.463175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:00.693999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:00.046349Z","time spent":"647.640799ms","remote":"127.0.0.1:55840","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-18T22:55:00.694275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.400985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:00.694346Z","caller":"traceutil/trace.go:171","msg":"trace[1811580261] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:730; }","duration":"157.499017ms","start":"2026-02-18T22:55:00.536839Z","end":"2026-02-18T22:55:00.694338Z","steps":["trace[1811580261] 'agreement among raft nodes before linearized reading' (duration: 157.412445ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:00.694652Z","caller":"traceutil/trace.go:171","msg":"trace[1914222141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:730; }","duration":"196.535363ms","start":"2026-02-18T22:55:00.498105Z","end":"2026-02-18T22:55:00.69464Z","steps":["trace[1914222141] 'agreement among raft nodes before linearized reading' (duration: 195.475867ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:01.008748Z","caller":"traceutil/trace.go:171","msg":"trace[1676470615] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"304.319506ms","start":"2026-02-18T22:55:00.704406Z","end":"2026-02-18T22:55:01.008725Z","steps":["trace[1676470615] 'process raft request' (duration: 285.119746ms)","trace[1676470615] 'compare' (duration: 18.682487ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:01.008935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:00.704386Z","time spent":"304.421009ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:01.664809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.117751ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-18T22:55:01.665515Z","caller":"traceutil/trace.go:171","msg":"trace[1919444185] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"573.818715ms","start":"2026-02-18T22:55:01.091676Z","end":"2026-02-18T22:55:01.665494Z","steps":["trace[1919444185] 'read index received' (duration: 66.973264ms)","trace[1919444185] 'applied index is now lower than readState.Index' (duration: 506.84334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:01.665581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.011264Z","time spent":"654.310606ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T22:55:01.665858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.143162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:01.665905Z","caller":"traceutil/trace.go:171","msg":"trace[156208424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:731; }","duration":"574.254675ms","start":"2026-02-18T22:55:01.091636Z","end":"2026-02-18T22:55:01.665891Z","steps":["trace[156208424] 'agreement among raft nodes before linearized reading' (duration: 574.141912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:01.665996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:01.091617Z","time spent":"574.370298ms","remote":"127.0.0.1:55840","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-18T22:55:01.863084Z","caller":"traceutil/trace.go:171","msg":"trace[1597228829] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"195.793855ms","start":"2026-02-18T22:55:01.667262Z","end":"2026-02-18T22:55:01.863056Z","steps":["trace[1597228829] 'read index received' (duration: 170.751768ms)","trace[1597228829] 'applied index is now lower than readState.Index' (duration: 25.040837ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:01.863241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.945679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:01.86329Z","caller":"traceutil/trace.go:171","msg":"trace[141772736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"196.064911ms","start":"2026-02-18T22:55:01.667211Z","end":"2026-02-18T22:55:01.863276Z","steps":["trace[141772736] 'agreement among raft nodes before linearized reading' (duration: 195.937198ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:01.863135Z","caller":"traceutil/trace.go:171","msg":"trace[1594177778] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"196.196205ms","start":"2026-02-18T22:55:01.666918Z","end":"2026-02-18T22:55:01.863114Z","steps":["trace[1594177778] 'process raft request' (duration: 171.157589ms)","trace[1594177778] 'compare' (duration: 24.821241ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:02.434503Z","caller":"traceutil/trace.go:171","msg":"trace[1382826452] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"261.166119ms","start":"2026-02-18T22:55:02.173164Z","end":"2026-02-18T22:55:02.43433Z","steps":["trace[1382826452] 'process raft request' (duration: 261.030905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:05.199801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.300806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-18T22:55:05.199818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.4966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:05.199859Z","caller":"traceutil/trace.go:171","msg":"trace[1492443016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"178.390988ms","start":"2026-02-18T22:55:05.021453Z","end":"2026-02-18T22:55:05.199844Z","steps":["trace[1492443016] 'range keys from in-memory index tree' (duration: 178.172773ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:05.199875Z","caller":"traceutil/trace.go:171","msg":"trace[1820843094] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:746; }","duration":"514.600703ms","start":"2026-02-18T22:55:04.68526Z","end":"2026-02-18T22:55:05.19986Z","steps":["trace[1820843094] 'count revisions from in-memory index tree' (duration: 514.427559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:05.199918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:04.68523Z","time spent":"514.667195ms","remote":"127.0.0.1:56314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:05.200119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.07999ms","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-18T22:55:05.200137Z","caller":"traceutil/trace.go:171","msg":"trace[1286245354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:746; }","duration":"486.115661ms","start":"2026-02-18T22:55:04.714015Z","end":"2026-02-18T22:55:05.200131Z","steps":["trace[1286245354] 'range keys from in-memory index tree' (duration: 485.984177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:05.200154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:04.714007Z","time spent":"486.141312ms","remote":"127.0.0.1:56108","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-18T22:55:05.200261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.861224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:55:05.200298Z","caller":"traceutil/trace.go:171","msg":"trace[1090200583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:746; }","duration":"483.898315ms","start":"2026-02-18T22:55:04.716392Z","end":"2026-02-18T22:55:05.200291Z","steps":["trace[1090200583] 'range keys from in-memory index tree' (duration: 483.801293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:05.200321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:04.716373Z","time spent":"483.941567ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:05.200442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.960207ms","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-18T22:55:05.200464Z","caller":"traceutil/trace.go:171","msg":"trace[1338683613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:746; }","duration":"483.991987ms","start":"2026-02-18T22:55:04.716465Z","end":"2026-02-18T22:55:05.200457Z","steps":["trace[1338683613] 'range keys from in-memory index tree' (duration: 483.883995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:05.200481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:04.716461Z","time spent":"484.016098ms","remote":"127.0.0.1:56108","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-18T22:55:18.67238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58224208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:18.672458Z","caller":"traceutil/trace.go:171","msg":"trace[1659551860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"1.582358304s","start":"2026-02-18T22:55:17.090082Z","end":"2026-02-18T22:55:18.67244Z","steps":["trace[1659551860] 'range keys from in-memory index tree' (duration: 1.582185539s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:18.672498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.090063Z","time spent":"1.582426185s","remote":"127.0.0.1:55840","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-18T22:55:18.672872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40913646s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:18.672906Z","caller":"traceutil/trace.go:171","msg":"trace[1012525836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:780; }","duration":"1.40919748s","start":"2026-02-18T22:55:17.2637Z","end":"2026-02-18T22:55:18.672897Z","steps":["trace[1012525836] 'range keys from in-memory index tree' (duration: 1.408994766s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:18.672932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.263683Z","time spent":"1.409242882s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:18.673144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369632601s","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-18T22:55:18.673171Z","caller":"traceutil/trace.go:171","msg":"trace[713108684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:780; }","duration":"1.369672662s","start":"2026-02-18T22:55:17.30349Z","end":"2026-02-18T22:55:18.673163Z","steps":["trace[713108684] 'range keys from in-memory index tree' (duration: 1.369560459s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:18.673195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.303484Z","time spent":"1.369706092s","remote":"127.0.0.1:56108","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-18T22:55:18.673398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.312543483s","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-18T22:55:18.673424Z","caller":"traceutil/trace.go:171","msg":"trace[244388737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:780; }","duration":"1.312595884s","start":"2026-02-18T22:55:17.36082Z","end":"2026-02-18T22:55:18.673416Z","steps":["trace[244388737] 'range keys from in-memory index tree' (duration: 1.31241746s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:18.673451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.360785Z","time spent":"1.312660226s","remote":"127.0.0.1:56108","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-18T22:55:18.67466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.092425ms","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-18T22:55:18.674716Z","caller":"traceutil/trace.go:171","msg":"trace[429145980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:780; }","duration":"174.187358ms","start":"2026-02-18T22:55:18.500514Z","end":"2026-02-18T22:55:18.674701Z","steps":["trace[429145980] 'range keys from in-memory index tree' (duration: 173.947912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:18.673585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304628989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:55:18.674894Z","caller":"traceutil/trace.go:171","msg":"trace[34433011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:780; }","duration":"1.30594357s","start":"2026-02-18T22:55:17.368937Z","end":"2026-02-18T22:55:18.67488Z","steps":["trace[34433011] 'range keys from in-memory index tree' (duration: 1.304542137s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:18.674934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:17.36893Z","time spent":"1.305989491s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:19.245773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344305830,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T22:55:19.379433Z","caller":"traceutil/trace.go:171","msg":"trace[1112136820] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:810; }","duration":"633.935584ms","start":"2026-02-18T22:55:18.745482Z","end":"2026-02-18T22:55:19.379417Z","steps":["trace[1112136820] 'read index received' (duration: 633.757249ms)","trace[1112136820] 'applied index is now lower than readState.Index' (duration: 177.805µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:19.379522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.030466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:19.379542Z","caller":"traceutil/trace.go:171","msg":"trace[481114855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:785; }","duration":"634.084798ms","start":"2026-02-18T22:55:18.745451Z","end":"2026-02-18T22:55:19.379536Z","steps":["trace[481114855] 'agreement among raft nodes before linearized reading' (duration: 634.025756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.379562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:18.745442Z","time spent":"634.115118ms","remote":"127.0.0.1:55840","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-18T22:55:19.379774Z","caller":"traceutil/trace.go:171","msg":"trace[778770987] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"697.811509ms","start":"2026-02-18T22:55:18.681955Z","end":"2026-02-18T22:55:19.379767Z","steps":["trace[778770987] 'process raft request' (duration: 697.296287ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:19.379783Z","caller":"traceutil/trace.go:171","msg":"trace[691900668] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"694.828636ms","start":"2026-02-18T22:55:18.684947Z","end":"2026-02-18T22:55:19.379775Z","steps":["trace[691900668] 'process raft request' (duration: 694.448177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.379828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:18.684929Z","time spent":"694.871417ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:19.379828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:18.681944Z","time spent":"697.84698ms","remote":"127.0.0.1:56108","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-18T22:55:19.379934Z","caller":"traceutil/trace.go:171","msg":"trace[1974903642] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"697.872881ms","start":"2026-02-18T22:55:18.682054Z","end":"2026-02-18T22:55:19.379927Z","steps":["trace[1974903642] 'process raft request' (duration: 697.277797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.379958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:18.682049Z","time spent":"697.895521ms","remote":"127.0.0.1:56108","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-18T22:55:19.38002Z","caller":"traceutil/trace.go:171","msg":"trace[1129577305] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"697.379828ms","start":"2026-02-18T22:55:18.682636Z","end":"2026-02-18T22:55:19.380016Z","steps":["trace[1129577305] 'process raft request' (duration: 696.741033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:19.380041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:18.682628Z","time spent":"697.401409ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:20.876181Z","caller":"traceutil/trace.go:171","msg":"trace[1715233383] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"131.916922ms","start":"2026-02-18T22:55:20.744248Z","end":"2026-02-18T22:55:20.876165Z","steps":["trace[1715233383] 'process raft request' (duration: 131.80137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.475693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.437275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T22:55:21.475764Z","caller":"traceutil/trace.go:171","msg":"trace[1137509962] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:786; }","duration":"485.547008ms","start":"2026-02-18T22:55:20.9902Z","end":"2026-02-18T22:55:21.475747Z","steps":["trace[1137509962] 'range keys from in-memory index tree' (duration: 485.25957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.475807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:20.990185Z","time spent":"485.606879ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.204.45.41\" "} {"level":"warn","ts":"2026-02-18T22:55:21.475973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.990089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:21.475987Z","caller":"traceutil/trace.go:171","msg":"trace[1626241403] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:786; }","duration":"424.02985ms","start":"2026-02-18T22:55:21.051953Z","end":"2026-02-18T22:55:21.475983Z","steps":["trace[1626241403] 'count revisions from in-memory index tree' (duration: 423.942098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:21.051939Z","time spent":"424.057101ms","remote":"127.0.0.1:56184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:21.476095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.264779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:21.476107Z","caller":"traceutil/trace.go:171","msg":"trace[1178579204] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:786; }","duration":"387.29976ms","start":"2026-02-18T22:55:21.088804Z","end":"2026-02-18T22:55:21.476103Z","steps":["trace[1178579204] 'range keys from in-memory index tree' (duration: 387.225579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:21.476118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:21.088788Z","time spent":"387.32753ms","remote":"127.0.0.1:55840","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-18T22:55:21.716157Z","caller":"traceutil/trace.go:171","msg":"trace[1678452265] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"234.089816ms","start":"2026-02-18T22:55:21.482045Z","end":"2026-02-18T22:55:21.716135Z","steps":["trace[1678452265] 'process raft request' (duration: 234.021545ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:21.716288Z","caller":"traceutil/trace.go:171","msg":"trace[84348810] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"232.384104ms","start":"2026-02-18T22:55:21.483879Z","end":"2026-02-18T22:55:21.716263Z","steps":["trace[84348810] 'process raft request' (duration: 232.221421ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:21.71639Z","caller":"traceutil/trace.go:171","msg":"trace[331340847] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"236.28879ms","start":"2026-02-18T22:55:21.480088Z","end":"2026-02-18T22:55:21.716377Z","steps":["trace[331340847] 'process raft request' (duration: 203.303021ms)","trace[331340847] 'compare' (duration: 32.393185ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:21.716469Z","caller":"traceutil/trace.go:171","msg":"trace[2024655097] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"235.369428ms","start":"2026-02-18T22:55:21.481092Z","end":"2026-02-18T22:55:21.716461Z","steps":["trace[2024655097] 'process raft request' (duration: 234.890147ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:21.73282Z","caller":"traceutil/trace.go:171","msg":"trace[1572463459] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"183.824995ms","start":"2026-02-18T22:55:21.548985Z","end":"2026-02-18T22:55:21.73281Z","steps":["trace[1572463459] 'process raft request' (duration: 183.766843ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:23.250122Z","caller":"traceutil/trace.go:171","msg":"trace[41134162] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"161.295462ms","start":"2026-02-18T22:55:23.088808Z","end":"2026-02-18T22:55:23.250104Z","steps":["trace[41134162] 'read index received' (duration: 161.145779ms)","trace[41134162] 'applied index is now lower than readState.Index' (duration: 148.973µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:23.25022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.393445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:23.250241Z","caller":"traceutil/trace.go:171","msg":"trace[335102247] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"161.456717ms","start":"2026-02-18T22:55:23.088778Z","end":"2026-02-18T22:55:23.250235Z","steps":["trace[335102247] 'agreement among raft nodes before linearized reading' (duration: 161.392915ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:23.250485Z","caller":"traceutil/trace.go:171","msg":"trace[1522603732] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"368.342627ms","start":"2026-02-18T22:55:22.882133Z","end":"2026-02-18T22:55:23.250476Z","steps":["trace[1522603732] 'process raft request' (duration: 367.870065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:23.250581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:22.882122Z","time spent":"368.378067ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:24.278573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.117917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:24.278624Z","caller":"traceutil/trace.go:171","msg":"trace[1358761438] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:792; }","duration":"112.21082ms","start":"2026-02-18T22:55:24.166402Z","end":"2026-02-18T22:55:24.278613Z","steps":["trace[1358761438] 'count revisions from in-memory index tree' (duration: 112.080946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.278858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.935352ms","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-18T22:55:24.278877Z","caller":"traceutil/trace.go:171","msg":"trace[1985025610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"557.980122ms","start":"2026-02-18T22:55:23.720891Z","end":"2026-02-18T22:55:24.278871Z","steps":["trace[1985025610] 'range keys from in-memory index tree' (duration: 557.8511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.278896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.720876Z","time spent":"558.013304ms","remote":"127.0.0.1:56108","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-18T22:55:24.279011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.588199ms","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-18T22:55:24.279027Z","caller":"traceutil/trace.go:171","msg":"trace[875957814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"556.61536ms","start":"2026-02-18T22:55:23.722407Z","end":"2026-02-18T22:55:24.279022Z","steps":["trace[875957814] 'range keys from in-memory index tree' (duration: 556.540178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.279042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.722403Z","time spent":"556.63509ms","remote":"127.0.0.1:56108","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-18T22:55:24.27914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.785704ms","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-18T22:55:24.279156Z","caller":"traceutil/trace.go:171","msg":"trace[961216175] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"556.801874ms","start":"2026-02-18T22:55:23.72235Z","end":"2026-02-18T22:55:24.279152Z","steps":["trace[961216175] 'range keys from in-memory index tree' (duration: 556.654921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.279171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.722301Z","time spent":"556.866106ms","remote":"127.0.0.1:56108","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-18T22:55:24.279265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.986175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:55:24.27928Z","caller":"traceutil/trace.go:171","msg":"trace[83000398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"556.014785ms","start":"2026-02-18T22:55:23.723261Z","end":"2026-02-18T22:55:24.279275Z","steps":["trace[83000398] 'range keys from in-memory index tree' (duration: 555.937783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.279295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:23.723253Z","time spent":"556.037906ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:24.279594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.12518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:24.279612Z","caller":"traceutil/trace.go:171","msg":"trace[364599344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"190.16979ms","start":"2026-02-18T22:55:24.089437Z","end":"2026-02-18T22:55:24.279607Z","steps":["trace[364599344] 'range keys from in-memory index tree' (duration: 190.085188ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:24.539708Z","caller":"traceutil/trace.go:171","msg":"trace[2069587784] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"255.48148ms","start":"2026-02-18T22:55:24.284205Z","end":"2026-02-18T22:55:24.539686Z","steps":["trace[2069587784] 'process raft request' (duration: 255.366597ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:24.951425Z","caller":"traceutil/trace.go:171","msg":"trace[1094958571] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"664.972823ms","start":"2026-02-18T22:55:24.286426Z","end":"2026-02-18T22:55:24.951399Z","steps":["trace[1094958571] 'process raft request' (duration: 664.896131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.951564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.286418Z","time spent":"665.075826ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:24.952075Z","caller":"traceutil/trace.go:171","msg":"trace[528097846] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"667.709641ms","start":"2026-02-18T22:55:24.284351Z","end":"2026-02-18T22:55:24.952061Z","steps":["trace[528097846] 'process raft request' (duration: 666.811429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.952171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.284346Z","time spent":"667.771263ms","remote":"127.0.0.1:56108","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-18T22:55:24.952527Z","caller":"traceutil/trace.go:171","msg":"trace[1293531995] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"668.118841ms","start":"2026-02-18T22:55:24.284395Z","end":"2026-02-18T22:55:24.952514Z","steps":["trace[1293531995] 'process raft request' (duration: 666.886581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:24.9526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.284391Z","time spent":"668.175122ms","remote":"127.0.0.1:56108","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-18T22:55:25.138305Z","caller":"traceutil/trace.go:171","msg":"trace[1368015662] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"481.735394ms","start":"2026-02-18T22:55:24.656547Z","end":"2026-02-18T22:55:25.138283Z","steps":["trace[1368015662] 'process raft request' (duration: 411.167034ms)","trace[1368015662] 'compare' (duration: 70.480948ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:25.138422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:24.656529Z","time spent":"481.833677ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:26.533688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.927485ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T22:55:27.254165Z","caller":"traceutil/trace.go:171","msg":"trace[556381804] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"296.69634ms","start":"2026-02-18T22:55:26.957444Z","end":"2026-02-18T22:55:27.25414Z","steps":["trace[556381804] 'read index received' (duration: 292.406685ms)","trace[556381804] 'applied index is now lower than readState.Index' (duration: 4.288595ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:27.254268Z","caller":"traceutil/trace.go:171","msg":"trace[111252914] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"709.821293ms","start":"2026-02-18T22:55:26.544437Z","end":"2026-02-18T22:55:27.254259Z","steps":["trace[111252914] 'process raft request' (duration: 709.530196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:27.254372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:26.544427Z","time spent":"709.873454ms","remote":"127.0.0.1:56108","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-18T22:55:27.254621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.173642ms","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-18T22:55:27.254662Z","caller":"traceutil/trace.go:171","msg":"trace[1065337334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"297.251204ms","start":"2026-02-18T22:55:26.9574Z","end":"2026-02-18T22:55:27.254651Z","steps":["trace[1065337334] 'agreement among raft nodes before linearized reading' (duration: 297.124221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:27.754297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344305881,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:28.747613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.758013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:28.747708Z","caller":"traceutil/trace.go:171","msg":"trace[1496482198] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"1.621675917s","start":"2026-02-18T22:55:27.126012Z","end":"2026-02-18T22:55:28.747688Z","steps":["trace[1496482198] 'process raft request' (duration: 673.76643ms)","trace[1496482198] 'compare' (duration: 946.856301ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:28.748489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.125994Z","time spent":"1.622453066s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:28.977432Z","caller":"traceutil/trace.go:171","msg":"trace[910330683] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:830; }","duration":"1.723181444s","start":"2026-02-18T22:55:27.25423Z","end":"2026-02-18T22:55:28.977412Z","steps":["trace[910330683] 'read index received' (duration: 545.564588ms)","trace[910330683] 'applied index is now lower than readState.Index' (duration: 1.177615936s)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:28.977701Z","caller":"traceutil/trace.go:171","msg":"trace[1413248361] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.718198353s","start":"2026-02-18T22:55:27.259494Z","end":"2026-02-18T22:55:28.977692Z","steps":["trace[1413248361] 'process raft request' (duration: 1.638656484s)","trace[1413248361] 'compare' (duration: 79.19199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:28.977778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.259478Z","time spent":"1.718247063s","remote":"127.0.0.1:56108","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-18T22:55:28.977915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.889995852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:28.977937Z","caller":"traceutil/trace.go:171","msg":"trace[2046233422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"1.890042422s","start":"2026-02-18T22:55:27.087889Z","end":"2026-02-18T22:55:28.977931Z","steps":["trace[2046233422] 'agreement among raft nodes before linearized reading' (duration: 1.890002381s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.977956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.087876Z","time spent":"1.890075173s","remote":"127.0.0.1:55840","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-18T22:55:28.978095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.019369602s","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-18T22:55:28.978111Z","caller":"traceutil/trace.go:171","msg":"trace[1286988196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"2.019396704s","start":"2026-02-18T22:55:26.958709Z","end":"2026-02-18T22:55:28.978106Z","steps":["trace[1286988196] 'agreement among raft nodes before linearized reading' (duration: 2.019327822s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.978127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:26.958704Z","time spent":"2.019419224s","remote":"127.0.0.1:56108","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-18T22:55:28.978234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.019902946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:55:28.978249Z","caller":"traceutil/trace.go:171","msg":"trace[1690037328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"2.019919156s","start":"2026-02-18T22:55:26.958326Z","end":"2026-02-18T22:55:28.978245Z","steps":["trace[1690037328] 'agreement among raft nodes before linearized reading' (duration: 2.019870935s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.978268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:26.958303Z","time spent":"2.019960826s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:28.978363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718202363s","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-18T22:55:28.978384Z","caller":"traceutil/trace.go:171","msg":"trace[588299101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"1.718233863s","start":"2026-02-18T22:55:27.260142Z","end":"2026-02-18T22:55:28.978376Z","steps":["trace[588299101] 'agreement among raft nodes before linearized reading' (duration: 1.718188892s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.978414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.260135Z","time spent":"1.718275244s","remote":"127.0.0.1:56108","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-18T22:55:28.978597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120361292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:28.978621Z","caller":"traceutil/trace.go:171","msg":"trace[681932654] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:801; }","duration":"1.120422394s","start":"2026-02-18T22:55:27.858193Z","end":"2026-02-18T22:55:28.978615Z","steps":["trace[681932654] 'agreement among raft nodes before linearized reading' (duration: 1.120385533s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.978638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.858174Z","time spent":"1.120459184s","remote":"127.0.0.1:56014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:28.978733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414086551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:28.978753Z","caller":"traceutil/trace.go:171","msg":"trace[1246444914] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:801; }","duration":"1.414135652s","start":"2026-02-18T22:55:27.564613Z","end":"2026-02-18T22:55:28.978749Z","steps":["trace[1246444914] 'agreement among raft nodes before linearized reading' (duration: 1.414102631s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.97877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.564595Z","time spent":"1.414170872s","remote":"127.0.0.1:56096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:28.978867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.598171822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:28.978889Z","caller":"traceutil/trace.go:171","msg":"trace[1035999092] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:801; }","duration":"1.598195062s","start":"2026-02-18T22:55:27.380688Z","end":"2026-02-18T22:55:28.978883Z","steps":["trace[1035999092] 'agreement among raft nodes before linearized reading' (duration: 1.598159341s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:28.978909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:27.380636Z","time spent":"1.598269094s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:41.803704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:42.304224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:42.462794Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.284878855s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:55:42.618649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44062247s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:42.618768Z","caller":"traceutil/trace.go:171","msg":"trace[579763394] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:924; }","duration":"1.315216258s","start":"2026-02-18T22:55:41.303541Z","end":"2026-02-18T22:55:42.618757Z","steps":["trace[579763394] 'read index received' (duration: 1.159391179s)","trace[579763394] 'applied index is now lower than readState.Index' (duration: 155.824489ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:42.618884Z","caller":"traceutil/trace.go:171","msg":"trace[1761348576] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"1.470916322s","start":"2026-02-18T22:55:41.147961Z","end":"2026-02-18T22:55:42.618877Z","steps":["trace[1761348576] 'process raft request' (duration: 30.010115ms)","trace[1761348576] 'compare' (duration: 1.440356623s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:42.618914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.147953Z","time spent":"1.470945163s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:42.618999Z","caller":"traceutil/trace.go:171","msg":"trace[127337603] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"1.469663672s","start":"2026-02-18T22:55:41.149331Z","end":"2026-02-18T22:55:42.618995Z","steps":["trace[127337603] 'process raft request' (duration: 1.469378825s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.619033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.149322Z","time spent":"1.469685653s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:42.619134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315591968s","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-18T22:55:42.619146Z","caller":"traceutil/trace.go:171","msg":"trace[61125071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"1.315626218s","start":"2026-02-18T22:55:41.303516Z","end":"2026-02-18T22:55:42.619142Z","steps":["trace[61125071] 'agreement among raft nodes before linearized reading' (duration: 1.315568976s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.619159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:41.303501Z","time spent":"1.315655038s","remote":"127.0.0.1:56108","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-18T22:55:42.619226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.640688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:42.619238Z","caller":"traceutil/trace.go:171","msg":"trace[1858531796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"529.680649ms","start":"2026-02-18T22:55:42.089553Z","end":"2026-02-18T22:55:42.619234Z","steps":["trace[1858531796] 'agreement among raft nodes before linearized reading' (duration: 529.661669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:42.619249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:42.089541Z","time spent":"529.706349ms","remote":"127.0.0.1:55840","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-18T22:55:44.362185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342057695s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:44.362272Z","caller":"traceutil/trace.go:171","msg":"trace[1049446339] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:891; }","duration":"1.342189758s","start":"2026-02-18T22:55:43.020062Z","end":"2026-02-18T22:55:44.362252Z","steps":["trace[1049446339] 'count revisions from in-memory index tree' (duration: 1.341985943s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.362379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:43.020038Z","time spent":"1.342321982s","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:44.362713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319532573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:55:44.362756Z","caller":"traceutil/trace.go:171","msg":"trace[114492506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:891; }","duration":"1.319593955s","start":"2026-02-18T22:55:43.043149Z","end":"2026-02-18T22:55:44.362743Z","steps":["trace[114492506] 'range keys from in-memory index tree' (duration: 1.31940849s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.36279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:43.043138Z","time spent":"1.319644596s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:44.362876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02274436s","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-18T22:55:44.363732Z","caller":"traceutil/trace.go:171","msg":"trace[1599513118] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:891; }","duration":"1.023643922s","start":"2026-02-18T22:55:43.340066Z","end":"2026-02-18T22:55:44.36371Z","steps":["trace[1599513118] 'count revisions from in-memory index tree' (duration: 1.021758406s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.363816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:43.34005Z","time spent":"1.023747445s","remote":"127.0.0.1:56298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:44.362987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287306934s","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-18T22:55:44.364292Z","caller":"traceutil/trace.go:171","msg":"trace[2075004556] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:891; }","duration":"1.288604636s","start":"2026-02-18T22:55:43.075671Z","end":"2026-02-18T22:55:44.364276Z","steps":["trace[2075004556] 'range keys from in-memory index tree' (duration: 1.287217551s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.36434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:43.075623Z","time spent":"1.288704848s","remote":"127.0.0.1:56108","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-18T22:55:44.363024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.278331074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:44.364501Z","caller":"traceutil/trace.go:171","msg":"trace[1042036887] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:891; }","duration":"1.2798299s","start":"2026-02-18T22:55:43.084661Z","end":"2026-02-18T22:55:44.364491Z","steps":["trace[1042036887] 'count revisions from in-memory index tree' (duration: 1.278285582s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.364561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:43.08465Z","time spent":"1.279897832s","remote":"127.0.0.1:56364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:44.363052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269011265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:44.364737Z","caller":"traceutil/trace.go:171","msg":"trace[1500087381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:891; }","duration":"1.270708747s","start":"2026-02-18T22:55:43.094019Z","end":"2026-02-18T22:55:44.364727Z","steps":["trace[1500087381] 'range keys from in-memory index tree' (duration: 1.268980154s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.364774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:43.094012Z","time spent":"1.270751819s","remote":"127.0.0.1:55840","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-18T22:55:44.476612Z","caller":"traceutil/trace.go:171","msg":"trace[124387653] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"105.054815ms","start":"2026-02-18T22:55:44.371538Z","end":"2026-02-18T22:55:44.476593Z","steps":["trace[124387653] 'process raft request' (duration: 104.784479ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:44.499141Z","caller":"traceutil/trace.go:171","msg":"trace[1014169821] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"125.419164ms","start":"2026-02-18T22:55:44.373703Z","end":"2026-02-18T22:55:44.499122Z","steps":["trace[1014169821] 'read index received' (duration: 103.587959ms)","trace[1014169821] 'applied index is now lower than readState.Index' (duration: 21.830485ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:44.499433Z","caller":"traceutil/trace.go:171","msg":"trace[1110216301] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"127.027522ms","start":"2026-02-18T22:55:44.372396Z","end":"2026-02-18T22:55:44.499424Z","steps":["trace[1110216301] 'process raft request' (duration: 126.633503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.499487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.779162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:44.500214Z","caller":"traceutil/trace.go:171","msg":"trace[638367390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"126.53097ms","start":"2026-02-18T22:55:44.373674Z","end":"2026-02-18T22:55:44.500205Z","steps":["trace[638367390] 'agreement among raft nodes before linearized reading' (duration: 125.781202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:44.824994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.897267ms","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-18T22:55:44.825043Z","caller":"traceutil/trace.go:171","msg":"trace[749867396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"101.99693ms","start":"2026-02-18T22:55:44.723034Z","end":"2026-02-18T22:55:44.825031Z","steps":["trace[749867396] 'range keys from in-memory index tree' (duration: 101.770424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:45.380257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.017865ms","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-18T22:55:45.380336Z","caller":"traceutil/trace.go:171","msg":"trace[1794789918] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:896; }","duration":"132.109037ms","start":"2026-02-18T22:55:45.248216Z","end":"2026-02-18T22:55:45.380325Z","steps":["trace[1794789918] 'range keys from in-memory index tree' (duration: 132.006935ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:46.695144Z","caller":"traceutil/trace.go:171","msg":"trace[782239156] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"143.309091ms","start":"2026-02-18T22:55:46.55182Z","end":"2026-02-18T22:55:46.695129Z","steps":["trace[782239156] 'process raft request' (duration: 112.720792ms)","trace[782239156] 'compare' (duration: 30.006134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:48.961244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.935527ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:48.961309Z","caller":"traceutil/trace.go:171","msg":"trace[1788682495] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"183.940458ms","start":"2026-02-18T22:55:48.777357Z","end":"2026-02-18T22:55:48.961297Z","steps":["trace[1788682495] 'process raft request' (duration: 48.896658ms)","trace[1788682495] 'compare' (duration: 134.873336ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:49.050245Z","caller":"traceutil/trace.go:171","msg":"trace[1800171318] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:942; }","duration":"191.441621ms","start":"2026-02-18T22:55:48.858787Z","end":"2026-02-18T22:55:49.050228Z","steps":["trace[1800171318] 'read index received' (duration: 191.331108ms)","trace[1800171318] 'applied index is now lower than readState.Index' (duration: 109.832µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:49.050443Z","caller":"traceutil/trace.go:171","msg":"trace[1488940859] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"271.912284ms","start":"2026-02-18T22:55:48.778523Z","end":"2026-02-18T22:55:49.050435Z","steps":["trace[1488940859] 'process raft request' (duration: 271.615856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:49.050649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.850071ms","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-18T22:55:49.050666Z","caller":"traceutil/trace.go:171","msg":"trace[521802659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:906; }","duration":"191.899843ms","start":"2026-02-18T22:55:48.858761Z","end":"2026-02-18T22:55:49.05066Z","steps":["trace[521802659] 'agreement among raft nodes before linearized reading' (duration: 191.823641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.531031Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.105227583s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T22:55:50.567561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374979923s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:50.567658Z","caller":"traceutil/trace.go:171","msg":"trace[53233471] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"1.141906701s","start":"2026-02-18T22:55:49.42574Z","end":"2026-02-18T22:55:50.567647Z","steps":["trace[53233471] 'process raft request' (duration: 1.141878761s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.567704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:49.425724Z","time spent":"1.141964952s","remote":"127.0.0.1:56108","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-18T22:55:50.567891Z","caller":"traceutil/trace.go:171","msg":"trace[1489115204] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"1.514146632s","start":"2026-02-18T22:55:49.05373Z","end":"2026-02-18T22:55:50.567876Z","steps":["trace[1489115204] 'process raft request' (duration: 138.80347ms)","trace[1489115204] 'compare' (duration: 1.374354088s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:50.567917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:49.053717Z","time spent":"1.514185752s","remote":"127.0.0.1:56108","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-18T22:55:50.567979Z","caller":"traceutil/trace.go:171","msg":"trace[542797356] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"1.479472082s","start":"2026-02-18T22:55:49.088502Z","end":"2026-02-18T22:55:50.567974Z","steps":["trace[542797356] 'read index received' (duration: 104.025368ms)","trace[542797356] 'applied index is now lower than readState.Index' (duration: 1.375446234s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:50.568083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479584766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:50.568096Z","caller":"traceutil/trace.go:171","msg":"trace[1230345797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:908; }","duration":"1.479620896s","start":"2026-02-18T22:55:49.08847Z","end":"2026-02-18T22:55:50.568091Z","steps":["trace[1230345797] 'agreement among raft nodes before linearized reading' (duration: 1.479597145s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:50.56811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:49.088452Z","time spent":"1.479653827s","remote":"127.0.0.1:55840","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-18T22:55:51.210687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.858007ms","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-18T22:55:51.210731Z","caller":"traceutil/trace.go:171","msg":"trace[1185842551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"432.946489ms","start":"2026-02-18T22:55:50.777774Z","end":"2026-02-18T22:55:51.21072Z","steps":["trace[1185842551] 'range keys from in-memory index tree' (duration: 432.753295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.210751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:50.777761Z","time spent":"432.98623ms","remote":"127.0.0.1:56108","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-18T22:55:51.210901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.679435ms","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-18T22:55:51.210914Z","caller":"traceutil/trace.go:171","msg":"trace[575550060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:909; }","duration":"246.715475ms","start":"2026-02-18T22:55:50.964195Z","end":"2026-02-18T22:55:51.21091Z","steps":["trace[575550060] 'range keys from in-memory index tree' (duration: 246.578302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.210995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.822527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.41\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-18T22:55:51.211006Z","caller":"traceutil/trace.go:171","msg":"trace[675971197] range","detail":"{range_begin:/registry/masterleases/199.204.45.41; range_end:; response_count:1; response_revision:909; }","duration":"219.870118ms","start":"2026-02-18T22:55:50.991132Z","end":"2026-02-18T22:55:51.211002Z","steps":["trace[675971197] 'range keys from in-memory index tree' (duration: 219.754365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.211083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.700635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:55:51.211094Z","caller":"traceutil/trace.go:171","msg":"trace[923395030] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"157.734966ms","start":"2026-02-18T22:55:51.053356Z","end":"2026-02-18T22:55:51.211091Z","steps":["trace[923395030] 'range keys from in-memory index tree' (duration: 157.643434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:51.551909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:51.213493Z","time spent":"338.412462ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-18T22:55:52.249127Z","caller":"traceutil/trace.go:171","msg":"trace[354117588] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.033897824s","start":"2026-02-18T22:55:51.215208Z","end":"2026-02-18T22:55:52.249106Z","steps":["trace[354117588] 'process raft request' (duration: 1.033492685s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.249261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:51.215199Z","time spent":"1.033985067s","remote":"127.0.0.1:56108","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-18T22:55:52.250986Z","caller":"traceutil/trace.go:171","msg":"trace[1122713291] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"1.033833013s","start":"2026-02-18T22:55:51.217138Z","end":"2026-02-18T22:55:52.250971Z","steps":["trace[1122713291] 'process raft request' (duration: 1.031717401s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.251068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:51.217133Z","time spent":"1.033895945s","remote":"127.0.0.1:56108","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-18T22:55:52.25187Z","caller":"traceutil/trace.go:171","msg":"trace[143672261] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"1.033015503s","start":"2026-02-18T22:55:51.21883Z","end":"2026-02-18T22:55:52.251845Z","steps":["trace[143672261] 'process raft request' (duration: 1.032684345s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.251988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:51.218824Z","time spent":"1.033098275s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:52.252249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.728524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:52.252277Z","caller":"traceutil/trace.go:171","msg":"trace[2140586862] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:912; }","duration":"885.782555ms","start":"2026-02-18T22:55:51.366489Z","end":"2026-02-18T22:55:52.252271Z","steps":["trace[2140586862] 'agreement among raft nodes before linearized reading' (duration: 885.723383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.252294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:51.366478Z","time spent":"885.811746ms","remote":"127.0.0.1:56310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:55:52.251605Z","caller":"traceutil/trace.go:171","msg":"trace[171531587] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:947; }","duration":"885.077098ms","start":"2026-02-18T22:55:51.366515Z","end":"2026-02-18T22:55:52.251592Z","steps":["trace[171531587] 'read index received' (duration: 185.440304ms)","trace[171531587] 'applied index is now lower than readState.Index' (duration: 699.635974ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:52.528976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.148566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:52.529035Z","caller":"traceutil/trace.go:171","msg":"trace[536963415] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"275.424489ms","start":"2026-02-18T22:55:52.253601Z","end":"2026-02-18T22:55:52.529026Z","steps":["trace[536963415] 'read index received' (duration: 119.133239ms)","trace[536963415] 'applied index is now lower than readState.Index' (duration: 156.29062ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:52.529078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.14133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:52.529089Z","caller":"traceutil/trace.go:171","msg":"trace[875299826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"441.18619ms","start":"2026-02-18T22:55:52.087899Z","end":"2026-02-18T22:55:52.529085Z","steps":["trace[875299826] 'agreement among raft nodes before linearized reading' (duration: 441.149849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:52.529105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:52.08788Z","time spent":"441.222191ms","remote":"127.0.0.1:55840","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-18T22:55:52.529211Z","caller":"traceutil/trace.go:171","msg":"trace[208719553] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"976.150519ms","start":"2026-02-18T22:55:51.553055Z","end":"2026-02-18T22:55:52.529206Z","steps":["trace[208719553] 'process raft request' (duration: 819.727366ms)","trace[208719553] 'compare' (duration: 155.951572ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:52.529237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:51.553038Z","time spent":"976.184859ms","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:55:52.715973Z","caller":"traceutil/trace.go:171","msg":"trace[457927649] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"108.14714ms","start":"2026-02-18T22:55:52.607809Z","end":"2026-02-18T22:55:52.715956Z","steps":["trace[457927649] 'process raft request' (duration: 70.703593ms)","trace[457927649] 'compare' (duration: 37.312684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:53.219804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.516563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:53.219859Z","caller":"traceutil/trace.go:171","msg":"trace[1626341650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"131.615584ms","start":"2026-02-18T22:55:53.088234Z","end":"2026-02-18T22:55:53.219849Z","steps":["trace[1626341650] 'range keys from in-memory index tree' (duration: 131.443991ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:54.636592Z","caller":"traceutil/trace.go:171","msg":"trace[421264949] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"382.117014ms","start":"2026-02-18T22:55:54.254461Z","end":"2026-02-18T22:55:54.636578Z","steps":["trace[421264949] 'read index received' (duration: 382.015711ms)","trace[421264949] 'applied index is now lower than readState.Index' (duration: 100.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:54.636736Z","caller":"traceutil/trace.go:171","msg":"trace[2104139161] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"383.007336ms","start":"2026-02-18T22:55:54.253723Z","end":"2026-02-18T22:55:54.63673Z","steps":["trace[2104139161] 'process raft request' (duration: 382.78674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:54.636805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.253715Z","time spent":"383.037016ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:55:54.63693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.466223ms","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-18T22:55:54.636945Z","caller":"traceutil/trace.go:171","msg":"trace[30590875] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:916; }","duration":"382.488903ms","start":"2026-02-18T22:55:54.254451Z","end":"2026-02-18T22:55:54.63694Z","steps":["trace[30590875] 'agreement among raft nodes before linearized reading' (duration: 382.432892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:54.636957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.254447Z","time spent":"382.507983ms","remote":"127.0.0.1:56108","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-18T22:55:54.637034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.518339ms","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-18T22:55:54.637046Z","caller":"traceutil/trace.go:171","msg":"trace[1967345628] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:916; }","duration":"381.529539ms","start":"2026-02-18T22:55:54.255512Z","end":"2026-02-18T22:55:54.637042Z","steps":["trace[1967345628] 'agreement among raft nodes before linearized reading' (duration: 381.496549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:54.637058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.255497Z","time spent":"381.558521ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:55:55.219191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306167,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:55:55.502617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.156588ms","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-18T22:55:55.502735Z","caller":"traceutil/trace.go:171","msg":"trace[1908098824] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:916; }","duration":"254.283421ms","start":"2026-02-18T22:55:55.248432Z","end":"2026-02-18T22:55:55.502715Z","steps":["trace[1908098824] 'range keys from in-memory index tree' (duration: 254.142087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:55.502766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.20807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:55:55.502836Z","caller":"traceutil/trace.go:171","msg":"trace[1163188030] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"861.252494ms","start":"2026-02-18T22:55:54.64157Z","end":"2026-02-18T22:55:55.502822Z","steps":["trace[1163188030] 'process raft request' (duration: 748.933522ms)","trace[1163188030] 'compare' (duration: 112.117387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:55.502876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.641558Z","time spent":"861.298356ms","remote":"127.0.0.1:56108","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-18T22:55:55.53098Z","caller":"traceutil/trace.go:171","msg":"trace[2020180140] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:954; }","duration":"811.825593ms","start":"2026-02-18T22:55:54.719138Z","end":"2026-02-18T22:55:55.530963Z","steps":["trace[2020180140] 'read index received' (duration: 671.387312ms)","trace[2020180140] 'applied index is now lower than readState.Index' (duration: 140.437471ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:55:55.531208Z","caller":"traceutil/trace.go:171","msg":"trace[932817515] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"889.514378ms","start":"2026-02-18T22:55:54.641682Z","end":"2026-02-18T22:55:55.531197Z","steps":["trace[932817515] 'process raft request' (duration: 889.156859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:55.531311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.641678Z","time spent":"889.558109ms","remote":"127.0.0.1:56108","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-18T22:55:55.531555Z","caller":"traceutil/trace.go:171","msg":"trace[1290191084] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"889.785474ms","start":"2026-02-18T22:55:54.641709Z","end":"2026-02-18T22:55:55.531494Z","steps":["trace[1290191084] 'process raft request' (duration: 889.21898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:55.531613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.641706Z","time spent":"889.876186ms","remote":"127.0.0.1:56108","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-18T22:55:55.531813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.675243ms","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-18T22:55:55.531836Z","caller":"traceutil/trace.go:171","msg":"trace[1946253266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"812.724895ms","start":"2026-02-18T22:55:54.719102Z","end":"2026-02-18T22:55:55.531827Z","steps":["trace[1946253266] 'agreement among raft nodes before linearized reading' (duration: 812.628442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:55.531855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.719086Z","time spent":"812.765575ms","remote":"127.0.0.1:56108","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-18T22:55:55.53194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.04244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:55.531958Z","caller":"traceutil/trace.go:171","msg":"trace[1608488657] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:919; }","duration":"717.076012ms","start":"2026-02-18T22:55:54.814875Z","end":"2026-02-18T22:55:55.531951Z","steps":["trace[1608488657] 'agreement among raft nodes before linearized reading' (duration: 717.050141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:55.531982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:54.814867Z","time spent":"717.110422ms","remote":"127.0.0.1:56010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:55:55.532149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.816751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:55:55.532166Z","caller":"traceutil/trace.go:171","msg":"trace[90018562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:919; }","duration":"440.872883ms","start":"2026-02-18T22:55:55.091288Z","end":"2026-02-18T22:55:55.532161Z","steps":["trace[90018562] 'agreement among raft nodes before linearized reading' (duration: 440.845372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:55.532181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:55:55.091267Z","time spent":"440.910523ms","remote":"127.0.0.1:55840","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-18T22:55:56.866539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.878627ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T22:55:56.866625Z","caller":"traceutil/trace.go:171","msg":"trace[49255373] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"225.495767ms","start":"2026-02-18T22:55:56.641114Z","end":"2026-02-18T22:55:56.866609Z","steps":["trace[49255373] 'read index received' (duration: 26.391µs)","trace[49255373] 'applied index is now lower than readState.Index' (duration: 225.467835ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:56.867337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.210554ms","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-18T22:55:56.867403Z","caller":"traceutil/trace.go:171","msg":"trace[174266777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"226.307795ms","start":"2026-02-18T22:55:56.641085Z","end":"2026-02-18T22:55:56.867393Z","steps":["trace[174266777] 'agreement among raft nodes before linearized reading' (duration: 226.147062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:56.867608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.898471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:55:56.867637Z","caller":"traceutil/trace.go:171","msg":"trace[648759608] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:921; }","duration":"102.933842ms","start":"2026-02-18T22:55:56.764695Z","end":"2026-02-18T22:55:56.867629Z","steps":["trace[648759608] 'agreement among raft nodes before linearized reading' (duration: 102.879661ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:57.735658Z","caller":"traceutil/trace.go:171","msg":"trace[1705890497] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"201.240511ms","start":"2026-02-18T22:55:57.534392Z","end":"2026-02-18T22:55:57.735632Z","steps":["trace[1705890497] 'read index received' (duration: 200.153954ms)","trace[1705890497] 'applied index is now lower than readState.Index' (duration: 1.084857ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:55:57.739689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.171126ms","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-18T22:55:57.739746Z","caller":"traceutil/trace.go:171","msg":"trace[1531430715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:923; }","duration":"198.240477ms","start":"2026-02-18T22:55:57.541482Z","end":"2026-02-18T22:55:57.739723Z","steps":["trace[1531430715] 'agreement among raft nodes before linearized reading' (duration: 197.990291ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:57.740079Z","caller":"traceutil/trace.go:171","msg":"trace[1288087675] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"228.848437ms","start":"2026-02-18T22:55:57.511212Z","end":"2026-02-18T22:55:57.74006Z","steps":["trace[1288087675] 'process raft request' (duration: 223.400813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:57.742215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.825972ms","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-18T22:55:57.742236Z","caller":"traceutil/trace.go:171","msg":"trace[1697355820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:923; }","duration":"207.867633ms","start":"2026-02-18T22:55:57.534362Z","end":"2026-02-18T22:55:57.74223Z","steps":["trace[1697355820] 'agreement among raft nodes before linearized reading' (duration: 207.811061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:55:57.74232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.563423ms","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-18T22:55:57.742337Z","caller":"traceutil/trace.go:171","msg":"trace[1799690410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:923; }","duration":"142.604804ms","start":"2026-02-18T22:55:57.599728Z","end":"2026-02-18T22:55:57.742333Z","steps":["trace[1799690410] 'agreement among raft nodes before linearized reading' (duration: 142.563813ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:57.870919Z","caller":"traceutil/trace.go:171","msg":"trace[1010074289] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"118.942624ms","start":"2026-02-18T22:55:57.751959Z","end":"2026-02-18T22:55:57.870901Z","steps":["trace[1010074289] 'process raft request' (duration: 118.80027ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:57.884681Z","caller":"traceutil/trace.go:171","msg":"trace[393882709] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"126.206542ms","start":"2026-02-18T22:55:57.758464Z","end":"2026-02-18T22:55:57.884671Z","steps":["trace[393882709] 'process raft request' (duration: 126.187442ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:55:57.884843Z","caller":"traceutil/trace.go:171","msg":"trace[1244590810] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"130.22326ms","start":"2026-02-18T22:55:57.754615Z","end":"2026-02-18T22:55:57.884838Z","steps":["trace[1244590810] 'process raft request' (duration: 129.996565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:01.24562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.725642ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-18T22:56:01.245689Z","caller":"traceutil/trace.go:171","msg":"trace[143489518] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"154.616518ms","start":"2026-02-18T22:56:01.091063Z","end":"2026-02-18T22:56:01.245679Z","steps":["trace[143489518] 'read index received' (duration: 20.11µs)","trace[143489518] 'applied index is now lower than readState.Index' (duration: 154.595338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:01.245855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.785562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:01.245872Z","caller":"traceutil/trace.go:171","msg":"trace[1602094891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"154.824574ms","start":"2026-02-18T22:56:01.09104Z","end":"2026-02-18T22:56:01.245865Z","steps":["trace[1602094891] 'agreement among raft nodes before linearized reading' (duration: 154.788623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:01.747165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:02.247838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:02.281794Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034745356s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:56:02.302098Z","caller":"traceutil/trace.go:171","msg":"trace[367572856] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"1.055007522s","start":"2026-02-18T22:56:01.247063Z","end":"2026-02-18T22:56:02.302071Z","steps":["trace[367572856] 'read index received' (duration: 1.034830077s)","trace[367572856] 'applied index is now lower than readState.Index' (duration: 20.175685ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:02.302432Z","caller":"traceutil/trace.go:171","msg":"trace[1032812407] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"1.055424142s","start":"2026-02-18T22:56:01.246992Z","end":"2026-02-18T22:56:02.302416Z","steps":["trace[1032812407] 'process raft request' (duration: 1.03495099s)","trace[1032812407] 'compare' (duration: 19.503369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:02.303159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:01.246977Z","time spent":"1.056004347s","remote":"127.0.0.1:55890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:02.304551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057471413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:02.304999Z","caller":"traceutil/trace.go:171","msg":"trace[1599432316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"1.057945004s","start":"2026-02-18T22:56:01.247037Z","end":"2026-02-18T22:56:02.304982Z","steps":["trace[1599432316] 'agreement among raft nodes before linearized reading' (duration: 1.057225767s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:02.30531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:01.247013Z","time spent":"1.058280562s","remote":"127.0.0.1:55854","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-18T22:56:02.306456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.332131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:56:02.306606Z","caller":"traceutil/trace.go:171","msg":"trace[1067220468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"547.519256ms","start":"2026-02-18T22:56:01.759073Z","end":"2026-02-18T22:56:02.306592Z","steps":["trace[1067220468] 'agreement among raft nodes before linearized reading' (duration: 547.127466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:02.306731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:01.759052Z","time spent":"547.664771ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:02.308155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.668981ms","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-18T22:56:02.308381Z","caller":"traceutil/trace.go:171","msg":"trace[120078493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"403.820165ms","start":"2026-02-18T22:56:01.90444Z","end":"2026-02-18T22:56:02.30826Z","steps":["trace[120078493] 'agreement among raft nodes before linearized reading' (duration: 403.657591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:02.308473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:01.904424Z","time spent":"404.039491ms","remote":"127.0.0.1:56108","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-18T22:56:02.308775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.792568ms","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-18T22:56:02.308953Z","caller":"traceutil/trace.go:171","msg":"trace[1206601186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"407.015654ms","start":"2026-02-18T22:56:01.901928Z","end":"2026-02-18T22:56:02.308944Z","steps":["trace[1206601186] 'agreement among raft nodes before linearized reading' (duration: 406.789668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:02.309045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:01.901907Z","time spent":"407.129196ms","remote":"127.0.0.1:56108","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-18T22:56:02.309284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.976613ms","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-18T22:56:02.30946Z","caller":"traceutil/trace.go:171","msg":"trace[1366796490] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"427.208478ms","start":"2026-02-18T22:56:01.882243Z","end":"2026-02-18T22:56:02.309452Z","steps":["trace[1366796490] 'agreement among raft nodes before linearized reading' (duration: 426.992743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:02.309565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:01.882163Z","time spent":"427.393543ms","remote":"127.0.0.1:56108","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-18T22:56:03.224026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.326542ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:03.224095Z","caller":"traceutil/trace.go:171","msg":"trace[2014009632] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"895.469242ms","start":"2026-02-18T22:56:02.328614Z","end":"2026-02-18T22:56:03.224083Z","steps":["trace[2014009632] 'process raft request' (duration: 770.029248ms)","trace[2014009632] 'compare' (duration: 125.102776ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:03.224127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:02.328606Z","time spent":"895.506794ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:03.890414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.196924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:03.890699Z","caller":"traceutil/trace.go:171","msg":"trace[2093290809] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:978; }","duration":"937.635136ms","start":"2026-02-18T22:56:02.953045Z","end":"2026-02-18T22:56:03.89068Z","steps":["trace[2093290809] 'read index received' (duration: 145.540627ms)","trace[2093290809] 'applied index is now lower than readState.Index' (duration: 792.093339ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:03.890981Z","caller":"traceutil/trace.go:171","msg":"trace[1646643949] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"1.562034856s","start":"2026-02-18T22:56:02.328926Z","end":"2026-02-18T22:56:03.89096Z","steps":["trace[1646643949] 'process raft request' (duration: 1.215204138s)","trace[1646643949] 'compare' (duration: 345.770232ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:03.89111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:02.32892Z","time spent":"1.562138178s","remote":"127.0.0.1:56108","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-18T22:56:03.891563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.387724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:03.891654Z","caller":"traceutil/trace.go:171","msg":"trace[1270783019] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:942; }","duration":"574.488957ms","start":"2026-02-18T22:56:03.317124Z","end":"2026-02-18T22:56:03.891613Z","steps":["trace[1270783019] 'agreement among raft nodes before linearized reading' (duration: 574.375384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:03.891711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:03.31711Z","time spent":"574.581779ms","remote":"127.0.0.1:56290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:56:03.891948Z","caller":"traceutil/trace.go:171","msg":"trace[712624253] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"1.559934234s","start":"2026-02-18T22:56:02.331996Z","end":"2026-02-18T22:56:03.89193Z","steps":["trace[712624253] 'process raft request' (duration: 1.558632142s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:03.892023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:02.331989Z","time spent":"1.559988225s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:03.892257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.207754ms","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-18T22:56:03.892285Z","caller":"traceutil/trace.go:171","msg":"trace[1384041235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"939.260236ms","start":"2026-02-18T22:56:02.953016Z","end":"2026-02-18T22:56:03.892276Z","steps":["trace[1384041235] 'agreement among raft nodes before linearized reading' (duration: 939.167053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:03.892314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:02.953Z","time spent":"939.304758ms","remote":"127.0.0.1:56108","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-18T22:56:03.892474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.341709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:03.892502Z","caller":"traceutil/trace.go:171","msg":"trace[1086247433] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:942; }","duration":"373.40385ms","start":"2026-02-18T22:56:03.519091Z","end":"2026-02-18T22:56:03.892495Z","steps":["trace[1086247433] 'agreement among raft nodes before linearized reading' (duration: 373.323538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:03.892527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:03.519071Z","time spent":"373.447772ms","remote":"127.0.0.1:56198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:56:03.891347Z","caller":"traceutil/trace.go:171","msg":"trace[217512915] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"1.560078578s","start":"2026-02-18T22:56:02.331254Z","end":"2026-02-18T22:56:03.891333Z","steps":["trace[217512915] 'process raft request' (duration: 1.559266698s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:03.895149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:02.331246Z","time spent":"1.563866101s","remote":"127.0.0.1:56108","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-18T22:56:04.814261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.008418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:04.814318Z","caller":"traceutil/trace.go:171","msg":"trace[821002088] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:944; }","duration":"408.128441ms","start":"2026-02-18T22:56:04.406177Z","end":"2026-02-18T22:56:04.814305Z","steps":["trace[821002088] 'count revisions from in-memory index tree' (duration: 407.916746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:04.814346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:04.406153Z","time spent":"408.184753ms","remote":"127.0.0.1:56038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-18T22:56:05.40604Z","caller":"traceutil/trace.go:171","msg":"trace[962996834] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"172.844815ms","start":"2026-02-18T22:56:05.23318Z","end":"2026-02-18T22:56:05.406025Z","steps":["trace[962996834] 'process raft request' (duration: 172.723763ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:05.571695Z","caller":"traceutil/trace.go:171","msg":"trace[133404066] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"168.505318ms","start":"2026-02-18T22:56:05.403171Z","end":"2026-02-18T22:56:05.571676Z","steps":["trace[133404066] 'process raft request' (duration: 161.572839ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:07.955393Z","caller":"traceutil/trace.go:171","msg":"trace[1134499561] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"207.234138ms","start":"2026-02-18T22:56:07.748143Z","end":"2026-02-18T22:56:07.955377Z","steps":["trace[1134499561] 'process raft request' (duration: 207.063284ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:10.103374Z","caller":"traceutil/trace.go:171","msg":"trace[290681672] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"113.263176ms","start":"2026-02-18T22:56:09.990076Z","end":"2026-02-18T22:56:10.103339Z","steps":["trace[290681672] 'read index received' (duration: 113.111722ms)","trace[290681672] 'applied index is now lower than readState.Index' (duration: 150.794µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:10.103464Z","caller":"traceutil/trace.go:171","msg":"trace[1584073264] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"130.093248ms","start":"2026-02-18T22:56:09.973365Z","end":"2026-02-18T22:56:10.103458Z","steps":["trace[1584073264] 'process raft request' (duration: 129.842472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:10.103618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.616089ms","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-18T22:56:10.10366Z","caller":"traceutil/trace.go:171","msg":"trace[2066682362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:980; }","duration":"103.692631ms","start":"2026-02-18T22:56:09.999956Z","end":"2026-02-18T22:56:10.103649Z","steps":["trace[2066682362] 'agreement among raft nodes before linearized reading' (duration: 103.582058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:10.103727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.641696ms","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-18T22:56:10.103747Z","caller":"traceutil/trace.go:171","msg":"trace[1805461256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:980; }","duration":"113.702086ms","start":"2026-02-18T22:56:09.990039Z","end":"2026-02-18T22:56:10.103741Z","steps":["trace[1805461256] 'agreement among raft nodes before linearized reading' (duration: 113.619724ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:12.236884Z","caller":"traceutil/trace.go:171","msg":"trace[182256750] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1035; }","duration":"123.429445ms","start":"2026-02-18T22:56:12.11344Z","end":"2026-02-18T22:56:12.23687Z","steps":["trace[182256750] 'read index received' (duration: 123.287801ms)","trace[182256750] 'applied index is now lower than readState.Index' (duration: 141.034µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:12.237135Z","caller":"traceutil/trace.go:171","msg":"trace[43419182] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"127.068113ms","start":"2026-02-18T22:56:12.110061Z","end":"2026-02-18T22:56:12.237129Z","steps":["trace[43419182] 'process raft request' (duration: 126.716004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:12.237318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.865085ms","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-18T22:56:12.237351Z","caller":"traceutil/trace.go:171","msg":"trace[1904526446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:994; }","duration":"123.909096ms","start":"2026-02-18T22:56:12.113425Z","end":"2026-02-18T22:56:12.237335Z","steps":["trace[1904526446] 'agreement among raft nodes before linearized reading' (duration: 123.813563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:12.237452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.944968ms","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-18T22:56:12.237465Z","caller":"traceutil/trace.go:171","msg":"trace[1284207089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"121.969608ms","start":"2026-02-18T22:56:12.115492Z","end":"2026-02-18T22:56:12.237461Z","steps":["trace[1284207089] 'agreement among raft nodes before linearized reading' (duration: 121.933827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:12.237613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.91695ms","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-18T22:56:12.237637Z","caller":"traceutil/trace.go:171","msg":"trace[840408110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"117.945551ms","start":"2026-02-18T22:56:12.119679Z","end":"2026-02-18T22:56:12.237625Z","steps":["trace[840408110] 'agreement among raft nodes before linearized reading' (duration: 117.884078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:12.746106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.235195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:12.746313Z","caller":"traceutil/trace.go:171","msg":"trace[1348155845] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"499.96629ms","start":"2026-02-18T22:56:12.246327Z","end":"2026-02-18T22:56:12.746293Z","steps":["trace[1348155845] 'process raft request' (duration: 499.93877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:12.746365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.246324Z","time spent":"500.018102ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:12.746449Z","caller":"traceutil/trace.go:171","msg":"trace[1764532241] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"500.149915ms","start":"2026-02-18T22:56:12.246293Z","end":"2026-02-18T22:56:12.746443Z","steps":["trace[1764532241] 'process raft request' (duration: 499.883779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:12.74648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.246287Z","time spent":"500.176236ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T22:56:12.746502Z","caller":"traceutil/trace.go:171","msg":"trace[213671691] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"505.186779ms","start":"2026-02-18T22:56:12.241295Z","end":"2026-02-18T22:56:12.746482Z","steps":["trace[213671691] 'process raft request' (duration: 202.512183ms)","trace[213671691] 'compare' (duration: 301.987469ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:12.746585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:12.241284Z","time spent":"505.2559ms","remote":"127.0.0.1:56108","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-18T22:56:16.502253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.468394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:16.502352Z","caller":"traceutil/trace.go:171","msg":"trace[688624874] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1054; }","duration":"412.60978ms","start":"2026-02-18T22:56:16.089727Z","end":"2026-02-18T22:56:16.502337Z","steps":["trace[688624874] 'read index received' (duration: 22.73µs)","trace[688624874] 'applied index is now lower than readState.Index' (duration: 412.58592ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:16.50253Z","caller":"traceutil/trace.go:171","msg":"trace[524524662] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"486.785619ms","start":"2026-02-18T22:56:16.015732Z","end":"2026-02-18T22:56:16.502518Z","steps":["trace[524524662] 'process raft request' (duration: 36.937175ms)","trace[524524662] 'compare' (duration: 448.957862ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:16.502589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.015722Z","time spent":"486.835049ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:16.502752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.02211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:16.50278Z","caller":"traceutil/trace.go:171","msg":"trace[131634004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"413.073632ms","start":"2026-02-18T22:56:16.089696Z","end":"2026-02-18T22:56:16.502769Z","steps":["trace[131634004] 'agreement among raft nodes before linearized reading' (duration: 413.029461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.502805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.089667Z","time spent":"413.133443ms","remote":"127.0.0.1:55854","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-18T22:56:16.511835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.221377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:16.511884Z","caller":"traceutil/trace.go:171","msg":"trace[949824218] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1013; }","duration":"300.310958ms","start":"2026-02-18T22:56:16.211562Z","end":"2026-02-18T22:56:16.511873Z","steps":["trace[949824218] 'agreement among raft nodes before linearized reading' (duration: 300.220446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:16.51192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:16.211547Z","time spent":"300.36742ms","remote":"127.0.0.1:55944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:16.512106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.229065ms","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-18T22:56:16.512125Z","caller":"traceutil/trace.go:171","msg":"trace[1253698989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1013; }","duration":"253.272365ms","start":"2026-02-18T22:56:16.258848Z","end":"2026-02-18T22:56:16.51212Z","steps":["trace[1253698989] 'agreement among raft nodes before linearized reading' (duration: 253.200834ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:16.617969Z","caller":"traceutil/trace.go:171","msg":"trace[770425861] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"103.438245ms","start":"2026-02-18T22:56:16.514509Z","end":"2026-02-18T22:56:16.617947Z","steps":["trace[770425861] 'process raft request' (duration: 103.316852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:24.803007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.093893ms","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-18T22:56:24.803155Z","caller":"traceutil/trace.go:171","msg":"trace[452837122] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"157.289658ms","start":"2026-02-18T22:56:24.645817Z","end":"2026-02-18T22:56:24.803106Z","steps":["trace[452837122] 'range keys from in-memory index tree' (duration: 156.666403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:24.803303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.600083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:24.80349Z","caller":"traceutil/trace.go:171","msg":"trace[912306626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"711.823149ms","start":"2026-02-18T22:56:24.091611Z","end":"2026-02-18T22:56:24.803434Z","steps":["trace[912306626] 'range keys from in-memory index tree' (duration: 711.549062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:24.803598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.091596Z","time spent":"711.938612ms","remote":"127.0.0.1:55840","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-18T22:56:25.316915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:25.818359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:26.318746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:26.819731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:27.32035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:27.554951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00015513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T22:56:27.748278Z","caller":"traceutil/trace.go:171","msg":"trace[598153865] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.193505193s","start":"2026-02-18T22:56:25.554711Z","end":"2026-02-18T22:56:27.748216Z","steps":["trace[598153865] 'agreement among raft nodes before linearized reading' (duration: 2.000191601s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:27.762508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:25.554686Z","time spent":"2.207666259s","remote":"127.0.0.1:55840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 22:56:27 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T22:56:27.969469Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12572251852344306472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T22:56:28.045339Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.501611168s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T22:56:28.045917Z","caller":"traceutil/trace.go:171","msg":"trace[511548387] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"3.23228616s","start":"2026-02-18T22:56:24.813594Z","end":"2026-02-18T22:56:28.04588Z","steps":["trace[511548387] 'process raft request' (duration: 3.231945321s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.046173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.813577Z","time spent":"3.232461125s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:28.102981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.011214438s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T22:56:28.103055Z","caller":"traceutil/trace.go:171","msg":"trace[1613072417] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.011359371s","start":"2026-02-18T22:56:26.09168Z","end":"2026-02-18T22:56:28.103039Z","steps":["trace[1613072417] 'agreement among raft nodes before linearized reading' (duration: 2.011272469s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.103097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:26.091632Z","time spent":"2.011457963s","remote":"127.0.0.1:55854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 22:56:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T22:56:28.511274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.241226ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T22:56:28.513631Z","caller":"traceutil/trace.go:171","msg":"trace[769458177] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"2.3459453s","start":"2026-02-18T22:56:26.167655Z","end":"2026-02-18T22:56:28.513601Z","steps":["trace[769458177] 'process raft request' (duration: 2.053254602s)","trace[769458177] 'compare' (duration: 290.027991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:28.513739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:26.167617Z","time spent":"2.346076104s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:56:28.700853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.401224ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-18T22:56:28.704814Z","caller":"traceutil/trace.go:171","msg":"trace[690307196] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1107; }","duration":"3.888954254s","start":"2026-02-18T22:56:24.815825Z","end":"2026-02-18T22:56:28.704779Z","steps":["trace[690307196] 'read index received' (duration: 3.229775236s)","trace[690307196] 'applied index is now lower than readState.Index' (duration: 659.164868ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:28.705261Z","caller":"traceutil/trace.go:171","msg":"trace[1697092444] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"941.469904ms","start":"2026-02-18T22:56:27.763763Z","end":"2026-02-18T22:56:28.705233Z","steps":["trace[1697092444] 'process raft request' (duration: 937.213836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.705528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:27.763746Z","time spent":"941.705359ms","remote":"127.0.0.1:55920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T22:56:28.713249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.850264474s","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-18T22:56:28.71332Z","caller":"traceutil/trace.go:171","msg":"trace[739889497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"3.850367826s","start":"2026-02-18T22:56:24.862938Z","end":"2026-02-18T22:56:28.713306Z","steps":["trace[739889497] 'agreement among raft nodes before linearized reading' (duration: 3.843176365s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.713404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.862933Z","time spent":"3.850415417s","remote":"127.0.0.1:56108","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-18T22:56:28.713703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.850859898s","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-18T22:56:28.713735Z","caller":"traceutil/trace.go:171","msg":"trace[1432318528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1066; }","duration":"3.85090794s","start":"2026-02-18T22:56:24.862819Z","end":"2026-02-18T22:56:28.713727Z","steps":["trace[1432318528] 'agreement among raft nodes before linearized reading' (duration: 3.843205596s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.71376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.862811Z","time spent":"3.850944191s","remote":"127.0.0.1:56108","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-18T22:56:28.713832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.898004971s","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-18T22:56:28.713852Z","caller":"traceutil/trace.go:171","msg":"trace[150392855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1066; }","duration":"3.898035583s","start":"2026-02-18T22:56:24.81581Z","end":"2026-02-18T22:56:28.713846Z","steps":["trace[150392855] 'agreement among raft nodes before linearized reading' (duration: 3.890229286s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.71387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:24.815804Z","time spent":"3.898061583s","remote":"127.0.0.1:56108","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-18T22:56:28.713914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.68652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:28.713933Z","caller":"traceutil/trace.go:171","msg":"trace[1604043499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1066; }","duration":"610.735272ms","start":"2026-02-18T22:56:28.103191Z","end":"2026-02-18T22:56:28.713926Z","steps":["trace[1604043499] 'agreement among raft nodes before linearized reading' (duration: 602.855484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.713948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:28.103174Z","time spent":"610.770152ms","remote":"127.0.0.1:55840","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-18T22:56:28.714058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"992.71378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:28.714077Z","caller":"traceutil/trace.go:171","msg":"trace[1254292975] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1066; }","duration":"992.769581ms","start":"2026-02-18T22:56:27.721302Z","end":"2026-02-18T22:56:28.714072Z","steps":["trace[1254292975] 'agreement among raft nodes before linearized reading' (duration: 984.75522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.714093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:27.721281Z","time spent":"992.806442ms","remote":"127.0.0.1:56020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:28.714213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305701487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-18T22:56:28.714235Z","caller":"traceutil/trace.go:171","msg":"trace[1187230939] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1066; }","duration":"1.305721757s","start":"2026-02-18T22:56:27.408506Z","end":"2026-02-18T22:56:28.714227Z","steps":["trace[1187230939] 'agreement among raft nodes before linearized reading' (duration: 1.297560602s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.71425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:27.408451Z","time spent":"1.3057949s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T22:56:28.714328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419052692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:28.714349Z","caller":"traceutil/trace.go:171","msg":"trace[365160798] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1066; }","duration":"1.419148144s","start":"2026-02-18T22:56:27.295196Z","end":"2026-02-18T22:56:28.714344Z","steps":["trace[365160798] 'agreement among raft nodes before linearized reading' (duration: 1.410876836s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.714365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:27.295164Z","time spent":"1.419194836s","remote":"127.0.0.1:56208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:28.714931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.19456705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-18T22:56:28.715014Z","caller":"traceutil/trace.go:171","msg":"trace[607746003] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1066; }","duration":"2.194695634s","start":"2026-02-18T22:56:26.520308Z","end":"2026-02-18T22:56:28.715004Z","steps":["trace[607746003] 'agreement among raft nodes before linearized reading' (duration: 2.185771719s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.715093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:26.520295Z","time spent":"2.194790156s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-18T22:56:28.715265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.334556225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T22:56:28.715335Z","caller":"traceutil/trace.go:171","msg":"trace[1524919103] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1066; }","duration":"2.334685928s","start":"2026-02-18T22:56:26.380638Z","end":"2026-02-18T22:56:28.715324Z","steps":["trace[1524919103] 'agreement among raft nodes before linearized reading' (duration: 2.325447996s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.715467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.140816715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:56:28.715497Z","caller":"traceutil/trace.go:171","msg":"trace[798918332] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1066; }","duration":"3.140874467s","start":"2026-02-18T22:56:25.574616Z","end":"2026-02-18T22:56:28.715491Z","steps":["trace[798918332] 'agreement among raft nodes before linearized reading' (duration: 3.13147913s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.715552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:25.574605Z","time spent":"3.140942028s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-18T22:56:28.715473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:26.380607Z","time spent":"2.334856653s","remote":"127.0.0.1:45556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-18T22:56:28.715647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.16335615s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T22:56:28.715881Z","caller":"traceutil/trace.go:171","msg":"trace[556579829] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1066; }","duration":"3.163614527s","start":"2026-02-18T22:56:25.552258Z","end":"2026-02-18T22:56:28.715872Z","steps":["trace[556579829] 'agreement among raft nodes before linearized reading' (duration: 3.153846721s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:28.716152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:56:25.552229Z","time spent":"3.163891884s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-18T22:56:32.281061Z","caller":"traceutil/trace.go:171","msg":"trace[79499100] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"183.332701ms","start":"2026-02-18T22:56:32.097713Z","end":"2026-02-18T22:56:32.281045Z","steps":["trace[79499100] 'read index received' (duration: 183.242519ms)","trace[79499100] 'applied index is now lower than readState.Index' (duration: 88.862µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:32.28116Z","caller":"traceutil/trace.go:171","msg":"trace[1421348216] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"221.159792ms","start":"2026-02-18T22:56:32.059993Z","end":"2026-02-18T22:56:32.281153Z","steps":["trace[1421348216] 'process raft request' (duration: 220.943857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:32.281275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.551357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:56:32.281293Z","caller":"traceutil/trace.go:171","msg":"trace[1284242928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1085; }","duration":"183.583288ms","start":"2026-02-18T22:56:32.097704Z","end":"2026-02-18T22:56:32.281288Z","steps":["trace[1284242928] 'agreement among raft nodes before linearized reading' (duration: 183.535407ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:32.970227Z","caller":"traceutil/trace.go:171","msg":"trace[485234975] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"117.366386ms","start":"2026-02-18T22:56:32.852838Z","end":"2026-02-18T22:56:32.970204Z","steps":["trace[485234975] 'process raft request' (duration: 58.227292ms)","trace[485234975] 'compare' (duration: 58.757455ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:32.970469Z","caller":"traceutil/trace.go:171","msg":"trace[196059672] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"110.527554ms","start":"2026-02-18T22:56:32.859902Z","end":"2026-02-18T22:56:32.970429Z","steps":["trace[196059672] 'process raft request' (duration: 110.449752ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:32.970542Z","caller":"traceutil/trace.go:171","msg":"trace[246242817] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"114.577126ms","start":"2026-02-18T22:56:32.855956Z","end":"2026-02-18T22:56:32.970534Z","steps":["trace[246242817] 'process raft request' (duration: 114.044472ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:35.038238Z","caller":"traceutil/trace.go:171","msg":"trace[1470080995] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"112.973006ms","start":"2026-02-18T22:56:34.925238Z","end":"2026-02-18T22:56:35.038211Z","steps":["trace[1470080995] 'process raft request' (duration: 112.784111ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:49.10538Z","caller":"traceutil/trace.go:171","msg":"trace[427599363] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1200; }","duration":"116.153425ms","start":"2026-02-18T22:56:48.989209Z","end":"2026-02-18T22:56:49.105363Z","steps":["trace[427599363] 'read index received' (duration: 76.791787ms)","trace[427599363] 'applied index is now lower than readState.Index' (duration: 39.360818ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:49.105791Z","caller":"traceutil/trace.go:171","msg":"trace[606739256] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"211.285043ms","start":"2026-02-18T22:56:48.894495Z","end":"2026-02-18T22:56:49.10578Z","steps":["trace[606739256] 'process raft request' (duration: 171.555696ms)","trace[606739256] 'compare' (duration: 39.089331ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:56:49.105929Z","caller":"traceutil/trace.go:171","msg":"trace[1897372639] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"211.362306ms","start":"2026-02-18T22:56:48.894559Z","end":"2026-02-18T22:56:49.105921Z","steps":["trace[1897372639] 'process raft request' (duration: 210.73146ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:49.106001Z","caller":"traceutil/trace.go:171","msg":"trace[848749516] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"185.632069ms","start":"2026-02-18T22:56:48.920363Z","end":"2026-02-18T22:56:49.105996Z","steps":["trace[848749516] 'process raft request' (duration: 184.966463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:56:49.106149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.928464ms","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-18T22:56:49.106171Z","caller":"traceutil/trace.go:171","msg":"trace[289509123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1154; }","duration":"116.963645ms","start":"2026-02-18T22:56:48.989202Z","end":"2026-02-18T22:56:49.106165Z","steps":["trace[289509123] 'agreement among raft nodes before linearized reading' (duration: 116.860162ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:49.353147Z","caller":"traceutil/trace.go:171","msg":"trace[141047026] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"147.364078ms","start":"2026-02-18T22:56:49.205762Z","end":"2026-02-18T22:56:49.353126Z","steps":["trace[141047026] 'read index received' (duration: 129.894649ms)","trace[141047026] 'applied index is now lower than readState.Index' (duration: 17.468539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:56:49.353382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.555553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T22:56:49.353543Z","caller":"traceutil/trace.go:171","msg":"trace[466630687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1158; }","duration":"147.667877ms","start":"2026-02-18T22:56:49.205745Z","end":"2026-02-18T22:56:49.353413Z","steps":["trace[466630687] 'agreement among raft nodes before linearized reading' (duration: 147.466922ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:56:49.354Z","caller":"traceutil/trace.go:171","msg":"trace[442678527] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"148.62358ms","start":"2026-02-18T22:56:49.205362Z","end":"2026-02-18T22:56:49.353985Z","steps":["trace[442678527] 'process raft request' (duration: 130.370841ms)","trace[442678527] 'compare' (duration: 17.303684ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:25.631772Z","caller":"traceutil/trace.go:171","msg":"trace[945634702] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"156.477371ms","start":"2026-02-18T22:57:25.47525Z","end":"2026-02-18T22:57:25.631727Z","steps":["trace[945634702] 'read index received' (duration: 156.175663ms)","trace[945634702] 'applied index is now lower than readState.Index' (duration: 300.648µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:25.632212Z","caller":"traceutil/trace.go:171","msg":"trace[1136117391] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"300.820448ms","start":"2026-02-18T22:57:25.331309Z","end":"2026-02-18T22:57:25.632129Z","steps":["trace[1136117391] 'process raft request' (duration: 300.232384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:25.63343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:57:25.331278Z","time spent":"302.053489ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T22:57:25.632704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.23089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-18T22:57:25.634272Z","caller":"traceutil/trace.go:171","msg":"trace[1101176929] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1302; }","duration":"152.827401ms","start":"2026-02-18T22:57:25.481428Z","end":"2026-02-18T22:57:25.634255Z","steps":["trace[1101176929] 'agreement among raft nodes before linearized reading' (duration: 151.047696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:25.632839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.591379ms","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-18T22:57:25.635158Z","caller":"traceutil/trace.go:171","msg":"trace[1807327021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"159.957998ms","start":"2026-02-18T22:57:25.47519Z","end":"2026-02-18T22:57:25.635148Z","steps":["trace[1807327021] 'agreement among raft nodes before linearized reading' (duration: 157.587218ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:43.895471Z","caller":"traceutil/trace.go:171","msg":"trace[1119723244] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1433; }","duration":"137.920238ms","start":"2026-02-18T22:57:43.757535Z","end":"2026-02-18T22:57:43.895456Z","steps":["trace[1119723244] 'read index received' (duration: 137.703513ms)","trace[1119723244] 'applied index is now lower than readState.Index' (duration: 216.065µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T22:57:43.895713Z","caller":"traceutil/trace.go:171","msg":"trace[1990762412] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"145.895417ms","start":"2026-02-18T22:57:43.749799Z","end":"2026-02-18T22:57:43.895695Z","steps":["trace[1990762412] 'process raft request' (duration: 145.494257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:43.895947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.38544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-18T22:57:43.895966Z","caller":"traceutil/trace.go:171","msg":"trace[1653427765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1374; }","duration":"138.440131ms","start":"2026-02-18T22:57:43.757519Z","end":"2026-02-18T22:57:43.89596Z","steps":["trace[1653427765] 'agreement among raft nodes before linearized reading' (duration: 138.299737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:43.896073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.690488ms","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-18T22:57:43.896099Z","caller":"traceutil/trace.go:171","msg":"trace[470261866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1374; }","duration":"130.73614ms","start":"2026-02-18T22:57:43.765358Z","end":"2026-02-18T22:57:43.896094Z","steps":["trace[470261866] 'agreement among raft nodes before linearized reading' (duration: 130.682318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:57:43.896279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.621831ms","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-18T22:57:43.896292Z","caller":"traceutil/trace.go:171","msg":"trace[1002097922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1374; }","duration":"123.657683ms","start":"2026-02-18T22:57:43.772631Z","end":"2026-02-18T22:57:43.896288Z","steps":["trace[1002097922] 'agreement among raft nodes before linearized reading' (duration: 123.620462ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:57:44.448699Z","caller":"traceutil/trace.go:171","msg":"trace[1405814959] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"151.037296ms","start":"2026-02-18T22:57:44.297644Z","end":"2026-02-18T22:57:44.448682Z","steps":["trace[1405814959] 'process raft request' (duration: 144.471741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:42.789438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.121635ms","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-18T22:59:42.789508Z","caller":"traceutil/trace.go:171","msg":"trace[717537205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1885; }","duration":"140.215718ms","start":"2026-02-18T22:59:42.649279Z","end":"2026-02-18T22:59:42.789495Z","steps":["trace[717537205] 'range keys from in-memory index tree' (duration: 140.008983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:42.789705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.89928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-18T22:59:42.789736Z","caller":"traceutil/trace.go:171","msg":"trace[808330268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1885; }","duration":"294.962521ms","start":"2026-02-18T22:59:42.494768Z","end":"2026-02-18T22:59:42.78973Z","steps":["trace[808330268] 'range keys from in-memory index tree' (duration: 294.816028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T22:59:42.789817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.56608ms","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-18T22:59:42.789834Z","caller":"traceutil/trace.go:171","msg":"trace[1548558441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1885; }","duration":"141.614302ms","start":"2026-02-18T22:59:42.648213Z","end":"2026-02-18T22:59:42.789827Z","steps":["trace[1548558441] 'range keys from in-memory index tree' (duration: 141.459268ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:45.434746Z","caller":"traceutil/trace.go:171","msg":"trace[1931958683] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"160.630707ms","start":"2026-02-18T22:59:45.274093Z","end":"2026-02-18T22:59:45.434723Z","steps":["trace[1931958683] 'process raft request' (duration: 160.515504ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T22:59:45.588796Z","caller":"traceutil/trace.go:171","msg":"trace[1169130013] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"128.490591ms","start":"2026-02-18T22:59:45.460286Z","end":"2026-02-18T22:59:45.588777Z","steps":["trace[1169130013] 'process raft request' (duration: 105.063778ms)","trace[1169130013] 'compare' (duration: 23.252299ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T22:59:46.38585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T22:59:46.055461Z","time spent":"330.08479ms","remote":"127.0.0.1:34274","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T22:59:46.386284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.723545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T22:59:46.386889Z","caller":"traceutil/trace.go:171","msg":"trace[1722308976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1901; }","duration":"295.361791ms","start":"2026-02-18T22:59:46.09151Z","end":"2026-02-18T22:59:46.386872Z","steps":["trace[1722308976] 'range keys from in-memory index tree' (duration: 294.672624ms)"],"step_count":1}