{"level":"warn","ts":"2026-02-12T22:46:34.586251Z","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-12T22:46:34.586331Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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://162.253.55.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217: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-12T22:46:34.586403Z","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-12T22:46:34.586412Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-12T22:46:34.586429Z","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-12T22:46:34.587089Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-12T22:46:34.587179Z","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://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217: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-12T22:46:34.594915Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.998015ms"} {"level":"info","ts":"2026-02-12T22:46:34.619497Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-12T22:46:34.61959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T22:46:34.619612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-12T22:46:34.619637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T22:46:34.619649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-12T22:46:34.61968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-12T22:46:34.628556Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T22:46:34.636079Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T22:46:34.639537Z","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-12T22:46:34.650473Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T22:46:34.657275Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T22:46:34.657484Z","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-12T22:46:34.657513Z","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-12T22:46:34.657521Z","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-12T22:46:34.65875Z","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-12T22:46:34.65893Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T22:46:34.658952Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T22:46:34.659068Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-12T22:46:34.659099Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-12T22:46:34.665155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-12T22:46:34.665232Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-12T22:46:35.019864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T22:46:35.019941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T22:46:35.019965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-12T22:46:35.019987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-12T22:46:35.020001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-12T22:46:35.020012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-12T22:46:35.020019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-12T22:46:35.021123Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T22:46:35.024509Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T22:46:35.024641Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T22:46:35.024967Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T22:46:35.026459Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T22:46:35.02705Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T22:46:35.027134Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T22:46:35.028204Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T22:46:35.028404Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T22:46:35.028619Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T22:46:35.036469Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-12T22:46:49.387367Z","caller":"traceutil/trace.go:171","msg":"trace[742866414] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"444.495578ms","start":"2026-02-12T22:46:48.94285Z","end":"2026-02-12T22:46:49.387346Z","steps":["trace[742866414] 'read index received' (duration: 444.319943ms)","trace[742866414] 'applied index is now lower than readState.Index' (duration: 174.855µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:46:49.387658Z","caller":"traceutil/trace.go:171","msg":"trace[1048374444] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"496.286291ms","start":"2026-02-12T22:46:48.891361Z","end":"2026-02-12T22:46:49.387647Z","steps":["trace[1048374444] 'process raft request' (duration: 495.859241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:46:49.388041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:46:48.89131Z","time spent":"496.373543ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:46:49.388185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.333056ms","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-12T22:46:49.388212Z","caller":"traceutil/trace.go:171","msg":"trace[1790494621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"445.395286ms","start":"2026-02-12T22:46:48.94281Z","end":"2026-02-12T22:46:49.388205Z","steps":["trace[1790494621] 'agreement among raft nodes before linearized reading' (duration: 445.350215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:46:49.388231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:46:48.94279Z","time spent":"445.436328ms","remote":"127.0.0.1:45070","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-12T22:46:49.388318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.216786ms","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-12T22:46:49.388335Z","caller":"traceutil/trace.go:171","msg":"trace[1625857312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"437.247287ms","start":"2026-02-12T22:46:48.951082Z","end":"2026-02-12T22:46:49.38833Z","steps":["trace[1625857312] 'agreement among raft nodes before linearized reading' (duration: 437.216246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:46:49.388357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:46:48.951075Z","time spent":"437.277667ms","remote":"127.0.0.1:45070","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-12T22:46:49.388475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.036244ms","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-12T22:46:49.388496Z","caller":"traceutil/trace.go:171","msg":"trace[165292176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"394.094645ms","start":"2026-02-12T22:46:48.994395Z","end":"2026-02-12T22:46:49.388489Z","steps":["trace[165292176] 'agreement among raft nodes before linearized reading' (duration: 394.061255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:46:49.388512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:46:48.994255Z","time spent":"394.252599ms","remote":"127.0.0.1:45070","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-12T22:46:49.497099Z","caller":"traceutil/trace.go:171","msg":"trace[54438474] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"103.907631ms","start":"2026-02-12T22:46:49.393168Z","end":"2026-02-12T22:46:49.497075Z","steps":["trace[54438474] 'process raft request' (duration: 91.999402ms)","trace[54438474] 'compare' (duration: 11.45552ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:46:49.497258Z","caller":"traceutil/trace.go:171","msg":"trace[1117649981] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"101.062062ms","start":"2026-02-12T22:46:49.396186Z","end":"2026-02-12T22:46:49.497248Z","steps":["trace[1117649981] 'process raft request' (duration: 100.547601ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:46:51.100356Z","caller":"traceutil/trace.go:171","msg":"trace[1201210310] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"275.120509ms","start":"2026-02-12T22:46:50.825215Z","end":"2026-02-12T22:46:51.100335Z","steps":["trace[1201210310] 'process raft request' (duration: 275.006937ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:46:55.614366Z","caller":"traceutil/trace.go:171","msg":"trace[1263566330] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"204.770529ms","start":"2026-02-12T22:46:55.409583Z","end":"2026-02-12T22:46:55.614353Z","steps":["trace[1263566330] 'process raft request' (duration: 204.650516ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:47:05.843907Z","caller":"traceutil/trace.go:171","msg":"trace[1323373993] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"180.989882ms","start":"2026-02-12T22:47:05.662902Z","end":"2026-02-12T22:47:05.843892Z","steps":["trace[1323373993] 'process raft request' (duration: 180.8708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:06.651189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.287615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:47:06.651277Z","caller":"traceutil/trace.go:171","msg":"trace[1809842537] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:528; }","duration":"970.397026ms","start":"2026-02-12T22:47:05.680866Z","end":"2026-02-12T22:47:06.651263Z","steps":["trace[1809842537] 'read index received' (duration: 163.000676ms)","trace[1809842537] 'applied index is now lower than readState.Index' (duration: 807.39537ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:06.651449Z","caller":"traceutil/trace.go:171","msg":"trace[170237251] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"986.36832ms","start":"2026-02-12T22:47:05.665069Z","end":"2026-02-12T22:47:06.651437Z","steps":["trace[170237251] 'process raft request' (duration: 551.647586ms)","trace[170237251] 'compare' (duration: 433.982489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:47:06.651507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:05.665063Z","time spent":"986.41291ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:47:06.651673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.802754ms","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-12T22:47:06.6517Z","caller":"traceutil/trace.go:171","msg":"trace[1503903679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"970.844275ms","start":"2026-02-12T22:47:05.680848Z","end":"2026-02-12T22:47:06.651692Z","steps":["trace[1503903679] 'agreement among raft nodes before linearized reading' (duration: 970.794224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:06.651726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:05.68084Z","time spent":"970.880325ms","remote":"127.0.0.1:45070","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-12T22:47:06.651892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.983518ms","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-12T22:47:06.651919Z","caller":"traceutil/trace.go:171","msg":"trace[1621470149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"971.018698ms","start":"2026-02-12T22:47:05.680893Z","end":"2026-02-12T22:47:06.651912Z","steps":["trace[1621470149] 'agreement among raft nodes before linearized reading' (duration: 970.969727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:06.651948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:05.68089Z","time spent":"971.051899ms","remote":"127.0.0.1:45070","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-12T22:47:06.652123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.178595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:47:06.652149Z","caller":"traceutil/trace.go:171","msg":"trace[789483336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"102.221055ms","start":"2026-02-12T22:47:06.54992Z","end":"2026-02-12T22:47:06.652141Z","steps":["trace[789483336] 'agreement among raft nodes before linearized reading' (duration: 102.183444ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:47:06.940245Z","caller":"traceutil/trace.go:171","msg":"trace[136388639] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"283.216998ms","start":"2026-02-12T22:47:06.657011Z","end":"2026-02-12T22:47:06.940228Z","steps":["trace[136388639] 'process raft request' (duration: 193.862391ms)","trace[136388639] 'compare' (duration: 89.228975ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:07.491728Z","caller":"traceutil/trace.go:171","msg":"trace[510590989] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"203.728817ms","start":"2026-02-12T22:47:07.287981Z","end":"2026-02-12T22:47:07.49171Z","steps":["trace[510590989] 'process raft request' (duration: 159.947423ms)","trace[510590989] 'compare' (duration: 43.509389ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:08.022775Z","caller":"traceutil/trace.go:171","msg":"trace[678582335] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"173.611928ms","start":"2026-02-12T22:47:07.849145Z","end":"2026-02-12T22:47:08.022757Z","steps":["trace[678582335] 'process raft request' (duration: 173.498036ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:47:08.447138Z","caller":"traceutil/trace.go:171","msg":"trace[1605928094] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"120.930387ms","start":"2026-02-12T22:47:08.326188Z","end":"2026-02-12T22:47:08.447119Z","steps":["trace[1605928094] 'process raft request' (duration: 23.039381ms)","trace[1605928094] 'compare' (duration: 97.786824ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:08.916438Z","caller":"traceutil/trace.go:171","msg":"trace[1091679939] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"155.442449ms","start":"2026-02-12T22:47:08.760975Z","end":"2026-02-12T22:47:08.916417Z","steps":["trace[1091679939] 'process raft request' (duration: 155.324297ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:47:12.919201Z","caller":"traceutil/trace.go:171","msg":"trace[2001056665] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"149.396943ms","start":"2026-02-12T22:47:12.769781Z","end":"2026-02-12T22:47:12.919178Z","steps":["trace[2001056665] 'process raft request' (duration: 149.25408ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:13.503595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.453747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:47:13.503682Z","caller":"traceutil/trace.go:171","msg":"trace[1236865881] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"512.359569ms","start":"2026-02-12T22:47:12.991292Z","end":"2026-02-12T22:47:13.503651Z","steps":["trace[1236865881] 'read index received' (duration: 359.848701ms)","trace[1236865881] 'applied index is now lower than readState.Index' (duration: 152.510148ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:13.503825Z","caller":"traceutil/trace.go:171","msg":"trace[523223559] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"568.756747ms","start":"2026-02-12T22:47:12.935047Z","end":"2026-02-12T22:47:13.503804Z","steps":["trace[523223559] 'process raft request' (duration: 416.141927ms)","trace[523223559] 'compare' (duration: 152.283454ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:47:13.503865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:12.935031Z","time spent":"568.812149ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:47:13.503983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.709266ms","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-12T22:47:13.504002Z","caller":"traceutil/trace.go:171","msg":"trace[2038907815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"512.756527ms","start":"2026-02-12T22:47:12.991238Z","end":"2026-02-12T22:47:13.503995Z","steps":["trace[2038907815] 'agreement among raft nodes before linearized reading' (duration: 512.721537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:13.50402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:12.991222Z","time spent":"512.794078ms","remote":"127.0.0.1:45070","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-12T22:47:13.504105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.353339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v98nb\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-12T22:47:13.504121Z","caller":"traceutil/trace.go:171","msg":"trace[1849288862] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v98nb; range_end:; response_count:1; response_revision:533; }","duration":"434.39382ms","start":"2026-02-12T22:47:13.069722Z","end":"2026-02-12T22:47:13.504116Z","steps":["trace[1849288862] 'agreement among raft nodes before linearized reading' (duration: 434.361319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:13.504136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:13.069708Z","time spent":"434.42418ms","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-v98nb\" "} {"level":"warn","ts":"2026-02-12T22:47:13.979937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.896339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:47:13.980027Z","caller":"traceutil/trace.go:171","msg":"trace[1154106075] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"470.792747ms","start":"2026-02-12T22:47:13.509193Z","end":"2026-02-12T22:47:13.980009Z","steps":["trace[1154106075] 'process raft request' (duration: 267.693933ms)","trace[1154106075] 'compare' (duration: 202.526062ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:47:13.980093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:13.509179Z","time spent":"470.886539ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:47:14.080109Z","caller":"traceutil/trace.go:171","msg":"trace[1476857660] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"553.582187ms","start":"2026-02-12T22:47:13.526504Z","end":"2026-02-12T22:47:14.080086Z","steps":["trace[1476857660] 'process raft request' (duration: 553.302102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:14.080218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:13.526485Z","time spent":"553.6805ms","remote":"127.0.0.1:45004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:47:14.082389Z","caller":"traceutil/trace.go:171","msg":"trace[1410310544] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:550; }","duration":"534.114711ms","start":"2026-02-12T22:47:13.548263Z","end":"2026-02-12T22:47:14.082378Z","steps":["trace[1410310544] 'read index received' (duration: 228.653467ms)","trace[1410310544] 'applied index is now lower than readState.Index' (duration: 305.460444ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:47:14.082576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.303214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:47:14.082624Z","caller":"traceutil/trace.go:171","msg":"trace[1567838295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"534.387837ms","start":"2026-02-12T22:47:13.548228Z","end":"2026-02-12T22:47:14.082616Z","steps":["trace[1567838295] 'agreement among raft nodes before linearized reading' (duration: 534.313105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:14.082645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:13.548219Z","time spent":"534.420677ms","remote":"127.0.0.1:44824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T22:47:26.118169Z","caller":"traceutil/trace.go:171","msg":"trace[1293962326] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"475.135276ms","start":"2026-02-12T22:47:25.643011Z","end":"2026-02-12T22:47:26.118146Z","steps":["trace[1293962326] 'process raft request' (duration: 474.993754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:26.118305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:25.642994Z","time spent":"475.245179ms","remote":"127.0.0.1:44988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4086,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:47:26.496636Z","caller":"traceutil/trace.go:171","msg":"trace[612631145] linearizableReadLoop","detail":"{readStateIndex:683; appliedIndex:682; }","duration":"828.947451ms","start":"2026-02-12T22:47:25.667668Z","end":"2026-02-12T22:47:26.496615Z","steps":["trace[612631145] 'read index received' (duration: 451.141774ms)","trace[612631145] 'applied index is now lower than readState.Index' (duration: 377.804697ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:26.496981Z","caller":"traceutil/trace.go:171","msg":"trace[969449842] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"844.256031ms","start":"2026-02-12T22:47:25.6527Z","end":"2026-02-12T22:47:26.496956Z","steps":["trace[969449842] 'process raft request' (duration: 843.774051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:26.497112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:25.652683Z","time spent":"844.357144ms","remote":"127.0.0.1:45268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:47:26.497294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.624616ms","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-12T22:47:26.497323Z","caller":"traceutil/trace.go:171","msg":"trace[1649845943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"829.677198ms","start":"2026-02-12T22:47:25.667639Z","end":"2026-02-12T22:47:26.497316Z","steps":["trace[1649845943] 'agreement among raft nodes before linearized reading' (duration: 829.573285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:26.497341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:25.667622Z","time spent":"829.714948ms","remote":"127.0.0.1:45070","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":"info","ts":"2026-02-12T22:47:26.504563Z","caller":"traceutil/trace.go:171","msg":"trace[379198806] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"379.277047ms","start":"2026-02-12T22:47:26.125275Z","end":"2026-02-12T22:47:26.504552Z","steps":["trace[379198806] 'process raft request' (duration: 379.211116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:26.504651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:26.125257Z","time spent":"379.346018ms","remote":"127.0.0.1:44988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4438,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:47:26.505108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.20617ms","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-12T22:47:26.505128Z","caller":"traceutil/trace.go:171","msg":"trace[489025053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:661; }","duration":"592.24626ms","start":"2026-02-12T22:47:25.912875Z","end":"2026-02-12T22:47:26.505121Z","steps":["trace[489025053] 'agreement among raft nodes before linearized reading' (duration: 592.178939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:26.505157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:25.91286Z","time spent":"592.293511ms","remote":"127.0.0.1:45070","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-12T22:47:26.505295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.706576ms","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-12T22:47:26.505317Z","caller":"traceutil/trace.go:171","msg":"trace[1316902825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"452.744717ms","start":"2026-02-12T22:47:26.052565Z","end":"2026-02-12T22:47:26.50531Z","steps":["trace[1316902825] 'agreement among raft nodes before linearized reading' (duration: 452.696106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:26.505329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:26.052552Z","time spent":"452.774407ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T22:47:26.50539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.300788ms","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-12T22:47:26.505401Z","caller":"traceutil/trace.go:171","msg":"trace[2136749314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"337.311828ms","start":"2026-02-12T22:47:26.168085Z","end":"2026-02-12T22:47:26.505397Z","steps":["trace[2136749314] 'agreement among raft nodes before linearized reading' (duration: 337.276757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:26.505414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:26.168048Z","time spent":"337.363628ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T22:47:34.805091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.588309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:47:34.805169Z","caller":"traceutil/trace.go:171","msg":"trace[393122269] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"198.486969ms","start":"2026-02-12T22:47:34.606669Z","end":"2026-02-12T22:47:34.805156Z","steps":["trace[393122269] 'process raft request' (duration: 44.773218ms)","trace[393122269] 'compare' (duration: 153.487037ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:35.118835Z","caller":"traceutil/trace.go:171","msg":"trace[1196652017] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"511.953289ms","start":"2026-02-12T22:47:34.606837Z","end":"2026-02-12T22:47:35.118791Z","steps":["trace[1196652017] 'process raft request' (duration: 461.327858ms)","trace[1196652017] 'compare' (duration: 50.455528ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:35.118968Z","caller":"traceutil/trace.go:171","msg":"trace[1073306570] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"510.195292ms","start":"2026-02-12T22:47:34.608756Z","end":"2026-02-12T22:47:35.118951Z","steps":["trace[1073306570] 'process raft request' (duration: 509.945437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:35.11909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:34.608747Z","time spent":"510.287383ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:47:35.118991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:34.606826Z","time spent":"512.076412ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:47:41.095136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.756992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:47:41.095203Z","caller":"traceutil/trace.go:171","msg":"trace[1136917176] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"275.108916ms","start":"2026-02-12T22:47:40.820081Z","end":"2026-02-12T22:47:41.095189Z","steps":["trace[1136917176] 'read index received' (duration: 69.17324ms)","trace[1136917176] 'applied index is now lower than readState.Index' (duration: 205.934946ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:47:41.095673Z","caller":"traceutil/trace.go:171","msg":"trace[1991164020] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"354.601242ms","start":"2026-02-12T22:47:40.741061Z","end":"2026-02-12T22:47:41.095662Z","steps":["trace[1991164020] 'process raft request' (duration: 148.251307ms)","trace[1991164020] 'compare' (duration: 205.346983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:47:41.095732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:40.741043Z","time spent":"354.664042ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:47:41.096214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.128007ms","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-12T22:47:41.096238Z","caller":"traceutil/trace.go:171","msg":"trace[545390097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"276.179508ms","start":"2026-02-12T22:47:40.820051Z","end":"2026-02-12T22:47:41.096231Z","steps":["trace[545390097] 'agreement among raft nodes before linearized reading' (duration: 276.083356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:41.096313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.645146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:47:41.096329Z","caller":"traceutil/trace.go:171","msg":"trace[1550953051] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:750; }","duration":"233.689636ms","start":"2026-02-12T22:47:40.862635Z","end":"2026-02-12T22:47:41.096324Z","steps":["trace[1550953051] 'agreement among raft nodes before linearized reading' (duration: 233.661136ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:47:57.110681Z","caller":"traceutil/trace.go:171","msg":"trace[1966105962] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"420.554444ms","start":"2026-02-12T22:47:56.690103Z","end":"2026-02-12T22:47:57.110657Z","steps":["trace[1966105962] 'process raft request' (duration: 420.415472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:57.110863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:56.690087Z","time spent":"420.669036ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:47:57.524431Z","caller":"traceutil/trace.go:171","msg":"trace[725619239] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"157.059512ms","start":"2026-02-12T22:47:57.367345Z","end":"2026-02-12T22:47:57.524405Z","steps":["trace[725619239] 'process raft request' (duration: 156.929309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:58.18371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.705517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:47:58.183853Z","caller":"traceutil/trace.go:171","msg":"trace[826447283] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"812.983339ms","start":"2026-02-12T22:47:57.370859Z","end":"2026-02-12T22:47:58.183842Z","steps":["trace[826447283] 'process raft request' (duration: 812.940128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:58.183891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:57.370853Z","time spent":"813.019949ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:47:58.184262Z","caller":"traceutil/trace.go:171","msg":"trace[1512793769] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"816.636534ms","start":"2026-02-12T22:47:57.367617Z","end":"2026-02-12T22:47:58.184254Z","steps":["trace[1512793769] 'process raft request' (duration: 572.335215ms)","trace[1512793769] 'compare' (duration: 243.581104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:47:58.18429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:57.367606Z","time spent":"816.669205ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:47:58.184362Z","caller":"traceutil/trace.go:171","msg":"trace[1799982083] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"816.681875ms","start":"2026-02-12T22:47:57.367674Z","end":"2026-02-12T22:47:58.184356Z","steps":["trace[1799982083] 'process raft request' (duration: 816.094513ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:47:58.184411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:47:57.367665Z","time spent":"816.707776ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:47:58.480948Z","caller":"traceutil/trace.go:171","msg":"trace[298820772] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"210.40047ms","start":"2026-02-12T22:47:58.270522Z","end":"2026-02-12T22:47:58.480923Z","steps":["trace[298820772] 'process raft request' (duration: 120.966225ms)","trace[298820772] 'compare' (duration: 89.343193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:02.093111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.409189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:48:02.093748Z","caller":"traceutil/trace.go:171","msg":"trace[578607602] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"555.98579ms","start":"2026-02-12T22:48:01.537731Z","end":"2026-02-12T22:48:02.093717Z","steps":["trace[578607602] 'process raft request' (duration: 250.895137ms)","trace[578607602] 'compare' (duration: 304.123653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:02.093989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:01.537716Z","time spent":"556.218736ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:02.469901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.297993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:02.469965Z","caller":"traceutil/trace.go:171","msg":"trace[774413018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"111.406125ms","start":"2026-02-12T22:48:02.358543Z","end":"2026-02-12T22:48:02.469949Z","steps":["trace[774413018] 'range keys from in-memory index tree' (duration: 111.073179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:02.470622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.716038ms","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-12T22:48:02.470667Z","caller":"traceutil/trace.go:171","msg":"trace[645364887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"232.78761ms","start":"2026-02-12T22:48:02.237866Z","end":"2026-02-12T22:48:02.470654Z","steps":["trace[645364887] 'range keys from in-memory index tree' (duration: 232.600936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:02.470936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.924053ms","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-12T22:48:02.471007Z","caller":"traceutil/trace.go:171","msg":"trace[1682331945] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"233.004264ms","start":"2026-02-12T22:48:02.237992Z","end":"2026-02-12T22:48:02.470996Z","steps":["trace[1682331945] 'range keys from in-memory index tree' (duration: 232.841581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:02.471355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.155647ms","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-12T22:48:02.471724Z","caller":"traceutil/trace.go:171","msg":"trace[504544829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"233.531945ms","start":"2026-02-12T22:48:02.238183Z","end":"2026-02-12T22:48:02.471715Z","steps":["trace[504544829] 'range keys from in-memory index tree' (duration: 232.667458ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:02.807197Z","caller":"traceutil/trace.go:171","msg":"trace[966939789] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"331.697231ms","start":"2026-02-12T22:48:02.475478Z","end":"2026-02-12T22:48:02.807175Z","steps":["trace[966939789] 'process raft request' (duration: 331.584659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:02.807328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:02.47546Z","time spent":"331.797833ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:03.014282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.867769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:48:03.01443Z","caller":"traceutil/trace.go:171","msg":"trace[1959515155] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:839; }","duration":"465.840762ms","start":"2026-02-12T22:48:02.548574Z","end":"2026-02-12T22:48:03.014415Z","steps":["trace[1959515155] 'read index received' (duration: 259.468767ms)","trace[1959515155] 'applied index is now lower than readState.Index' (duration: 206.371125ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:03.01465Z","caller":"traceutil/trace.go:171","msg":"trace[1416137869] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"535.610255ms","start":"2026-02-12T22:48:02.479028Z","end":"2026-02-12T22:48:03.014638Z","steps":["trace[1416137869] 'process raft request' (duration: 418.319327ms)","trace[1416137869] 'compare' (duration: 116.599774ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:03.014713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:02.479008Z","time spent":"535.672236ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:48:03.01488Z","caller":"traceutil/trace.go:171","msg":"trace[43600364] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"534.270707ms","start":"2026-02-12T22:48:02.4806Z","end":"2026-02-12T22:48:03.01487Z","steps":["trace[43600364] 'process raft request' (duration: 533.762996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:03.01493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:02.480593Z","time spent":"534.308357ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:03.015107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.529446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:03.015137Z","caller":"traceutil/trace.go:171","msg":"trace[2052762693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"466.579867ms","start":"2026-02-12T22:48:02.548546Z","end":"2026-02-12T22:48:03.015125Z","steps":["trace[2052762693] 'agreement among raft nodes before linearized reading' (duration: 466.535696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:03.015165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:02.54853Z","time spent":"466.629099ms","remote":"127.0.0.1:44840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:48:03.015343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.052702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:03.015376Z","caller":"traceutil/trace.go:171","msg":"trace[483955445] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:811; }","duration":"231.085863ms","start":"2026-02-12T22:48:02.784279Z","end":"2026-02-12T22:48:03.015365Z","steps":["trace[483955445] 'agreement among raft nodes before linearized reading' (duration: 231.012591ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:03.450882Z","caller":"traceutil/trace.go:171","msg":"trace[1389665520] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"288.67951ms","start":"2026-02-12T22:48:03.162185Z","end":"2026-02-12T22:48:03.450864Z","steps":["trace[1389665520] 'process raft request' (duration: 288.585848ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:14.321227Z","caller":"traceutil/trace.go:171","msg":"trace[143508653] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"173.084718ms","start":"2026-02-12T22:48:14.148126Z","end":"2026-02-12T22:48:14.32121Z","steps":["trace[143508653] 'process raft request' (duration: 172.983376ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:15.415058Z","caller":"traceutil/trace.go:171","msg":"trace[400645054] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"358.83506ms","start":"2026-02-12T22:48:15.056207Z","end":"2026-02-12T22:48:15.415043Z","steps":["trace[400645054] 'read index received' (duration: 358.748058ms)","trace[400645054] 'applied index is now lower than readState.Index' (duration: 86.372µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:15.415131Z","caller":"traceutil/trace.go:171","msg":"trace[2087092592] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"557.810029ms","start":"2026-02-12T22:48:14.857313Z","end":"2026-02-12T22:48:15.415123Z","steps":["trace[2087092592] 'process raft request' (duration: 557.598155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:15.415198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:14.857304Z","time spent":"557.843111ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:15.415322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.120016ms","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-12T22:48:15.41534Z","caller":"traceutil/trace.go:171","msg":"trace[337729503] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:893; }","duration":"359.169107ms","start":"2026-02-12T22:48:15.056163Z","end":"2026-02-12T22:48:15.415332Z","steps":["trace[337729503] 'agreement among raft nodes before linearized reading' (duration: 359.102175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:15.415356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:15.056149Z","time spent":"359.203558ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T22:48:15.415439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.764584ms","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-12T22:48:15.415451Z","caller":"traceutil/trace.go:171","msg":"trace[120145423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:893; }","duration":"353.801754ms","start":"2026-02-12T22:48:15.061645Z","end":"2026-02-12T22:48:15.415446Z","steps":["trace[120145423] 'agreement among raft nodes before linearized reading' (duration: 353.767793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:15.415472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:15.06162Z","time spent":"353.849066ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T22:48:16.126462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.495416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:48:16.126526Z","caller":"traceutil/trace.go:171","msg":"trace[1196885726] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"707.183131ms","start":"2026-02-12T22:48:15.419331Z","end":"2026-02-12T22:48:16.126514Z","steps":["trace[1196885726] 'process raft request' (duration: 511.584242ms)","trace[1196885726] 'compare' (duration: 195.425555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:16.126557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:15.419313Z","time spent":"707.229822ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:48:16.126923Z","caller":"traceutil/trace.go:171","msg":"trace[200616470] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:928; }","duration":"608.83934ms","start":"2026-02-12T22:48:15.518078Z","end":"2026-02-12T22:48:16.126917Z","steps":["trace[200616470] 'read index received' (duration: 412.912944ms)","trace[200616470] 'applied index is now lower than readState.Index' (duration: 195.925926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:16.128254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.181627ms","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-12T22:48:16.128278Z","caller":"traceutil/trace.go:171","msg":"trace[1148216664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"610.240629ms","start":"2026-02-12T22:48:15.518032Z","end":"2026-02-12T22:48:16.128272Z","steps":["trace[1148216664] 'agreement among raft nodes before linearized reading' (duration: 610.145447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:16.128295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:15.517989Z","time spent":"610.30292ms","remote":"127.0.0.1:45070","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":"info","ts":"2026-02-12T22:48:16.128409Z","caller":"traceutil/trace.go:171","msg":"trace[418218689] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"708.499548ms","start":"2026-02-12T22:48:15.419904Z","end":"2026-02-12T22:48:16.128403Z","steps":["trace[418218689] 'process raft request' (duration: 706.969776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:16.128445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:15.419802Z","time spent":"708.622441ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:16.12871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.927811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:16.12873Z","caller":"traceutil/trace.go:171","msg":"trace[2141829171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"577.987882ms","start":"2026-02-12T22:48:15.550738Z","end":"2026-02-12T22:48:16.128726Z","steps":["trace[2141829171] 'agreement among raft nodes before linearized reading' (duration: 577.958922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:16.128748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:15.550719Z","time spent":"578.025344ms","remote":"127.0.0.1:44824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:48:16.826988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.241884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:48:16.827052Z","caller":"traceutil/trace.go:171","msg":"trace[51380263] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"502.503461ms","start":"2026-02-12T22:48:16.324533Z","end":"2026-02-12T22:48:16.827037Z","steps":["trace[51380263] 'read index received' (duration: 21.62µs)","trace[51380263] 'applied index is now lower than readState.Index' (duration: 502.481271ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:16.827189Z","caller":"traceutil/trace.go:171","msg":"trace[216566751] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"692.983933ms","start":"2026-02-12T22:48:16.134198Z","end":"2026-02-12T22:48:16.827182Z","steps":["trace[216566751] 'process raft request' (duration: 160.490694ms)","trace[216566751] 'compare' (duration: 532.156132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:16.827222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:16.134185Z","time spent":"693.020894ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:16.827354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.825019ms","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-12T22:48:16.827367Z","caller":"traceutil/trace.go:171","msg":"trace[1274771294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"502.860649ms","start":"2026-02-12T22:48:16.324502Z","end":"2026-02-12T22:48:16.827363Z","steps":["trace[1274771294] 'agreement among raft nodes before linearized reading' (duration: 502.801338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:16.827383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:16.324489Z","time spent":"502.889679ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T22:48:16.827469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.095853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T22:48:16.827483Z","caller":"traceutil/trace.go:171","msg":"trace[1780065257] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:896; }","duration":"294.140254ms","start":"2026-02-12T22:48:16.533338Z","end":"2026-02-12T22:48:16.827478Z","steps":["trace[1780065257] 'agreement among raft nodes before linearized reading' (duration: 294.114364ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:17.525945Z","caller":"traceutil/trace.go:171","msg":"trace[55499682] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"103.810376ms","start":"2026-02-12T22:48:17.422113Z","end":"2026-02-12T22:48:17.525924Z","steps":["trace[55499682] 'process raft request' (duration: 103.665623ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:26.41828Z","caller":"traceutil/trace.go:171","msg":"trace[519257044] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"124.041374ms","start":"2026-02-12T22:48:26.294224Z","end":"2026-02-12T22:48:26.418265Z","steps":["trace[519257044] 'process raft request' (duration: 123.824769ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:27.152009Z","caller":"traceutil/trace.go:171","msg":"trace[898034402] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"291.727802ms","start":"2026-02-12T22:48:26.860264Z","end":"2026-02-12T22:48:27.151992Z","steps":["trace[898034402] 'process raft request' (duration: 291.545138ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:27.152193Z","caller":"traceutil/trace.go:171","msg":"trace[2039110273] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:961; }","duration":"249.961287ms","start":"2026-02-12T22:48:26.902225Z","end":"2026-02-12T22:48:27.152186Z","steps":["trace[2039110273] 'read index received' (duration: 249.958027ms)","trace[2039110273] 'applied index is now lower than readState.Index' (duration: 2.65µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:27.152331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.092061ms","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-12T22:48:27.152359Z","caller":"traceutil/trace.go:171","msg":"trace[1415595453] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"250.148003ms","start":"2026-02-12T22:48:26.902203Z","end":"2026-02-12T22:48:27.152351Z","steps":["trace[1415595453] 'agreement among raft nodes before linearized reading' (duration: 250.013819ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:29.70338Z","caller":"traceutil/trace.go:171","msg":"trace[522291339] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"135.455893ms","start":"2026-02-12T22:48:29.56791Z","end":"2026-02-12T22:48:29.703366Z","steps":["trace[522291339] 'process raft request' (duration: 135.194008ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:30.509693Z","caller":"traceutil/trace.go:171","msg":"trace[719807821] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"155.673876ms","start":"2026-02-12T22:48:30.354006Z","end":"2026-02-12T22:48:30.50968Z","steps":["trace[719807821] 'process raft request' (duration: 155.35727ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:31.08937Z","caller":"traceutil/trace.go:171","msg":"trace[603186710] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"273.694885ms","start":"2026-02-12T22:48:30.815663Z","end":"2026-02-12T22:48:31.089358Z","steps":["trace[603186710] 'read index received' (duration: 273.595003ms)","trace[603186710] 'applied index is now lower than readState.Index' (duration: 99.362µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:31.089461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.782207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T22:48:31.089477Z","caller":"traceutil/trace.go:171","msg":"trace[1132557321] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:935; }","duration":"273.835267ms","start":"2026-02-12T22:48:30.815637Z","end":"2026-02-12T22:48:31.089472Z","steps":["trace[1132557321] 'agreement among raft nodes before linearized reading' (duration: 273.772066ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:31.089632Z","caller":"traceutil/trace.go:171","msg":"trace[564536500] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"577.407266ms","start":"2026-02-12T22:48:30.51222Z","end":"2026-02-12T22:48:31.089627Z","steps":["trace[564536500] 'process raft request' (duration: 577.068409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:31.089675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:30.512208Z","time spent":"577.436677ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:31.411345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.497414ms","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-12T22:48:31.411386Z","caller":"traceutil/trace.go:171","msg":"trace[1265137328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:935; }","duration":"201.564016ms","start":"2026-02-12T22:48:31.209813Z","end":"2026-02-12T22:48:31.411377Z","steps":["trace[1265137328] 'range keys from in-memory index tree' (duration: 201.444093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:31.850476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.318517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:48:31.850537Z","caller":"traceutil/trace.go:171","msg":"trace[966476691] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"299.941914ms","start":"2026-02-12T22:48:31.550585Z","end":"2026-02-12T22:48:31.850527Z","steps":["trace[966476691] 'read index received' (duration: 28.651µs)","trace[966476691] 'applied index is now lower than readState.Index' (duration: 299.912673ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:31.850657Z","caller":"traceutil/trace.go:171","msg":"trace[657528618] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"435.411167ms","start":"2026-02-12T22:48:31.41524Z","end":"2026-02-12T22:48:31.850651Z","steps":["trace[657528618] 'process raft request' (duration: 106.864805ms)","trace[657528618] 'compare' (duration: 328.254576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:31.850686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:31.415234Z","time spent":"435.436948ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:31.850782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.197619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:31.850795Z","caller":"traceutil/trace.go:171","msg":"trace[1894907203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"300.242019ms","start":"2026-02-12T22:48:31.550548Z","end":"2026-02-12T22:48:31.85079Z","steps":["trace[1894907203] 'agreement among raft nodes before linearized reading' (duration: 300.218989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:31.850808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:31.550531Z","time spent":"300.27443ms","remote":"127.0.0.1:44824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:48:31.850918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.249478ms","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-12T22:48:31.850932Z","caller":"traceutil/trace.go:171","msg":"trace[1278163191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:937; }","duration":"144.285138ms","start":"2026-02-12T22:48:31.706644Z","end":"2026-02-12T22:48:31.850929Z","steps":["trace[1278163191] 'agreement among raft nodes before linearized reading' (duration: 144.223547ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:32.184196Z","caller":"traceutil/trace.go:171","msg":"trace[1511591955] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"158.910445ms","start":"2026-02-12T22:48:32.025264Z","end":"2026-02-12T22:48:32.184174Z","steps":["trace[1511591955] 'process raft request' (duration: 158.857364ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:32.184488Z","caller":"traceutil/trace.go:171","msg":"trace[1266614135] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"330.166916ms","start":"2026-02-12T22:48:31.854311Z","end":"2026-02-12T22:48:32.184478Z","steps":["trace[1266614135] 'process raft request' (duration: 324.047168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:32.184572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:31.854297Z","time spent":"330.214747ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:48:32.184692Z","caller":"traceutil/trace.go:171","msg":"trace[833740040] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"237.438306ms","start":"2026-02-12T22:48:31.947244Z","end":"2026-02-12T22:48:32.184683Z","steps":["trace[833740040] 'read index received' (duration: 231.124764ms)","trace[833740040] 'applied index is now lower than readState.Index' (duration: 6.312622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:32.184781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.531408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T22:48:32.184807Z","caller":"traceutil/trace.go:171","msg":"trace[812636238] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:939; }","duration":"237.596259ms","start":"2026-02-12T22:48:31.947203Z","end":"2026-02-12T22:48:32.184799Z","steps":["trace[812636238] 'agreement among raft nodes before linearized reading' (duration: 237.510008ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:32.707575Z","caller":"traceutil/trace.go:171","msg":"trace[422631558] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"185.176214ms","start":"2026-02-12T22:48:32.522381Z","end":"2026-02-12T22:48:32.707558Z","steps":["trace[422631558] 'process raft request' (duration: 185.004529ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:33.602016Z","caller":"traceutil/trace.go:171","msg":"trace[729156768] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"506.351521ms","start":"2026-02-12T22:48:33.095641Z","end":"2026-02-12T22:48:33.601992Z","steps":["trace[729156768] 'process raft request' (duration: 506.217298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:33.60216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:33.09563Z","time spent":"506.460894ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:33.965712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.309527ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T22:48:33.965806Z","caller":"traceutil/trace.go:171","msg":"trace[200323706] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"478.780833ms","start":"2026-02-12T22:48:33.487005Z","end":"2026-02-12T22:48:33.965786Z","steps":["trace[200323706] 'read index received' (duration: 115.887153ms)","trace[200323706] 'applied index is now lower than readState.Index' (duration: 362.89229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:33.966082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.601663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:33.96612Z","caller":"traceutil/trace.go:171","msg":"trace[902798274] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:941; }","duration":"415.674505ms","start":"2026-02-12T22:48:33.550431Z","end":"2026-02-12T22:48:33.966106Z","steps":["trace[902798274] 'agreement among raft nodes before linearized reading' (duration: 415.609953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:33.966155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:33.550412Z","time spent":"415.732576ms","remote":"127.0.0.1:44824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:48:33.96643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.278976ms","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-12T22:48:33.96646Z","caller":"traceutil/trace.go:171","msg":"trace[606897971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"110.313097ms","start":"2026-02-12T22:48:33.856138Z","end":"2026-02-12T22:48:33.966451Z","steps":["trace[606897971] 'agreement among raft nodes before linearized reading' (duration: 110.187844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:33.966629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.184519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-12T22:48:33.966613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.58052ms","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-12T22:48:33.966658Z","caller":"traceutil/trace.go:171","msg":"trace[616674996] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:941; }","duration":"279.249001ms","start":"2026-02-12T22:48:33.6874Z","end":"2026-02-12T22:48:33.966649Z","steps":["trace[616674996] 'agreement among raft nodes before linearized reading' (duration: 279.19455ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:33.966684Z","caller":"traceutil/trace.go:171","msg":"trace[2096649438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"479.697742ms","start":"2026-02-12T22:48:33.486968Z","end":"2026-02-12T22:48:33.966665Z","steps":["trace[2096649438] 'agreement among raft nodes before linearized reading' (duration: 478.967987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:33.966728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:33.486953Z","time spent":"479.766893ms","remote":"127.0.0.1:45070","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-12T22:48:34.172163Z","caller":"traceutil/trace.go:171","msg":"trace[1684949869] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"192.187279ms","start":"2026-02-12T22:48:33.979949Z","end":"2026-02-12T22:48:34.172136Z","steps":["trace[1684949869] 'process raft request' (duration: 192.036066ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:35.093636Z","caller":"traceutil/trace.go:171","msg":"trace[1640216789] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"377.189128ms","start":"2026-02-12T22:48:34.716427Z","end":"2026-02-12T22:48:35.093616Z","steps":["trace[1640216789] 'process raft request' (duration: 377.070455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:35.093775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:34.715455Z","time spent":"378.24107ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:48:36.0559Z","caller":"traceutil/trace.go:171","msg":"trace[747521866] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"427.963622ms","start":"2026-02-12T22:48:35.627922Z","end":"2026-02-12T22:48:36.055886Z","steps":["trace[747521866] 'read index received' (duration: 427.729387ms)","trace[747521866] 'applied index is now lower than readState.Index' (duration: 233.375µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:36.05673Z","caller":"traceutil/trace.go:171","msg":"trace[689645159] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"439.822169ms","start":"2026-02-12T22:48:35.616269Z","end":"2026-02-12T22:48:36.056091Z","steps":["trace[689645159] 'process raft request' (duration: 439.448621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:36.056868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:35.616256Z","time spent":"440.500602ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:36.057132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.203197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:36.057165Z","caller":"traceutil/trace.go:171","msg":"trace[1599479013] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:946; }","duration":"429.265428ms","start":"2026-02-12T22:48:35.627889Z","end":"2026-02-12T22:48:36.057154Z","steps":["trace[1599479013] 'agreement among raft nodes before linearized reading' (duration: 429.190316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:36.057254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:35.627876Z","time spent":"429.36442ms","remote":"127.0.0.1:45322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-12T22:48:36.313724Z","caller":"traceutil/trace.go:171","msg":"trace[1553339174] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"136.446043ms","start":"2026-02-12T22:48:36.177257Z","end":"2026-02-12T22:48:36.313703Z","steps":["trace[1553339174] 'read index received' (duration: 135.900782ms)","trace[1553339174] 'applied index is now lower than readState.Index' (duration: 544.401µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:36.314249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.975075ms","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-12T22:48:36.315646Z","caller":"traceutil/trace.go:171","msg":"trace[1970367490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:947; }","duration":"138.411255ms","start":"2026-02-12T22:48:36.177223Z","end":"2026-02-12T22:48:36.315635Z","steps":["trace[1970367490] 'agreement among raft nodes before linearized reading' (duration: 136.891513ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:36.314076Z","caller":"traceutil/trace.go:171","msg":"trace[860707194] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"251.265636ms","start":"2026-02-12T22:48:36.062797Z","end":"2026-02-12T22:48:36.314062Z","steps":["trace[860707194] 'process raft request' (duration: 250.417138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:36.315575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.170473ms","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-12T22:48:36.316086Z","caller":"traceutil/trace.go:171","msg":"trace[1091012952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:947; }","duration":"118.696383ms","start":"2026-02-12T22:48:36.197377Z","end":"2026-02-12T22:48:36.316074Z","steps":["trace[1091012952] 'agreement among raft nodes before linearized reading' (duration: 118.09129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:36.571403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.568697ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:48:36.571657Z","caller":"traceutil/trace.go:171","msg":"trace[1047542118] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"249.903377ms","start":"2026-02-12T22:48:36.32174Z","end":"2026-02-12T22:48:36.571644Z","steps":["trace[1047542118] 'process raft request' (duration: 113.053944ms)","trace[1047542118] 'compare' (duration: 136.442665ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:36.57172Z","caller":"traceutil/trace.go:171","msg":"trace[1933556167] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"249.858607ms","start":"2026-02-12T22:48:36.321858Z","end":"2026-02-12T22:48:36.571716Z","steps":["trace[1933556167] 'process raft request' (duration: 249.609422ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:40.480407Z","caller":"traceutil/trace.go:171","msg":"trace[1540769380] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"147.880333ms","start":"2026-02-12T22:48:40.33223Z","end":"2026-02-12T22:48:40.480111Z","steps":["trace[1540769380] 'process raft request' (duration: 147.7298ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:42.849812Z","caller":"traceutil/trace.go:171","msg":"trace[619783305] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"114.631648ms","start":"2026-02-12T22:48:42.735164Z","end":"2026-02-12T22:48:42.849795Z","steps":["trace[619783305] 'process raft request' (duration: 114.545996ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:42.852519Z","caller":"traceutil/trace.go:171","msg":"trace[2095352768] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"116.718652ms","start":"2026-02-12T22:48:42.735792Z","end":"2026-02-12T22:48:42.852511Z","steps":["trace[2095352768] 'process raft request' (duration: 116.487307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:43.802428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.328108ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T22:48:43.802502Z","caller":"traceutil/trace.go:171","msg":"trace[1422117517] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1036; }","duration":"251.537721ms","start":"2026-02-12T22:48:43.550951Z","end":"2026-02-12T22:48:43.802489Z","steps":["trace[1422117517] 'read index received' (duration: 76.151822ms)","trace[1422117517] 'applied index is now lower than readState.Index' (duration: 175.384779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:43.802552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.593992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:43.80257Z","caller":"traceutil/trace.go:171","msg":"trace[1229567124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"251.651763ms","start":"2026-02-12T22:48:43.55091Z","end":"2026-02-12T22:48:43.802561Z","steps":["trace[1229567124] 'agreement among raft nodes before linearized reading' (duration: 251.611082ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:49.411782Z","caller":"traceutil/trace.go:171","msg":"trace[877897786] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"530.331181ms","start":"2026-02-12T22:48:48.881428Z","end":"2026-02-12T22:48:49.411759Z","steps":["trace[877897786] 'process raft request' (duration: 530.178108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:49.411954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:48.881412Z","time spent":"530.465884ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:50.052983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.272412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:48:50.053048Z","caller":"traceutil/trace.go:171","msg":"trace[1709558326] linearizableReadLoop","detail":"{readStateIndex:1068; appliedIndex:1067; }","duration":"1.108131965s","start":"2026-02-12T22:48:48.944904Z","end":"2026-02-12T22:48:50.053036Z","steps":["trace[1709558326] 'read index received' (duration: 467.742262ms)","trace[1709558326] 'applied index is now lower than readState.Index' (duration: 640.388903ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:50.053189Z","caller":"traceutil/trace.go:171","msg":"trace[13972721] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"1.169643852s","start":"2026-02-12T22:48:48.883534Z","end":"2026-02-12T22:48:50.053178Z","steps":["trace[13972721] 'process raft request' (duration: 887.120194ms)","trace[13972721] 'compare' (duration: 282.063558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:50.053228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:48.88352Z","time spent":"1.169686972s","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:48:50.053443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108532333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-12T22:48:50.053469Z","caller":"traceutil/trace.go:171","msg":"trace[687960933] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1026; }","duration":"1.108603946s","start":"2026-02-12T22:48:48.944856Z","end":"2026-02-12T22:48:50.05346Z","steps":["trace[687960933] 'agreement among raft nodes before linearized reading' (duration: 1.108503573s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:50.053489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:48.944836Z","time spent":"1.108648226s","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-12T22:48:50.053609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.923304ms","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-12T22:48:50.053627Z","caller":"traceutil/trace.go:171","msg":"trace[748955871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"912.974154ms","start":"2026-02-12T22:48:49.140646Z","end":"2026-02-12T22:48:50.053621Z","steps":["trace[748955871] 'agreement among raft nodes before linearized reading' (duration: 912.923793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:50.053649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:49.140628Z","time spent":"913.016385ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T22:48:50.05378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.999281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:50.053796Z","caller":"traceutil/trace.go:171","msg":"trace[69390012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"504.049961ms","start":"2026-02-12T22:48:49.549741Z","end":"2026-02-12T22:48:50.053791Z","steps":["trace[69390012] 'agreement among raft nodes before linearized reading' (duration: 504.023991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:50.053811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:49.549722Z","time spent":"504.085703ms","remote":"127.0.0.1:44824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T22:48:50.480252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.890362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:48:50.480589Z","caller":"traceutil/trace.go:171","msg":"trace[1708627327] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"421.061026ms","start":"2026-02-12T22:48:50.059514Z","end":"2026-02-12T22:48:50.480575Z","steps":["trace[1708627327] 'process raft request' (duration: 317.789646ms)","trace[1708627327] 'compare' (duration: 102.442873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:50.48063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:50.059502Z","time spent":"421.107217ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T22:48:50.480718Z","caller":"traceutil/trace.go:171","msg":"trace[1908198884] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"365.384432ms","start":"2026-02-12T22:48:50.115327Z","end":"2026-02-12T22:48:50.480711Z","steps":["trace[1908198884] 'read index received' (duration: 262.00574ms)","trace[1908198884] 'applied index is now lower than readState.Index' (duration: 103.378102ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:50.48137Z","caller":"traceutil/trace.go:171","msg":"trace[1449630385] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"298.641275ms","start":"2026-02-12T22:48:50.182717Z","end":"2026-02-12T22:48:50.481358Z","steps":["trace[1449630385] 'process raft request' (duration: 297.608954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:50.48171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.378792ms","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-12T22:48:50.481734Z","caller":"traceutil/trace.go:171","msg":"trace[383425789] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1029; }","duration":"366.427113ms","start":"2026-02-12T22:48:50.115298Z","end":"2026-02-12T22:48:50.481725Z","steps":["trace[383425789] 'agreement among raft nodes before linearized reading' (duration: 366.348291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:50.481752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:50.115285Z","time spent":"366.463224ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T22:48:50.481922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.597673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T22:48:50.481944Z","caller":"traceutil/trace.go:171","msg":"trace[887402554] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1029; }","duration":"241.622873ms","start":"2026-02-12T22:48:50.240316Z","end":"2026-02-12T22:48:50.481938Z","steps":["trace[887402554] 'agreement among raft nodes before linearized reading' (duration: 241.584482ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:51.426304Z","caller":"traceutil/trace.go:171","msg":"trace[813733177] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"126.170578ms","start":"2026-02-12T22:48:51.300115Z","end":"2026-02-12T22:48:51.426286Z","steps":["trace[813733177] 'process raft request' (duration: 125.774869ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:48:51.701748Z","caller":"traceutil/trace.go:171","msg":"trace[822655783] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"169.488846ms","start":"2026-02-12T22:48:51.532242Z","end":"2026-02-12T22:48:51.701731Z","steps":["trace[822655783] 'read index received' (duration: 90.532075ms)","trace[822655783] 'applied index is now lower than readState.Index' (duration: 78.956041ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:48:51.702046Z","caller":"traceutil/trace.go:171","msg":"trace[1100108456] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"271.261093ms","start":"2026-02-12T22:48:51.430773Z","end":"2026-02-12T22:48:51.702034Z","steps":["trace[1100108456] 'process raft request' (duration: 192.053827ms)","trace[1100108456] 'compare' (duration: 78.774327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:48:51.702403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.270643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:48:51.703946Z","caller":"traceutil/trace.go:171","msg":"trace[1448694152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1033; }","duration":"152.911019ms","start":"2026-02-12T22:48:51.551024Z","end":"2026-02-12T22:48:51.703935Z","steps":["trace[1448694152] 'agreement among raft nodes before linearized reading' (duration: 151.359766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:51.702147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.896664ms","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-12T22:48:51.704338Z","caller":"traceutil/trace.go:171","msg":"trace[234931171] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1033; }","duration":"172.12224ms","start":"2026-02-12T22:48:51.532205Z","end":"2026-02-12T22:48:51.704328Z","steps":["trace[234931171] 'agreement among raft nodes before linearized reading' (duration: 169.866503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:53.172953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.878604ms","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-12T22:48:53.173047Z","caller":"traceutil/trace.go:171","msg":"trace[770773913] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1050; }","duration":"499.008177ms","start":"2026-02-12T22:48:52.674021Z","end":"2026-02-12T22:48:53.173029Z","steps":["trace[770773913] 'range keys from in-memory index tree' (duration: 498.824862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:48:53.17309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:48:52.674007Z","time spent":"499.070258ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-12T22:48:53.362952Z","caller":"traceutil/trace.go:171","msg":"trace[177726465] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"185.158323ms","start":"2026-02-12T22:48:53.177777Z","end":"2026-02-12T22:48:53.362935Z","steps":["trace[177726465] 'process raft request' (duration: 185.065291ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:51:58.010826Z","caller":"traceutil/trace.go:171","msg":"trace[601853138] transaction","detail":"{read_only:false; response_revision:1837; number_of_response:1; }","duration":"107.80241ms","start":"2026-02-12T22:51:57.902999Z","end":"2026-02-12T22:51:58.010801Z","steps":["trace[601853138] 'process raft request' (duration: 107.643947ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:52:04.129256Z","caller":"traceutil/trace.go:171","msg":"trace[137954324] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"104.023913ms","start":"2026-02-12T22:52:04.025211Z","end":"2026-02-12T22:52:04.129235Z","steps":["trace[137954324] 'process raft request' (duration: 103.89583ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T22:52:04.872338Z","caller":"traceutil/trace.go:171","msg":"trace[1138297688] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"300.685323ms","start":"2026-02-12T22:52:04.571635Z","end":"2026-02-12T22:52:04.87232Z","steps":["trace[1138297688] 'process raft request' (duration: 300.578381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:52:04.872444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:52:04.571625Z","time spent":"300.761335ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:52:05.124373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.725916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T22:52:05.12446Z","caller":"traceutil/trace.go:171","msg":"trace[1129616706] linearizableReadLoop","detail":"{readStateIndex:1951; appliedIndex:1950; }","duration":"235.653317ms","start":"2026-02-12T22:52:04.88879Z","end":"2026-02-12T22:52:05.124443Z","steps":["trace[1129616706] 'read index received' (duration: 119.728797ms)","trace[1129616706] 'applied index is now lower than readState.Index' (duration: 115.92347ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T22:52:05.12465Z","caller":"traceutil/trace.go:171","msg":"trace[1961582466] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"507.486663ms","start":"2026-02-12T22:52:04.617151Z","end":"2026-02-12T22:52:05.124637Z","steps":["trace[1961582466] 'process raft request' (duration: 391.42785ms)","trace[1961582466] 'compare' (duration: 115.606734ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T22:52:05.124708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T22:52:04.617131Z","time spent":"507.546454ms","remote":"127.0.0.1:45070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T22:52:05.124886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.092556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T22:52:05.124913Z","caller":"traceutil/trace.go:171","msg":"trace[1807705287] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1869; }","duration":"236.142626ms","start":"2026-02-12T22:52:04.888762Z","end":"2026-02-12T22:52:05.124905Z","steps":["trace[1807705287] 'agreement among raft nodes before linearized reading' (duration: 236.097275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T22:52:05.125176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.614723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T22:52:05.125207Z","caller":"traceutil/trace.go:171","msg":"trace[1014608433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1869; }","duration":"202.676134ms","start":"2026-02-12T22:52:04.922522Z","end":"2026-02-12T22:52:05.125198Z","steps":["trace[1014608433] 'agreement among raft nodes before linearized reading' (duration: 202.561282ms)"],"step_count":1}