{"level":"warn","ts":"2026-02-19T18:56:55.257242Z","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-19T18:56:55.25734Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-19T18:56:55.257419Z","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-19T18:56:55.257435Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-02-19T18:56:55.257456Z","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-19T18:56:55.258256Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-02-19T18:56:55.258348Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-19T18:56:55.262533Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.913501ms"} {"level":"info","ts":"2026-02-19T18:56:55.268162Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-02-19T18:56:55.268254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T18:56:55.268285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-02-19T18:56:55.268305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T18:56:55.268318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-02-19T18:56:55.268374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-02-19T18:56:55.27591Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T18:56:55.277791Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T18:56:55.278946Z","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-19T18:56:55.280946Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T18:56:55.281106Z","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-19T18:56:55.281179Z","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-19T18:56:55.281282Z","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-19T18:56:55.281227Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T18:56:55.288854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-02-19T18:56:55.289024Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-02-19T18:56:55.291058Z","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-19T18:56:55.291194Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-02-19T18:56:55.291408Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T18:56:55.291454Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T18:56:55.29124Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-02-19T18:56:55.369018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T18:56:55.369061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T18:56:55.369105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-02-19T18:56:55.369131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-02-19T18:56:55.369149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-02-19T18:56:55.36917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-02-19T18:56:55.369189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-02-19T18:56:55.370096Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T18:56:55.370172Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T18:56:55.370524Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T18:56:55.370711Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T18:56:55.37098Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T18:56:55.370627Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T18:56:55.372125Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T18:56:55.372598Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T18:56:55.372699Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T18:56:55.373331Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T18:56:55.375671Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-02-19T18:57:20.368816Z","caller":"traceutil/trace.go:171","msg":"trace[933375353] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"328.762753ms","start":"2026-02-19T18:57:20.040029Z","end":"2026-02-19T18:57:20.368792Z","steps":["trace[933375353] 'process raft request' (duration: 328.574608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:20.368979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.943103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:57:20.369062Z","caller":"traceutil/trace.go:171","msg":"trace[436667388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"201.069986ms","start":"2026-02-19T18:57:20.167976Z","end":"2026-02-19T18:57:20.369046Z","steps":["trace[436667388] 'agreement among raft nodes before linearized reading' (duration: 200.940063ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:20.368787Z","caller":"traceutil/trace.go:171","msg":"trace[1822735857] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"200.716958ms","start":"2026-02-19T18:57:20.168025Z","end":"2026-02-19T18:57:20.368742Z","steps":["trace[1822735857] 'read index received' (duration: 200.486212ms)","trace[1822735857] 'applied index is now lower than readState.Index' (duration: 229.696µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:57:20.369193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:20.040008Z","time spent":"328.905826ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:57:20.645268Z","caller":"traceutil/trace.go:171","msg":"trace[698947679] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"103.080617ms","start":"2026-02-19T18:57:20.542167Z","end":"2026-02-19T18:57:20.645247Z","steps":["trace[698947679] 'process raft request' (duration: 102.986015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:24.625664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.186713ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T18:57:24.625764Z","caller":"traceutil/trace.go:171","msg":"trace[763953580] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"456.826548ms","start":"2026-02-19T18:57:24.168919Z","end":"2026-02-19T18:57:24.625746Z","steps":["trace[763953580] 'read index received' (duration: 39.601µs)","trace[763953580] 'applied index is now lower than readState.Index' (duration: 456.785757ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:57:24.62586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.93221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:57:24.625893Z","caller":"traceutil/trace.go:171","msg":"trace[88922461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"457.006312ms","start":"2026-02-19T18:57:24.168878Z","end":"2026-02-19T18:57:24.625885Z","steps":["trace[88922461] 'agreement among raft nodes before linearized reading' (duration: 456.94302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:24.625924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:24.168859Z","time spent":"457.056902ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:57:24.625961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.549858ms","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-19T18:57:24.626048Z","caller":"traceutil/trace.go:171","msg":"trace[2004193852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"236.700421ms","start":"2026-02-19T18:57:24.389333Z","end":"2026-02-19T18:57:24.626033Z","steps":["trace[2004193852] 'agreement among raft nodes before linearized reading' (duration: 236.525947ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:26.748705Z","caller":"traceutil/trace.go:171","msg":"trace[2078023649] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"111.161884ms","start":"2026-02-19T18:57:26.63752Z","end":"2026-02-19T18:57:26.748682Z","steps":["trace[2078023649] 'process raft request' (duration: 110.577411ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:30.828835Z","caller":"traceutil/trace.go:171","msg":"trace[699058254] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"160.509556ms","start":"2026-02-19T18:57:30.668301Z","end":"2026-02-19T18:57:30.82881Z","steps":["trace[699058254] 'process raft request' (duration: 160.24876ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:31.041564Z","caller":"traceutil/trace.go:171","msg":"trace[1091913106] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"209.224235ms","start":"2026-02-19T18:57:30.832313Z","end":"2026-02-19T18:57:31.041537Z","steps":["trace[1091913106] 'process raft request' (duration: 209.042721ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:31.047409Z","caller":"traceutil/trace.go:171","msg":"trace[1419276210] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:501; }","duration":"122.725882ms","start":"2026-02-19T18:57:30.924662Z","end":"2026-02-19T18:57:31.047388Z","steps":["trace[1419276210] 'read index received' (duration: 116.770244ms)","trace[1419276210] 'applied index is now lower than readState.Index' (duration: 5.954828ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:57:31.047485Z","caller":"traceutil/trace.go:171","msg":"trace[2001124953] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"213.620997ms","start":"2026-02-19T18:57:30.833854Z","end":"2026-02-19T18:57:31.047475Z","steps":["trace[2001124953] 'process raft request' (duration: 213.432362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:31.047629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.962467ms","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-19T18:57:31.047658Z","caller":"traceutil/trace.go:171","msg":"trace[155551627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"123.040379ms","start":"2026-02-19T18:57:30.92461Z","end":"2026-02-19T18:57:31.047651Z","steps":["trace[155551627] 'agreement among raft nodes before linearized reading' (duration: 122.923396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:31.658833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.903604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:57:31.659114Z","caller":"traceutil/trace.go:171","msg":"trace[161207018] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:503; }","duration":"421.685704ms","start":"2026-02-19T18:57:31.237404Z","end":"2026-02-19T18:57:31.659089Z","steps":["trace[161207018] 'read index received' (duration: 420.747602ms)","trace[161207018] 'applied index is now lower than readState.Index' (duration: 936.592µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:57:31.659313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.901519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-19T18:57:31.659349Z","caller":"traceutil/trace.go:171","msg":"trace[1572822281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"421.93746ms","start":"2026-02-19T18:57:31.2374Z","end":"2026-02-19T18:57:31.659338Z","steps":["trace[1572822281] 'agreement among raft nodes before linearized reading' (duration: 421.773796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:31.659392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:31.237377Z","time spent":"422.006791ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T18:57:31.659553Z","caller":"traceutil/trace.go:171","msg":"trace[1830319540] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"435.535425ms","start":"2026-02-19T18:57:31.223968Z","end":"2026-02-19T18:57:31.659503Z","steps":["trace[1830319540] 'process raft request' (duration: 435.017093ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:31.659625Z","caller":"traceutil/trace.go:171","msg":"trace[989619661] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"608.692774ms","start":"2026-02-19T18:57:31.05088Z","end":"2026-02-19T18:57:31.659573Z","steps":["trace[989619661] 'process raft request' (duration: 120.841768ms)","trace[989619661] 'compare' (duration: 486.694169ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:57:31.659781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:31.223946Z","time spent":"435.702418ms","remote":"127.0.0.1:45486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4346,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:57:31.659791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:31.050866Z","time spent":"608.835168ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:57:31.695612Z","caller":"traceutil/trace.go:171","msg":"trace[659675252] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"239.999377ms","start":"2026-02-19T18:57:31.455584Z","end":"2026-02-19T18:57:31.695583Z","steps":["trace[659675252] 'process raft request' (duration: 239.860324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:32.763024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.418458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:57:32.763124Z","caller":"traceutil/trace.go:171","msg":"trace[1206014572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"595.56235ms","start":"2026-02-19T18:57:32.167545Z","end":"2026-02-19T18:57:32.763107Z","steps":["trace[1206014572] 'range keys from in-memory index tree' (duration: 595.289784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:32.763162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:32.167525Z","time spent":"595.629062ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T18:57:33.283249Z","caller":"traceutil/trace.go:171","msg":"trace[370310996] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:507; }","duration":"232.267768ms","start":"2026-02-19T18:57:33.050954Z","end":"2026-02-19T18:57:33.283221Z","steps":["trace[370310996] 'read index received' (duration: 231.964751ms)","trace[370310996] 'applied index is now lower than readState.Index' (duration: 301.787µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:57:33.283335Z","caller":"traceutil/trace.go:171","msg":"trace[465882571] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"233.369134ms","start":"2026-02-19T18:57:33.049938Z","end":"2026-02-19T18:57:33.283307Z","steps":["trace[465882571] 'process raft request' (duration: 233.056857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:33.283494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.971402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:57:33.283558Z","caller":"traceutil/trace.go:171","msg":"trace[1030218217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"115.051854ms","start":"2026-02-19T18:57:33.16847Z","end":"2026-02-19T18:57:33.283522Z","steps":["trace[1030218217] 'agreement among raft nodes before linearized reading' (duration: 114.949312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:33.283582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.625456ms","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-19T18:57:33.28363Z","caller":"traceutil/trace.go:171","msg":"trace[1945931712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:489; }","duration":"232.674377ms","start":"2026-02-19T18:57:33.05094Z","end":"2026-02-19T18:57:33.283614Z","steps":["trace[1945931712] 'agreement among raft nodes before linearized reading' (duration: 232.408211ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:35.187033Z","caller":"traceutil/trace.go:171","msg":"trace[903448559] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"223.024165ms","start":"2026-02-19T18:57:34.96399Z","end":"2026-02-19T18:57:35.187014Z","steps":["trace[903448559] 'process raft request' (duration: 222.937853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:47.3298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.39591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:57:47.329911Z","caller":"traceutil/trace.go:171","msg":"trace[1749913293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:659; }","duration":"162.571154ms","start":"2026-02-19T18:57:47.16732Z","end":"2026-02-19T18:57:47.329891Z","steps":["trace[1749913293] 'range keys from in-memory index tree' (duration: 162.297467ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:53.004507Z","caller":"traceutil/trace.go:171","msg":"trace[970179613] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"170.206602ms","start":"2026-02-19T18:57:52.834284Z","end":"2026-02-19T18:57:53.004491Z","steps":["trace[970179613] 'process raft request' (duration: 170.099939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:54.829799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.258665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:57:54.829877Z","caller":"traceutil/trace.go:171","msg":"trace[1850933141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"662.351047ms","start":"2026-02-19T18:57:54.167508Z","end":"2026-02-19T18:57:54.829859Z","steps":["trace[1850933141] 'range keys from in-memory index tree' (duration: 662.184303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:54.829909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:54.167447Z","time spent":"662.455109ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:57:54.830016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.633522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-19T18:57:54.830061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328009259s","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-19T18:57:54.830092Z","caller":"traceutil/trace.go:171","msg":"trace[104516872] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:678; }","duration":"934.723064ms","start":"2026-02-19T18:57:53.89535Z","end":"2026-02-19T18:57:54.830073Z","steps":["trace[104516872] 'range keys from in-memory index tree' (duration: 934.491869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:54.830046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.419093637s","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-19T18:57:54.830126Z","caller":"traceutil/trace.go:171","msg":"trace[87774637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"1.328106241s","start":"2026-02-19T18:57:53.502004Z","end":"2026-02-19T18:57:54.83011Z","steps":["trace[87774637] 'range keys from in-memory index tree' (duration: 1.327860956s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:54.830134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:53.895301Z","time spent":"934.823496ms","remote":"127.0.0.1:45576","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-19T18:57:54.830155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:53.501992Z","time spent":"1.328157083s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T18:57:54.830164Z","caller":"traceutil/trace.go:171","msg":"trace[166723843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"1.419258871s","start":"2026-02-19T18:57:53.410874Z","end":"2026-02-19T18:57:54.830133Z","steps":["trace[166723843] 'range keys from in-memory index tree' (duration: 1.418875703s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:54.830207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:53.410852Z","time spent":"1.419345894s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T18:57:54.830334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.056565ms","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-19T18:57:54.830374Z","caller":"traceutil/trace.go:171","msg":"trace[585044863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:678; }","duration":"952.141327ms","start":"2026-02-19T18:57:53.878221Z","end":"2026-02-19T18:57:54.830362Z","steps":["trace[585044863] 'range keys from in-memory index tree' (duration: 951.163965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:54.830401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:53.878196Z","time spent":"952.197418ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T18:57:55.331948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511627362986435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T18:57:55.832389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511627362986435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T18:57:55.885308Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.710542666s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-19T18:57:56.602725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.205077ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T18:57:56.643955Z","caller":"traceutil/trace.go:171","msg":"trace[389463461] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.8095713s","start":"2026-02-19T18:57:54.834363Z","end":"2026-02-19T18:57:56.643934Z","steps":["trace[389463461] 'process raft request' (duration: 1.809513628s)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:56.643964Z","caller":"traceutil/trace.go:171","msg":"trace[2051616145] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"1.809708263s","start":"2026-02-19T18:57:54.834236Z","end":"2026-02-19T18:57:56.643945Z","steps":["trace[2051616145] 'process raft request' (duration: 1.80958725s)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:56.643939Z","caller":"traceutil/trace.go:171","msg":"trace[98711922] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"1.810004969s","start":"2026-02-19T18:57:54.833906Z","end":"2026-02-19T18:57:56.643911Z","steps":["trace[98711922] 'process raft request' (duration: 1.809786334s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:56.6441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:54.834224Z","time spent":"1.809793235s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:57:56.644115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:54.833894Z","time spent":"1.810137393s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:57:56.644216Z","caller":"traceutil/trace.go:171","msg":"trace[1883556808] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:702; }","duration":"1.812967188s","start":"2026-02-19T18:57:54.831237Z","end":"2026-02-19T18:57:56.644204Z","steps":["trace[1883556808] 'read index received' (duration: 1.054236929s)","trace[1883556808] 'applied index is now lower than readState.Index' (duration: 758.729219ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:57:56.644299Z","caller":"traceutil/trace.go:171","msg":"trace[1083103995] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.809913787s","start":"2026-02-19T18:57:54.834377Z","end":"2026-02-19T18:57:56.644291Z","steps":["trace[1083103995] 'process raft request' (duration: 1.809529758s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:56.644285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:54.834344Z","time spent":"1.809668062s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:57:56.64436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:54.834351Z","time spent":"1.809974839s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:57:56.64454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635634452s","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":"warn","ts":"2026-02-19T18:57:56.644564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.813323656s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:57:56.644588Z","caller":"traceutil/trace.go:171","msg":"trace[1003318352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.635712554s","start":"2026-02-19T18:57:55.008863Z","end":"2026-02-19T18:57:56.644575Z","steps":["trace[1003318352] 'agreement among raft nodes before linearized reading' (duration: 1.635582661s)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:56.644598Z","caller":"traceutil/trace.go:171","msg":"trace[1590650374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"1.813354997s","start":"2026-02-19T18:57:54.831232Z","end":"2026-02-19T18:57:56.644587Z","steps":["trace[1590650374] 'agreement among raft nodes before linearized reading' (duration: 1.813305036s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:57:56.644622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:55.008847Z","time spent":"1.635766855s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T18:57:56.644625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:57:54.831191Z","time spent":"1.813428619s","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T18:57:56.761205Z","caller":"traceutil/trace.go:171","msg":"trace[735445888] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"107.040739ms","start":"2026-02-19T18:57:56.654139Z","end":"2026-02-19T18:57:56.76118Z","steps":["trace[735445888] 'read index received' (duration: 52.424284ms)","trace[735445888] 'applied index is now lower than readState.Index' (duration: 54.615645ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:57:56.761325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.169271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:57:56.761374Z","caller":"traceutil/trace.go:171","msg":"trace[2053135756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"107.235023ms","start":"2026-02-19T18:57:56.654132Z","end":"2026-02-19T18:57:56.761367Z","steps":["trace[2053135756] 'agreement among raft nodes before linearized reading' (duration: 107.12628ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:57:56.761323Z","caller":"traceutil/trace.go:171","msg":"trace[1725643875] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"109.095886ms","start":"2026-02-19T18:57:56.652195Z","end":"2026-02-19T18:57:56.761291Z","steps":["trace[1725643875] 'process raft request' (duration: 54.41191ms)","trace[1725643875] 'compare' (duration: 54.491282ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:02.558571Z","caller":"traceutil/trace.go:171","msg":"trace[359602618] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"123.340736ms","start":"2026-02-19T18:58:02.435211Z","end":"2026-02-19T18:58:02.558552Z","steps":["trace[359602618] 'process raft request' (duration: 123.219073ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:02.965636Z","caller":"traceutil/trace.go:171","msg":"trace[737983056] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"161.915518ms","start":"2026-02-19T18:58:02.803695Z","end":"2026-02-19T18:58:02.965611Z","steps":["trace[737983056] 'read index received' (duration: 161.762514ms)","trace[737983056] 'applied index is now lower than readState.Index' (duration: 150.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:02.965675Z","caller":"traceutil/trace.go:171","msg":"trace[302434071] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"174.51789ms","start":"2026-02-19T18:58:02.791126Z","end":"2026-02-19T18:58:02.965644Z","steps":["trace[302434071] 'process raft request' (duration: 174.318815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:02.965766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.046081ms","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-19T18:58:02.965802Z","caller":"traceutil/trace.go:171","msg":"trace[1698549793] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:706; }","duration":"162.115843ms","start":"2026-02-19T18:58:02.803675Z","end":"2026-02-19T18:58:02.965791Z","steps":["trace[1698549793] 'agreement among raft nodes before linearized reading' (duration: 162.031531ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:03.110149Z","caller":"traceutil/trace.go:171","msg":"trace[1994137682] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"140.256798ms","start":"2026-02-19T18:58:02.969864Z","end":"2026-02-19T18:58:03.11012Z","steps":["trace[1994137682] 'process raft request' (duration: 130.160954ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:25.490748Z","caller":"traceutil/trace.go:171","msg":"trace[1899204883] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"179.358043ms","start":"2026-02-19T18:58:25.311212Z","end":"2026-02-19T18:58:25.49057Z","steps":["trace[1899204883] 'process raft request' (duration: 179.053556ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:27.076626Z","caller":"traceutil/trace.go:171","msg":"trace[1542379986] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"146.833281ms","start":"2026-02-19T18:58:26.929728Z","end":"2026-02-19T18:58:27.076561Z","steps":["trace[1542379986] 'process raft request' (duration: 98.698756ms)","trace[1542379986] 'compare' (duration: 48.003252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:58:27.668774Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511627362986695,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T18:58:28.116037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.302469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:58:28.116149Z","caller":"traceutil/trace.go:171","msg":"trace[1847840219] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"1.186130343s","start":"2026-02-19T18:58:26.930007Z","end":"2026-02-19T18:58:28.116137Z","steps":["trace[1847840219] 'process raft request' (duration: 880.658431ms)","trace[1847840219] 'compare' (duration: 304.93951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:58:28.116186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:26.929983Z","time spent":"1.186186645s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:58:28.116274Z","caller":"traceutil/trace.go:171","msg":"trace[28071948] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:802; }","duration":"947.726263ms","start":"2026-02-19T18:58:27.168542Z","end":"2026-02-19T18:58:28.116268Z","steps":["trace[28071948] 'read index received' (duration: 642.111277ms)","trace[28071948] 'applied index is now lower than readState.Index' (duration: 305.614376ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:28.116342Z","caller":"traceutil/trace.go:171","msg":"trace[1017744162] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"1.185918009s","start":"2026-02-19T18:58:26.930418Z","end":"2026-02-19T18:58:28.116336Z","steps":["trace[1017744162] 'process raft request' (duration: 1.185682933s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:28.116364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:26.930402Z","time spent":"1.185949499s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:58:28.116435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.903627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:58:28.116448Z","caller":"traceutil/trace.go:171","msg":"trace[1369361441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"947.944898ms","start":"2026-02-19T18:58:27.168498Z","end":"2026-02-19T18:58:28.116443Z","steps":["trace[1369361441] 'agreement among raft nodes before linearized reading' (duration: 947.903947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:28.11646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:27.168482Z","time spent":"947.975559ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:58:28.116756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.847687ms","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-19T18:58:28.116771Z","caller":"traceutil/trace.go:171","msg":"trace[494956310] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:772; }","duration":"716.897619ms","start":"2026-02-19T18:58:27.399869Z","end":"2026-02-19T18:58:28.116766Z","steps":["trace[494956310] 'agreement among raft nodes before linearized reading' (duration: 716.864558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:28.116798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:27.399852Z","time spent":"716.942139ms","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-19T18:58:28.116919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.272685ms","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-19T18:58:28.117016Z","caller":"traceutil/trace.go:171","msg":"trace[1341901638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"621.384207ms","start":"2026-02-19T18:58:27.495614Z","end":"2026-02-19T18:58:28.116999Z","steps":["trace[1341901638] 'agreement among raft nodes before linearized reading' (duration: 621.135171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:28.117063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:27.495559Z","time spent":"621.493589ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T18:58:29.430251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511627362986704,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T18:58:29.830229Z","caller":"traceutil/trace.go:171","msg":"trace[282795749] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"1.013017676s","start":"2026-02-19T18:58:28.81718Z","end":"2026-02-19T18:58:29.830198Z","steps":["trace[282795749] 'process raft request' (duration: 926.039101ms)","trace[282795749] 'compare' (duration: 86.829371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:58:29.830413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:28.817164Z","time spent":"1.013154859s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:58:29.887395Z","caller":"traceutil/trace.go:171","msg":"trace[2014369733] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"957.91994ms","start":"2026-02-19T18:58:28.929448Z","end":"2026-02-19T18:58:29.887368Z","steps":["trace[2014369733] 'read index received' (duration: 813.877925ms)","trace[2014369733] 'applied index is now lower than readState.Index' (duration: 144.040975ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:29.887513Z","caller":"traceutil/trace.go:171","msg":"trace[792422826] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"469.355529ms","start":"2026-02-19T18:58:29.418149Z","end":"2026-02-19T18:58:29.887504Z","steps":["trace[792422826] 'process raft request' (duration: 469.061862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:29.887612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:29.418129Z","time spent":"469.409079ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:58:29.887822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.697718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T18:58:29.887811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.092868ms","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-19T18:58:29.88788Z","caller":"traceutil/trace.go:171","msg":"trace[2022432116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:775; }","duration":"720.768799ms","start":"2026-02-19T18:58:29.167097Z","end":"2026-02-19T18:58:29.887866Z","steps":["trace[2022432116] 'agreement among raft nodes before linearized reading' (duration: 720.677377ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:29.887895Z","caller":"traceutil/trace.go:171","msg":"trace[608798316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"807.19765ms","start":"2026-02-19T18:58:29.080678Z","end":"2026-02-19T18:58:29.887876Z","steps":["trace[608798316] 'agreement among raft nodes before linearized reading' (duration: 806.992566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:29.887914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:29.167091Z","time spent":"720.81647ms","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:58:29.887933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:29.080671Z","time spent":"807.254462ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-19T18:58:29.888081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.626847ms","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-19T18:58:29.888115Z","caller":"traceutil/trace.go:171","msg":"trace[1319857873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:775; }","duration":"958.677058ms","start":"2026-02-19T18:58:28.929428Z","end":"2026-02-19T18:58:29.888105Z","steps":["trace[1319857873] 'agreement among raft nodes before linearized reading' (duration: 958.528994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:29.888146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:28.92942Z","time spent":"958.719129ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T18:58:30.50709Z","caller":"traceutil/trace.go:171","msg":"trace[1392508881] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"615.059142ms","start":"2026-02-19T18:58:29.891978Z","end":"2026-02-19T18:58:30.507037Z","steps":["trace[1392508881] 'process raft request' (duration: 567.978332ms)","trace[1392508881] 'compare' (duration: 46.986108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:58:30.507359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:29.891946Z","time spent":"615.294538ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:58:30.553999Z","caller":"traceutil/trace.go:171","msg":"trace[1717659249] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:808; }","duration":"531.135449ms","start":"2026-02-19T18:58:30.022848Z","end":"2026-02-19T18:58:30.553983Z","steps":["trace[1717659249] 'read index received' (duration: 437.123422ms)","trace[1717659249] 'applied index is now lower than readState.Index' (duration: 94.011037ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:30.554093Z","caller":"traceutil/trace.go:171","msg":"trace[1175020541] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"660.839551ms","start":"2026-02-19T18:58:29.89322Z","end":"2026-02-19T18:58:30.55406Z","steps":["trace[1175020541] 'process raft request' (duration: 660.674558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:30.554219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.360894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T18:58:30.554254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:29.893199Z","time spent":"660.975825ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:58:30.554325Z","caller":"traceutil/trace.go:171","msg":"trace[365000223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"531.459865ms","start":"2026-02-19T18:58:30.022818Z","end":"2026-02-19T18:58:30.554278Z","steps":["trace[365000223] 'agreement among raft nodes before linearized reading' (duration: 531.317532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:30.554389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.022806Z","time spent":"531.572079ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:58:30.554374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.556011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T18:58:30.554457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.783177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-19T18:58:30.55451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.97646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T18:58:30.554528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.183152ms","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-19T18:58:30.554585Z","caller":"traceutil/trace.go:171","msg":"trace[1789781971] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:777; }","duration":"387.106513ms","start":"2026-02-19T18:58:30.167459Z","end":"2026-02-19T18:58:30.554566Z","steps":["trace[1789781971] 'agreement among raft nodes before linearized reading' (duration: 386.97518ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:30.554575Z","caller":"traceutil/trace.go:171","msg":"trace[91117617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"434.929061ms","start":"2026-02-19T18:58:30.119603Z","end":"2026-02-19T18:58:30.554532Z","steps":["trace[91117617] 'agreement among raft nodes before linearized reading' (duration: 434.558833ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:30.554603Z","caller":"traceutil/trace.go:171","msg":"trace[320818386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"345.306556ms","start":"2026-02-19T18:58:30.209278Z","end":"2026-02-19T18:58:30.554585Z","steps":["trace[320818386] 'agreement among raft nodes before linearized reading' (duration: 345.06256ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:30.554487Z","caller":"traceutil/trace.go:171","msg":"trace[1583209166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"386.698244ms","start":"2026-02-19T18:58:30.167762Z","end":"2026-02-19T18:58:30.55446Z","steps":["trace[1583209166] 'agreement among raft nodes before linearized reading' (duration: 386.510549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:30.554629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.167438Z","time spent":"387.183015ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-19T18:58:30.554653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.209268Z","time spent":"345.375847ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T18:58:30.554673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.119593Z","time spent":"435.066114ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T18:58:30.554667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.16775Z","time spent":"386.901989ms","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:58:30.554537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.47336ms","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-19T18:58:30.55481Z","caller":"traceutil/trace.go:171","msg":"trace[515028921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"415.773337ms","start":"2026-02-19T18:58:30.139025Z","end":"2026-02-19T18:58:30.554798Z","steps":["trace[515028921] 'agreement among raft nodes before linearized reading' (duration: 415.416229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:30.554879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.139007Z","time spent":"415.860809ms","remote":"127.0.0.1:45576","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":"info","ts":"2026-02-19T18:58:31.273062Z","caller":"traceutil/trace.go:171","msg":"trace[2058252769] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"714.539775ms","start":"2026-02-19T18:58:30.558498Z","end":"2026-02-19T18:58:31.273038Z","steps":["trace[2058252769] 'process raft request' (duration: 714.387342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:31.273215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.558479Z","time spent":"714.653697ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:58:31.285473Z","caller":"traceutil/trace.go:171","msg":"trace[215425700] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:810; }","duration":"118.76225ms","start":"2026-02-19T18:58:31.166686Z","end":"2026-02-19T18:58:31.285448Z","steps":["trace[215425700] 'read index received' (duration: 106.316331ms)","trace[215425700] 'applied index is now lower than readState.Index' (duration: 12.444909ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:31.285509Z","caller":"traceutil/trace.go:171","msg":"trace[582610774] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"726.673676ms","start":"2026-02-19T18:58:30.558804Z","end":"2026-02-19T18:58:31.285478Z","steps":["trace[582610774] 'process raft request' (duration: 719.310686ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:31.285639Z","caller":"traceutil/trace.go:171","msg":"trace[603335494] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"725.94928ms","start":"2026-02-19T18:58:30.559667Z","end":"2026-02-19T18:58:31.285616Z","steps":["trace[603335494] 'process raft request' (duration: 725.733145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:31.285791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.55966Z","time spent":"726.060032ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:58:31.285801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:30.558781Z","time spent":"726.936362ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:58:31.285638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.947644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:58:31.285919Z","caller":"traceutil/trace.go:171","msg":"trace[252090402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:780; }","duration":"119.287452ms","start":"2026-02-19T18:58:31.166606Z","end":"2026-02-19T18:58:31.285893Z","steps":["trace[252090402] 'agreement among raft nodes before linearized reading' (duration: 118.941744ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:42.836898Z","caller":"traceutil/trace.go:171","msg":"trace[2040325398] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:896; }","duration":"185.939335ms","start":"2026-02-19T18:58:42.650932Z","end":"2026-02-19T18:58:42.836871Z","steps":["trace[2040325398] 'read index received' (duration: 185.776221ms)","trace[2040325398] 'applied index is now lower than readState.Index' (duration: 161.764µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:58:42.837088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.134369ms","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-19T18:58:42.837134Z","caller":"traceutil/trace.go:171","msg":"trace[516864649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:860; }","duration":"186.238312ms","start":"2026-02-19T18:58:42.650886Z","end":"2026-02-19T18:58:42.837124Z","steps":["trace[516864649] 'agreement among raft nodes before linearized reading' (duration: 186.085818ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:42.837155Z","caller":"traceutil/trace.go:171","msg":"trace[1806089689] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"251.922953ms","start":"2026-02-19T18:58:42.585204Z","end":"2026-02-19T18:58:42.837127Z","steps":["trace[1806089689] 'process raft request' (duration: 251.511883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:43.489244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.24845ms","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-19T18:58:43.489363Z","caller":"traceutil/trace.go:171","msg":"trace[1222070079] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:860; }","duration":"518.384243ms","start":"2026-02-19T18:58:42.970961Z","end":"2026-02-19T18:58:43.489346Z","steps":["trace[1222070079] 'range keys from in-memory index tree' (duration: 518.23334ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:43.489902Z","caller":"traceutil/trace.go:171","msg":"trace[386554617] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"153.368872ms","start":"2026-02-19T18:58:43.336521Z","end":"2026-02-19T18:58:43.48989Z","steps":["trace[386554617] 'read index received' (duration: 151.58016ms)","trace[386554617] 'applied index is now lower than readState.Index' (duration: 1.787862ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:43.490142Z","caller":"traceutil/trace.go:171","msg":"trace[1732674982] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"648.849974ms","start":"2026-02-19T18:58:42.841282Z","end":"2026-02-19T18:58:43.490132Z","steps":["trace[1732674982] 'process raft request' (duration: 646.877728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:43.490233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:42.841257Z","time spent":"648.919516ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:58:43.490356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.814342ms","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-19T18:58:43.490486Z","caller":"traceutil/trace.go:171","msg":"trace[602178624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"153.965546ms","start":"2026-02-19T18:58:43.336495Z","end":"2026-02-19T18:58:43.49046Z","steps":["trace[602178624] 'agreement among raft nodes before linearized reading' (duration: 153.690679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:43.491619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.651259ms","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-19T18:58:43.491778Z","caller":"traceutil/trace.go:171","msg":"trace[478354640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:862; }","duration":"147.812453ms","start":"2026-02-19T18:58:43.343938Z","end":"2026-02-19T18:58:43.49175Z","steps":["trace[478354640] 'agreement among raft nodes before linearized reading' (duration: 146.634405ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:43.490618Z","caller":"traceutil/trace.go:171","msg":"trace[171261287] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"118.076614ms","start":"2026-02-19T18:58:43.372528Z","end":"2026-02-19T18:58:43.490605Z","steps":["trace[171261287] 'process raft request' (duration: 117.941281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:43.492219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.673645ms","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-19T18:58:43.492262Z","caller":"traceutil/trace.go:171","msg":"trace[536304294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:862; }","duration":"133.743137ms","start":"2026-02-19T18:58:43.358498Z","end":"2026-02-19T18:58:43.492241Z","steps":["trace[536304294] 'agreement among raft nodes before linearized reading' (duration: 133.586883ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:45.72547Z","caller":"traceutil/trace.go:171","msg":"trace[52082043] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"163.745662ms","start":"2026-02-19T18:58:45.561707Z","end":"2026-02-19T18:58:45.725453Z","steps":["trace[52082043] 'process raft request' (duration: 163.712601ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:45.7256Z","caller":"traceutil/trace.go:171","msg":"trace[611198181] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"164.438877ms","start":"2026-02-19T18:58:45.561156Z","end":"2026-02-19T18:58:45.725595Z","steps":["trace[611198181] 'process raft request' (duration: 164.167181ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:55.753412Z","caller":"traceutil/trace.go:171","msg":"trace[1556215160] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"113.829936ms","start":"2026-02-19T18:58:55.639562Z","end":"2026-02-19T18:58:55.753391Z","steps":["trace[1556215160] 'read index received' (duration: 113.659292ms)","trace[1556215160] 'applied index is now lower than readState.Index' (duration: 169.734µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:55.753695Z","caller":"traceutil/trace.go:171","msg":"trace[268758893] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"870.178819ms","start":"2026-02-19T18:58:54.883482Z","end":"2026-02-19T18:58:55.75366Z","steps":["trace[268758893] 'process raft request' (duration: 869.80009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:55.753802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:54.883454Z","time spent":"870.278341ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:58:55.753977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.401449ms","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-19T18:58:55.754066Z","caller":"traceutil/trace.go:171","msg":"trace[285582638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"114.535742ms","start":"2026-02-19T18:58:55.639509Z","end":"2026-02-19T18:58:55.754045Z","steps":["trace[285582638] 'agreement among raft nodes before linearized reading' (duration: 114.316667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:56.15887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.567933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:58:56.159226Z","caller":"traceutil/trace.go:171","msg":"trace[2021902875] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"400.555654ms","start":"2026-02-19T18:58:55.75865Z","end":"2026-02-19T18:58:56.159206Z","steps":["trace[2021902875] 'process raft request' (duration: 285.568162ms)","trace[2021902875] 'compare' (duration: 114.371408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:58:56.159327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:55.758627Z","time spent":"400.635917ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:58:57.072866Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.028665078s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-19T18:58:57.310843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.753595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:58:57.310935Z","caller":"traceutil/trace.go:171","msg":"trace[509738928] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:934; }","duration":"1.536547917s","start":"2026-02-19T18:58:55.774375Z","end":"2026-02-19T18:58:57.310923Z","steps":["trace[509738928] 'read index received' (duration: 269.858138ms)","trace[509738928] 'applied index is now lower than readState.Index' (duration: 1.266688899s)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:58:57.310998Z","caller":"traceutil/trace.go:171","msg":"trace[1621102146] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"1.552179809s","start":"2026-02-19T18:58:55.75881Z","end":"2026-02-19T18:58:57.31099Z","steps":["trace[1621102146] 'process raft request' (duration: 1.314200909s)","trace[1621102146] 'compare' (duration: 237.246483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:58:57.311039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:55.758788Z","time spent":"1.55223049s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:58:57.311216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536843864s","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-19T18:58:57.311241Z","caller":"traceutil/trace.go:171","msg":"trace[281753819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"1.536900365s","start":"2026-02-19T18:58:55.774333Z","end":"2026-02-19T18:58:57.311233Z","steps":["trace[281753819] 'agreement among raft nodes before linearized reading' (duration: 1.536814393s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:57.31126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:55.774315Z","time spent":"1.536941166s","remote":"127.0.0.1:45576","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-19T18:58:57.311344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504997456s","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-19T18:58:57.311364Z","caller":"traceutil/trace.go:171","msg":"trace[1104797445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"1.505038338s","start":"2026-02-19T18:58:55.80632Z","end":"2026-02-19T18:58:57.311358Z","steps":["trace[1104797445] 'agreement among raft nodes before linearized reading' (duration: 1.504989837s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:57.311379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:55.806308Z","time spent":"1.505067078s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T18:58:57.311439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098038624s","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-19T18:58:57.311458Z","caller":"traceutil/trace.go:171","msg":"trace[2024813118] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:896; }","duration":"1.098089025s","start":"2026-02-19T18:58:56.213365Z","end":"2026-02-19T18:58:57.311454Z","steps":["trace[2024813118] 'agreement among raft nodes before linearized reading' (duration: 1.098059604s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:57.311474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:56.213343Z","time spent":"1.098127385s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-19T18:58:57.311525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145875641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:58:57.311543Z","caller":"traceutil/trace.go:171","msg":"trace[1035551731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"1.145935892s","start":"2026-02-19T18:58:56.165602Z","end":"2026-02-19T18:58:57.311538Z","steps":["trace[1035551731] 'agreement among raft nodes before linearized reading' (duration: 1.145909411s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:57.311557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:56.165584Z","time spent":"1.145969873s","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:58:57.311656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452295397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:58:57.311679Z","caller":"traceutil/trace.go:171","msg":"trace[1307694971] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:896; }","duration":"1.452363418s","start":"2026-02-19T18:58:55.859309Z","end":"2026-02-19T18:58:57.311673Z","steps":["trace[1307694971] 'agreement among raft nodes before linearized reading' (duration: 1.452329737s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:58:57.311696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:58:55.859283Z","time spent":"1.452408779s","remote":"127.0.0.1:45642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"info","ts":"2026-02-19T18:58:58.35479Z","caller":"traceutil/trace.go:171","msg":"trace[2132169062] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"188.894163ms","start":"2026-02-19T18:58:58.165874Z","end":"2026-02-19T18:58:58.354768Z","steps":["trace[2132169062] 'process raft request' (duration: 188.789511ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:58:59.893608Z","caller":"traceutil/trace.go:171","msg":"trace[415174373] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"119.05844ms","start":"2026-02-19T18:58:59.774523Z","end":"2026-02-19T18:58:59.893582Z","steps":["trace[415174373] 'process raft request' (duration: 118.914317ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:10.888484Z","caller":"traceutil/trace.go:171","msg":"trace[1102674646] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"310.521837ms","start":"2026-02-19T18:59:10.577939Z","end":"2026-02-19T18:59:10.888461Z","steps":["trace[1102674646] 'process raft request' (duration: 310.314492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:10.888624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:10.577915Z","time spent":"310.63846ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:59:11.391139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.221138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:59:11.391213Z","caller":"traceutil/trace.go:171","msg":"trace[201794888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"222.343401ms","start":"2026-02-19T18:59:11.168853Z","end":"2026-02-19T18:59:11.391197Z","steps":["trace[201794888] 'range keys from in-memory index tree' (duration: 222.136407ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:15.821897Z","caller":"traceutil/trace.go:171","msg":"trace[1916442582] linearizableReadLoop","detail":"{readStateIndex:1011; appliedIndex:1010; }","duration":"244.853168ms","start":"2026-02-19T18:59:15.577014Z","end":"2026-02-19T18:59:15.821867Z","steps":["trace[1916442582] 'read index received' (duration: 244.627263ms)","trace[1916442582] 'applied index is now lower than readState.Index' (duration: 224.625µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:59:15.82212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.094313ms","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-19T18:59:15.822162Z","caller":"traceutil/trace.go:171","msg":"trace[601731543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:967; }","duration":"245.182226ms","start":"2026-02-19T18:59:15.576968Z","end":"2026-02-19T18:59:15.82215Z","steps":["trace[601731543] 'agreement among raft nodes before linearized reading' (duration: 245.036062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:15.822388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.499382ms","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-19T18:59:15.822486Z","caller":"traceutil/trace.go:171","msg":"trace[889416367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:967; }","duration":"230.653345ms","start":"2026-02-19T18:59:15.591815Z","end":"2026-02-19T18:59:15.822468Z","steps":["trace[889416367] 'agreement among raft nodes before linearized reading' (duration: 230.231485ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:15.822804Z","caller":"traceutil/trace.go:171","msg":"trace[1433366203] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"248.581286ms","start":"2026-02-19T18:59:15.573395Z","end":"2026-02-19T18:59:15.821976Z","steps":["trace[1433366203] 'process raft request' (duration: 248.319009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:16.588957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511627362987171,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T18:59:17.055214Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.227308473s","expected-duration":"1s"} {"level":"info","ts":"2026-02-19T18:59:17.055594Z","caller":"traceutil/trace.go:171","msg":"trace[1570553012] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"1.227756685s","start":"2026-02-19T18:59:15.827803Z","end":"2026-02-19T18:59:17.05556Z","steps":["trace[1570553012] 'process raft request' (duration: 1.227641072s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:17.055722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:15.827779Z","time spent":"1.227870157s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:59:17.191357Z","caller":"traceutil/trace.go:171","msg":"trace[854688434] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"1.103219795s","start":"2026-02-19T18:59:16.088112Z","end":"2026-02-19T18:59:17.191332Z","steps":["trace[854688434] 'read index received' (duration: 967.333491ms)","trace[854688434] 'applied index is now lower than readState.Index' (duration: 135.885184ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:59:17.191591Z","caller":"traceutil/trace.go:171","msg":"trace[918108414] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.360506835s","start":"2026-02-19T18:59:15.831071Z","end":"2026-02-19T18:59:17.191578Z","steps":["trace[918108414] 'process raft request' (duration: 1.26554293s)","trace[918108414] 'compare' (duration: 94.386002ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:59:17.19169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:15.831046Z","time spent":"1.360573667s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:59:17.191747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.519355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-19T18:59:17.19177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103638785s","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-19T18:59:17.191798Z","caller":"traceutil/trace.go:171","msg":"trace[1563643940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"577.619107ms","start":"2026-02-19T18:59:16.614164Z","end":"2026-02-19T18:59:17.191783Z","steps":["trace[1563643940] 'agreement among raft nodes before linearized reading' (duration: 577.447313ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:17.191831Z","caller":"traceutil/trace.go:171","msg":"trace[414237179] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"1.103731438s","start":"2026-02-19T18:59:16.088081Z","end":"2026-02-19T18:59:17.191813Z","steps":["trace[414237179] 'agreement among raft nodes before linearized reading' (duration: 1.103541343s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:17.191838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:16.614144Z","time spent":"577.686388ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T18:59:17.191876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:16.088067Z","time spent":"1.103788179s","remote":"127.0.0.1:45576","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":"info","ts":"2026-02-19T18:59:18.116951Z","caller":"traceutil/trace.go:171","msg":"trace[1428601631] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"120.521485ms","start":"2026-02-19T18:59:17.996381Z","end":"2026-02-19T18:59:18.116902Z","steps":["trace[1428601631] 'process raft request' (duration: 120.283669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:19.79687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.378957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:59:19.797016Z","caller":"traceutil/trace.go:171","msg":"trace[254673280] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:1019; }","duration":"814.99748ms","start":"2026-02-19T18:59:18.981995Z","end":"2026-02-19T18:59:19.796992Z","steps":["trace[254673280] 'read index received' (duration: 17.396597ms)","trace[254673280] 'applied index is now lower than readState.Index' (duration: 797.598203ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:59:19.797085Z","caller":"traceutil/trace.go:171","msg":"trace[1104813505] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"898.303413ms","start":"2026-02-19T18:59:18.898716Z","end":"2026-02-19T18:59:19.79702Z","steps":["trace[1104813505] 'process raft request' (duration: 100.655809ms)","trace[1104813505] 'compare' (duration: 797.164292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:59:19.797295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.292258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:59:19.797364Z","caller":"traceutil/trace.go:171","msg":"trace[1259325580] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:975; }","duration":"815.376559ms","start":"2026-02-19T18:59:18.981949Z","end":"2026-02-19T18:59:19.797325Z","steps":["trace[1259325580] 'agreement among raft nodes before linearized reading' (duration: 815.166514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:19.797389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.407011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T18:59:19.797403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:18.981932Z","time spent":"815.462352ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-19T18:59:19.797447Z","caller":"traceutil/trace.go:171","msg":"trace[1292417384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"629.508103ms","start":"2026-02-19T18:59:19.167923Z","end":"2026-02-19T18:59:19.797431Z","steps":["trace[1292417384] 'agreement among raft nodes before linearized reading' (duration: 629.38288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:19.797481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.167909Z","time spent":"629.563935ms","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T18:59:19.797474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.277452ms","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":"warn","ts":"2026-02-19T18:59:19.797477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.853933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-19T18:59:19.797486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.43935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:59:19.797532Z","caller":"traceutil/trace.go:171","msg":"trace[2094261467] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"601.393214ms","start":"2026-02-19T18:59:19.196125Z","end":"2026-02-19T18:59:19.797518Z","steps":["trace[2094261467] 'agreement among raft nodes before linearized reading' (duration: 601.1835ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:19.797548Z","caller":"traceutil/trace.go:171","msg":"trace[152294104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:975; }","duration":"737.978266ms","start":"2026-02-19T18:59:19.059551Z","end":"2026-02-19T18:59:19.797529Z","steps":["trace[152294104] 'agreement among raft nodes before linearized reading' (duration: 737.73212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:19.797568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.196105Z","time spent":"601.458816ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T18:59:19.797546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.669595ms","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-19T18:59:19.797569Z","caller":"traceutil/trace.go:171","msg":"trace[2111356785] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:975; }","duration":"538.563622ms","start":"2026-02-19T18:59:19.258983Z","end":"2026-02-19T18:59:19.797547Z","steps":["trace[2111356785] 'agreement among raft nodes before linearized reading' (duration: 538.398968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:19.797599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.059531Z","time spent":"738.060347ms","remote":"127.0.0.1:45576","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":"info","ts":"2026-02-19T18:59:19.797647Z","caller":"traceutil/trace.go:171","msg":"trace[7966134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"515.819229ms","start":"2026-02-19T18:59:19.281809Z","end":"2026-02-19T18:59:19.797628Z","steps":["trace[7966134] 'agreement among raft nodes before linearized reading' (duration: 515.526372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:19.79767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.258973Z","time spent":"538.685025ms","remote":"127.0.0.1:60518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-19T18:59:19.797695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.281793Z","time spent":"515.89294ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T18:59:19.798092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:18.898696Z","time spent":"898.511528ms","remote":"127.0.0.1:45388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T18:59:19.798244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.576888ms","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-19T18:59:19.798294Z","caller":"traceutil/trace.go:171","msg":"trace[966044842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"566.67193ms","start":"2026-02-19T18:59:19.231608Z","end":"2026-02-19T18:59:19.79828Z","steps":["trace[966044842] 'agreement among raft nodes before linearized reading' (duration: 566.439915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:19.798324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.231578Z","time spent":"566.739162ms","remote":"127.0.0.1:45576","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":"info","ts":"2026-02-19T18:59:20.054137Z","caller":"traceutil/trace.go:171","msg":"trace[1567603261] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"250.299146ms","start":"2026-02-19T18:59:19.803747Z","end":"2026-02-19T18:59:20.054047Z","steps":["trace[1567603261] 'process raft request' (duration: 177.795707ms)","trace[1567603261] 'compare' (duration: 72.278614ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:59:20.112291Z","caller":"traceutil/trace.go:171","msg":"trace[2090801377] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"308.025589ms","start":"2026-02-19T18:59:19.804218Z","end":"2026-02-19T18:59:20.112243Z","steps":["trace[2090801377] 'process raft request' (duration: 307.889586ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:20.112347Z","caller":"traceutil/trace.go:171","msg":"trace[922694788] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"307.123727ms","start":"2026-02-19T18:59:19.805207Z","end":"2026-02-19T18:59:20.112331Z","steps":["trace[922694788] 'process raft request' (duration: 306.934593ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:20.112399Z","caller":"traceutil/trace.go:171","msg":"trace[1235754561] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"308.202173ms","start":"2026-02-19T18:59:19.804126Z","end":"2026-02-19T18:59:20.112329Z","steps":["trace[1235754561] 'process raft request' (duration: 307.810524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:20.112526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.804207Z","time spent":"308.190003ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:59:20.112526Z","caller":"traceutil/trace.go:171","msg":"trace[1216514289] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"307.25506ms","start":"2026-02-19T18:59:19.805252Z","end":"2026-02-19T18:59:20.112507Z","steps":["trace[1216514289] 'process raft request' (duration: 306.917602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:20.112555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.805196Z","time spent":"307.238571ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:59:20.112618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.80524Z","time spent":"307.338613ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:59:20.112622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.804116Z","time spent":"308.385917ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:59:20.112242Z","caller":"traceutil/trace.go:171","msg":"trace[376837208] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1020; }","duration":"307.109877ms","start":"2026-02-19T18:59:19.805091Z","end":"2026-02-19T18:59:20.112201Z","steps":["trace[376837208] 'read index received' (duration: 176.490816ms)","trace[376837208] 'applied index is now lower than readState.Index' (duration: 130.618061ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:59:20.112845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.726321ms","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-19T18:59:20.113127Z","caller":"traceutil/trace.go:171","msg":"trace[1026915559] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:980; }","duration":"308.014138ms","start":"2026-02-19T18:59:19.805084Z","end":"2026-02-19T18:59:20.113098Z","steps":["trace[1026915559] 'agreement among raft nodes before linearized reading' (duration: 307.630919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:20.113184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:19.805034Z","time spent":"308.135251ms","remote":"127.0.0.1:45400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-19T18:59:20.151531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.626598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:59:20.15159Z","caller":"traceutil/trace.go:171","msg":"trace[616455000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"126.740331ms","start":"2026-02-19T18:59:20.024835Z","end":"2026-02-19T18:59:20.151575Z","steps":["trace[616455000] 'agreement among raft nodes before linearized reading' (duration: 126.617418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:20.151913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.209861ms","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-19T18:59:20.152056Z","caller":"traceutil/trace.go:171","msg":"trace[1441788652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"281.418246ms","start":"2026-02-19T18:59:19.870613Z","end":"2026-02-19T18:59:20.152031Z","steps":["trace[1441788652] 'agreement among raft nodes before linearized reading' (duration: 280.834792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:20.300212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.94728ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:59:20.300451Z","caller":"traceutil/trace.go:171","msg":"trace[278913071] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"143.35199ms","start":"2026-02-19T18:59:20.157044Z","end":"2026-02-19T18:59:20.300396Z","steps":["trace[278913071] 'process raft request' (duration: 143.285398ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:20.300488Z","caller":"traceutil/trace.go:171","msg":"trace[43214976] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"184.590266ms","start":"2026-02-19T18:59:20.11586Z","end":"2026-02-19T18:59:20.30045Z","steps":["trace[43214976] 'process raft request' (duration: 44.323119ms)","trace[43214976] 'compare' (duration: 139.800056ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:59:20.486099Z","caller":"traceutil/trace.go:171","msg":"trace[1009460227] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"176.098117ms","start":"2026-02-19T18:59:20.309971Z","end":"2026-02-19T18:59:20.486069Z","steps":["trace[1009460227] 'process raft request' (duration: 131.694596ms)","trace[1009460227] 'compare' (duration: 44.255607ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:59:22.518327Z","caller":"traceutil/trace.go:171","msg":"trace[1497642536] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"394.889295ms","start":"2026-02-19T18:59:22.1234Z","end":"2026-02-19T18:59:22.518289Z","steps":["trace[1497642536] 'process raft request' (duration: 394.782133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:22.518486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:22.123386Z","time spent":"394.993787ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:59:22.937729Z","caller":"traceutil/trace.go:171","msg":"trace[1718500522] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"812.327608ms","start":"2026-02-19T18:59:22.125369Z","end":"2026-02-19T18:59:22.937697Z","steps":["trace[1718500522] 'process raft request' (duration: 811.994111ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:22.937811Z","caller":"traceutil/trace.go:171","msg":"trace[259604142] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1031; }","duration":"769.29181ms","start":"2026-02-19T18:59:22.168509Z","end":"2026-02-19T18:59:22.937801Z","steps":["trace[259604142] 'read index received' (duration: 349.755347ms)","trace[259604142] 'applied index is now lower than readState.Index' (duration: 419.535403ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:59:22.937914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:22.125345Z","time spent":"812.448471ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:59:22.937987Z","caller":"traceutil/trace.go:171","msg":"trace[1950784508] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"803.215995ms","start":"2026-02-19T18:59:22.134747Z","end":"2026-02-19T18:59:22.937963Z","steps":["trace[1950784508] 'process raft request' (duration: 802.913018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:22.938014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.508305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:59:22.938054Z","caller":"traceutil/trace.go:171","msg":"trace[1577970795] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"769.548165ms","start":"2026-02-19T18:59:22.168495Z","end":"2026-02-19T18:59:22.938043Z","steps":["trace[1577970795] 'agreement among raft nodes before linearized reading' (duration: 769.484514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:22.938094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.83623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-19T18:59:22.938109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:22.168443Z","time spent":"769.637657ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T18:59:22.938121Z","caller":"traceutil/trace.go:171","msg":"trace[905560101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"620.906132ms","start":"2026-02-19T18:59:22.317208Z","end":"2026-02-19T18:59:22.938115Z","steps":["trace[905560101] 'agreement among raft nodes before linearized reading' (duration: 620.783539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:22.938119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:22.134737Z","time spent":"803.306916ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:59:22.938144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:22.317192Z","time spent":"620.948443ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T18:59:22.93774Z","caller":"traceutil/trace.go:171","msg":"trace[499776502] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"805.1466ms","start":"2026-02-19T18:59:22.132571Z","end":"2026-02-19T18:59:22.937717Z","steps":["trace[499776502] 'process raft request' (duration: 805.062458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:22.938342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:22.132562Z","time spent":"805.760974ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:59:23.380997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.619614ms","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-19T18:59:23.381145Z","caller":"traceutil/trace.go:171","msg":"trace[1307086546] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:990; }","duration":"410.792608ms","start":"2026-02-19T18:59:22.970329Z","end":"2026-02-19T18:59:23.381122Z","steps":["trace[1307086546] 'range keys from in-memory index tree' (duration: 410.597404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:23.381265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.521358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T18:59:23.381377Z","caller":"traceutil/trace.go:171","msg":"trace[1805045645] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"438.807324ms","start":"2026-02-19T18:59:22.942549Z","end":"2026-02-19T18:59:23.381357Z","steps":["trace[1805045645] 'process raft request' (duration: 99.00001ms)","trace[1805045645] 'compare' (duration: 339.378934ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:59:23.381448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:22.942537Z","time spent":"438.881816ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T18:59:25.216577Z","caller":"traceutil/trace.go:171","msg":"trace[1259667772] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"273.908318ms","start":"2026-02-19T18:59:24.942646Z","end":"2026-02-19T18:59:25.216555Z","steps":["trace[1259667772] 'read index received' (duration: 204.674767ms)","trace[1259667772] 'applied index is now lower than readState.Index' (duration: 69.232521ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:59:25.216683Z","caller":"traceutil/trace.go:171","msg":"trace[862838116] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"590.019817ms","start":"2026-02-19T18:59:24.626643Z","end":"2026-02-19T18:59:25.216663Z","steps":["trace[862838116] 'process raft request' (duration: 520.740984ms)","trace[862838116] 'compare' (duration: 68.794672ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T18:59:25.216794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.219733ms","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":"warn","ts":"2026-02-19T18:59:25.216794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T18:59:24.626619Z","time spent":"590.105048ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T18:59:25.216797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.151644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-19T18:59:25.216825Z","caller":"traceutil/trace.go:171","msg":"trace[344891918] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"273.281313ms","start":"2026-02-19T18:59:24.943534Z","end":"2026-02-19T18:59:25.216816Z","steps":["trace[344891918] 'agreement among raft nodes before linearized reading' (duration: 273.13789ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:25.216842Z","caller":"traceutil/trace.go:171","msg":"trace[893446609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"274.238776ms","start":"2026-02-19T18:59:24.94259Z","end":"2026-02-19T18:59:25.216829Z","steps":["trace[893446609] 'agreement among raft nodes before linearized reading' (duration: 274.068602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:25.216845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.10957ms","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-19T18:59:25.216909Z","caller":"traceutil/trace.go:171","msg":"trace[371474931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:993; }","duration":"273.185611ms","start":"2026-02-19T18:59:24.943708Z","end":"2026-02-19T18:59:25.216893Z","steps":["trace[371474931] 'agreement among raft nodes before linearized reading' (duration: 272.988897ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:25.679984Z","caller":"traceutil/trace.go:171","msg":"trace[510111829] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"132.108526ms","start":"2026-02-19T18:59:25.54785Z","end":"2026-02-19T18:59:25.679959Z","steps":["trace[510111829] 'read index received' (duration: 131.287727ms)","trace[510111829] 'applied index is now lower than readState.Index' (duration: 819.509µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T18:59:25.680241Z","caller":"traceutil/trace.go:171","msg":"trace[219535295] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"186.719346ms","start":"2026-02-19T18:59:25.493497Z","end":"2026-02-19T18:59:25.680216Z","steps":["trace[219535295] 'process raft request' (duration: 185.701622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T18:59:25.680444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.235629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T18:59:25.680602Z","caller":"traceutil/trace.go:171","msg":"trace[1221550024] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:998; }","duration":"132.763452ms","start":"2026-02-19T18:59:25.547821Z","end":"2026-02-19T18:59:25.680585Z","steps":["trace[1221550024] 'agreement among raft nodes before linearized reading' (duration: 132.229579ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T18:59:27.973902Z","caller":"traceutil/trace.go:171","msg":"trace[688802920] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"219.612027ms","start":"2026-02-19T18:59:27.754265Z","end":"2026-02-19T18:59:27.973877Z","steps":["trace[688802920] 'process raft request' (duration: 219.444723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:00:15.944167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.299075ms","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-19T19:00:15.944287Z","caller":"traceutil/trace.go:171","msg":"trace[286907958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1225; }","duration":"102.485649ms","start":"2026-02-19T19:00:15.841779Z","end":"2026-02-19T19:00:15.944264Z","steps":["trace[286907958] 'range keys from in-memory index tree' (duration: 102.131901ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:00:28.256851Z","caller":"traceutil/trace.go:171","msg":"trace[1757288573] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"122.783739ms","start":"2026-02-19T19:00:28.134041Z","end":"2026-02-19T19:00:28.256825Z","steps":["trace[1757288573] 'process raft request' (duration: 122.627025ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:00:28.281445Z","caller":"traceutil/trace.go:171","msg":"trace[1057448602] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1333; }","duration":"100.403377ms","start":"2026-02-19T19:00:28.181022Z","end":"2026-02-19T19:00:28.281425Z","steps":["trace[1057448602] 'read index received' (duration: 76.014346ms)","trace[1057448602] 'applied index is now lower than readState.Index' (duration: 24.388171ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:00:28.281651Z","caller":"traceutil/trace.go:171","msg":"trace[1385739041] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"147.367636ms","start":"2026-02-19T19:00:28.134252Z","end":"2026-02-19T19:00:28.281619Z","steps":["trace[1385739041] 'process raft request' (duration: 147.013377ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:00:28.281738Z","caller":"traceutil/trace.go:171","msg":"trace[2040329912] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"146.165727ms","start":"2026-02-19T19:00:28.135561Z","end":"2026-02-19T19:00:28.281726Z","steps":["trace[2040329912] 'process raft request' (duration: 145.822978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:00:28.28169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.614862ms","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:509"} {"level":"info","ts":"2026-02-19T19:00:28.281881Z","caller":"traceutil/trace.go:171","msg":"trace[1914135526] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1277; }","duration":"100.874379ms","start":"2026-02-19T19:00:28.18099Z","end":"2026-02-19T19:00:28.281864Z","steps":["trace[1914135526] 'agreement among raft nodes before linearized reading' (duration: 100.526351ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:35.698699Z","caller":"traceutil/trace.go:171","msg":"trace[1516706142] transaction","detail":"{read_only:false; response_revision:1550; number_of_response:1; }","duration":"106.086083ms","start":"2026-02-19T19:01:35.592591Z","end":"2026-02-19T19:01:35.698677Z","steps":["trace[1516706142] 'process raft request' (duration: 105.927239ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:37.862064Z","caller":"traceutil/trace.go:171","msg":"trace[871765021] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"155.652755ms","start":"2026-02-19T19:01:37.706393Z","end":"2026-02-19T19:01:37.862046Z","steps":["trace[871765021] 'process raft request' (duration: 155.556823ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:38.29847Z","caller":"traceutil/trace.go:171","msg":"trace[1605507337] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"146.952085ms","start":"2026-02-19T19:01:38.151498Z","end":"2026-02-19T19:01:38.29845Z","steps":["trace[1605507337] 'process raft request' (duration: 146.835322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:38.693555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.048718ms","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-19T19:01:38.693628Z","caller":"traceutil/trace.go:171","msg":"trace[697958977] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1560; }","duration":"100.13152ms","start":"2026-02-19T19:01:38.593479Z","end":"2026-02-19T19:01:38.693611Z","steps":["trace[697958977] 'range keys from in-memory index tree' (duration: 99.876873ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:39.425044Z","caller":"traceutil/trace.go:171","msg":"trace[1560951009] transaction","detail":"{read_only:false; response_revision:1565; number_of_response:1; }","duration":"291.051423ms","start":"2026-02-19T19:01:39.133969Z","end":"2026-02-19T19:01:39.42502Z","steps":["trace[1560951009] 'process raft request' (duration: 290.93334ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:39.567419Z","caller":"traceutil/trace.go:171","msg":"trace[724685302] linearizableReadLoop","detail":"{readStateIndex:1639; appliedIndex:1637; }","duration":"399.166415ms","start":"2026-02-19T19:01:39.168226Z","end":"2026-02-19T19:01:39.567392Z","steps":["trace[724685302] 'read index received' (duration: 256.709627ms)","trace[724685302] 'applied index is now lower than readState.Index' (duration: 142.455658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:01:39.567816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.701887ms","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:526"} {"level":"info","ts":"2026-02-19T19:01:39.567853Z","caller":"traceutil/trace.go:171","msg":"trace[1151699402] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1566; }","duration":"384.765288ms","start":"2026-02-19T19:01:39.183075Z","end":"2026-02-19T19:01:39.567841Z","steps":["trace[1151699402] 'agreement among raft nodes before linearized reading' (duration: 384.621604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:39.567885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:39.183069Z","time spent":"384.80911ms","remote":"127.0.0.1:45576","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-19T19:01:39.568068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.426691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:01:39.568132Z","caller":"traceutil/trace.go:171","msg":"trace[404154715] transaction","detail":"{read_only:false; response_revision:1566; number_of_response:1; }","duration":"433.04436ms","start":"2026-02-19T19:01:39.135076Z","end":"2026-02-19T19:01:39.56812Z","steps":["trace[404154715] 'process raft request' (duration: 409.937184ms)","trace[404154715] 'compare' (duration: 22.269695ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:01:39.568162Z","caller":"traceutil/trace.go:171","msg":"trace[667657710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1566; }","duration":"399.962444ms","start":"2026-02-19T19:01:39.168177Z","end":"2026-02-19T19:01:39.568139Z","steps":["trace[667657710] 'agreement among raft nodes before linearized reading' (duration: 399.430061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:39.568207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:39.168163Z","time spent":"400.033886ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T19:01:39.568216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:01:39.135051Z","time spent":"433.110562ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:01:40.70828Z","caller":"traceutil/trace.go:171","msg":"trace[1765936906] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"223.351475ms","start":"2026-02-19T19:01:40.484904Z","end":"2026-02-19T19:01:40.708255Z","steps":["trace[1765936906] 'process raft request' (duration: 223.223901ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:41.621941Z","caller":"traceutil/trace.go:171","msg":"trace[657794663] transaction","detail":"{read_only:false; response_revision:1574; number_of_response:1; }","duration":"116.032502ms","start":"2026-02-19T19:01:41.505875Z","end":"2026-02-19T19:01:41.621908Z","steps":["trace[657794663] 'process raft request' (duration: 37.00611ms)","trace[657794663] 'compare' (duration: 78.846707ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:01:42.88623Z","caller":"traceutil/trace.go:171","msg":"trace[1355567238] linearizableReadLoop","detail":"{readStateIndex:1650; appliedIndex:1649; }","duration":"134.674551ms","start":"2026-02-19T19:01:42.75153Z","end":"2026-02-19T19:01:42.886204Z","steps":["trace[1355567238] 'read index received' (duration: 134.545448ms)","trace[1355567238] 'applied index is now lower than readState.Index' (duration: 128.073µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:01:42.886306Z","caller":"traceutil/trace.go:171","msg":"trace[1206041695] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"171.256231ms","start":"2026-02-19T19:01:42.715018Z","end":"2026-02-19T19:01:42.886274Z","steps":["trace[1206041695] 'process raft request' (duration: 171.019296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:42.886439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.878916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-19T19:01:42.886483Z","caller":"traceutil/trace.go:171","msg":"trace[794383453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1577; }","duration":"134.970337ms","start":"2026-02-19T19:01:42.751501Z","end":"2026-02-19T19:01:42.886471Z","steps":["trace[794383453] 'agreement among raft nodes before linearized reading' (duration: 134.808033ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:43.164776Z","caller":"traceutil/trace.go:171","msg":"trace[332133731] linearizableReadLoop","detail":"{readStateIndex:1652; appliedIndex:1651; }","duration":"133.350538ms","start":"2026-02-19T19:01:43.031401Z","end":"2026-02-19T19:01:43.164752Z","steps":["trace[332133731] 'read index received' (duration: 69.990194ms)","trace[332133731] 'applied index is now lower than readState.Index' (duration: 63.359034ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T19:01:43.164894Z","caller":"traceutil/trace.go:171","msg":"trace[922692830] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"133.750719ms","start":"2026-02-19T19:01:43.031132Z","end":"2026-02-19T19:01:43.164882Z","steps":["trace[922692830] 'process raft request' (duration: 70.308882ms)","trace[922692830] 'compare' (duration: 63.18068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:01:43.164954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.842892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:01:43.165Z","caller":"traceutil/trace.go:171","msg":"trace[1764547987] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1579; }","duration":"121.919704ms","start":"2026-02-19T19:01:43.043069Z","end":"2026-02-19T19:01:43.164988Z","steps":["trace[1764547987] 'agreement among raft nodes before linearized reading' (duration: 121.837302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:01:43.16516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.752718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:01:43.165187Z","caller":"traceutil/trace.go:171","msg":"trace[1187441288] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1579; }","duration":"133.79696ms","start":"2026-02-19T19:01:43.031382Z","end":"2026-02-19T19:01:43.165179Z","steps":["trace[1187441288] 'agreement among raft nodes before linearized reading' (duration: 133.755359ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:44.119489Z","caller":"traceutil/trace.go:171","msg":"trace[1770776710] transaction","detail":"{read_only:false; response_revision:1583; number_of_response:1; }","duration":"153.024123ms","start":"2026-02-19T19:01:43.96644Z","end":"2026-02-19T19:01:44.119464Z","steps":["trace[1770776710] 'process raft request' (duration: 152.839038ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:01:44.145535Z","caller":"traceutil/trace.go:171","msg":"trace[1371523665] transaction","detail":"{read_only:false; response_revision:1584; number_of_response:1; }","duration":"139.828455ms","start":"2026-02-19T19:01:44.005683Z","end":"2026-02-19T19:01:44.145512Z","steps":["trace[1371523665] 'process raft request' (duration: 139.552778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:17.270649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.599213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:17.270751Z","caller":"traceutil/trace.go:171","msg":"trace[512803812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1719; }","duration":"102.720536ms","start":"2026-02-19T19:02:17.168009Z","end":"2026-02-19T19:02:17.270729Z","steps":["trace[512803812] 'range keys from in-memory index tree' (duration: 102.511981ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:27.875786Z","caller":"traceutil/trace.go:171","msg":"trace[1595851554] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"113.310615ms","start":"2026-02-19T19:02:27.76223Z","end":"2026-02-19T19:02:27.875541Z","steps":["trace[1595851554] 'process raft request' (duration: 113.199632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:37.419486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.974707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T19:02:37.419479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.895072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-19T19:02:37.419568Z","caller":"traceutil/trace.go:171","msg":"trace[1182729625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1830; }","duration":"231.10499ms","start":"2026-02-19T19:02:37.188446Z","end":"2026-02-19T19:02:37.419551Z","steps":["trace[1182729625] 'range keys from in-memory index tree' (duration: 230.887885ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:37.419593Z","caller":"traceutil/trace.go:171","msg":"trace[599512680] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1830; }","duration":"108.045026ms","start":"2026-02-19T19:02:37.31152Z","end":"2026-02-19T19:02:37.419565Z","steps":["trace[599512680] 'range keys from in-memory index tree' (duration: 107.718489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:37.419494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.080522ms","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-19T19:02:37.419717Z","caller":"traceutil/trace.go:171","msg":"trace[397262785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1830; }","duration":"267.311857ms","start":"2026-02-19T19:02:37.152357Z","end":"2026-02-19T19:02:37.419669Z","steps":["trace[397262785] 'range keys from in-memory index tree' (duration: 266.935438ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:37.55441Z","caller":"traceutil/trace.go:171","msg":"trace[131836242] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"129.83036ms","start":"2026-02-19T19:02:37.424548Z","end":"2026-02-19T19:02:37.554379Z","steps":["trace[131836242] 'process raft request' (duration: 129.657436ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:37.586537Z","caller":"traceutil/trace.go:171","msg":"trace[146567427] linearizableReadLoop","detail":"{readStateIndex:1917; appliedIndex:1915; }","duration":"115.481958ms","start":"2026-02-19T19:02:37.471027Z","end":"2026-02-19T19:02:37.586509Z","steps":["trace[146567427] 'read index received' (duration: 83.146966ms)","trace[146567427] 'applied index is now lower than readState.Index' (duration: 32.334042ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:37.587285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.776696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-19T19:02:37.587354Z","caller":"traceutil/trace.go:171","msg":"trace[1918674519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1832; }","duration":"116.35505ms","start":"2026-02-19T19:02:37.470975Z","end":"2026-02-19T19:02:37.58733Z","steps":["trace[1918674519] 'agreement among raft nodes before linearized reading' (duration: 115.698504ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:37.586776Z","caller":"traceutil/trace.go:171","msg":"trace[309419499] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"162.07829ms","start":"2026-02-19T19:02:37.424683Z","end":"2026-02-19T19:02:37.586761Z","steps":["trace[309419499] 'process raft request' (duration: 161.700921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:37.772054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.302997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:02:37.772192Z","caller":"traceutil/trace.go:171","msg":"trace[2108009472] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"178.946353ms","start":"2026-02-19T19:02:37.593205Z","end":"2026-02-19T19:02:37.772152Z","steps":["trace[2108009472] 'process raft request' (duration: 38.473552ms)","trace[2108009472] 'compare' (duration: 140.206484ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:38.089919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.288922ms","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-19T19:02:38.089989Z","caller":"traceutil/trace.go:171","msg":"trace[1867589524] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1833; }","duration":"168.393364ms","start":"2026-02-19T19:02:37.92158Z","end":"2026-02-19T19:02:38.089973Z","steps":["trace[1867589524] 'range keys from in-memory index tree' (duration: 168.160259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:38.953505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011511627362989368,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T19:02:39.147692Z","caller":"traceutil/trace.go:171","msg":"trace[299468519] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"1.054278831s","start":"2026-02-19T19:02:38.093395Z","end":"2026-02-19T19:02:39.147674Z","steps":["trace[299468519] 'process raft request' (duration: 1.054155378s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:39.147823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:38.093363Z","time spent":"1.054393124s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T19:02:39.412002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.855855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T19:02:39.412137Z","caller":"traceutil/trace.go:171","msg":"trace[2016307740] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"1.318319198s","start":"2026-02-19T19:02:38.093782Z","end":"2026-02-19T19:02:39.412101Z","steps":["trace[2016307740] 'process raft request' (duration: 1.138260748s)","trace[2016307740] 'compare' (duration: 179.611429ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:39.412294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:38.093764Z","time spent":"1.318451831s","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T19:02:39.412505Z","caller":"traceutil/trace.go:171","msg":"trace[628904672] linearizableReadLoop","detail":"{readStateIndex:1920; appliedIndex:1918; }","duration":"959.233384ms","start":"2026-02-19T19:02:38.452858Z","end":"2026-02-19T19:02:39.412092Z","steps":["trace[628904672] 'read index received' (duration: 694.715865ms)","trace[628904672] 'applied index is now lower than readState.Index' (duration: 264.515859ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:39.412753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.89345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-19T19:02:39.412793Z","caller":"traceutil/trace.go:171","msg":"trace[734657508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1835; }","duration":"959.962752ms","start":"2026-02-19T19:02:38.452819Z","end":"2026-02-19T19:02:39.412781Z","steps":["trace[734657508] 'agreement among raft nodes before linearized reading' (duration: 959.812348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:39.412824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:38.452803Z","time spent":"960.014653ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T19:02:39.41353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.63916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T19:02:39.413557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.978214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-19T19:02:39.413589Z","caller":"traceutil/trace.go:171","msg":"trace[839441109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1835; }","duration":"246.763194ms","start":"2026-02-19T19:02:39.16681Z","end":"2026-02-19T19:02:39.413573Z","steps":["trace[839441109] 'agreement among raft nodes before linearized reading' (duration: 246.648901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:39.413615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.092624ms","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-19T19:02:39.413656Z","caller":"traceutil/trace.go:171","msg":"trace[718245128] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1835; }","duration":"568.171956ms","start":"2026-02-19T19:02:38.845471Z","end":"2026-02-19T19:02:39.413643Z","steps":["trace[718245128] 'agreement among raft nodes before linearized reading' (duration: 568.009742ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:39.41363Z","caller":"traceutil/trace.go:171","msg":"trace[536323454] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1835; }","duration":"671.109197ms","start":"2026-02-19T19:02:38.7425Z","end":"2026-02-19T19:02:39.413609Z","steps":["trace[536323454] 'agreement among raft nodes before linearized reading' (duration: 670.874171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:39.413683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:38.845453Z","time spent":"568.223787ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-19T19:02:39.413697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T19:02:38.742476Z","time spent":"671.21196ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-02-19T19:02:39.990861Z","caller":"traceutil/trace.go:171","msg":"trace[1416124648] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"106.116339ms","start":"2026-02-19T19:02:39.884706Z","end":"2026-02-19T19:02:39.990823Z","steps":["trace[1416124648] 'process raft request' (duration: 106.073758ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:39.990899Z","caller":"traceutil/trace.go:171","msg":"trace[1689799022] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"141.663679ms","start":"2026-02-19T19:02:39.849203Z","end":"2026-02-19T19:02:39.990866Z","steps":["trace[1689799022] 'process raft request' (duration: 141.460804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:43.805969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.541905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-19T19:02:43.806026Z","caller":"traceutil/trace.go:171","msg":"trace[824991602] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1859; }","duration":"114.664489ms","start":"2026-02-19T19:02:43.69135Z","end":"2026-02-19T19:02:43.806014Z","steps":["trace[824991602] 'range keys from in-memory index tree' (duration: 114.413972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:44.091966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.42931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-19T19:02:44.092072Z","caller":"traceutil/trace.go:171","msg":"trace[1516090229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"138.582794ms","start":"2026-02-19T19:02:43.953466Z","end":"2026-02-19T19:02:44.092049Z","steps":["trace[1516090229] 'range keys from in-memory index tree' (duration: 137.683183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:44.395646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.230323ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-19T19:02:44.395753Z","caller":"traceutil/trace.go:171","msg":"trace[697066423] linearizableReadLoop","detail":"{readStateIndex:1948; appliedIndex:1947; }","duration":"228.931707ms","start":"2026-02-19T19:02:44.166804Z","end":"2026-02-19T19:02:44.395736Z","steps":["trace[697066423] 'read index received' (duration: 66.598091ms)","trace[697066423] 'applied index is now lower than readState.Index' (duration: 162.332046ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T19:02:44.395923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.087211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T19:02:44.396011Z","caller":"traceutil/trace.go:171","msg":"trace[235511513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1861; }","duration":"229.220375ms","start":"2026-02-19T19:02:44.166772Z","end":"2026-02-19T19:02:44.395993Z","steps":["trace[235511513] 'agreement among raft nodes before linearized reading' (duration: 229.03276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:49.101892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.336998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-controller-manager\" ","response":"range_response_count:1 size:1039"} {"level":"info","ts":"2026-02-19T19:02:49.102009Z","caller":"traceutil/trace.go:171","msg":"trace[1324605303] range","detail":"{range_begin:/registry/clusterroles/system:kube-controller-manager; range_end:; response_count:1; response_revision:1882; }","duration":"158.466391ms","start":"2026-02-19T19:02:48.943495Z","end":"2026-02-19T19:02:49.101961Z","steps":["trace[1324605303] 'range keys from in-memory index tree' (duration: 158.227736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T19:02:49.102122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.038637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:1 size:595"} {"level":"info","ts":"2026-02-19T19:02:49.102198Z","caller":"traceutil/trace.go:171","msg":"trace[1647952260] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:1; response_revision:1882; }","duration":"146.146429ms","start":"2026-02-19T19:02:48.956034Z","end":"2026-02-19T19:02:49.102181Z","steps":["trace[1647952260] 'range keys from in-memory index tree' (duration: 145.973646ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T19:02:56.189204Z","caller":"traceutil/trace.go:171","msg":"trace[1782426702] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"118.335658ms","start":"2026-02-19T19:02:56.070847Z","end":"2026-02-19T19:02:56.189182Z","steps":["trace[1782426702] 'process raft request' (duration: 118.035841ms)"],"step_count":1}