{"level":"warn","ts":"2026-02-13T14:23:24.177713Z","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-13T14:23:24.177807Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44: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.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-13T14:23:24.177878Z","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-13T14:23:24.177887Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-02-13T14:23:24.177918Z","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-13T14:23:24.178763Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-02-13T14:23:24.178845Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-13T14:23:24.183662Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.565086ms"} {"level":"info","ts":"2026-02-13T14:23:24.191498Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-02-13T14:23:24.191548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-02-13T14:23:24.191565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-02-13T14:23:24.191574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-13T14:23:24.191585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-02-13T14:23:24.191615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-02-13T14:23:24.204215Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-13T14:23:24.205478Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-13T14:23:24.20662Z","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-13T14:23:24.210272Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-13T14:23:24.21507Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-13T14:23:24.215344Z","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-13T14:23:24.215374Z","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-13T14:23:24.215382Z","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-13T14:23:24.220878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-02-13T14:23:24.221019Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-02-13T14:23:24.22145Z","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-13T14:23:24.221655Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-13T14:23:24.221683Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-13T14:23:24.221741Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-02-13T14:23:24.221752Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-02-13T14:23:24.592656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-02-13T14:23:24.592735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-13T14:23:24.592771Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-02-13T14:23:24.593095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-02-13T14:23:24.593166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-02-13T14:23:24.593182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-02-13T14:23:24.593189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-02-13T14:23:24.594692Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:23:24.594824Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-02-13T14:23:24.594997Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T14:23:24.595135Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-13T14:23:24.595203Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-13T14:23:24.595434Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-13T14:23:24.601243Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-13T14:23:24.601753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-02-13T14:23:24.601881Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:23:24.63242Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:23:24.634003Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-13T14:23:34.516215Z","caller":"traceutil/trace.go:171","msg":"trace[1272168479] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:364; }","duration":"135.66497ms","start":"2026-02-13T14:23:34.38054Z","end":"2026-02-13T14:23:34.516205Z","steps":["trace[1272168479] 'read index received' (duration: 50.704696ms)","trace[1272168479] 'applied index is now lower than readState.Index' (duration: 84.959674ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:34.516479Z","caller":"traceutil/trace.go:171","msg":"trace[540080859] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"137.514913ms","start":"2026-02-13T14:23:34.378957Z","end":"2026-02-13T14:23:34.516472Z","steps":["trace[540080859] 'process raft request' (duration: 117.485913ms)","trace[540080859] 'compare' (duration: 19.662692ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:34.516605Z","caller":"traceutil/trace.go:171","msg":"trace[421324111] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"136.171632ms","start":"2026-02-13T14:23:34.380427Z","end":"2026-02-13T14:23:34.516599Z","steps":["trace[421324111] 'process raft request' (duration: 135.754543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:34.516755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.202213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3943"} {"level":"info","ts":"2026-02-13T14:23:34.516802Z","caller":"traceutil/trace.go:171","msg":"trace[1150751321] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:355; }","duration":"136.274705ms","start":"2026-02-13T14:23:34.380521Z","end":"2026-02-13T14:23:34.516796Z","steps":["trace[1150751321] 'agreement among raft nodes before linearized reading' (duration: 136.200923ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:34.517088Z","caller":"traceutil/trace.go:171","msg":"trace[1189855083] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"138.083576ms","start":"2026-02-13T14:23:34.378999Z","end":"2026-02-13T14:23:34.517082Z","steps":["trace[1189855083] 'process raft request' (duration: 137.161105ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:34.558817Z","caller":"traceutil/trace.go:171","msg":"trace[1424329547] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"116.95709ms","start":"2026-02-13T14:23:34.441849Z","end":"2026-02-13T14:23:34.558806Z","steps":["trace[1424329547] 'process raft request' (duration: 116.899349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:34.55912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.175864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-02-13T14:23:34.559154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.144864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-13T14:23:34.55948Z","caller":"traceutil/trace.go:171","msg":"trace[319864226] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:356; }","duration":"150.491552ms","start":"2026-02-13T14:23:34.408983Z","end":"2026-02-13T14:23:34.559475Z","steps":["trace[319864226] 'agreement among raft nodes before linearized reading' (duration: 150.159794ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:34.55944Z","caller":"traceutil/trace.go:171","msg":"trace[550588797] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:356; }","duration":"100.530223ms","start":"2026-02-13T14:23:34.458901Z","end":"2026-02-13T14:23:34.559431Z","steps":["trace[550588797] 'agreement among raft nodes before linearized reading' (duration: 100.194025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.133393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.61798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:35.133738Z","caller":"traceutil/trace.go:171","msg":"trace[858762171] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"241.904914ms","start":"2026-02-13T14:23:34.89175Z","end":"2026-02-13T14:23:35.133655Z","steps":["trace[858762171] 'process raft request' (duration: 128.538767ms)","trace[858762171] 'compare' (duration: 112.500638ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:35.136842Z","caller":"traceutil/trace.go:171","msg":"trace[1003047439] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:386; }","duration":"196.551312ms","start":"2026-02-13T14:23:34.940277Z","end":"2026-02-13T14:23:35.136828Z","steps":["trace[1003047439] 'read index received' (duration: 80.039981ms)","trace[1003047439] 'applied index is now lower than readState.Index' (duration: 116.51057ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:35.139172Z","caller":"traceutil/trace.go:171","msg":"trace[232149501] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"246.451439ms","start":"2026-02-13T14:23:34.89271Z","end":"2026-02-13T14:23:35.139161Z","steps":["trace[232149501] 'process raft request' (duration: 243.790128ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:35.139394Z","caller":"traceutil/trace.go:171","msg":"trace[1034030954] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"246.571572ms","start":"2026-02-13T14:23:34.892815Z","end":"2026-02-13T14:23:35.139387Z","steps":["trace[1034030954] 'process raft request' (duration: 243.769738ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:35.139679Z","caller":"traceutil/trace.go:171","msg":"trace[663949148] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"246.434669ms","start":"2026-02-13T14:23:34.893237Z","end":"2026-02-13T14:23:35.139671Z","steps":["trace[663949148] 'process raft request' (duration: 243.380859ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:35.139986Z","caller":"traceutil/trace.go:171","msg":"trace[810198022] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"245.824045ms","start":"2026-02-13T14:23:34.894153Z","end":"2026-02-13T14:23:35.139977Z","steps":["trace[810198022] 'process raft request' (duration: 242.56728ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:35.140594Z","caller":"traceutil/trace.go:171","msg":"trace[1344850130] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"244.960884ms","start":"2026-02-13T14:23:34.895605Z","end":"2026-02-13T14:23:35.140566Z","steps":["trace[1344850130] 'process raft request' (duration: 241.156267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.140891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.604155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-13T14:23:35.141029Z","caller":"traceutil/trace.go:171","msg":"trace[1487179578] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:380; }","duration":"200.766218ms","start":"2026-02-13T14:23:34.940254Z","end":"2026-02-13T14:23:35.14102Z","steps":["trace[1487179578] 'agreement among raft nodes before linearized reading' (duration: 200.604244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.141258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.136844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:23:35.14143Z","caller":"traceutil/trace.go:171","msg":"trace[1258060986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:380; }","duration":"200.320828ms","start":"2026-02-13T14:23:34.941101Z","end":"2026-02-13T14:23:35.141422Z","steps":["trace[1258060986] 'agreement among raft nodes before linearized reading' (duration: 200.110363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.141958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.60169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T14:23:35.142258Z","caller":"traceutil/trace.go:171","msg":"trace[394047112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"182.921738ms","start":"2026-02-13T14:23:34.959328Z","end":"2026-02-13T14:23:35.14225Z","steps":["trace[394047112] 'agreement among raft nodes before linearized reading' (duration: 182.602501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.58669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.666238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:35.586808Z","caller":"traceutil/trace.go:171","msg":"trace[1676147690] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"435.408215ms","start":"2026-02-13T14:23:35.151369Z","end":"2026-02-13T14:23:35.586777Z","steps":["trace[1676147690] 'process raft request' (duration: 137.589115ms)","trace[1676147690] 'compare' (duration: 297.37697ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:35.586924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.15136Z","time spent":"435.505797ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:23:35.587071Z","caller":"traceutil/trace.go:171","msg":"trace[1668615632] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"428.489716ms","start":"2026-02-13T14:23:35.158563Z","end":"2026-02-13T14:23:35.587053Z","steps":["trace[1668615632] 'process raft request' (duration: 428.449755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.587339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.158558Z","time spent":"428.550428ms","remote":"127.0.0.1:45576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3737,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:35.587545Z","caller":"traceutil/trace.go:171","msg":"trace[1264646639] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"431.840383ms","start":"2026-02-13T14:23:35.155694Z","end":"2026-02-13T14:23:35.587534Z","steps":["trace[1264646639] 'read index received' (duration: 133.274995ms)","trace[1264646639] 'applied index is now lower than readState.Index' (duration: 298.564048ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:35.587677Z","caller":"traceutil/trace.go:171","msg":"trace[2077214919] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"431.218629ms","start":"2026-02-13T14:23:35.156449Z","end":"2026-02-13T14:23:35.587668Z","steps":["trace[2077214919] 'process raft request' (duration: 430.359399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.587736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.156441Z","time spent":"431.2647ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3979,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:35.587987Z","caller":"traceutil/trace.go:171","msg":"trace[420557949] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"430.123584ms","start":"2026-02-13T14:23:35.157853Z","end":"2026-02-13T14:23:35.587977Z","steps":["trace[420557949] 'process raft request' (duration: 429.050709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.588052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.157848Z","time spent":"430.174895ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:35.588179Z","caller":"traceutil/trace.go:171","msg":"trace[1567905043] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"430.283787ms","start":"2026-02-13T14:23:35.157884Z","end":"2026-02-13T14:23:35.588168Z","steps":["trace[1567905043] 'process raft request' (duration: 429.055169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.588286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.157881Z","time spent":"430.329528ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:35.58842Z","caller":"traceutil/trace.go:171","msg":"trace[135216769] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"430.482872ms","start":"2026-02-13T14:23:35.157927Z","end":"2026-02-13T14:23:35.588409Z","steps":["trace[135216769] 'process raft request' (duration: 429.050749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.588471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.157728Z","time spent":"430.720818ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T14:23:35.588692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.99149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-13T14:23:35.588731Z","caller":"traceutil/trace.go:171","msg":"trace[1160135079] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:386; }","duration":"433.030251ms","start":"2026-02-13T14:23:35.155692Z","end":"2026-02-13T14:23:35.588722Z","steps":["trace[1160135079] 'agreement among raft nodes before linearized reading' (duration: 432.96557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:35.588762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:35.155673Z","time spent":"433.080852ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6918,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-13T14:23:35.588918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.41942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T14:23:35.589888Z","caller":"traceutil/trace.go:171","msg":"trace[1562231300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:386; }","duration":"140.479861ms","start":"2026-02-13T14:23:35.448465Z","end":"2026-02-13T14:23:35.588945Z","steps":["trace[1562231300] 'agreement among raft nodes before linearized reading' (duration: 140.42321ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:36.992465Z","caller":"traceutil/trace.go:171","msg":"trace[858713518] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"188.902575ms","start":"2026-02-13T14:23:36.80354Z","end":"2026-02-13T14:23:36.992442Z","steps":["trace[858713518] 'process raft request' (duration: 188.765902ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:45.877374Z","caller":"traceutil/trace.go:171","msg":"trace[1764340170] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"204.3697ms","start":"2026-02-13T14:23:45.672988Z","end":"2026-02-13T14:23:45.877357Z","steps":["trace[1764340170] 'read index received' (duration: 204.199147ms)","trace[1764340170] 'applied index is now lower than readState.Index' (duration: 169.753µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:45.877595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.561105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:23:45.87765Z","caller":"traceutil/trace.go:171","msg":"trace[1967299856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:454; }","duration":"204.630127ms","start":"2026-02-13T14:23:45.673006Z","end":"2026-02-13T14:23:45.877636Z","steps":["trace[1967299856] 'agreement among raft nodes before linearized reading' (duration: 204.539715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:45.877803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.812992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T14:23:45.877832Z","caller":"traceutil/trace.go:171","msg":"trace[1668524270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:454; }","duration":"204.847312ms","start":"2026-02-13T14:23:45.67297Z","end":"2026-02-13T14:23:45.877817Z","steps":["trace[1668524270] 'agreement among raft nodes before linearized reading' (duration: 204.814771ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:45.877495Z","caller":"traceutil/trace.go:171","msg":"trace[1020152424] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"220.338748ms","start":"2026-02-13T14:23:45.657148Z","end":"2026-02-13T14:23:45.877487Z","steps":["trace[1020152424] 'process raft request' (duration: 220.088743ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:47.593818Z","caller":"traceutil/trace.go:171","msg":"trace[1501650694] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"398.480276ms","start":"2026-02-13T14:23:47.195318Z","end":"2026-02-13T14:23:47.593798Z","steps":["trace[1501650694] 'process raft request' (duration: 398.349613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:47.593979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:47.195301Z","time spent":"398.580978ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:23:47.686401Z","caller":"traceutil/trace.go:171","msg":"trace[787283177] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"443.970533ms","start":"2026-02-13T14:23:47.242413Z","end":"2026-02-13T14:23:47.686384Z","steps":["trace[787283177] 'process raft request' (duration: 421.510537ms)","trace[787283177] 'compare' (duration: 22.394904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:47.686501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:47.242397Z","time spent":"444.046794ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:54.965612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.047556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:54.965828Z","caller":"traceutil/trace.go:171","msg":"trace[1697059645] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"165.141629ms","start":"2026-02-13T14:23:54.800672Z","end":"2026-02-13T14:23:54.965813Z","steps":["trace[1697059645] 'process raft request' (duration: 46.212273ms)","trace[1697059645] 'compare' (duration: 117.751549ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:54.966154Z","caller":"traceutil/trace.go:171","msg":"trace[1548077185] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"163.292437ms","start":"2026-02-13T14:23:54.802856Z","end":"2026-02-13T14:23:54.966149Z","steps":["trace[1548077185] 'process raft request' (duration: 163.204855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:56.300254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.612574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T14:23:56.300323Z","caller":"traceutil/trace.go:171","msg":"trace[1646056652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"263.720687ms","start":"2026-02-13T14:23:56.036586Z","end":"2026-02-13T14:23:56.300306Z","steps":["trace[1646056652] 'range keys from in-memory index tree' (duration: 263.530432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:56.300596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.110257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:23:56.300652Z","caller":"traceutil/trace.go:171","msg":"trace[579049509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"261.202939ms","start":"2026-02-13T14:23:56.039436Z","end":"2026-02-13T14:23:56.300639Z","steps":["trace[579049509] 'range keys from in-memory index tree' (duration: 261.053095ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:57.858816Z","caller":"traceutil/trace.go:171","msg":"trace[361720944] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"209.57066ms","start":"2026-02-13T14:23:57.649222Z","end":"2026-02-13T14:23:57.858793Z","steps":["trace[361720944] 'process raft request' (duration: 209.409736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:58.494278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.459229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:23:58.49437Z","caller":"traceutil/trace.go:171","msg":"trace[1648605531] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"500.959384ms","start":"2026-02-13T14:23:57.993394Z","end":"2026-02-13T14:23:58.494354Z","steps":["trace[1648605531] 'read index received' (duration: 31.29341ms)","trace[1648605531] 'applied index is now lower than readState.Index' (duration: 469.664844ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:23:58.49456Z","caller":"traceutil/trace.go:171","msg":"trace[1554328347] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"547.75777ms","start":"2026-02-13T14:23:57.94679Z","end":"2026-02-13T14:23:58.494548Z","steps":["trace[1554328347] 'process raft request' (duration: 77.944203ms)","trace[1554328347] 'compare' (duration: 469.07313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:58.494648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:57.946769Z","time spent":"547.818411ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:23:58.494906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.519116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T14:23:58.49494Z","caller":"traceutil/trace.go:171","msg":"trace[397422245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"501.579728ms","start":"2026-02-13T14:23:57.99335Z","end":"2026-02-13T14:23:58.49493Z","steps":["trace[397422245] 'agreement among raft nodes before linearized reading' (duration: 501.450405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:58.494968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:23:57.993318Z","time spent":"501.644329ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T14:23:58.495103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.285715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:23:58.495128Z","caller":"traceutil/trace.go:171","msg":"trace[1725692296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"239.343995ms","start":"2026-02-13T14:23:58.255776Z","end":"2026-02-13T14:23:58.49512Z","steps":["trace[1725692296] 'agreement among raft nodes before linearized reading' (duration: 239.305894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:58.495384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.860059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-13T14:23:58.495415Z","caller":"traceutil/trace.go:171","msg":"trace[1815480733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"183.936311ms","start":"2026-02-13T14:23:58.31147Z","end":"2026-02-13T14:23:58.495406Z","steps":["trace[1815480733] 'agreement among raft nodes before linearized reading' (duration: 183.844259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:23:58.495564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.728719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-13T14:23:58.495591Z","caller":"traceutil/trace.go:171","msg":"trace[688306788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"184.77862ms","start":"2026-02-13T14:23:58.310806Z","end":"2026-02-13T14:23:58.495584Z","steps":["trace[688306788] 'agreement among raft nodes before linearized reading' (duration: 184.708588ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:58.704259Z","caller":"traceutil/trace.go:171","msg":"trace[1824348123] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"198.405684ms","start":"2026-02-13T14:23:58.505845Z","end":"2026-02-13T14:23:58.704251Z","steps":["trace[1824348123] 'process raft request' (duration: 198.021635ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:58.704073Z","caller":"traceutil/trace.go:171","msg":"trace[289076695] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"197.028872ms","start":"2026-02-13T14:23:58.507025Z","end":"2026-02-13T14:23:58.704054Z","steps":["trace[289076695] 'read index received' (duration: 196.92365ms)","trace[289076695] 'applied index is now lower than readState.Index' (duration: 104.482µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:23:58.704535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.495693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T14:23:58.704556Z","caller":"traceutil/trace.go:171","msg":"trace[1367829897] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:517; }","duration":"197.558905ms","start":"2026-02-13T14:23:58.50699Z","end":"2026-02-13T14:23:58.704549Z","steps":["trace[1367829897] 'agreement among raft nodes before linearized reading' (duration: 197.490583ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:58.707674Z","caller":"traceutil/trace.go:171","msg":"trace[982438630] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"200.580574ms","start":"2026-02-13T14:23:58.507084Z","end":"2026-02-13T14:23:58.707664Z","steps":["trace[982438630] 'process raft request' (duration: 200.170265ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:23:58.70851Z","caller":"traceutil/trace.go:171","msg":"trace[1106447829] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"199.029708ms","start":"2026-02-13T14:23:58.509141Z","end":"2026-02-13T14:23:58.708171Z","steps":["trace[1106447829] 'process raft request' (duration: 198.306591ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:00.035846Z","caller":"traceutil/trace.go:171","msg":"trace[30436064] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"169.240764ms","start":"2026-02-13T14:23:59.86658Z","end":"2026-02-13T14:24:00.035821Z","steps":["trace[30436064] 'process raft request' (duration: 169.120351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:01.217288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536539729306474,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T14:24:01.451507Z","caller":"traceutil/trace.go:171","msg":"trace[229721675] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"735.791825ms","start":"2026-02-13T14:24:00.7157Z","end":"2026-02-13T14:24:01.451492Z","steps":["trace[229721675] 'process raft request' (duration: 735.672042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:01.45161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:00.715688Z","time spent":"735.865767ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:02.799208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128919687s","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-13T14:24:02.799333Z","caller":"traceutil/trace.go:171","msg":"trace[794548607] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:522; }","duration":"1.12905865s","start":"2026-02-13T14:24:01.670259Z","end":"2026-02-13T14:24:02.799317Z","steps":["trace[794548607] 'range keys from in-memory index tree' (duration: 1.128904307s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.844006Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.392671534s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T14:24:02.844329Z","caller":"traceutil/trace.go:171","msg":"trace[1945904955] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"2.127608859s","start":"2026-02-13T14:24:00.716699Z","end":"2026-02-13T14:24:02.844308Z","steps":["trace[1945904955] 'read index received' (duration: 735.184911ms)","trace[1945904955] 'applied index is now lower than readState.Index' (duration: 1.392422868s)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:02.844583Z","caller":"traceutil/trace.go:171","msg":"trace[1397311384] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"2.125536282s","start":"2026-02-13T14:24:00.719036Z","end":"2026-02-13T14:24:02.844572Z","steps":["trace[1397311384] 'process raft request' (duration: 2.125144663s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.844673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:00.719029Z","time spent":"2.125582783s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:02.84491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.128206043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:24:02.84495Z","caller":"traceutil/trace.go:171","msg":"trace[1141206844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"2.128256895s","start":"2026-02-13T14:24:00.716682Z","end":"2026-02-13T14:24:02.844939Z","steps":["trace[1141206844] 'agreement among raft nodes before linearized reading' (duration: 2.128145882s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.845008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:00.716676Z","time spent":"2.128311006s","remote":"127.0.0.1:45352","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-13T14:24:02.846609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.591390225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:02.846677Z","caller":"traceutil/trace.go:171","msg":"trace[2119231592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"1.591482638s","start":"2026-02-13T14:24:01.255184Z","end":"2026-02-13T14:24:02.846667Z","steps":["trace[2119231592] 'agreement among raft nodes before linearized reading' (duration: 1.591378435s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.846707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:01.255172Z","time spent":"1.591528258s","remote":"127.0.0.1:45112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T14:24:02.846899Z","caller":"traceutil/trace.go:171","msg":"trace[917760895] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.298012637s","start":"2026-02-13T14:24:01.548877Z","end":"2026-02-13T14:24:02.846889Z","steps":["trace[917760895] 'process raft request' (duration: 1.297624658s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.846957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:01.548851Z","time spent":"1.298071908s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:02.84711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.30315ms","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-13T14:24:02.847137Z","caller":"traceutil/trace.go:171","msg":"trace[1584158305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"807.370891ms","start":"2026-02-13T14:24:02.039756Z","end":"2026-02-13T14:24:02.847127Z","steps":["trace[1584158305] 'agreement among raft nodes before linearized reading' (duration: 807.293549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:02.847161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:02.039729Z","time spent":"807.427472ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-13T14:24:05.741133Z","caller":"traceutil/trace.go:171","msg":"trace[541367511] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"223.382298ms","start":"2026-02-13T14:24:05.517727Z","end":"2026-02-13T14:24:05.741109Z","steps":["trace[541367511] 'process raft request' (duration: 223.173083ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:08.020344Z","caller":"traceutil/trace.go:171","msg":"trace[1991606051] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"139.590741ms","start":"2026-02-13T14:24:07.880723Z","end":"2026-02-13T14:24:08.020313Z","steps":["trace[1991606051] 'read index received' (duration: 139.451068ms)","trace[1991606051] 'applied index is now lower than readState.Index' (duration: 139.083µs)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:08.020497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.748985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8btmz\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-13T14:24:08.020518Z","caller":"traceutil/trace.go:171","msg":"trace[2080615301] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8btmz; range_end:; response_count:1; response_revision:538; }","duration":"139.817627ms","start":"2026-02-13T14:24:07.880694Z","end":"2026-02-13T14:24:08.020511Z","steps":["trace[2080615301] 'agreement among raft nodes before linearized reading' (duration: 139.698484ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:08.020558Z","caller":"traceutil/trace.go:171","msg":"trace[1091811417] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"272.610381ms","start":"2026-02-13T14:24:07.747923Z","end":"2026-02-13T14:24:08.020534Z","steps":["trace[1091811417] 'process raft request' (duration: 272.293674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:08.757876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536539729306513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:24:09.258547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536539729306513,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:24:09.634276Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.601413595s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T14:24:09.679705Z","caller":"traceutil/trace.go:171","msg":"trace[2145598229] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.646916012s","start":"2026-02-13T14:24:08.03277Z","end":"2026-02-13T14:24:09.679686Z","steps":["trace[2145598229] 'process raft request' (duration: 1.601769863s)","trace[2145598229] 'compare' (duration: 44.357311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:09.679792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.032754Z","time spent":"1.646987794s","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:09.818538Z","caller":"traceutil/trace.go:171","msg":"trace[958082488] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:557; }","duration":"1.561106839s","start":"2026-02-13T14:24:08.257394Z","end":"2026-02-13T14:24:09.818501Z","steps":["trace[958082488] 'read index received' (duration: 1.377153987s)","trace[958082488] 'applied index is now lower than readState.Index' (duration: 183.951842ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:09.818838Z","caller":"traceutil/trace.go:171","msg":"trace[668001163] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"982.258773ms","start":"2026-02-13T14:24:08.836563Z","end":"2026-02-13T14:24:09.818822Z","steps":["trace[668001163] 'process raft request' (duration: 946.45168ms)","trace[668001163] 'compare' (duration: 35.397344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:09.818944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.836545Z","time spent":"982.332876ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:09.819178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561779544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:09.819214Z","caller":"traceutil/trace.go:171","msg":"trace[1903041396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.561838315s","start":"2026-02-13T14:24:08.257362Z","end":"2026-02-13T14:24:09.8192Z","steps":["trace[1903041396] 'agreement among raft nodes before linearized reading' (duration: 1.561786044s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:09.819253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.257347Z","time spent":"1.561897347s","remote":"127.0.0.1:45112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:24:09.819545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311707322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T14:24:09.819582Z","caller":"traceutil/trace.go:171","msg":"trace[1137080250] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:540; }","duration":"1.311780034s","start":"2026-02-13T14:24:08.507791Z","end":"2026-02-13T14:24:09.819571Z","steps":["trace[1137080250] 'agreement among raft nodes before linearized reading' (duration: 1.311651621s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:09.819618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.507772Z","time spent":"1.311839305s","remote":"127.0.0.1:45134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"warn","ts":"2026-02-13T14:24:09.819918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.015714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:24:09.819962Z","caller":"traceutil/trace.go:171","msg":"trace[342589035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"934.107226ms","start":"2026-02-13T14:24:08.885845Z","end":"2026-02-13T14:24:09.819952Z","steps":["trace[342589035] 'agreement among raft nodes before linearized reading' (duration: 933.995734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:09.820001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.885837Z","time spent":"934.155057ms","remote":"127.0.0.1:45352","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-13T14:24:09.820253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.546789ms","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-13T14:24:09.820298Z","caller":"traceutil/trace.go:171","msg":"trace[1425648083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"935.58958ms","start":"2026-02-13T14:24:08.884697Z","end":"2026-02-13T14:24:09.820287Z","steps":["trace[1425648083] 'agreement among raft nodes before linearized reading' (duration: 935.523539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:09.820333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.884647Z","time spent":"935.677162ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T14:24:09.82051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.719083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T14:24:09.820548Z","caller":"traceutil/trace.go:171","msg":"trace[665495710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"945.790115ms","start":"2026-02-13T14:24:08.874749Z","end":"2026-02-13T14:24:09.820539Z","steps":["trace[665495710] 'agreement among raft nodes before linearized reading' (duration: 945.723433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:09.820579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.874732Z","time spent":"945.841596ms","remote":"127.0.0.1:45352","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-13T14:24:09.820705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160541995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:09.820736Z","caller":"traceutil/trace.go:171","msg":"trace[548647101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.160608326s","start":"2026-02-13T14:24:08.660119Z","end":"2026-02-13T14:24:09.820728Z","steps":["trace[548647101] 'agreement among raft nodes before linearized reading' (duration: 1.160562885s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:09.820768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:08.660098Z","time spent":"1.160661777s","remote":"127.0.0.1:45108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T14:24:11.014056Z","caller":"traceutil/trace.go:171","msg":"trace[2069029186] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"118.038685ms","start":"2026-02-13T14:24:10.895997Z","end":"2026-02-13T14:24:11.014036Z","steps":["trace[2069029186] 'process raft request' (duration: 117.941153ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:11.018986Z","caller":"traceutil/trace.go:171","msg":"trace[1214899614] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"113.650914ms","start":"2026-02-13T14:24:10.905318Z","end":"2026-02-13T14:24:11.018969Z","steps":["trace[1214899614] 'process raft request' (duration: 113.527191ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:12.466511Z","caller":"traceutil/trace.go:171","msg":"trace[200964210] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"435.196081ms","start":"2026-02-13T14:24:12.031294Z","end":"2026-02-13T14:24:12.46649Z","steps":["trace[200964210] 'read index received' (duration: 434.652088ms)","trace[200964210] 'applied index is now lower than readState.Index' (duration: 542.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:12.466732Z","caller":"traceutil/trace.go:171","msg":"trace[454074518] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"568.768124ms","start":"2026-02-13T14:24:11.897943Z","end":"2026-02-13T14:24:12.466711Z","steps":["trace[454074518] 'process raft request' (duration: 568.060107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:12.466978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:11.897934Z","time spent":"568.905427ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T14:24:12.467494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.632486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:12.46754Z","caller":"traceutil/trace.go:171","msg":"trace[328291022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"210.703357ms","start":"2026-02-13T14:24:12.256826Z","end":"2026-02-13T14:24:12.467529Z","steps":["trace[328291022] 'agreement among raft nodes before linearized reading' (duration: 210.635965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:12.468762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.447492ms","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-13T14:24:12.466516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.629003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wgpr9\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-13T14:24:12.469686Z","caller":"traceutil/trace.go:171","msg":"trace[226797631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"438.419875ms","start":"2026-02-13T14:24:12.031256Z","end":"2026-02-13T14:24:12.469676Z","steps":["trace[226797631] 'agreement among raft nodes before linearized reading' (duration: 435.660441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:12.469756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:12.031238Z","time spent":"438.492906ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-13T14:24:12.469608Z","caller":"traceutil/trace.go:171","msg":"trace[86472379] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wgpr9; range_end:; response_count:1; response_revision:559; }","duration":"572.814796ms","start":"2026-02-13T14:24:11.896783Z","end":"2026-02-13T14:24:12.469598Z","steps":["trace[86472379] 'range keys from in-memory index tree' (duration: 569.545511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:12.470265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:11.896745Z","time spent":"573.469691ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4659,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wgpr9\" "} {"level":"warn","ts":"2026-02-13T14:24:14.390874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.050257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:24:14.391038Z","caller":"traceutil/trace.go:171","msg":"trace[2114507596] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"125.579369ms","start":"2026-02-13T14:24:14.265441Z","end":"2026-02-13T14:24:14.39102Z","steps":["trace[2114507596] 'process raft request' (duration: 125.529028ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:14.391156Z","caller":"traceutil/trace.go:171","msg":"trace[1573305714] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"158.198728ms","start":"2026-02-13T14:24:14.232929Z","end":"2026-02-13T14:24:14.391128Z","steps":["trace[1573305714] 'process raft request' (duration: 45.801323ms)","trace[1573305714] 'compare' (duration: 111.930735ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:14.391294Z","caller":"traceutil/trace.go:171","msg":"trace[2004213889] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"131.357352ms","start":"2026-02-13T14:24:14.259926Z","end":"2026-02-13T14:24:14.391283Z","steps":["trace[2004213889] 'read index received' (duration: 18.810553ms)","trace[2004213889] 'applied index is now lower than readState.Index' (duration: 112.545699ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:14.391388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.454424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:14.391416Z","caller":"traceutil/trace.go:171","msg":"trace[2047042947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"131.506425ms","start":"2026-02-13T14:24:14.259901Z","end":"2026-02-13T14:24:14.391407Z","steps":["trace[2047042947] 'agreement among raft nodes before linearized reading' (duration: 131.420793ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:14.757924Z","caller":"traceutil/trace.go:171","msg":"trace[1624207248] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"225.922377ms","start":"2026-02-13T14:24:14.531989Z","end":"2026-02-13T14:24:14.757912Z","steps":["trace[1624207248] 'read index received' (duration: 225.6542ms)","trace[1624207248] 'applied index is now lower than readState.Index' (duration: 267.587µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:14.758077Z","caller":"traceutil/trace.go:171","msg":"trace[1858006737] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"406.272335ms","start":"2026-02-13T14:24:14.351798Z","end":"2026-02-13T14:24:14.75807Z","steps":["trace[1858006737] 'process raft request' (duration: 405.870356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:14.758156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:14.351769Z","time spent":"406.326167ms","remote":"127.0.0.1:45142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T14:24:14.758358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.368057ms","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-13T14:24:14.758382Z","caller":"traceutil/trace.go:171","msg":"trace[1303554120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"226.419328ms","start":"2026-02-13T14:24:14.531957Z","end":"2026-02-13T14:24:14.758376Z","steps":["trace[1303554120] 'agreement among raft nodes before linearized reading' (duration: 226.344266ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:14.900548Z","caller":"traceutil/trace.go:171","msg":"trace[210221206] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"142.550649ms","start":"2026-02-13T14:24:14.757976Z","end":"2026-02-13T14:24:14.900526Z","steps":["trace[210221206] 'read index received' (duration: 110.990153ms)","trace[210221206] 'applied index is now lower than readState.Index' (duration: 31.559626ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:14.900719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.928513ms","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-13T14:24:14.900766Z","caller":"traceutil/trace.go:171","msg":"trace[1375585924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:609; }","duration":"278.031065ms","start":"2026-02-13T14:24:14.622725Z","end":"2026-02-13T14:24:14.900756Z","steps":["trace[1375585924] 'agreement among raft nodes before linearized reading' (duration: 277.906113ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:14.901189Z","caller":"traceutil/trace.go:171","msg":"trace[1009774038] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"324.091275ms","start":"2026-02-13T14:24:14.577084Z","end":"2026-02-13T14:24:14.901175Z","steps":["trace[1009774038] 'process raft request' (duration: 291.873174ms)","trace[1009774038] 'compare' (duration: 31.379612ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:14.901267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:14.577053Z","time spent":"324.167336ms","remote":"127.0.0.1:45142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:24:14.901407Z","caller":"traceutil/trace.go:171","msg":"trace[1700510803] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"148.930766ms","start":"2026-02-13T14:24:14.752465Z","end":"2026-02-13T14:24:14.901396Z","steps":["trace[1700510803] 'process raft request' (duration: 148.022545ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:14.91161Z","caller":"traceutil/trace.go:171","msg":"trace[1860614253] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"143.999063ms","start":"2026-02-13T14:24:14.7676Z","end":"2026-02-13T14:24:14.911599Z","steps":["trace[1860614253] 'process raft request' (duration: 143.969162ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:14.911817Z","caller":"traceutil/trace.go:171","msg":"trace[468124917] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"145.548578ms","start":"2026-02-13T14:24:14.766241Z","end":"2026-02-13T14:24:14.91179Z","steps":["trace[468124917] 'process raft request' (duration: 145.259482ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:15.129785Z","caller":"traceutil/trace.go:171","msg":"trace[356823381] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"104.847571ms","start":"2026-02-13T14:24:15.024906Z","end":"2026-02-13T14:24:15.129754Z","steps":["trace[356823381] 'process raft request' (duration: 64.928343ms)","trace[356823381] 'compare' (duration: 39.797506ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:15.184458Z","caller":"traceutil/trace.go:171","msg":"trace[654053338] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"113.641414ms","start":"2026-02-13T14:24:15.070794Z","end":"2026-02-13T14:24:15.184435Z","steps":["trace[654053338] 'process raft request' (duration: 113.556433ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:15.184849Z","caller":"traceutil/trace.go:171","msg":"trace[330033338] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"117.206497ms","start":"2026-02-13T14:24:15.067632Z","end":"2026-02-13T14:24:15.184838Z","steps":["trace[330033338] 'process raft request' (duration: 116.562252ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:15.295225Z","caller":"traceutil/trace.go:171","msg":"trace[182131507] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"145.17962ms","start":"2026-02-13T14:24:15.15003Z","end":"2026-02-13T14:24:15.295209Z","steps":["trace[182131507] 'read index received' (duration: 35.491566ms)","trace[182131507] 'applied index is now lower than readState.Index' (duration: 109.686134ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:15.295606Z","caller":"traceutil/trace.go:171","msg":"trace[2014958940] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"177.773539ms","start":"2026-02-13T14:24:15.117824Z","end":"2026-02-13T14:24:15.295597Z","steps":["trace[2014958940] 'process raft request' (duration: 144.992465ms)","trace[2014958940] 'compare' (duration: 32.275053ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:15.295819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.775473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-13T14:24:15.295843Z","caller":"traceutil/trace.go:171","msg":"trace[474706081] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:617; }","duration":"145.843695ms","start":"2026-02-13T14:24:15.149991Z","end":"2026-02-13T14:24:15.295835Z","steps":["trace[474706081] 'agreement among raft nodes before linearized reading' (duration: 145.739153ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:17.720846Z","caller":"traceutil/trace.go:171","msg":"trace[956973515] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"126.386437ms","start":"2026-02-13T14:24:17.594444Z","end":"2026-02-13T14:24:17.72083Z","steps":["trace[956973515] 'process raft request' (duration: 126.190213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:23.205066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.470679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-13T14:24:23.205148Z","caller":"traceutil/trace.go:171","msg":"trace[823351125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"129.568541ms","start":"2026-02-13T14:24:23.075554Z","end":"2026-02-13T14:24:23.205122Z","steps":["trace[823351125] 'range keys from in-memory index tree' (duration: 129.366686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:23.20562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.559676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-lj2cp\" ","response":"range_response_count:1 size:4732"} {"level":"info","ts":"2026-02-13T14:24:23.205649Z","caller":"traceutil/trace.go:171","msg":"trace[189492759] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-lj2cp; range_end:; response_count:1; response_revision:676; }","duration":"234.609026ms","start":"2026-02-13T14:24:22.971032Z","end":"2026-02-13T14:24:23.205641Z","steps":["trace[189492759] 'range keys from in-memory index tree' (duration: 234.492124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:23.205786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.646605ms","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-13T14:24:23.205817Z","caller":"traceutil/trace.go:171","msg":"trace[448708353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"133.697865ms","start":"2026-02-13T14:24:23.072105Z","end":"2026-02-13T14:24:23.205802Z","steps":["trace[448708353] 'range keys from in-memory index tree' (duration: 133.582533ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:23.387985Z","caller":"traceutil/trace.go:171","msg":"trace[1129884323] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"178.357982ms","start":"2026-02-13T14:24:23.209612Z","end":"2026-02-13T14:24:23.38797Z","steps":["trace[1129884323] 'process raft request' (duration: 178.25302ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:23.698711Z","caller":"traceutil/trace.go:171","msg":"trace[1563443101] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:699; }","duration":"442.403927ms","start":"2026-02-13T14:24:23.256294Z","end":"2026-02-13T14:24:23.698698Z","steps":["trace[1563443101] 'read index received' (duration: 131.637518ms)","trace[1563443101] 'applied index is now lower than readState.Index' (duration: 310.765869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:23.698787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.484069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:23.698805Z","caller":"traceutil/trace.go:171","msg":"trace[685627490] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"442.546291ms","start":"2026-02-13T14:24:23.256254Z","end":"2026-02-13T14:24:23.6988Z","steps":["trace[685627490] 'agreement among raft nodes before linearized reading' (duration: 442.494679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:23.698838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:23.256241Z","time spent":"442.591991ms","remote":"127.0.0.1:45108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T14:24:23.698989Z","caller":"traceutil/trace.go:171","msg":"trace[1673767943] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"487.160556ms","start":"2026-02-13T14:24:23.211823Z","end":"2026-02-13T14:24:23.698984Z","steps":["trace[1673767943] 'process raft request' (duration: 486.747416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:23.699036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:23.211812Z","time spent":"487.193107ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:23.699152Z","caller":"traceutil/trace.go:171","msg":"trace[1450036168] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"483.108523ms","start":"2026-02-13T14:24:23.216038Z","end":"2026-02-13T14:24:23.699146Z","steps":["trace[1450036168] 'process raft request' (duration: 482.617661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:23.699183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:23.216029Z","time spent":"483.138904ms","remote":"127.0.0.1:45288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5007,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:23.747077Z","caller":"traceutil/trace.go:171","msg":"trace[2100787786] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"345.071847ms","start":"2026-02-13T14:24:23.401985Z","end":"2026-02-13T14:24:23.747057Z","steps":["trace[2100787786] 'process raft request' (duration: 342.367655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:23.747185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:23.401971Z","time spent":"345.15474ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:24.099749Z","caller":"traceutil/trace.go:171","msg":"trace[1542543715] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:723; }","duration":"188.846224ms","start":"2026-02-13T14:24:23.910889Z","end":"2026-02-13T14:24:24.099736Z","steps":["trace[1542543715] 'read index received' (duration: 182.627211ms)","trace[1542543715] 'applied index is now lower than readState.Index' (duration: 6.218403ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:24.099941Z","caller":"traceutil/trace.go:171","msg":"trace[1301319247] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"251.562406ms","start":"2026-02-13T14:24:23.848373Z","end":"2026-02-13T14:24:24.099936Z","steps":["trace[1301319247] 'process raft request' (duration: 245.131878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:24.100108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.208132ms","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-13T14:24:24.100129Z","caller":"traceutil/trace.go:171","msg":"trace[1370980351] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"189.257563ms","start":"2026-02-13T14:24:23.910866Z","end":"2026-02-13T14:24:24.100124Z","steps":["trace[1370980351] 'agreement among raft nodes before linearized reading' (duration: 189.177391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:24.101836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.189057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:24:24.101859Z","caller":"traceutil/trace.go:171","msg":"trace[1995897025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"144.213688ms","start":"2026-02-13T14:24:23.95764Z","end":"2026-02-13T14:24:24.101854Z","steps":["trace[1995897025] 'agreement among raft nodes before linearized reading' (duration: 144.145506ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:24.101996Z","caller":"traceutil/trace.go:171","msg":"trace[670829734] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"174.705169ms","start":"2026-02-13T14:24:23.927286Z","end":"2026-02-13T14:24:24.101991Z","steps":["trace[670829734] 'process raft request' (duration: 174.441923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:28.669488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536539729306846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:24:29.170559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536539729306846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:24:29.671091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536539729306846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-13T14:24:29.694676Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.10882963s","expected-duration":"1s"} {"level":"info","ts":"2026-02-13T14:24:29.694918Z","caller":"traceutil/trace.go:171","msg":"trace[1541834169] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"1.526044278s","start":"2026-02-13T14:24:28.168855Z","end":"2026-02-13T14:24:29.694899Z","steps":["trace[1541834169] 'read index received' (duration: 1.525935085s)","trace[1541834169] 'applied index is now lower than readState.Index' (duration: 108.373µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:29.694991Z","caller":"traceutil/trace.go:171","msg":"trace[2121839091] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"1.548164946s","start":"2026-02-13T14:24:28.14682Z","end":"2026-02-13T14:24:29.694985Z","steps":["trace[2121839091] 'process raft request' (duration: 1.547974651s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:29.695071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:28.146809Z","time spent":"1.548199207s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:29.695136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525504275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-13T14:24:29.695174Z","caller":"traceutil/trace.go:171","msg":"trace[1000705144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:724; }","duration":"1.525565817s","start":"2026-02-13T14:24:28.169593Z","end":"2026-02-13T14:24:29.695158Z","steps":["trace[1000705144] 'agreement among raft nodes before linearized reading' (duration: 1.525450654s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:29.695201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:28.169584Z","time spent":"1.525611648s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T14:24:29.695302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526446217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-13T14:24:29.695317Z","caller":"traceutil/trace.go:171","msg":"trace[1443790418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:724; }","duration":"1.526493577s","start":"2026-02-13T14:24:28.168818Z","end":"2026-02-13T14:24:29.695312Z","steps":["trace[1443790418] 'agreement among raft nodes before linearized reading' (duration: 1.526448166s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:29.695332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:28.168806Z","time spent":"1.526521889s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T14:24:29.695401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.819264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T14:24:29.69542Z","caller":"traceutil/trace.go:171","msg":"trace[1568093242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"255.861955ms","start":"2026-02-13T14:24:29.439553Z","end":"2026-02-13T14:24:29.695415Z","steps":["trace[1568093242] 'agreement among raft nodes before linearized reading' (duration: 255.816464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:29.695514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439065957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-13T14:24:29.695523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187392587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-13T14:24:29.695592Z","caller":"traceutil/trace.go:171","msg":"trace[1856870334] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:724; }","duration":"1.187447319s","start":"2026-02-13T14:24:28.508097Z","end":"2026-02-13T14:24:29.695544Z","steps":["trace[1856870334] 'agreement among raft nodes before linearized reading' (duration: 1.187354916s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:29.695614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:28.508084Z","time spent":"1.18752457s","remote":"127.0.0.1:45134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"info","ts":"2026-02-13T14:24:29.69553Z","caller":"traceutil/trace.go:171","msg":"trace[561124962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"1.439098397s","start":"2026-02-13T14:24:28.256426Z","end":"2026-02-13T14:24:29.695524Z","steps":["trace[561124962] 'agreement among raft nodes before linearized reading' (duration: 1.439074367s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:29.695713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:28.256417Z","time spent":"1.439291241s","remote":"127.0.0.1:45108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:24:30.120695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.188687ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-13T14:24:30.120939Z","caller":"traceutil/trace.go:171","msg":"trace[131170016] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:749; }","duration":"415.107292ms","start":"2026-02-13T14:24:29.705819Z","end":"2026-02-13T14:24:30.120926Z","steps":["trace[131170016] 'read index received' (duration: 153.689259ms)","trace[131170016] 'applied index is now lower than readState.Index' (duration: 261.417343ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:30.120996Z","caller":"traceutil/trace.go:171","msg":"trace[1735855981] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"418.453109ms","start":"2026-02-13T14:24:29.702537Z","end":"2026-02-13T14:24:30.12099Z","steps":["trace[1735855981] 'process raft request' (duration: 418.322826ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:30.12098Z","caller":"traceutil/trace.go:171","msg":"trace[959278227] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"421.309594ms","start":"2026-02-13T14:24:29.699648Z","end":"2026-02-13T14:24:30.120957Z","steps":["trace[959278227] 'process raft request' (duration: 421.12943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:30.121061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:29.702528Z","time spent":"418.48572ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:30.121108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:29.699637Z","time spent":"421.406757ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:30.1212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:29.697942Z","time spent":"423.255149ms","remote":"127.0.0.1:45134","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-13T14:24:30.121291Z","caller":"traceutil/trace.go:171","msg":"trace[874065128] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"418.668914ms","start":"2026-02-13T14:24:29.702615Z","end":"2026-02-13T14:24:30.121284Z","steps":["trace[874065128] 'process raft request' (duration: 418.287495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:30.121327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:29.70261Z","time spent":"418.698104ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:30.121468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.645484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:30.121487Z","caller":"traceutil/trace.go:171","msg":"trace[362127940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:727; }","duration":"415.663774ms","start":"2026-02-13T14:24:29.705817Z","end":"2026-02-13T14:24:30.121481Z","steps":["trace[362127940] 'agreement among raft nodes before linearized reading' (duration: 415.627954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:30.121504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:29.7058Z","time spent":"415.700196ms","remote":"127.0.0.1:45108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:24:30.121605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.122307ms","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-13T14:24:30.121631Z","caller":"traceutil/trace.go:171","msg":"trace[907548481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"366.171628ms","start":"2026-02-13T14:24:29.755452Z","end":"2026-02-13T14:24:30.121624Z","steps":["trace[907548481] 'agreement among raft nodes before linearized reading' (duration: 366.059685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:30.12165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:29.755442Z","time spent":"366.204009ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-13T14:24:30.391975Z","caller":"traceutil/trace.go:171","msg":"trace[823135216] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:754; }","duration":"217.066024ms","start":"2026-02-13T14:24:30.174889Z","end":"2026-02-13T14:24:30.391955Z","steps":["trace[823135216] 'read index received' (duration: 168.015188ms)","trace[823135216] 'applied index is now lower than readState.Index' (duration: 49.050026ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:30.39216Z","caller":"traceutil/trace.go:171","msg":"trace[1714272088] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"264.066033ms","start":"2026-02-13T14:24:30.128072Z","end":"2026-02-13T14:24:30.392138Z","steps":["trace[1714272088] 'process raft request' (duration: 214.793702ms)","trace[1714272088] 'compare' (duration: 48.705068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:30.392269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.2707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-13T14:24:30.392351Z","caller":"traceutil/trace.go:171","msg":"trace[473423101] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:730; }","duration":"217.485664ms","start":"2026-02-13T14:24:30.174853Z","end":"2026-02-13T14:24:30.392339Z","steps":["trace[473423101] 'agreement among raft nodes before linearized reading' (duration: 217.263879ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:30.392373Z","caller":"traceutil/trace.go:171","msg":"trace[1474835565] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"255.779463ms","start":"2026-02-13T14:24:30.136586Z","end":"2026-02-13T14:24:30.392366Z","steps":["trace[1474835565] 'process raft request' (duration: 255.319012ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:31.861448Z","caller":"traceutil/trace.go:171","msg":"trace[1657939122] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"157.656479ms","start":"2026-02-13T14:24:31.703774Z","end":"2026-02-13T14:24:31.86143Z","steps":["trace[1657939122] 'process raft request' (duration: 157.569007ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:32.214618Z","caller":"traceutil/trace.go:171","msg":"trace[1945150248] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"120.23133ms","start":"2026-02-13T14:24:32.094367Z","end":"2026-02-13T14:24:32.214598Z","steps":["trace[1945150248] 'process raft request' (duration: 120.137198ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:32.92833Z","caller":"traceutil/trace.go:171","msg":"trace[960308917] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"526.269956ms","start":"2026-02-13T14:24:32.402042Z","end":"2026-02-13T14:24:32.928312Z","steps":["trace[960308917] 'process raft request' (duration: 526.164983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:32.928454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:32.402022Z","time spent":"526.368098ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:24:45.092052Z","caller":"traceutil/trace.go:171","msg":"trace[83875395] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"113.986838ms","start":"2026-02-13T14:24:44.978049Z","end":"2026-02-13T14:24:45.092036Z","steps":["trace[83875395] 'process raft request' (duration: 113.890126ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:48.072089Z","caller":"traceutil/trace.go:171","msg":"trace[972362555] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"157.813603ms","start":"2026-02-13T14:24:47.914256Z","end":"2026-02-13T14:24:48.07207Z","steps":["trace[972362555] 'process raft request' (duration: 157.697281ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:49.283466Z","caller":"traceutil/trace.go:171","msg":"trace[1047208781] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"101.723066ms","start":"2026-02-13T14:24:49.181723Z","end":"2026-02-13T14:24:49.283446Z","steps":["trace[1047208781] 'process raft request' (duration: 101.564672ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:50.269214Z","caller":"traceutil/trace.go:171","msg":"trace[1678179428] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"187.197318ms","start":"2026-02-13T14:24:50.081982Z","end":"2026-02-13T14:24:50.269179Z","steps":["trace[1678179428] 'process raft request' (duration: 186.904312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:50.514891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.418073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:24:50.515622Z","caller":"traceutil/trace.go:171","msg":"trace[1925722402] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"126.372281ms","start":"2026-02-13T14:24:50.38923Z","end":"2026-02-13T14:24:50.515603Z","steps":["trace[1925722402] 'read index received' (duration: 29.51µs)","trace[1925722402] 'applied index is now lower than readState.Index' (duration: 126.341521ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:50.515926Z","caller":"traceutil/trace.go:171","msg":"trace[67222873] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"210.416072ms","start":"2026-02-13T14:24:50.305437Z","end":"2026-02-13T14:24:50.515853Z","steps":["trace[67222873] 'process raft request' (duration: 55.941365ms)","trace[67222873] 'compare' (duration: 153.332711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:50.516171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.890884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:24:50.516364Z","caller":"traceutil/trace.go:171","msg":"trace[486868174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:788; }","duration":"127.128709ms","start":"2026-02-13T14:24:50.389194Z","end":"2026-02-13T14:24:50.516322Z","steps":["trace[486868174] 'agreement among raft nodes before linearized reading' (duration: 126.712839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:50.516752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.959805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T14:24:50.517026Z","caller":"traceutil/trace.go:171","msg":"trace[1246422699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:788; }","duration":"127.278082ms","start":"2026-02-13T14:24:50.389735Z","end":"2026-02-13T14:24:50.517013Z","steps":["trace[1246422699] 'agreement among raft nodes before linearized reading' (duration: 126.938785ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:50.663703Z","caller":"traceutil/trace.go:171","msg":"trace[1923940363] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"141.61305ms","start":"2026-02-13T14:24:50.522063Z","end":"2026-02-13T14:24:50.663677Z","steps":["trace[1923940363] 'process raft request' (duration: 119.157515ms)","trace[1923940363] 'compare' (duration: 21.956054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:51.492429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.141994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:24:51.492507Z","caller":"traceutil/trace.go:171","msg":"trace[1865611406] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:790; }","duration":"726.272827ms","start":"2026-02-13T14:24:50.766217Z","end":"2026-02-13T14:24:51.49249Z","steps":["trace[1865611406] 'count revisions from in-memory index tree' (duration: 726.074912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:51.492546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:50.7662Z","time spent":"726.336098ms","remote":"127.0.0.1:45456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-02-13T14:24:51.492775Z","caller":"traceutil/trace.go:171","msg":"trace[735450473] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:821; }","duration":"234.482164ms","start":"2026-02-13T14:24:51.258279Z","end":"2026-02-13T14:24:51.492761Z","steps":["trace[735450473] 'read index received' (duration: 193.562965ms)","trace[735450473] 'applied index is now lower than readState.Index' (duration: 40.918349ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:51.492854Z","caller":"traceutil/trace.go:171","msg":"trace[1833159373] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"519.15422ms","start":"2026-02-13T14:24:50.97369Z","end":"2026-02-13T14:24:51.492844Z","steps":["trace[1833159373] 'process raft request' (duration: 478.230721ms)","trace[1833159373] 'compare' (duration: 40.267114ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:24:51.492928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:50.973663Z","time spent":"519.211872ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:51.493089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.319268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-13T14:24:51.493124Z","caller":"traceutil/trace.go:171","msg":"trace[2140043261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"206.370748ms","start":"2026-02-13T14:24:51.286743Z","end":"2026-02-13T14:24:51.493113Z","steps":["trace[2140043261] 'agreement among raft nodes before linearized reading' (duration: 206.262896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:51.493172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.890184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:51.493203Z","caller":"traceutil/trace.go:171","msg":"trace[411912741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"234.946714ms","start":"2026-02-13T14:24:51.258246Z","end":"2026-02-13T14:24:51.493193Z","steps":["trace[411912741] 'agreement among raft nodes before linearized reading' (duration: 234.886063ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:51.60149Z","caller":"traceutil/trace.go:171","msg":"trace[2062531524] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"101.818688ms","start":"2026-02-13T14:24:51.499412Z","end":"2026-02-13T14:24:51.601231Z","steps":["trace[2062531524] 'process raft request' (duration: 98.280568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:52.481921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.078622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:52.481991Z","caller":"traceutil/trace.go:171","msg":"trace[470326685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"223.185944ms","start":"2026-02-13T14:24:52.258786Z","end":"2026-02-13T14:24:52.481972Z","steps":["trace[470326685] 'range keys from in-memory index tree' (duration: 222.864527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:52.482082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.952698ms","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-13T14:24:52.482133Z","caller":"traceutil/trace.go:171","msg":"trace[1317198659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"209.072221ms","start":"2026-02-13T14:24:52.273047Z","end":"2026-02-13T14:24:52.482119Z","steps":["trace[1317198659] 'range keys from in-memory index tree' (duration: 208.785754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:53.021649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536539729307047,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T14:24:53.439346Z","caller":"traceutil/trace.go:171","msg":"trace[1230797393] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"918.755353ms","start":"2026-02-13T14:24:52.520565Z","end":"2026-02-13T14:24:53.43932Z","steps":["trace[1230797393] 'read index received' (duration: 918.473737ms)","trace[1230797393] 'applied index is now lower than readState.Index' (duration: 280.606µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:53.439584Z","caller":"traceutil/trace.go:171","msg":"trace[1340552978] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"952.850626ms","start":"2026-02-13T14:24:52.486718Z","end":"2026-02-13T14:24:53.439568Z","steps":["trace[1340552978] 'process raft request' (duration: 952.418426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:53.439707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:52.486702Z","time spent":"952.917507ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:24:53.439909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.519242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:24:53.439965Z","caller":"traceutil/trace.go:171","msg":"trace[985103615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:793; }","duration":"892.642174ms","start":"2026-02-13T14:24:52.547306Z","end":"2026-02-13T14:24:53.439949Z","steps":["trace[985103615] 'agreement among raft nodes before linearized reading' (duration: 892.441579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:53.440008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:52.547288Z","time spent":"892.709185ms","remote":"127.0.0.1:45352","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-13T14:24:53.440218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.645244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T14:24:53.44026Z","caller":"traceutil/trace.go:171","msg":"trace[245112188] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:793; }","duration":"919.687905ms","start":"2026-02-13T14:24:52.52056Z","end":"2026-02-13T14:24:53.440248Z","steps":["trace[245112188] 'agreement among raft nodes before linearized reading' (duration: 919.531221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:53.440296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:52.520514Z","time spent":"919.772237ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T14:24:53.440408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.578879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:24:53.440445Z","caller":"traceutil/trace.go:171","msg":"trace[365641826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"181.658081ms","start":"2026-02-13T14:24:53.258776Z","end":"2026-02-13T14:24:53.440434Z","steps":["trace[365641826] 'agreement among raft nodes before linearized reading' (duration: 181.59606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:53.440735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.130337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T14:24:53.441379Z","caller":"traceutil/trace.go:171","msg":"trace[1033712564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"772.816573ms","start":"2026-02-13T14:24:52.668528Z","end":"2026-02-13T14:24:53.441344Z","steps":["trace[1033712564] 'agreement among raft nodes before linearized reading' (duration: 772.112947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:53.441684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:24:52.668509Z","time spent":"773.15514ms","remote":"127.0.0.1:45352","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-13T14:24:53.623215Z","caller":"traceutil/trace.go:171","msg":"trace[332306688] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"175.419788ms","start":"2026-02-13T14:24:53.447776Z","end":"2026-02-13T14:24:53.623196Z","steps":["trace[332306688] 'process raft request' (duration: 89.210848ms)","trace[332306688] 'compare' (duration: 85.705908ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:24:53.623378Z","caller":"traceutil/trace.go:171","msg":"trace[1300829184] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"173.670198ms","start":"2026-02-13T14:24:53.449699Z","end":"2026-02-13T14:24:53.623369Z","steps":["trace[1300829184] 'process raft request' (duration: 173.084444ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:24:53.623483Z","caller":"traceutil/trace.go:171","msg":"trace[2109600180] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"172.801107ms","start":"2026-02-13T14:24:53.450675Z","end":"2026-02-13T14:24:53.623476Z","steps":["trace[2109600180] 'process raft request' (duration: 172.181003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:24:54.029851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.790441ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T14:25:07.705484Z","caller":"traceutil/trace.go:171","msg":"trace[921855814] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"202.546361ms","start":"2026-02-13T14:25:07.502925Z","end":"2026-02-13T14:25:07.705471Z","steps":["trace[921855814] 'process raft request' (duration: 202.372447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:08.552334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.458636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:25:08.552461Z","caller":"traceutil/trace.go:171","msg":"trace[1102904899] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:918; }","duration":"828.799751ms","start":"2026-02-13T14:25:07.72365Z","end":"2026-02-13T14:25:08.55245Z","steps":["trace[1102904899] 'read index received' (duration: 303.212972ms)","trace[1102904899] 'applied index is now lower than readState.Index' (duration: 525.586059ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:25:08.552604Z","caller":"traceutil/trace.go:171","msg":"trace[601603943] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"833.805126ms","start":"2026-02-13T14:25:07.71879Z","end":"2026-02-13T14:25:08.552595Z","steps":["trace[601603943] 'process raft request' (duration: 308.008823ms)","trace[601603943] 'compare' (duration: 525.241281ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:08.552651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:07.718774Z","time spent":"833.855647ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:25:08.552764Z","caller":"traceutil/trace.go:171","msg":"trace[446091620] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"833.851037ms","start":"2026-02-13T14:25:07.718904Z","end":"2026-02-13T14:25:08.552755Z","steps":["trace[446091620] 'process raft request' (duration: 833.511249ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:08.552794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:07.718897Z","time spent":"833.880148ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:25:08.552957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.304043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:25:08.552986Z","caller":"traceutil/trace.go:171","msg":"trace[139896895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"829.346964ms","start":"2026-02-13T14:25:07.723631Z","end":"2026-02-13T14:25:08.552978Z","steps":["trace[139896895] 'agreement among raft nodes before linearized reading' (duration: 829.232212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:08.553004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:07.723625Z","time spent":"829.374845ms","remote":"127.0.0.1:45352","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-13T14:25:08.553131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.538625ms","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-13T14:25:08.553149Z","caller":"traceutil/trace.go:171","msg":"trace[1349352128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"777.582755ms","start":"2026-02-13T14:25:07.775561Z","end":"2026-02-13T14:25:08.553144Z","steps":["trace[1349352128] 'agreement among raft nodes before linearized reading' (duration: 777.532164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:08.553167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:07.775533Z","time spent":"777.630056ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-13T14:25:12.692458Z","caller":"traceutil/trace.go:171","msg":"trace[1921066175] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"125.688756ms","start":"2026-02-13T14:25:12.566755Z","end":"2026-02-13T14:25:12.692444Z","steps":["trace[1921066175] 'read index received' (duration: 125.562053ms)","trace[1921066175] 'applied index is now lower than readState.Index' (duration: 126.163µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:25:12.692651Z","caller":"traceutil/trace.go:171","msg":"trace[157393255] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"127.867697ms","start":"2026-02-13T14:25:12.564777Z","end":"2026-02-13T14:25:12.692645Z","steps":["trace[157393255] 'process raft request' (duration: 127.57543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:12.692805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.036134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T14:25:12.692848Z","caller":"traceutil/trace.go:171","msg":"trace[1813219100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"126.074235ms","start":"2026-02-13T14:25:12.566743Z","end":"2026-02-13T14:25:12.692817Z","steps":["trace[1813219100] 'agreement among raft nodes before linearized reading' (duration: 126.001374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:12.692936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.1904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-13T14:25:12.692948Z","caller":"traceutil/trace.go:171","msg":"trace[1322712966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"120.20469ms","start":"2026-02-13T14:25:12.57274Z","end":"2026-02-13T14:25:12.692945Z","steps":["trace[1322712966] 'agreement among raft nodes before linearized reading' (duration: 120.1676ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:12.6931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.51951ms","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-13T14:25:12.693114Z","caller":"traceutil/trace.go:171","msg":"trace[1289598811] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"114.54576ms","start":"2026-02-13T14:25:12.578564Z","end":"2026-02-13T14:25:12.69311Z","steps":["trace[1289598811] 'agreement among raft nodes before linearized reading' (duration: 114.506139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:12.906777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.9162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:25:12.906858Z","caller":"traceutil/trace.go:171","msg":"trace[2122468862] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"210.817511ms","start":"2026-02-13T14:25:12.696027Z","end":"2026-02-13T14:25:12.906845Z","steps":["trace[2122468862] 'process raft request' (duration: 71.781298ms)","trace[2122468862] 'compare' (duration: 138.731776ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:25:12.909889Z","caller":"traceutil/trace.go:171","msg":"trace[841756491] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"211.895636ms","start":"2026-02-13T14:25:12.697986Z","end":"2026-02-13T14:25:12.909882Z","steps":["trace[841756491] 'process raft request' (duration: 211.876815ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:12.91005Z","caller":"traceutil/trace.go:171","msg":"trace[992653985] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"212.92629ms","start":"2026-02-13T14:25:12.697119Z","end":"2026-02-13T14:25:12.910045Z","steps":["trace[992653985] 'process raft request' (duration: 212.701754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:14.391127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.474906ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-13T14:25:14.391209Z","caller":"traceutil/trace.go:171","msg":"trace[148154534] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"132.926652ms","start":"2026-02-13T14:25:14.258271Z","end":"2026-02-13T14:25:14.391198Z","steps":["trace[148154534] 'read index received' (duration: 23.801µs)","trace[148154534] 'applied index is now lower than readState.Index' (duration: 132.901681ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:14.391274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.996074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:25:14.391291Z","caller":"traceutil/trace.go:171","msg":"trace[239868778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"133.041524ms","start":"2026-02-13T14:25:14.258245Z","end":"2026-02-13T14:25:14.391286Z","steps":["trace[239868778] 'agreement among raft nodes before linearized reading' (duration: 132.980403ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:14.759268Z","caller":"traceutil/trace.go:171","msg":"trace[775087667] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"134.183001ms","start":"2026-02-13T14:25:14.625069Z","end":"2026-02-13T14:25:14.759252Z","steps":["trace[775087667] 'process raft request' (duration: 133.969616ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:20.074988Z","caller":"traceutil/trace.go:171","msg":"trace[481255023] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"237.596016ms","start":"2026-02-13T14:25:19.837375Z","end":"2026-02-13T14:25:20.074971Z","steps":["trace[481255023] 'process raft request' (duration: 237.474823ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:23.082072Z","caller":"traceutil/trace.go:171","msg":"trace[1463173286] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"273.611772ms","start":"2026-02-13T14:25:22.808446Z","end":"2026-02-13T14:25:23.082058Z","steps":["trace[1463173286] 'process raft request' (duration: 273.380857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:25.621015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710536539729307393,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-13T14:25:25.641615Z","caller":"traceutil/trace.go:171","msg":"trace[535739573] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"552.387605ms","start":"2026-02-13T14:25:25.089207Z","end":"2026-02-13T14:25:25.641595Z","steps":["trace[535739573] 'process raft request' (duration: 552.219881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:25.641743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:25.089191Z","time spent":"552.472256ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:25:25.693105Z","caller":"traceutil/trace.go:171","msg":"trace[746650791] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"572.941826ms","start":"2026-02-13T14:25:25.120142Z","end":"2026-02-13T14:25:25.693084Z","steps":["trace[746650791] 'read index received' (duration: 522.019077ms)","trace[746650791] 'applied index is now lower than readState.Index' (duration: 50.921919ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:25:25.693583Z","caller":"traceutil/trace.go:171","msg":"trace[1573524674] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"588.594836ms","start":"2026-02-13T14:25:25.10492Z","end":"2026-02-13T14:25:25.693515Z","steps":["trace[1573524674] 'process raft request' (duration: 588.059774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:25.694011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:25.10491Z","time spent":"589.034466ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:25:25.732394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.237189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-13T14:25:25.732474Z","caller":"traceutil/trace.go:171","msg":"trace[650442333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"612.339961ms","start":"2026-02-13T14:25:25.120117Z","end":"2026-02-13T14:25:25.732457Z","steps":["trace[650442333] 'agreement among raft nodes before linearized reading' (duration: 574.359579ms)","trace[650442333] 'range keys from in-memory index tree' (duration: 37.800119ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:25.732511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:25.120105Z","time spent":"612.397413ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-13T14:25:25.732709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.514785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-13T14:25:25.732749Z","caller":"traceutil/trace.go:171","msg":"trace[622114265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"612.562967ms","start":"2026-02-13T14:25:25.120173Z","end":"2026-02-13T14:25:25.732736Z","steps":["trace[622114265] 'agreement among raft nodes before linearized reading' (duration: 574.324759ms)","trace[622114265] 'range keys from in-memory index tree' (duration: 38.159926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:25.732788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:25.120168Z","time spent":"612.613308ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-13T14:25:25.73296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.827504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-13T14:25:25.73301Z","caller":"traceutil/trace.go:171","msg":"trace[1508043556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"609.919196ms","start":"2026-02-13T14:25:25.123081Z","end":"2026-02-13T14:25:25.733Z","steps":["trace[1508043556] 'agreement among raft nodes before linearized reading' (duration: 571.422782ms)","trace[1508043556] 'range keys from in-memory index tree' (duration: 38.399682ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:25.733043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:25.123071Z","time spent":"609.965587ms","remote":"127.0.0.1:45352","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-13T14:25:30.453513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.601588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:25:30.453572Z","caller":"traceutil/trace.go:171","msg":"trace[1498997978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"197.690089ms","start":"2026-02-13T14:25:30.255871Z","end":"2026-02-13T14:25:30.453561Z","steps":["trace[1498997978] 'range keys from in-memory index tree' (duration: 197.557537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:32.437641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.916784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:25:32.437757Z","caller":"traceutil/trace.go:171","msg":"trace[465168232] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"604.446519ms","start":"2026-02-13T14:25:31.833273Z","end":"2026-02-13T14:25:32.437719Z","steps":["trace[465168232] 'process raft request' (duration: 170.375781ms)","trace[465168232] 'compare' (duration: 433.658808ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:32.437814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:31.83326Z","time spent":"604.52791ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:25:32.439764Z","caller":"traceutil/trace.go:171","msg":"trace[579248030] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"313.826686ms","start":"2026-02-13T14:25:32.125915Z","end":"2026-02-13T14:25:32.439741Z","steps":["trace[579248030] 'read index received' (duration: 313.679073ms)","trace[579248030] 'applied index is now lower than readState.Index' (duration: 146.903µs)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:25:32.439947Z","caller":"traceutil/trace.go:171","msg":"trace[108632547] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"606.253351ms","start":"2026-02-13T14:25:31.833683Z","end":"2026-02-13T14:25:32.439937Z","steps":["trace[108632547] 'process raft request' (duration: 605.960564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:32.440013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:31.833658Z","time spent":"606.321132ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:25:32.440374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.806913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:25:32.443612Z","caller":"traceutil/trace.go:171","msg":"trace[817945623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"181.080338ms","start":"2026-02-13T14:25:32.262517Z","end":"2026-02-13T14:25:32.443597Z","steps":["trace[817945623] 'agreement among raft nodes before linearized reading' (duration: 177.774302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:32.441324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.408302ms","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-13T14:25:32.444096Z","caller":"traceutil/trace.go:171","msg":"trace[1499073840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"318.181176ms","start":"2026-02-13T14:25:32.125902Z","end":"2026-02-13T14:25:32.444083Z","steps":["trace[1499073840] 'agreement among raft nodes before linearized reading' (duration: 315.32812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:32.44425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:32.125847Z","time spent":"318.386672ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-13T14:25:32.440464Z","caller":"traceutil/trace.go:171","msg":"trace[1825284262] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"606.428464ms","start":"2026-02-13T14:25:31.834018Z","end":"2026-02-13T14:25:32.440447Z","steps":["trace[1825284262] 'process raft request' (duration: 605.694107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:32.446289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:31.834012Z","time spent":"612.217517ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:25:32.770923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.445464ms","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-13T14:25:32.77098Z","caller":"traceutil/trace.go:171","msg":"trace[716175274] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:971; }","duration":"163.535165ms","start":"2026-02-13T14:25:32.607432Z","end":"2026-02-13T14:25:32.770967Z","steps":["trace[716175274] 'count revisions from in-memory index tree' (duration: 163.400962ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:33.004078Z","caller":"traceutil/trace.go:171","msg":"trace[1450587672] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"116.920772ms","start":"2026-02-13T14:25:32.887144Z","end":"2026-02-13T14:25:33.004065Z","steps":["trace[1450587672] 'process raft request' (duration: 116.83519ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:25:33.146581Z","caller":"traceutil/trace.go:171","msg":"trace[940809143] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"143.374524ms","start":"2026-02-13T14:25:33.003184Z","end":"2026-02-13T14:25:33.146559Z","steps":["trace[940809143] 'process raft request' (duration: 143.259402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:34.131404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.241166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:25:34.131457Z","caller":"traceutil/trace.go:171","msg":"trace[926852785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"874.330687ms","start":"2026-02-13T14:25:33.257115Z","end":"2026-02-13T14:25:34.131446Z","steps":["trace[926852785] 'range keys from in-memory index tree' (duration: 874.183905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:34.131482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:33.2571Z","time spent":"874.378189ms","remote":"127.0.0.1:45108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:25:34.131694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.044611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-13T14:25:34.131707Z","caller":"traceutil/trace.go:171","msg":"trace[40252609] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"299.082611ms","start":"2026-02-13T14:25:33.83262Z","end":"2026-02-13T14:25:34.131703Z","steps":["trace[40252609] 'range keys from in-memory index tree' (duration: 298.954539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:34.140603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.25448ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-13T14:25:42.681261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.548197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:25:42.68183Z","caller":"traceutil/trace.go:171","msg":"trace[642422927] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1066; }","duration":"425.272859ms","start":"2026-02-13T14:25:42.256538Z","end":"2026-02-13T14:25:42.681811Z","steps":["trace[642422927] 'read index received' (duration: 8.096867ms)","trace[642422927] 'applied index is now lower than readState.Index' (duration: 417.174702ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:25:42.681901Z","caller":"traceutil/trace.go:171","msg":"trace[1524257814] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"458.527645ms","start":"2026-02-13T14:25:42.223365Z","end":"2026-02-13T14:25:42.681892Z","steps":["trace[1524257814] 'process raft request' (duration: 457.995923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:42.681971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.223356Z","time spent":"458.568816ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:25:42.68198Z","caller":"traceutil/trace.go:171","msg":"trace[1821620972] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"461.542524ms","start":"2026-02-13T14:25:42.220411Z","end":"2026-02-13T14:25:42.681953Z","steps":["trace[1821620972] 'process raft request' (duration: 44.242349ms)","trace[1821620972] 'compare' (duration: 415.34496ms)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:42.682085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.2204Z","time spent":"461.636247ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-13T14:25:42.682178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.634567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:25:42.682207Z","caller":"traceutil/trace.go:171","msg":"trace[105556353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"425.696429ms","start":"2026-02-13T14:25:42.256501Z","end":"2026-02-13T14:25:42.682198Z","steps":["trace[105556353] 'agreement among raft nodes before linearized reading' (duration: 425.646838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:42.682237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.256486Z","time spent":"425.74207ms","remote":"127.0.0.1:45108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-13T14:25:43.811882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019247797s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-13T14:25:43.812127Z","caller":"traceutil/trace.go:171","msg":"trace[1931457868] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1068; }","duration":"1.130243185s","start":"2026-02-13T14:25:42.681863Z","end":"2026-02-13T14:25:43.812106Z","steps":["trace[1931457868] 'read index received' (duration: 110.65187ms)","trace[1931457868] 'applied index is now lower than readState.Index' (duration: 1.019590045s)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:25:43.812445Z","caller":"traceutil/trace.go:171","msg":"trace[1987354659] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"1.437115915s","start":"2026-02-13T14:25:42.375308Z","end":"2026-02-13T14:25:43.812424Z","steps":["trace[1987354659] 'process raft request' (duration: 417.259344ms)","trace[1987354659] 'compare' (duration: 1.018686054s)"],"step_count":2} {"level":"warn","ts":"2026-02-13T14:25:43.812656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.375254Z","time spent":"1.437241288s","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":808,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-13T14:25:43.813132Z","caller":"traceutil/trace.go:171","msg":"trace[1150567680] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"1.238091029s","start":"2026-02-13T14:25:42.575022Z","end":"2026-02-13T14:25:43.813113Z","steps":["trace[1150567680] 'process raft request' (duration: 1.236965933s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:43.81324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.575005Z","time spent":"1.238177361s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:25:43.813524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267280921s","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-13T14:25:43.813963Z","caller":"traceutil/trace.go:171","msg":"trace[368667282] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"1.267744792s","start":"2026-02-13T14:25:42.546207Z","end":"2026-02-13T14:25:43.813952Z","steps":["trace[368667282] 'agreement among raft nodes before linearized reading' (duration: 1.267235411s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:43.814017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.546194Z","time spent":"1.267812824s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-13T14:25:43.814254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319198808s","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-13T14:25:43.81428Z","caller":"traceutil/trace.go:171","msg":"trace[477024270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1027; }","duration":"1.31926448s","start":"2026-02-13T14:25:42.495008Z","end":"2026-02-13T14:25:43.814272Z","steps":["trace[477024270] 'agreement among raft nodes before linearized reading' (duration: 1.319170108s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:43.814305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.494998Z","time spent":"1.31929927s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-13T14:25:43.814699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327410337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-13T14:25:43.814721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335151296s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-13T14:25:43.814752Z","caller":"traceutil/trace.go:171","msg":"trace[1998631645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"1.335216918s","start":"2026-02-13T14:25:42.479527Z","end":"2026-02-13T14:25:43.814743Z","steps":["trace[1998631645] 'agreement among raft nodes before linearized reading' (duration: 1.335135186s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:43.814778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.479509Z","time spent":"1.335261758s","remote":"127.0.0.1:45352","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-13T14:25:43.814731Z","caller":"traceutil/trace.go:171","msg":"trace[1924709229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"1.327463108s","start":"2026-02-13T14:25:42.487257Z","end":"2026-02-13T14:25:43.814721Z","steps":["trace[1924709229] 'agreement among raft nodes before linearized reading' (duration: 1.327346666s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:43.814902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.487247Z","time spent":"1.327643922s","remote":"127.0.0.1:45352","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-13T14:25:43.814942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512884791s","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-13T14:25:43.81497Z","caller":"traceutil/trace.go:171","msg":"trace[1727654552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"1.512927282s","start":"2026-02-13T14:25:42.302035Z","end":"2026-02-13T14:25:43.814962Z","steps":["trace[1727654552] 'agreement among raft nodes before linearized reading' (duration: 1.51284984s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:43.814993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.302026Z","time spent":"1.512960752s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-13T14:25:43.815065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52019924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:25:43.815089Z","caller":"traceutil/trace.go:171","msg":"trace[249417678] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1027; }","duration":"1.5202489s","start":"2026-02-13T14:25:42.294832Z","end":"2026-02-13T14:25:43.815081Z","steps":["trace[249417678] 'agreement among raft nodes before linearized reading' (duration: 1.52021411s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:43.815112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.294816Z","time spent":"1.520289172s","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-13T14:25:43.815274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131219988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:25:43.815302Z","caller":"traceutil/trace.go:171","msg":"trace[1790567704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"1.131285258s","start":"2026-02-13T14:25:42.684009Z","end":"2026-02-13T14:25:43.815294Z","steps":["trace[1790567704] 'agreement among raft nodes before linearized reading' (duration: 1.131242587s)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:25:43.815326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:25:42.683988Z","time spent":"1.13133183s","remote":"127.0.0.1:45112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-13T14:28:38.832771Z","caller":"traceutil/trace.go:171","msg":"trace[204275417] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"104.992092ms","start":"2026-02-13T14:28:38.727765Z","end":"2026-02-13T14:28:38.832757Z","steps":["trace[204275417] 'process raft request' (duration: 104.88506ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:28:38.899987Z","caller":"traceutil/trace.go:171","msg":"trace[1339514852] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"171.719632ms","start":"2026-02-13T14:28:38.72825Z","end":"2026-02-13T14:28:38.899969Z","steps":["trace[1339514852] 'process raft request' (duration: 171.476986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:44.980213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.988943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-02-13T14:28:44.980411Z","caller":"traceutil/trace.go:171","msg":"trace[206912247] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1806; }","duration":"161.228359ms","start":"2026-02-13T14:28:44.819161Z","end":"2026-02-13T14:28:44.980389Z","steps":["trace[206912247] 'range keys from in-memory index tree' (duration: 160.844241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:44.981024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.648684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-13T14:28:44.981072Z","caller":"traceutil/trace.go:171","msg":"trace[1276970149] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1806; }","duration":"104.721965ms","start":"2026-02-13T14:28:44.876343Z","end":"2026-02-13T14:28:44.981065Z","steps":["trace[1276970149] 'count revisions from in-memory index tree' (duration: 104.582092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:44.986156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.43466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-13T14:28:44.986475Z","caller":"traceutil/trace.go:171","msg":"trace[59263601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1806; }","duration":"137.570073ms","start":"2026-02-13T14:28:44.848648Z","end":"2026-02-13T14:28:44.986218Z","steps":["trace[59263601] 'range keys from in-memory index tree' (duration: 132.000755ms)"],"step_count":1} {"level":"info","ts":"2026-02-13T14:28:45.295738Z","caller":"traceutil/trace.go:171","msg":"trace[2054008486] transaction","detail":"{read_only:false; response_revision:1807; number_of_response:1; }","duration":"307.413951ms","start":"2026-02-13T14:28:44.988308Z","end":"2026-02-13T14:28:45.295722Z","steps":["trace[2054008486] 'process raft request' (duration: 306.572881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:45.295944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:28:44.988296Z","time spent":"307.564714ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:28:45.359897Z","caller":"traceutil/trace.go:171","msg":"trace[572328662] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1888; }","duration":"100.218352ms","start":"2026-02-13T14:28:45.259659Z","end":"2026-02-13T14:28:45.359877Z","steps":["trace[572328662] 'read index received' (duration: 36.634085ms)","trace[572328662] 'applied index is now lower than readState.Index' (duration: 63.583187ms)"],"step_count":2} {"level":"info","ts":"2026-02-13T14:28:45.359929Z","caller":"traceutil/trace.go:171","msg":"trace[1509546827] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"366.821951ms","start":"2026-02-13T14:28:44.993055Z","end":"2026-02-13T14:28:45.359877Z","steps":["trace[1509546827] 'process raft request' (duration: 366.657267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:45.360519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:28:44.993046Z","time spent":"367.300391ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:28:45.360816Z","caller":"traceutil/trace.go:171","msg":"trace[302574537] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"369.137414ms","start":"2026-02-13T14:28:44.991664Z","end":"2026-02-13T14:28:45.360801Z","steps":["trace[302574537] 'process raft request' (duration: 367.945236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:45.362845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:28:44.991648Z","time spent":"371.129581ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:28:45.361161Z","caller":"traceutil/trace.go:171","msg":"trace[124599653] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"366.841981ms","start":"2026-02-13T14:28:44.994306Z","end":"2026-02-13T14:28:45.361148Z","steps":["trace[124599653] 'process raft request' (duration: 365.50884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:45.364351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:28:44.994299Z","time spent":"370.019975ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:28:45.361204Z","caller":"traceutil/trace.go:171","msg":"trace[217990275] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"368.119131ms","start":"2026-02-13T14:28:44.993076Z","end":"2026-02-13T14:28:45.361195Z","steps":["trace[217990275] 'process raft request' (duration: 366.675488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:45.364692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:28:44.993066Z","time spent":"371.468068ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:28:45.36123Z","caller":"traceutil/trace.go:171","msg":"trace[66821642] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"368.125981ms","start":"2026-02-13T14:28:44.993097Z","end":"2026-02-13T14:28:45.361223Z","steps":["trace[66821642] 'process raft request' (duration: 366.690798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:45.36508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:28:44.993092Z","time spent":"371.830806ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-13T14:28:45.361268Z","caller":"traceutil/trace.go:171","msg":"trace[1471218576] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"365.828318ms","start":"2026-02-13T14:28:44.995432Z","end":"2026-02-13T14:28:45.36126Z","steps":["trace[1471218576] 'process raft request' (duration: 364.410215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-13T14:28:45.366625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-13T14:28:44.995425Z","time spent":"371.171542ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-13T14:28:45.361339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.666535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-13T14:28:45.366808Z","caller":"traceutil/trace.go:171","msg":"trace[2100903663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1813; }","duration":"107.122191ms","start":"2026-02-13T14:28:45.25962Z","end":"2026-02-13T14:28:45.366742Z","steps":["trace[2100903663] 'agreement among raft nodes before linearized reading' (duration: 101.673805ms)"],"step_count":1}