{"level":"warn","ts":"2026-02-12T03:39:28.605549Z","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-12T03:39:28.605867Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-12T03:39:28.606059Z","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-12T03:39:28.606636Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-12T03:39:28.60675Z","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-12T03:39:28.611446Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-12T03:39:28.611667Z","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.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-12T03:39:28.615772Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.758876ms"} {"level":"info","ts":"2026-02-12T03:39:28.621536Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-12T03:39:28.621713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T03:39:28.621783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-12T03:39:28.621839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T03:39:28.621876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-12T03:39:28.621943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-12T03:39:28.632511Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T03:39:28.651831Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T03:39:28.655998Z","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-12T03:39:28.659444Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T03:39:28.664358Z","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-12T03:39:28.667645Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T03:39:28.667417Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-12T03:39:28.668784Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-12T03:39:28.667834Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T03:39:28.667854Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T03:39:28.667994Z","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-12T03:39:28.669141Z","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-12T03:39:28.669208Z","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-12T03:39:28.668508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-12T03:39:28.669461Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-12T03:39:28.822325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T03:39:28.822424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T03:39:28.822507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-12T03:39:28.82261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-12T03:39:28.822668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-12T03:39:28.822731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-12T03:39:28.822773Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-12T03:39:28.854146Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T03:39:28.854395Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:39:28.854525Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T03:39:28.854731Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T03:39:28.854765Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T03:39:28.857346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T03:39:28.858605Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:39:28.862055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-12T03:39:28.862952Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:39:28.873996Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:39:28.874822Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T03:39:35.864294Z","caller":"traceutil/trace.go:171","msg":"trace[749494363] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"125.63724ms","start":"2026-02-12T03:39:35.738642Z","end":"2026-02-12T03:39:35.864279Z","steps":["trace[749494363] 'process raft request' (duration: 123.431719ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:39:38.581751Z","caller":"traceutil/trace.go:171","msg":"trace[575927924] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"111.325179ms","start":"2026-02-12T03:39:38.470396Z","end":"2026-02-12T03:39:38.581721Z","steps":["trace[575927924] 'process raft request' (duration: 32.06913ms)","trace[575927924] 'compare' (duration: 79.105916ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:39:46.481393Z","caller":"traceutil/trace.go:171","msg":"trace[966879182] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"127.032211ms","start":"2026-02-12T03:39:46.354346Z","end":"2026-02-12T03:39:46.481378Z","steps":["trace[966879182] 'read index received' (duration: 64.793105ms)","trace[966879182] 'applied index is now lower than readState.Index' (duration: 62.238286ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:39:46.481523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.123183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-12T03:39:46.481518Z","caller":"traceutil/trace.go:171","msg":"trace[1956592888] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"133.465251ms","start":"2026-02-12T03:39:46.348032Z","end":"2026-02-12T03:39:46.481497Z","steps":["trace[1956592888] 'process raft request' (duration: 102.864155ms)","trace[1956592888] 'compare' (duration: 30.375751ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:39:46.481645Z","caller":"traceutil/trace.go:171","msg":"trace[1433901291] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"133.486021ms","start":"2026-02-12T03:39:46.348153Z","end":"2026-02-12T03:39:46.481639Z","steps":["trace[1433901291] 'process raft request' (duration: 133.190994ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:39:46.481546Z","caller":"traceutil/trace.go:171","msg":"trace[1722268821] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:422; }","duration":"127.219956ms","start":"2026-02-12T03:39:46.354317Z","end":"2026-02-12T03:39:46.481537Z","steps":["trace[1722268821] 'agreement among raft nodes before linearized reading' (duration: 127.120094ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:39:46.755605Z","caller":"traceutil/trace.go:171","msg":"trace[1988467491] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"125.632139ms","start":"2026-02-12T03:39:46.629951Z","end":"2026-02-12T03:39:46.755583Z","steps":["trace[1988467491] 'process raft request' (duration: 109.718231ms)","trace[1988467491] 'compare' (duration: 15.834496ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:39:51.980604Z","caller":"traceutil/trace.go:171","msg":"trace[1588918713] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"240.144983ms","start":"2026-02-12T03:39:51.740443Z","end":"2026-02-12T03:39:51.980588Z","steps":["trace[1588918713] 'process raft request' (duration: 240.066671ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:39:51.980721Z","caller":"traceutil/trace.go:171","msg":"trace[1189458339] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:474; }","duration":"201.552661ms","start":"2026-02-12T03:39:51.779142Z","end":"2026-02-12T03:39:51.980694Z","steps":["trace[1189458339] 'read index received' (duration: 201.543861ms)","trace[1189458339] 'applied index is now lower than readState.Index' (duration: 7.44µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:39:51.980896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.718955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:39:51.980964Z","caller":"traceutil/trace.go:171","msg":"trace[1955678563] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:458; }","duration":"201.838447ms","start":"2026-02-12T03:39:51.779116Z","end":"2026-02-12T03:39:51.980954Z","steps":["trace[1955678563] 'agreement among raft nodes before linearized reading' (duration: 201.706744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:52.481632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088397638136300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:39:52.982098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088397638136300,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:39:53.347248Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.366732165s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T03:39:53.347487Z","caller":"traceutil/trace.go:171","msg":"trace[840279797] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:474; }","duration":"1.366663054s","start":"2026-02-12T03:39:51.980794Z","end":"2026-02-12T03:39:53.347457Z","steps":["trace[840279797] 'read index received' (duration: 1.366541761s)","trace[840279797] 'applied index is now lower than readState.Index' (duration: 120.803µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:39:53.347673Z","caller":"traceutil/trace.go:171","msg":"trace[628333955] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"1.542701867s","start":"2026-02-12T03:39:51.804966Z","end":"2026-02-12T03:39:53.347668Z","steps":["trace[628333955] 'process raft request' (duration: 1.54240633s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:53.349522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.367596636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:39:53.349626Z","caller":"traceutil/trace.go:171","msg":"trace[1522648109] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:459; }","duration":"1.367713438s","start":"2026-02-12T03:39:51.981875Z","end":"2026-02-12T03:39:53.349588Z","steps":["trace[1522648109] 'agreement among raft nodes before linearized reading' (duration: 1.365856095s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:53.349633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:51.80495Z","time spent":"1.542736997s","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:39:53.349674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:51.981865Z","time spent":"1.36779864s","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:39:53.350215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.256663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T03:39:53.350248Z","caller":"traceutil/trace.go:171","msg":"trace[270831785] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:459; }","duration":"505.311445ms","start":"2026-02-12T03:39:52.844917Z","end":"2026-02-12T03:39:53.350229Z","steps":["trace[270831785] 'agreement among raft nodes before linearized reading' (duration: 505.272164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:53.350267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:52.844899Z","time spent":"505.362566ms","remote":"127.0.0.1:51328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-12T03:39:53.350752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570644661s","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-12T03:39:53.350775Z","caller":"traceutil/trace.go:171","msg":"trace[1891523001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"1.570675661s","start":"2026-02-12T03:39:51.780093Z","end":"2026-02-12T03:39:53.350769Z","steps":["trace[1891523001] 'agreement among raft nodes before linearized reading' (duration: 1.570590259s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:53.350868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:51.780088Z","time spent":"1.570776553s","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:39:53.351258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.818044ms","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-12T03:39:53.351278Z","caller":"traceutil/trace.go:171","msg":"trace[1704430572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:459; }","duration":"440.837145ms","start":"2026-02-12T03:39:52.910436Z","end":"2026-02-12T03:39:53.351273Z","steps":["trace[1704430572] 'agreement among raft nodes before linearized reading' (duration: 440.719502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:53.351359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:52.910389Z","time spent":"440.898676ms","remote":"127.0.0.1:51556","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-12T03:39:53.35205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06021355s","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-12T03:39:53.352142Z","caller":"traceutil/trace.go:171","msg":"trace[2034155633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:459; }","duration":"1.060323933s","start":"2026-02-12T03:39:52.291807Z","end":"2026-02-12T03:39:53.352131Z","steps":["trace[2034155633] 'agreement among raft nodes before linearized reading' (duration: 1.0602145s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:53.35222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:52.291795Z","time spent":"1.060418456s","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T03:39:55.850085Z","caller":"traceutil/trace.go:171","msg":"trace[542976355] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"474.761747ms","start":"2026-02-12T03:39:55.3753Z","end":"2026-02-12T03:39:55.850062Z","steps":["trace[542976355] 'read index received' (duration: 474.561992ms)","trace[542976355] 'applied index is now lower than readState.Index' (duration: 198.935µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:39:55.850433Z","caller":"traceutil/trace.go:171","msg":"trace[456233586] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"567.529728ms","start":"2026-02-12T03:39:55.282892Z","end":"2026-02-12T03:39:55.850422Z","steps":["trace[456233586] 'process raft request' (duration: 567.040647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:55.850532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:55.282868Z","time spent":"567.601609ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:39:55.850738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.433582ms","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-12T03:39:55.850779Z","caller":"traceutil/trace.go:171","msg":"trace[2080109147] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:465; }","duration":"475.503864ms","start":"2026-02-12T03:39:55.375264Z","end":"2026-02-12T03:39:55.850768Z","steps":["trace[2080109147] 'agreement among raft nodes before linearized reading' (duration: 475.440903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:55.850812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:55.37525Z","time spent":"475.555765ms","remote":"127.0.0.1:51556","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-12T03:39:55.851007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.649038ms","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-12T03:39:55.851042Z","caller":"traceutil/trace.go:171","msg":"trace[958877291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"475.698579ms","start":"2026-02-12T03:39:55.375335Z","end":"2026-02-12T03:39:55.851034Z","steps":["trace[958877291] 'agreement among raft nodes before linearized reading' (duration: 475.646668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:55.851073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:55.37533Z","time spent":"475.73712ms","remote":"127.0.0.1:51556","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-12T03:39:55.851263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.53879ms","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-12T03:39:55.851301Z","caller":"traceutil/trace.go:171","msg":"trace[533643566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"470.603102ms","start":"2026-02-12T03:39:55.380687Z","end":"2026-02-12T03:39:55.85129Z","steps":["trace[533643566] 'agreement among raft nodes before linearized reading' (duration: 470.548091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:55.851334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:55.38067Z","time spent":"470.657073ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:39:56.630101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.018592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:39:56.630212Z","caller":"traceutil/trace.go:171","msg":"trace[187589052] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:482; }","duration":"638.506587ms","start":"2026-02-12T03:39:55.991696Z","end":"2026-02-12T03:39:56.630202Z","steps":["trace[187589052] 'read index received' (duration: 167.26187ms)","trace[187589052] 'applied index is now lower than readState.Index' (duration: 471.244197ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:39:56.630276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.573569ms","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-12T03:39:56.630289Z","caller":"traceutil/trace.go:171","msg":"trace[1614339463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"638.61798ms","start":"2026-02-12T03:39:55.991667Z","end":"2026-02-12T03:39:56.630285Z","steps":["trace[1614339463] 'agreement among raft nodes before linearized reading' (duration: 638.560509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:56.630307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:55.991654Z","time spent":"638.64705ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T03:39:56.630434Z","caller":"traceutil/trace.go:171","msg":"trace[1932780761] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"773.39742ms","start":"2026-02-12T03:39:55.857031Z","end":"2026-02-12T03:39:56.630428Z","steps":["trace[1932780761] 'process raft request' (duration: 301.917838ms)","trace[1932780761] 'compare' (duration: 470.828108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:39:56.630462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:55.857017Z","time spent":"773.430191ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:39:56.630515Z","caller":"traceutil/trace.go:171","msg":"trace[765772736] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"772.08111ms","start":"2026-02-12T03:39:55.85843Z","end":"2026-02-12T03:39:56.630511Z","steps":["trace[765772736] 'process raft request' (duration: 771.733322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:56.630536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:55.858413Z","time spent":"772.110901ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:39:56.630587Z","caller":"traceutil/trace.go:171","msg":"trace[1177870641] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"770.383951ms","start":"2026-02-12T03:39:55.860199Z","end":"2026-02-12T03:39:56.630583Z","steps":["trace[1177870641] 'process raft request' (duration: 769.987972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:39:56.630608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:39:55.860192Z","time spent":"770.404281ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:39:58.80986Z","caller":"traceutil/trace.go:171","msg":"trace[931265961] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"131.570037ms","start":"2026-02-12T03:39:58.678261Z","end":"2026-02-12T03:39:58.809831Z","steps":["trace[931265961] 'process raft request' (duration: 64.10556ms)","trace[931265961] 'compare' (duration: 67.097569ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:01.100531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.17503ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:01.100618Z","caller":"traceutil/trace.go:171","msg":"trace[681528114] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:512; }","duration":"158.271002ms","start":"2026-02-12T03:40:00.942337Z","end":"2026-02-12T03:40:01.100608Z","steps":["trace[681528114] 'range keys from in-memory index tree' (duration: 158.16349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:09.528524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.799028ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-12T03:40:09.528595Z","caller":"traceutil/trace.go:171","msg":"trace[190316200] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:549; }","duration":"830.329261ms","start":"2026-02-12T03:40:08.698255Z","end":"2026-02-12T03:40:09.528584Z","steps":["trace[190316200] 'read index received' (duration: 241.37211ms)","trace[190316200] 'applied index is now lower than readState.Index' (duration: 588.956311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:09.528721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.457414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rql98\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-12T03:40:09.528738Z","caller":"traceutil/trace.go:171","msg":"trace[990235150] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rql98; range_end:; response_count:1; response_revision:530; }","duration":"830.508565ms","start":"2026-02-12T03:40:08.698223Z","end":"2026-02-12T03:40:09.528731Z","steps":["trace[990235150] 'agreement among raft nodes before linearized reading' (duration: 830.413243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:09.528762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:08.698194Z","time spent":"830.562067ms","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16678,"request content":"key:\"/registry/pods/kube-system/cilium-rql98\" "} {"level":"warn","ts":"2026-02-12T03:40:09.529079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.618521ms","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-12T03:40:09.529095Z","caller":"traceutil/trace.go:171","msg":"trace[1814055906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"740.647511ms","start":"2026-02-12T03:40:08.788443Z","end":"2026-02-12T03:40:09.529091Z","steps":["trace[1814055906] 'agreement among raft nodes before linearized reading' (duration: 740.610811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:09.529108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:08.788437Z","time spent":"740.667752ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:09.529199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.517461ms","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-12T03:40:09.529211Z","caller":"traceutil/trace.go:171","msg":"trace[2119151884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"640.565192ms","start":"2026-02-12T03:40:08.888642Z","end":"2026-02-12T03:40:09.529207Z","steps":["trace[2119151884] 'agreement among raft nodes before linearized reading' (duration: 640.531071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:09.529222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:08.888624Z","time spent":"640.595332ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:09.529387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.410972ms","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-12T03:40:09.529407Z","caller":"traceutil/trace.go:171","msg":"trace[294545024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"752.441304ms","start":"2026-02-12T03:40:08.776962Z","end":"2026-02-12T03:40:09.529404Z","steps":["trace[294545024] 'agreement among raft nodes before linearized reading' (duration: 752.396323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:09.52942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:08.776957Z","time spent":"752.459324ms","remote":"127.0.0.1:51556","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-12T03:40:09.529534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.913606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:09.529546Z","caller":"traceutil/trace.go:171","msg":"trace[427331451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"759.969628ms","start":"2026-02-12T03:40:08.769573Z","end":"2026-02-12T03:40:09.529543Z","steps":["trace[427331451] 'agreement among raft nodes before linearized reading' (duration: 759.950137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:09.529559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:08.76955Z","time spent":"760.004788ms","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:40:09.530348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.077005ms","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-12T03:40:09.530366Z","caller":"traceutil/trace.go:171","msg":"trace[2135869129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"807.095995ms","start":"2026-02-12T03:40:08.723264Z","end":"2026-02-12T03:40:09.53036Z","steps":["trace[2135869129] 'agreement among raft nodes before linearized reading' (duration: 805.748814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:09.53038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:08.723212Z","time spent":"807.164728ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T03:40:09.690602Z","caller":"traceutil/trace.go:171","msg":"trace[87320227] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"135.511098ms","start":"2026-02-12T03:40:09.555073Z","end":"2026-02-12T03:40:09.690584Z","steps":["trace[87320227] 'process raft request' (duration: 135.402416ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:09.691726Z","caller":"traceutil/trace.go:171","msg":"trace[707350034] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"155.59626ms","start":"2026-02-12T03:40:09.536057Z","end":"2026-02-12T03:40:09.691653Z","steps":["trace[707350034] 'process raft request' (duration: 154.26672ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:09.691815Z","caller":"traceutil/trace.go:171","msg":"trace[2140085678] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"151.858114ms","start":"2026-02-12T03:40:09.539943Z","end":"2026-02-12T03:40:09.691801Z","steps":["trace[2140085678] 'process raft request' (duration: 150.480973ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:09.691853Z","caller":"traceutil/trace.go:171","msg":"trace[337519178] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"150.063022ms","start":"2026-02-12T03:40:09.541784Z","end":"2026-02-12T03:40:09.691847Z","steps":["trace[337519178] 'process raft request' (duration: 148.66455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:11.539847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.640874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:11.539935Z","caller":"traceutil/trace.go:171","msg":"trace[603718991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"770.760287ms","start":"2026-02-12T03:40:10.769153Z","end":"2026-02-12T03:40:11.539913Z","steps":["trace[603718991] 'range keys from in-memory index tree' (duration: 770.581313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:11.539984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:10.769138Z","time spent":"770.833759ms","remote":"127.0.0.1:51302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:40:11.540369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.835936ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:11.540463Z","caller":"traceutil/trace.go:171","msg":"trace[814814273] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:535; }","duration":"597.933458ms","start":"2026-02-12T03:40:10.942516Z","end":"2026-02-12T03:40:11.54045Z","steps":["trace[814814273] 'range keys from in-memory index tree' (duration: 597.825476ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:11.751997Z","caller":"traceutil/trace.go:171","msg":"trace[1453939112] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"135.240462ms","start":"2026-02-12T03:40:11.616734Z","end":"2026-02-12T03:40:11.751975Z","steps":["trace[1453939112] 'process raft request' (duration: 133.91404ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:12.02854Z","caller":"traceutil/trace.go:171","msg":"trace[290810334] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"269.807407ms","start":"2026-02-12T03:40:11.75871Z","end":"2026-02-12T03:40:12.028518Z","steps":["trace[290810334] 'process raft request' (duration: 269.768506ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:12.028878Z","caller":"traceutil/trace.go:171","msg":"trace[276362320] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"271.047825ms","start":"2026-02-12T03:40:11.757816Z","end":"2026-02-12T03:40:12.028864Z","steps":["trace[276362320] 'process raft request' (duration: 206.000794ms)","trace[276362320] 'compare' (duration: 64.379656ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:40:12.028996Z","caller":"traceutil/trace.go:171","msg":"trace[1307988594] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"271.071527ms","start":"2026-02-12T03:40:11.757913Z","end":"2026-02-12T03:40:12.028985Z","steps":["trace[1307988594] 'process raft request' (duration: 270.510593ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:14.373892Z","caller":"traceutil/trace.go:171","msg":"trace[1224737050] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"303.889617ms","start":"2026-02-12T03:40:14.069984Z","end":"2026-02-12T03:40:14.373874Z","steps":["trace[1224737050] 'process raft request' (duration: 248.866729ms)","trace[1224737050] 'compare' (duration: 54.946736ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:14.37399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:14.069968Z","time spent":"303.971188ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:40:14.565397Z","caller":"traceutil/trace.go:171","msg":"trace[925927794] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"487.465962ms","start":"2026-02-12T03:40:14.0779Z","end":"2026-02-12T03:40:14.565366Z","steps":["trace[925927794] 'process raft request' (duration: 485.842943ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:14.56574Z","caller":"traceutil/trace.go:171","msg":"trace[1048740806] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:578; }","duration":"449.012522ms","start":"2026-02-12T03:40:14.11672Z","end":"2026-02-12T03:40:14.565732Z","steps":["trace[1048740806] 'read index received' (duration: 202.216452ms)","trace[1048740806] 'applied index is now lower than readState.Index' (duration: 246.79537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:14.565866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.136295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-vs4gd\" ","response":"range_response_count:1 size:4822"} {"level":"info","ts":"2026-02-12T03:40:14.565887Z","caller":"traceutil/trace.go:171","msg":"trace[2012198114] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-vs4gd; range_end:; response_count:1; response_revision:564; }","duration":"449.192366ms","start":"2026-02-12T03:40:14.116687Z","end":"2026-02-12T03:40:14.56588Z","steps":["trace[2012198114] 'agreement among raft nodes before linearized reading' (duration: 449.072063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:14.565907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:14.116672Z","time spent":"449.230607ms","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4844,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-vs4gd\" "} {"level":"warn","ts":"2026-02-12T03:40:14.566029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:14.077877Z","time spent":"487.570275ms","remote":"127.0.0.1:51772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:40:14.566164Z","caller":"traceutil/trace.go:171","msg":"trace[1060223515] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"488.175499ms","start":"2026-02-12T03:40:14.077982Z","end":"2026-02-12T03:40:14.566158Z","steps":["trace[1060223515] 'process raft request' (duration: 487.36409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:14.566203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:14.077975Z","time spent":"488.203871ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:40:14.566293Z","caller":"traceutil/trace.go:171","msg":"trace[493591005] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"488.230341ms","start":"2026-02-12T03:40:14.078056Z","end":"2026-02-12T03:40:14.566286Z","steps":["trace[493591005] 'process raft request' (duration: 487.590236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:14.566322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:14.07805Z","time spent":"488.256592ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:40:14.566396Z","caller":"traceutil/trace.go:171","msg":"trace[1499079684] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"488.238641ms","start":"2026-02-12T03:40:14.078151Z","end":"2026-02-12T03:40:14.56639Z","steps":["trace[1499079684] 'process raft request' (duration: 487.515804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:14.566425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:14.078144Z","time spent":"488.265412ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:40:14.566552Z","caller":"traceutil/trace.go:171","msg":"trace[1007748715] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"470.348562ms","start":"2026-02-12T03:40:14.096198Z","end":"2026-02-12T03:40:14.566546Z","steps":["trace[1007748715] 'process raft request' (duration: 469.488572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:14.566578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:14.096181Z","time spent":"470.384644ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:40:14.566644Z","caller":"traceutil/trace.go:171","msg":"trace[1746335243] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"268.901129ms","start":"2026-02-12T03:40:14.297737Z","end":"2026-02-12T03:40:14.566638Z","steps":["trace[1746335243] 'process raft request' (duration: 267.974828ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:14.575165Z","caller":"traceutil/trace.go:171","msg":"trace[1335811360] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"231.946334ms","start":"2026-02-12T03:40:14.343197Z","end":"2026-02-12T03:40:14.575144Z","steps":["trace[1335811360] 'process raft request' (duration: 231.75021ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:14.575338Z","caller":"traceutil/trace.go:171","msg":"trace[99906804] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"195.576795ms","start":"2026-02-12T03:40:14.379753Z","end":"2026-02-12T03:40:14.575329Z","steps":["trace[99906804] 'process raft request' (duration: 195.254906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:17.252525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.156327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-12T03:40:17.252602Z","caller":"traceutil/trace.go:171","msg":"trace[1801123055] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"158.27503ms","start":"2026-02-12T03:40:17.094311Z","end":"2026-02-12T03:40:17.252586Z","steps":["trace[1801123055] 'range keys from in-memory index tree' (duration: 158.024163ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:17.84187Z","caller":"traceutil/trace.go:171","msg":"trace[1590841979] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"241.293127ms","start":"2026-02-12T03:40:17.599825Z","end":"2026-02-12T03:40:17.841118Z","steps":["trace[1590841979] 'process raft request' (duration: 241.070362ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:18.111303Z","caller":"traceutil/trace.go:171","msg":"trace[1502763751] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"379.480137ms","start":"2026-02-12T03:40:17.731806Z","end":"2026-02-12T03:40:18.111287Z","steps":["trace[1502763751] 'process raft request' (duration: 319.287005ms)","trace[1502763751] 'compare' (duration: 58.430969ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.111436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:17.73178Z","time spent":"379.571069ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:40:18.11709Z","caller":"traceutil/trace.go:171","msg":"trace[1141763464] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"356.886817ms","start":"2026-02-12T03:40:17.760194Z","end":"2026-02-12T03:40:18.117081Z","steps":["trace[1141763464] 'process raft request' (duration: 350.120674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:18.11714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:17.760185Z","time spent":"356.933128ms","remote":"127.0.0.1:51366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":739,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T03:40:18.117295Z","caller":"traceutil/trace.go:171","msg":"trace[1088544967] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"358.211008ms","start":"2026-02-12T03:40:17.759076Z","end":"2026-02-12T03:40:18.117287Z","steps":["trace[1088544967] 'read index received' (duration: 82.6818ms)","trace[1088544967] 'applied index is now lower than readState.Index' (duration: 275.528338ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.279206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.835513ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-12T03:40:18.279424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.295419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rql98\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-12T03:40:18.279573Z","caller":"traceutil/trace.go:171","msg":"trace[1105936393] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rql98; range_end:; response_count:1; response_revision:615; }","duration":"520.481584ms","start":"2026-02-12T03:40:17.759047Z","end":"2026-02-12T03:40:18.279529Z","steps":["trace[1105936393] 'agreement among raft nodes before linearized reading' (duration: 358.28489ms)","trace[1105936393] 'range keys from in-memory index tree' (duration: 161.902937ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.279653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:17.759036Z","time spent":"520.603546ms","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17940,"request content":"key:\"/registry/pods/kube-system/cilium-rql98\" "} {"level":"info","ts":"2026-02-12T03:40:18.280259Z","caller":"traceutil/trace.go:171","msg":"trace[972828708] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"162.936321ms","start":"2026-02-12T03:40:18.117309Z","end":"2026-02-12T03:40:18.280245Z","steps":["trace[972828708] 'read index received' (duration: 32.291µs)","trace[972828708] 'applied index is now lower than readState.Index' (duration: 162.90324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.280389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.393318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:18.280484Z","caller":"traceutil/trace.go:171","msg":"trace[1009769623] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"509.535601ms","start":"2026-02-12T03:40:17.770941Z","end":"2026-02-12T03:40:18.280476Z","steps":["trace[1009769623] 'agreement among raft nodes before linearized reading' (duration: 509.391007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:18.280805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:17.770933Z","time spent":"509.861738ms","remote":"127.0.0.1:51302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:40:18.320068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.304237ms","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-12T03:40:18.320124Z","caller":"traceutil/trace.go:171","msg":"trace[1599872092] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"213.432491ms","start":"2026-02-12T03:40:18.106678Z","end":"2026-02-12T03:40:18.32011Z","steps":["trace[1599872092] 'agreement among raft nodes before linearized reading' (duration: 174.58371ms)","trace[1599872092] 'range keys from in-memory index tree' (duration: 38.713997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.320508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.608422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-12T03:40:18.320531Z","caller":"traceutil/trace.go:171","msg":"trace[1017565858] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"225.660533ms","start":"2026-02-12T03:40:18.094863Z","end":"2026-02-12T03:40:18.320523Z","steps":["trace[1017565858] 'agreement among raft nodes before linearized reading' (duration: 186.410463ms)","trace[1017565858] 'range keys from in-memory index tree' (duration: 39.193949ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.320715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.162616ms","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-12T03:40:18.320734Z","caller":"traceutil/trace.go:171","msg":"trace[1841190770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"282.215878ms","start":"2026-02-12T03:40:18.038513Z","end":"2026-02-12T03:40:18.320729Z","steps":["trace[1841190770] 'agreement among raft nodes before linearized reading' (duration: 242.771373ms)","trace[1841190770] 'range keys from in-memory index tree' (duration: 39.399694ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.321346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.597288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-12T03:40:18.321369Z","caller":"traceutil/trace.go:171","msg":"trace[1601916474] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:616; }","duration":"424.652258ms","start":"2026-02-12T03:40:17.896711Z","end":"2026-02-12T03:40:18.321363Z","steps":["trace[1601916474] 'agreement among raft nodes before linearized reading' (duration: 384.580698ms)","trace[1601916474] 'range keys from in-memory index tree' (duration: 40.018689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.322197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:17.896696Z","time spent":"424.968397ms","remote":"127.0.0.1:51332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"info","ts":"2026-02-12T03:40:18.730216Z","caller":"traceutil/trace.go:171","msg":"trace[665640884] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"142.099843ms","start":"2026-02-12T03:40:18.588099Z","end":"2026-02-12T03:40:18.730198Z","steps":["trace[665640884] 'process raft request' (duration: 141.99066ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:18.862053Z","caller":"traceutil/trace.go:171","msg":"trace[1680800737] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"255.708704ms","start":"2026-02-12T03:40:18.606335Z","end":"2026-02-12T03:40:18.862044Z","steps":["trace[1680800737] 'process raft request' (duration: 243.365688ms)","trace[1680800737] 'compare' (duration: 11.419823ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.869723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.042488ms","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-12T03:40:18.869752Z","caller":"traceutil/trace.go:171","msg":"trace[1198803590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"263.10685ms","start":"2026-02-12T03:40:18.606637Z","end":"2026-02-12T03:40:18.869744Z","steps":["trace[1198803590] 'agreement among raft nodes before linearized reading' (duration: 263.010947ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:18.861224Z","caller":"traceutil/trace.go:171","msg":"trace[376513235] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"254.543774ms","start":"2026-02-12T03:40:18.606668Z","end":"2026-02-12T03:40:18.861211Z","steps":["trace[376513235] 'read index received' (duration: 123.786794ms)","trace[376513235] 'applied index is now lower than readState.Index' (duration: 130.75618ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:18.869982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.154225ms","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-12T03:40:18.87Z","caller":"traceutil/trace.go:171","msg":"trace[2106733435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"208.196236ms","start":"2026-02-12T03:40:18.661798Z","end":"2026-02-12T03:40:18.869995Z","steps":["trace[2106733435] 'agreement among raft nodes before linearized reading' (duration: 208.148355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:18.870105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.369512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-12T03:40:18.870125Z","caller":"traceutil/trace.go:171","msg":"trace[561442935] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:622; }","duration":"206.396543ms","start":"2026-02-12T03:40:18.663722Z","end":"2026-02-12T03:40:18.870118Z","steps":["trace[561442935] 'agreement among raft nodes before linearized reading' (duration: 206.336761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:18.870266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.610311ms","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-12T03:40:18.870284Z","caller":"traceutil/trace.go:171","msg":"trace[1144166329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"207.636191ms","start":"2026-02-12T03:40:18.662644Z","end":"2026-02-12T03:40:18.87028Z","steps":["trace[1144166329] 'agreement among raft nodes before linearized reading' (duration: 207.59156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:18.87024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.030988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rql98\" ","response":"range_response_count:1 size:18075"} {"level":"info","ts":"2026-02-12T03:40:18.870373Z","caller":"traceutil/trace.go:171","msg":"trace[121276424] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rql98; range_end:; response_count:1; response_revision:622; }","duration":"103.194491ms","start":"2026-02-12T03:40:18.767165Z","end":"2026-02-12T03:40:18.87036Z","steps":["trace[121276424] 'agreement among raft nodes before linearized reading' (duration: 102.802942ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:19.32212Z","caller":"traceutil/trace.go:171","msg":"trace[81788766] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"417.63404ms","start":"2026-02-12T03:40:18.90447Z","end":"2026-02-12T03:40:19.322104Z","steps":["trace[81788766] 'process raft request' (duration: 417.598439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:19.322317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:18.904467Z","time spent":"417.761553ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:40:19.322536Z","caller":"traceutil/trace.go:171","msg":"trace[397607177] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"418.116001ms","start":"2026-02-12T03:40:18.904413Z","end":"2026-02-12T03:40:19.322529Z","steps":["trace[397607177] 'process raft request' (duration: 401.403221ms)","trace[397607177] 'compare' (duration: 15.87966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:19.322895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:18.904408Z","time spent":"418.46738ms","remote":"127.0.0.1:51482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18235,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:40:19.323036Z","caller":"traceutil/trace.go:171","msg":"trace[532351743] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"418.584162ms","start":"2026-02-12T03:40:18.904447Z","end":"2026-02-12T03:40:19.323031Z","steps":["trace[532351743] 'process raft request' (duration: 417.575549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:19.323579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:18.904443Z","time spent":"419.116225ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:40:19.32812Z","caller":"traceutil/trace.go:171","msg":"trace[1275285522] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:645; }","duration":"149.762555ms","start":"2026-02-12T03:40:19.178346Z","end":"2026-02-12T03:40:19.328109Z","steps":["trace[1275285522] 'read index received' (duration: 127.487112ms)","trace[1275285522] 'applied index is now lower than readState.Index' (duration: 22.274663ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:19.328504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.150454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:19.328586Z","caller":"traceutil/trace.go:171","msg":"trace[1973345576] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:628; }","duration":"150.234606ms","start":"2026-02-12T03:40:19.178343Z","end":"2026-02-12T03:40:19.328577Z","steps":["trace[1973345576] 'agreement among raft nodes before linearized reading' (duration: 150.134324ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:19.328734Z","caller":"traceutil/trace.go:171","msg":"trace[823201674] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"260.240712ms","start":"2026-02-12T03:40:19.068474Z","end":"2026-02-12T03:40:19.328715Z","steps":["trace[823201674] 'process raft request' (duration: 259.530685ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:19.328795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.40393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:19.329326Z","caller":"traceutil/trace.go:171","msg":"trace[1829761770] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:628; }","duration":"150.945204ms","start":"2026-02-12T03:40:19.178373Z","end":"2026-02-12T03:40:19.329318Z","steps":["trace[1829761770] 'agreement among raft nodes before linearized reading' (duration: 150.39955ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:21.502922Z","caller":"traceutil/trace.go:171","msg":"trace[1191852145] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"165.487213ms","start":"2026-02-12T03:40:21.33742Z","end":"2026-02-12T03:40:21.502908Z","steps":["trace[1191852145] 'process raft request' (duration: 165.39288ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:21.508432Z","caller":"traceutil/trace.go:171","msg":"trace[1401338766] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"170.43245ms","start":"2026-02-12T03:40:21.33799Z","end":"2026-02-12T03:40:21.508423Z","steps":["trace[1401338766] 'process raft request' (duration: 170.370938ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:26.535687Z","caller":"traceutil/trace.go:171","msg":"trace[1231169327] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"108.515849ms","start":"2026-02-12T03:40:26.42715Z","end":"2026-02-12T03:40:26.535665Z","steps":["trace[1231169327] 'process raft request' (duration: 108.114179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:36.122223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088397638136845,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T03:40:36.353947Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.283392331s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T03:40:36.354268Z","caller":"traceutil/trace.go:171","msg":"trace[918957872] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"732.241894ms","start":"2026-02-12T03:40:35.622007Z","end":"2026-02-12T03:40:36.354249Z","steps":["trace[918957872] 'read index received' (duration: 732.180123ms)","trace[918957872] 'applied index is now lower than readState.Index' (duration: 61.011µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:40:36.354324Z","caller":"traceutil/trace.go:171","msg":"trace[2118090850] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"1.283830162s","start":"2026-02-12T03:40:35.070486Z","end":"2026-02-12T03:40:36.354316Z","steps":["trace[2118090850] 'process raft request' (duration: 1.283651127s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:36.354401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:35.070475Z","time spent":"1.283867082s","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:40:36.354471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.45805ms","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-12T03:40:36.354499Z","caller":"traceutil/trace.go:171","msg":"trace[121695692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"732.511931ms","start":"2026-02-12T03:40:35.621978Z","end":"2026-02-12T03:40:36.35449Z","steps":["trace[121695692] 'agreement among raft nodes before linearized reading' (duration: 732.417289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:36.35452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:35.621965Z","time spent":"732.550571ms","remote":"127.0.0.1:51556","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-12T03:40:36.855134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088397638136848,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T03:40:37.131662Z","caller":"traceutil/trace.go:171","msg":"trace[1905488825] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"983.783687ms","start":"2026-02-12T03:40:36.147863Z","end":"2026-02-12T03:40:37.131647Z","steps":["trace[1905488825] 'process raft request' (duration: 983.695525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:37.131757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:36.147841Z","time spent":"983.868149ms","remote":"127.0.0.1:51478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:40:38.301162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.171352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:40:38.301259Z","caller":"traceutil/trace.go:171","msg":"trace[187505241] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"1.94695092s","start":"2026-02-12T03:40:36.354297Z","end":"2026-02-12T03:40:38.301247Z","steps":["trace[187505241] 'read index received' (duration: 777.362844ms)","trace[187505241] 'applied index is now lower than readState.Index' (duration: 1.169587596s)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:40:38.301296Z","caller":"traceutil/trace.go:171","msg":"trace[380161762] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"1.943383895s","start":"2026-02-12T03:40:36.357907Z","end":"2026-02-12T03:40:38.301291Z","steps":["trace[380161762] 'process raft request' (duration: 1.842037329s)","trace[380161762] 'compare' (duration: 100.848015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:38.301324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:36.357891Z","time spent":"1.943417046s","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:40:38.301384Z","caller":"traceutil/trace.go:171","msg":"trace[2000289015] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.398451696s","start":"2026-02-12T03:40:36.902929Z","end":"2026-02-12T03:40:38.30138Z","steps":["trace[2000289015] 'process raft request' (duration: 1.398285583s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.301406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:36.902907Z","time spent":"1.398485797s","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:40:38.301534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.66357721s","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-12T03:40:38.301573Z","caller":"traceutil/trace.go:171","msg":"trace[191367845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"2.663638812s","start":"2026-02-12T03:40:35.637923Z","end":"2026-02-12T03:40:38.301561Z","steps":["trace[191367845] 'agreement among raft nodes before linearized reading' (duration: 2.663521739s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.301603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:35.637913Z","time spent":"2.663684083s","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:38.673443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.456016ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-12T03:40:38.673633Z","caller":"traceutil/trace.go:171","msg":"trace[748603974] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"367.997963ms","start":"2026-02-12T03:40:38.305622Z","end":"2026-02-12T03:40:38.67362Z","steps":["trace[748603974] 'process raft request' (duration: 367.911521ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:38.67366Z","caller":"traceutil/trace.go:171","msg":"trace[1984165172] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"372.354326ms","start":"2026-02-12T03:40:38.301272Z","end":"2026-02-12T03:40:38.673627Z","steps":["trace[1984165172] 'read index received' (duration: 116.712495ms)","trace[1984165172] 'applied index is now lower than readState.Index' (duration: 255.637501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:38.673704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:38.305602Z","time spent":"368.056594ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:40:38.673823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.28615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:40:38.673848Z","caller":"traceutil/trace.go:171","msg":"trace[655823034] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:739; }","duration":"561.353462ms","start":"2026-02-12T03:40:38.112486Z","end":"2026-02-12T03:40:38.67384Z","steps":["trace[655823034] 'agreement among raft nodes before linearized reading' (duration: 561.25873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.673876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:38.112475Z","time spent":"561.395354ms","remote":"127.0.0.1:51694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:40:38.67403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.90477872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:38.674054Z","caller":"traceutil/trace.go:171","msg":"trace[690183393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"1.904823401s","start":"2026-02-12T03:40:36.769223Z","end":"2026-02-12T03:40:38.674046Z","steps":["trace[690183393] 'agreement among raft nodes before linearized reading' (duration: 1.90478774s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.674074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:36.769213Z","time spent":"1.904856312s","remote":"127.0.0.1:51302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:40:38.674146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.983619558s","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-12T03:40:38.674188Z","caller":"traceutil/trace.go:171","msg":"trace[402151129] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:739; }","duration":"1.983680389s","start":"2026-02-12T03:40:36.690499Z","end":"2026-02-12T03:40:38.67418Z","steps":["trace[402151129] 'agreement among raft nodes before linearized reading' (duration: 1.983578687s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.674201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.501054ms","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":"warn","ts":"2026-02-12T03:40:38.674209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:36.690489Z","time spent":"1.98371441s","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-12T03:40:38.674225Z","caller":"traceutil/trace.go:171","msg":"trace[2094034254] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:739; }","duration":"779.557336ms","start":"2026-02-12T03:40:37.89466Z","end":"2026-02-12T03:40:38.674217Z","steps":["trace[2094034254] 'agreement among raft nodes before linearized reading' (duration: 779.522465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.674248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:37.894642Z","time spent":"779.597958ms","remote":"127.0.0.1:51764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:40:38.674336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029432025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-12T03:40:38.674344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.536095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:40:38.674355Z","caller":"traceutil/trace.go:171","msg":"trace[291510657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:739; }","duration":"2.029451625s","start":"2026-02-12T03:40:36.644897Z","end":"2026-02-12T03:40:38.674349Z","steps":["trace[291510657] 'agreement among raft nodes before linearized reading' (duration: 2.029397424s)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:38.674362Z","caller":"traceutil/trace.go:171","msg":"trace[1769013965] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:739; }","duration":"918.554205ms","start":"2026-02-12T03:40:37.755802Z","end":"2026-02-12T03:40:38.674356Z","steps":["trace[1769013965] 'agreement among raft nodes before linearized reading' (duration: 918.523245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.674373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:36.64484Z","time spent":"2.029529217s","remote":"127.0.0.1:51556","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-12T03:40:38.674383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:37.755743Z","time spent":"918.635157ms","remote":"127.0.0.1:51750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:40:38.67448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.109465ms","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":"warn","ts":"2026-02-12T03:40:38.674485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.442367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T03:40:38.674499Z","caller":"traceutil/trace.go:171","msg":"trace[2055054876] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:739; }","duration":"974.163476ms","start":"2026-02-12T03:40:37.700328Z","end":"2026-02-12T03:40:38.674492Z","steps":["trace[2055054876] 'agreement among raft nodes before linearized reading' (duration: 974.136406ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:38.674504Z","caller":"traceutil/trace.go:171","msg":"trace[2110521583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:739; }","duration":"316.485429ms","start":"2026-02-12T03:40:38.358012Z","end":"2026-02-12T03:40:38.674498Z","steps":["trace[2110521583] 'agreement among raft nodes before linearized reading' (duration: 316.433087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.674517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:37.70031Z","time spent":"974.202968ms","remote":"127.0.0.1:51502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:40:38.674521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:38.357979Z","time spent":"316.539079ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:38.674612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370871745s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:40:38.677158Z","caller":"traceutil/trace.go:171","msg":"trace[569670320] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:739; }","duration":"1.370931157s","start":"2026-02-12T03:40:37.303695Z","end":"2026-02-12T03:40:38.674626Z","steps":["trace[569670320] 'agreement among raft nodes before linearized reading' (duration: 1.370897486s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:38.677207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:37.303675Z","time spent":"1.373519888s","remote":"127.0.0.1:51714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-12T03:40:42.563235Z","caller":"traceutil/trace.go:171","msg":"trace[332499682] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"249.449093ms","start":"2026-02-12T03:40:42.313768Z","end":"2026-02-12T03:40:42.563217Z","steps":["trace[332499682] 'process raft request' (duration: 249.347111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:43.064483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.069427ms","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-12T03:40:43.064542Z","caller":"traceutil/trace.go:171","msg":"trace[482686100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:753; }","duration":"336.150129ms","start":"2026-02-12T03:40:42.728378Z","end":"2026-02-12T03:40:43.064528Z","steps":["trace[482686100] 'range keys from in-memory index tree' (duration: 335.963905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:43.064577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:42.728373Z","time spent":"336.1975ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:43.064615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.01857ms","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-12T03:40:43.064659Z","caller":"traceutil/trace.go:171","msg":"trace[2143948744] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"332.088362ms","start":"2026-02-12T03:40:42.73256Z","end":"2026-02-12T03:40:43.064648Z","steps":["trace[2143948744] 'range keys from in-memory index tree' (duration: 331.934778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:43.064681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:42.732555Z","time spent":"332.121723ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:43.064783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.35149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T03:40:43.064796Z","caller":"traceutil/trace.go:171","msg":"trace[798578106] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:753; }","duration":"216.395361ms","start":"2026-02-12T03:40:42.848396Z","end":"2026-02-12T03:40:43.064791Z","steps":["trace[798578106] 'range keys from in-memory index tree' (duration: 216.276659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:43.064802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.085565ms","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-12T03:40:43.064818Z","caller":"traceutil/trace.go:171","msg":"trace[1756199758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"376.127766ms","start":"2026-02-12T03:40:42.688686Z","end":"2026-02-12T03:40:43.064814Z","steps":["trace[1756199758] 'range keys from in-memory index tree' (duration: 376.019054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:43.064831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:42.688671Z","time spent":"376.157237ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:43.064913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.614696ms","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-12T03:40:43.064937Z","caller":"traceutil/trace.go:171","msg":"trace[941195922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"345.638237ms","start":"2026-02-12T03:40:42.719295Z","end":"2026-02-12T03:40:43.064933Z","steps":["trace[941195922] 'range keys from in-memory index tree' (duration: 345.536164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:43.064949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:42.719277Z","time spent":"345.668867ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T03:40:43.566313Z","caller":"traceutil/trace.go:171","msg":"trace[2085679483] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"403.578654ms","start":"2026-02-12T03:40:43.162713Z","end":"2026-02-12T03:40:43.566291Z","steps":["trace[2085679483] 'process raft request' (duration: 369.465857ms)","trace[2085679483] 'compare' (duration: 33.899932ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:43.566408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:43.162677Z","time spent":"403.681597ms","remote":"127.0.0.1:51328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:40:43.934245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.068368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:43.934349Z","caller":"traceutil/trace.go:171","msg":"trace[352893763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"184.200871ms","start":"2026-02-12T03:40:43.750121Z","end":"2026-02-12T03:40:43.934322Z","steps":["trace[352893763] 'range keys from in-memory index tree' (duration: 184.012766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:43.934443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.482194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:43.934494Z","caller":"traceutil/trace.go:171","msg":"trace[1630276254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"168.547496ms","start":"2026-02-12T03:40:43.76593Z","end":"2026-02-12T03:40:43.934477Z","steps":["trace[1630276254] 'range keys from in-memory index tree' (duration: 168.448344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:43.934648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.690596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:40:43.934733Z","caller":"traceutil/trace.go:171","msg":"trace[1551993190] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:758; }","duration":"243.804809ms","start":"2026-02-12T03:40:43.690913Z","end":"2026-02-12T03:40:43.934718Z","steps":["trace[1551993190] 'count revisions from in-memory index tree' (duration: 243.626424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:45.805365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.756976ms","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-12T03:40:45.805419Z","caller":"traceutil/trace.go:171","msg":"trace[1069521325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"638.830887ms","start":"2026-02-12T03:40:45.166575Z","end":"2026-02-12T03:40:45.805406Z","steps":["trace[1069521325] 'range keys from in-memory index tree' (duration: 638.660013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:45.805444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:45.166571Z","time spent":"638.866158ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:45.805539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.162718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:40:45.805565Z","caller":"traceutil/trace.go:171","msg":"trace[216014146] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:761; }","duration":"267.22382ms","start":"2026-02-12T03:40:45.538334Z","end":"2026-02-12T03:40:45.805558Z","steps":["trace[216014146] 'count revisions from in-memory index tree' (duration: 267.105647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:45.805577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.048042ms","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-12T03:40:45.805598Z","caller":"traceutil/trace.go:171","msg":"trace[310452308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:761; }","duration":"639.067512ms","start":"2026-02-12T03:40:45.166523Z","end":"2026-02-12T03:40:45.80559Z","steps":["trace[310452308] 'range keys from in-memory index tree' (duration: 638.868928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:45.805614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:45.166484Z","time spent":"639.125314ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T03:40:45.805728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.062982ms","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-12T03:40:45.805747Z","caller":"traceutil/trace.go:171","msg":"trace[1666461376] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"639.084093ms","start":"2026-02-12T03:40:45.166657Z","end":"2026-02-12T03:40:45.805741Z","steps":["trace[1666461376] 'range keys from in-memory index tree' (duration: 639.009302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:45.805748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.270277ms","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":"warn","ts":"2026-02-12T03:40:45.805768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:45.166644Z","time spent":"639.116564ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-12T03:40:45.805772Z","caller":"traceutil/trace.go:171","msg":"trace[587426670] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:761; }","duration":"132.315599ms","start":"2026-02-12T03:40:45.673449Z","end":"2026-02-12T03:40:45.805764Z","steps":["trace[587426670] 'count revisions from in-memory index tree' (duration: 132.218856ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:56.055784Z","caller":"traceutil/trace.go:171","msg":"trace[485680602] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"149.172903ms","start":"2026-02-12T03:40:55.906584Z","end":"2026-02-12T03:40:56.055757Z","steps":["trace[485680602] 'read index received' (duration: 149.0457ms)","trace[485680602] 'applied index is now lower than readState.Index' (duration: 126.213µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:40:56.055859Z","caller":"traceutil/trace.go:171","msg":"trace[2106398929] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"188.173926ms","start":"2026-02-12T03:40:55.867678Z","end":"2026-02-12T03:40:56.055852Z","steps":["trace[2106398929] 'process raft request' (duration: 187.969441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:56.056016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.425018ms","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-12T03:40:56.056036Z","caller":"traceutil/trace.go:171","msg":"trace[889677244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"149.47729ms","start":"2026-02-12T03:40:55.906553Z","end":"2026-02-12T03:40:56.05603Z","steps":["trace[889677244] 'agreement among raft nodes before linearized reading' (duration: 149.384358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:56.056089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.745281ms","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-12T03:40:56.056115Z","caller":"traceutil/trace.go:171","msg":"trace[263679332] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"130.798362ms","start":"2026-02-12T03:40:55.925309Z","end":"2026-02-12T03:40:56.056107Z","steps":["trace[263679332] 'agreement among raft nodes before linearized reading' (duration: 130.7276ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:57.025328Z","caller":"traceutil/trace.go:171","msg":"trace[1920566003] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:823; }","duration":"259.859713ms","start":"2026-02-12T03:40:56.765451Z","end":"2026-02-12T03:40:57.025311Z","steps":["trace[1920566003] 'read index received' (duration: 259.74603ms)","trace[1920566003] 'applied index is now lower than readState.Index' (duration: 112.993µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:57.025422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.955165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:57.025443Z","caller":"traceutil/trace.go:171","msg":"trace[1812786944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:793; }","duration":"260.009437ms","start":"2026-02-12T03:40:56.765426Z","end":"2026-02-12T03:40:57.025435Z","steps":["trace[1812786944] 'agreement among raft nodes before linearized reading' (duration: 259.963696ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:57.025473Z","caller":"traceutil/trace.go:171","msg":"trace[1229709459] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"394.436914ms","start":"2026-02-12T03:40:56.631025Z","end":"2026-02-12T03:40:57.025462Z","steps":["trace[1229709459] 'process raft request' (duration: 394.186689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:57.025536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:56.631014Z","time spent":"394.481976ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:40:59.374809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.89172ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-12T03:40:59.376956Z","caller":"traceutil/trace.go:171","msg":"trace[811280292] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"609.580616ms","start":"2026-02-12T03:40:58.767359Z","end":"2026-02-12T03:40:59.376939Z","steps":["trace[811280292] 'read index received' (duration: 3.629636ms)","trace[811280292] 'applied index is now lower than readState.Index' (duration: 605.94986ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:40:59.377401Z","caller":"traceutil/trace.go:171","msg":"trace[391170499] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"777.135298ms","start":"2026-02-12T03:40:58.600251Z","end":"2026-02-12T03:40:59.377386Z","steps":["trace[391170499] 'process raft request' (duration: 774.668659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:59.378568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:58.600228Z","time spent":"778.283777ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:40:59.782902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.105327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:40:59.782981Z","caller":"traceutil/trace.go:171","msg":"trace[62920105] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:798; }","duration":"351.22952ms","start":"2026-02-12T03:40:59.431735Z","end":"2026-02-12T03:40:59.782964Z","steps":["trace[62920105] 'count revisions from in-memory index tree' (duration: 351.048225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:59.783023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:59.431721Z","time spent":"351.292101ms","remote":"127.0.0.1:51614","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:40:59.783177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015780733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-12T03:40:59.783231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.848748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:59.783262Z","caller":"traceutil/trace.go:171","msg":"trace[1959116888] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:798; }","duration":"291.91476ms","start":"2026-02-12T03:40:59.491338Z","end":"2026-02-12T03:40:59.783253Z","steps":["trace[1959116888] 'count revisions from in-memory index tree' (duration: 291.783687ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:59.783276Z","caller":"traceutil/trace.go:171","msg":"trace[311108075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"1.015919726s","start":"2026-02-12T03:40:58.76733Z","end":"2026-02-12T03:40:59.78325Z","steps":["trace[311108075] 'agreement among raft nodes before linearized reading' (duration: 611.521683ms)","trace[311108075] 'range keys from in-memory index tree' (duration: 404.26165ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:59.783321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:58.767319Z","time spent":"1.015993237s","remote":"127.0.0.1:51314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T03:40:59.783429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.444448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:40:59.783458Z","caller":"traceutil/trace.go:171","msg":"trace[1524301494] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:798; }","duration":"220.475348ms","start":"2026-02-12T03:40:59.562975Z","end":"2026-02-12T03:40:59.783451Z","steps":["trace[1524301494] 'count revisions from in-memory index tree' (duration: 220.280764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:40:59.784249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.223594ms","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-12T03:40:59.784287Z","caller":"traceutil/trace.go:171","msg":"trace[1554824849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"755.299616ms","start":"2026-02-12T03:40:59.028977Z","end":"2026-02-12T03:40:59.784276Z","steps":["trace[1554824849] 'agreement among raft nodes before linearized reading' (duration: 349.892008ms)","trace[1554824849] 'range keys from in-memory index tree' (duration: 404.693449ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:59.784313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:59.028957Z","time spent":"755.349698ms","remote":"127.0.0.1:51556","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-12T03:40:59.784474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.199899ms","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-12T03:40:59.784497Z","caller":"traceutil/trace.go:171","msg":"trace[1707163309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"513.264381ms","start":"2026-02-12T03:40:59.271223Z","end":"2026-02-12T03:40:59.784488Z","steps":["trace[1707163309] 'agreement among raft nodes before linearized reading' (duration: 107.69474ms)","trace[1707163309] 'range keys from in-memory index tree' (duration: 405.50627ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:59.784547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:40:59.2712Z","time spent":"513.340632ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T03:40:59.975897Z","caller":"traceutil/trace.go:171","msg":"trace[404790588] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"183.898074ms","start":"2026-02-12T03:40:59.791976Z","end":"2026-02-12T03:40:59.975874Z","steps":["trace[404790588] 'process raft request' (duration: 183.761821ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:59.980109Z","caller":"traceutil/trace.go:171","msg":"trace[577446407] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"183.979625ms","start":"2026-02-12T03:40:59.79603Z","end":"2026-02-12T03:40:59.98001Z","steps":["trace[577446407] 'read index received' (duration: 180.639576ms)","trace[577446407] 'applied index is now lower than readState.Index' (duration: 3.338439ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:40:59.980564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.507698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:40:59.980732Z","caller":"traceutil/trace.go:171","msg":"trace[1722010416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"184.703293ms","start":"2026-02-12T03:40:59.795985Z","end":"2026-02-12T03:40:59.980689Z","steps":["trace[1722010416] 'agreement among raft nodes before linearized reading' (duration: 184.466097ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:40:59.981516Z","caller":"traceutil/trace.go:171","msg":"trace[500537377] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"186.459595ms","start":"2026-02-12T03:40:59.795036Z","end":"2026-02-12T03:40:59.981495Z","steps":["trace[500537377] 'process raft request' (duration: 184.651781ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:00.18616Z","caller":"traceutil/trace.go:171","msg":"trace[1747341108] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"107.531634ms","start":"2026-02-12T03:41:00.078604Z","end":"2026-02-12T03:41:00.186136Z","steps":["trace[1747341108] 'process raft request' (duration: 106.399428ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:00.294355Z","caller":"traceutil/trace.go:171","msg":"trace[1487874366] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"100.778373ms","start":"2026-02-12T03:41:00.193558Z","end":"2026-02-12T03:41:00.294336Z","steps":["trace[1487874366] 'process raft request' (duration: 75.437386ms)","trace[1487874366] 'compare' (duration: 25.138562ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:41:00.316947Z","caller":"traceutil/trace.go:171","msg":"trace[1847388866] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"121.624762ms","start":"2026-02-12T03:41:00.195303Z","end":"2026-02-12T03:41:00.316928Z","steps":["trace[1847388866] 'process raft request' (duration: 121.550301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:13.017677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.751006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:41:13.017749Z","caller":"traceutil/trace.go:171","msg":"trace[408762847] linearizableReadLoop","detail":"{readStateIndex:922; appliedIndex:921; }","duration":"167.494411ms","start":"2026-02-12T03:41:12.850243Z","end":"2026-02-12T03:41:13.017737Z","steps":["trace[408762847] 'read index received' (duration: 63.521µs)","trace[408762847] 'applied index is now lower than readState.Index' (duration: 167.43015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:41:13.01788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.634874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T03:41:13.017903Z","caller":"traceutil/trace.go:171","msg":"trace[56543364] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:887; }","duration":"167.690796ms","start":"2026-02-12T03:41:12.850205Z","end":"2026-02-12T03:41:13.017895Z","steps":["trace[56543364] 'agreement among raft nodes before linearized reading' (duration: 167.563063ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:13.018061Z","caller":"traceutil/trace.go:171","msg":"trace[141905170] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"508.785283ms","start":"2026-02-12T03:41:12.509267Z","end":"2026-02-12T03:41:13.018052Z","steps":["trace[141905170] 'process raft request' (duration: 74.611497ms)","trace[141905170] 'compare' (duration: 433.646263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:41:13.018101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:12.509253Z","time spent":"508.825395ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:41:13.482697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:13.023874Z","time spent":"458.819676ms","remote":"127.0.0.1:51328","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T03:41:14.300778Z","caller":"traceutil/trace.go:171","msg":"trace[2053759022] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"168.206209ms","start":"2026-02-12T03:41:14.132557Z","end":"2026-02-12T03:41:14.300763Z","steps":["trace[2053759022] 'read index received' (duration: 168.088775ms)","trace[2053759022] 'applied index is now lower than readState.Index' (duration: 116.834µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:41:14.300939Z","caller":"traceutil/trace.go:171","msg":"trace[1911289839] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"169.998361ms","start":"2026-02-12T03:41:14.130936Z","end":"2026-02-12T03:41:14.300934Z","steps":["trace[1911289839] 'process raft request' (duration: 169.752225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:14.301082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.512946ms","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-12T03:41:14.301098Z","caller":"traceutil/trace.go:171","msg":"trace[983727328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"168.546827ms","start":"2026-02-12T03:41:14.132546Z","end":"2026-02-12T03:41:14.301093Z","steps":["trace[983727328] 'agreement among raft nodes before linearized reading' (duration: 168.476865ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:14.955964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088397638137245,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T03:41:14.971443Z","caller":"traceutil/trace.go:171","msg":"trace[1434501480] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"665.411074ms","start":"2026-02-12T03:41:14.306016Z","end":"2026-02-12T03:41:14.971427Z","steps":["trace[1434501480] 'process raft request' (duration: 665.311511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:14.971576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:14.306002Z","time spent":"665.504946ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:41:15.025168Z","caller":"traceutil/trace.go:171","msg":"trace[819340419] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"571.429523ms","start":"2026-02-12T03:41:14.453723Z","end":"2026-02-12T03:41:15.025153Z","steps":["trace[819340419] 'read index received' (duration: 518.140507ms)","trace[819340419] 'applied index is now lower than readState.Index' (duration: 53.288426ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:41:15.025321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.585517ms","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-12T03:41:15.02534Z","caller":"traceutil/trace.go:171","msg":"trace[1178678030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"571.653319ms","start":"2026-02-12T03:41:14.453681Z","end":"2026-02-12T03:41:15.025334Z","steps":["trace[1178678030] 'agreement among raft nodes before linearized reading' (duration: 571.544646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:15.025359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:14.453656Z","time spent":"571.699149ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T03:41:15.02541Z","caller":"traceutil/trace.go:171","msg":"trace[934938158] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"440.972059ms","start":"2026-02-12T03:41:14.584419Z","end":"2026-02-12T03:41:15.025391Z","steps":["trace[934938158] 'process raft request' (duration: 440.653261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:15.025498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:14.584403Z","time spent":"441.0457ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:41:16.441324Z","caller":"traceutil/trace.go:171","msg":"trace[1817196320] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"131.619182ms","start":"2026-02-12T03:41:16.309691Z","end":"2026-02-12T03:41:16.44131Z","steps":["trace[1817196320] 'process raft request' (duration: 131.208421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:23.255084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.175335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-12T03:41:23.256422Z","caller":"traceutil/trace.go:171","msg":"trace[1306586014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:913; }","duration":"179.482514ms","start":"2026-02-12T03:41:23.076876Z","end":"2026-02-12T03:41:23.256359Z","steps":["trace[1306586014] 'range keys from in-memory index tree' (duration: 177.94623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:23.255159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.538421ms","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-12T03:41:23.256572Z","caller":"traceutil/trace.go:171","msg":"trace[41929225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:913; }","duration":"259.100785ms","start":"2026-02-12T03:41:22.997438Z","end":"2026-02-12T03:41:23.256539Z","steps":["trace[41929225] 'range keys from in-memory index tree' (duration: 257.324537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:23.255415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.602858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T03:41:23.256753Z","caller":"traceutil/trace.go:171","msg":"trace[2003114757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:913; }","duration":"190.959568ms","start":"2026-02-12T03:41:23.065782Z","end":"2026-02-12T03:41:23.256742Z","steps":["trace[2003114757] 'range keys from in-memory index tree' (duration: 189.525756ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:24.668759Z","caller":"traceutil/trace.go:171","msg":"trace[770678718] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"144.99781ms","start":"2026-02-12T03:41:24.523746Z","end":"2026-02-12T03:41:24.668744Z","steps":["trace[770678718] 'read index received' (duration: 144.871567ms)","trace[770678718] 'applied index is now lower than readState.Index' (duration: 125.703µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:41:24.668985Z","caller":"traceutil/trace.go:171","msg":"trace[827567324] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"203.099696ms","start":"2026-02-12T03:41:24.465878Z","end":"2026-02-12T03:41:24.668978Z","steps":["trace[827567324] 'process raft request' (duration: 202.787129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:24.669163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.404699ms","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-12T03:41:24.669182Z","caller":"traceutil/trace.go:171","msg":"trace[824864554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:917; }","duration":"145.45492ms","start":"2026-02-12T03:41:24.523721Z","end":"2026-02-12T03:41:24.669175Z","steps":["trace[824864554] 'agreement among raft nodes before linearized reading' (duration: 145.376519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:24.669264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.324702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:41:24.669278Z","caller":"traceutil/trace.go:171","msg":"trace[950018449] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:917; }","duration":"143.350714ms","start":"2026-02-12T03:41:24.525923Z","end":"2026-02-12T03:41:24.669274Z","steps":["trace[950018449] 'agreement among raft nodes before linearized reading' (duration: 143.323413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:25.962726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.824393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:41:25.96285Z","caller":"traceutil/trace.go:171","msg":"trace[2105634445] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:957; }","duration":"575.770276ms","start":"2026-02-12T03:41:25.38707Z","end":"2026-02-12T03:41:25.962841Z","steps":["trace[2105634445] 'read index received' (duration: 225.790769ms)","trace[2105634445] 'applied index is now lower than readState.Index' (duration: 349.979027ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:41:25.962962Z","caller":"traceutil/trace.go:171","msg":"trace[634756321] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"684.795869ms","start":"2026-02-12T03:41:25.278159Z","end":"2026-02-12T03:41:25.962955Z","steps":["trace[634756321] 'process raft request' (duration: 334.69109ms)","trace[634756321] 'compare' (duration: 349.670369ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:41:25.962994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:25.278152Z","time spent":"684.82363ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:41:25.963077Z","caller":"traceutil/trace.go:171","msg":"trace[316746678] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"654.964228ms","start":"2026-02-12T03:41:25.308109Z","end":"2026-02-12T03:41:25.963073Z","steps":["trace[316746678] 'process raft request' (duration: 654.685332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:25.963099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:25.308095Z","time spent":"654.991859ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:41:25.963152Z","caller":"traceutil/trace.go:171","msg":"trace[1121892467] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"618.043922ms","start":"2026-02-12T03:41:25.345104Z","end":"2026-02-12T03:41:25.963148Z","steps":["trace[1121892467] 'process raft request' (duration: 617.720295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:25.963173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:25.345081Z","time spent":"618.080752ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:41:25.963255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.182815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T03:41:25.963268Z","caller":"traceutil/trace.go:171","msg":"trace[687197288] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:922; }","duration":"576.220505ms","start":"2026-02-12T03:41:25.387043Z","end":"2026-02-12T03:41:25.963263Z","steps":["trace[687197288] 'agreement among raft nodes before linearized reading' (duration: 576.194004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:25.963281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:25.387029Z","time spent":"576.248395ms","remote":"127.0.0.1:51476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:41:25.963343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.736626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:41:25.963354Z","caller":"traceutil/trace.go:171","msg":"trace[79275739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"196.771167ms","start":"2026-02-12T03:41:25.766579Z","end":"2026-02-12T03:41:25.96335Z","steps":["trace[79275739] 'agreement among raft nodes before linearized reading' (duration: 196.754597ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:29.159833Z","caller":"traceutil/trace.go:171","msg":"trace[2052302355] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"425.801006ms","start":"2026-02-12T03:41:28.734019Z","end":"2026-02-12T03:41:29.15982Z","steps":["trace[2052302355] 'process raft request' (duration: 425.725924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:29.159916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:28.734003Z","time spent":"425.869957ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:41:29.160119Z","caller":"traceutil/trace.go:171","msg":"trace[1689875896] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:967; }","duration":"389.191425ms","start":"2026-02-12T03:41:28.770923Z","end":"2026-02-12T03:41:29.160114Z","steps":["trace[1689875896] 'read index received' (duration: 389.188995ms)","trace[1689875896] 'applied index is now lower than readState.Index' (duration: 2.14µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:41:29.16016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.235966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:41:29.160174Z","caller":"traceutil/trace.go:171","msg":"trace[44245445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"389.268957ms","start":"2026-02-12T03:41:28.770901Z","end":"2026-02-12T03:41:29.16017Z","steps":["trace[44245445] 'agreement among raft nodes before linearized reading' (duration: 389.241497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:29.160186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:28.770891Z","time spent":"389.292588ms","remote":"127.0.0.1:51302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-12T03:41:29.580702Z","caller":"traceutil/trace.go:171","msg":"trace[159835389] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"225.683196ms","start":"2026-02-12T03:41:29.355001Z","end":"2026-02-12T03:41:29.580684Z","steps":["trace[159835389] 'process raft request' (duration: 225.570274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:30.237415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.596298ms","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-12T03:41:30.23746Z","caller":"traceutil/trace.go:171","msg":"trace[979451710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"260.68959ms","start":"2026-02-12T03:41:29.97676Z","end":"2026-02-12T03:41:30.23745Z","steps":["trace[979451710] 'range keys from in-memory index tree' (duration: 260.483166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:30.237595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.326173ms","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-12T03:41:30.237608Z","caller":"traceutil/trace.go:171","msg":"trace[1441204967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"256.363335ms","start":"2026-02-12T03:41:29.981241Z","end":"2026-02-12T03:41:30.237604Z","steps":["trace[1441204967] 'range keys from in-memory index tree' (duration: 256.228872ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:30.285008Z","caller":"traceutil/trace.go:171","msg":"trace[908086341] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"169.792828ms","start":"2026-02-12T03:41:30.115196Z","end":"2026-02-12T03:41:30.284989Z","steps":["trace[908086341] 'process raft request' (duration: 169.683535ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:30.525837Z","caller":"traceutil/trace.go:171","msg":"trace[1698120286] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:970; }","duration":"171.139059ms","start":"2026-02-12T03:41:30.354678Z","end":"2026-02-12T03:41:30.525817Z","steps":["trace[1698120286] 'read index received' (duration: 147.100747ms)","trace[1698120286] 'applied index is now lower than readState.Index' (duration: 24.037602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:41:30.525946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.283221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:41:30.525967Z","caller":"traceutil/trace.go:171","msg":"trace[1680303006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"171.349363ms","start":"2026-02-12T03:41:30.354611Z","end":"2026-02-12T03:41:30.52596Z","steps":["trace[1680303006] 'agreement among raft nodes before linearized reading' (duration: 171.272081ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:30.526163Z","caller":"traceutil/trace.go:171","msg":"trace[1627401137] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"279.600248ms","start":"2026-02-12T03:41:30.246554Z","end":"2026-02-12T03:41:30.526154Z","steps":["trace[1627401137] 'process raft request' (duration: 255.27525ms)","trace[1627401137] 'compare' (duration: 23.814057ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:41:30.526251Z","caller":"traceutil/trace.go:171","msg":"trace[972147037] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"279.586699ms","start":"2026-02-12T03:41:30.246658Z","end":"2026-02-12T03:41:30.526245Z","steps":["trace[972147037] 'process raft request' (duration: 279.121618ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:32.873755Z","caller":"traceutil/trace.go:171","msg":"trace[1547158705] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"337.876259ms","start":"2026-02-12T03:41:32.535863Z","end":"2026-02-12T03:41:32.873739Z","steps":["trace[1547158705] 'process raft request' (duration: 337.744766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:32.87384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:32.535848Z","time spent":"337.948861ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:41:32.909477Z","caller":"traceutil/trace.go:171","msg":"trace[1798827056] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"139.022326ms","start":"2026-02-12T03:41:32.770444Z","end":"2026-02-12T03:41:32.909467Z","steps":["trace[1798827056] 'read index received' (duration: 103.686275ms)","trace[1798827056] 'applied index is now lower than readState.Index' (duration: 35.335281ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:41:32.909686Z","caller":"traceutil/trace.go:171","msg":"trace[1617307083] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"177.56287ms","start":"2026-02-12T03:41:32.732118Z","end":"2026-02-12T03:41:32.90968Z","steps":["trace[1617307083] 'process raft request' (duration: 177.281474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:32.909795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.336724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:41:32.909816Z","caller":"traceutil/trace.go:171","msg":"trace[1586929383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"139.392095ms","start":"2026-02-12T03:41:32.770418Z","end":"2026-02-12T03:41:32.90981Z","steps":["trace[1586929383] 'agreement among raft nodes before linearized reading' (duration: 139.350054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:33.143752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.615756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T03:41:33.143914Z","caller":"traceutil/trace.go:171","msg":"trace[965904916] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"230.094643ms","start":"2026-02-12T03:41:32.913801Z","end":"2026-02-12T03:41:33.143896Z","steps":["trace[965904916] 'process raft request' (duration: 36.273072ms)","trace[965904916] 'compare' (duration: 193.486594ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:41:33.144052Z","caller":"traceutil/trace.go:171","msg":"trace[116523222] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"210.865857ms","start":"2026-02-12T03:41:32.933173Z","end":"2026-02-12T03:41:33.144039Z","steps":["trace[116523222] 'process raft request' (duration: 210.642202ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:41:46.580203Z","caller":"traceutil/trace.go:171","msg":"trace[240071876] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"309.047811ms","start":"2026-02-12T03:41:46.271137Z","end":"2026-02-12T03:41:46.580185Z","steps":["trace[240071876] 'process raft request' (duration: 308.950289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:46.580333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:46.271116Z","time spent":"309.139203ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T03:41:47.06864Z","caller":"traceutil/trace.go:171","msg":"trace[535899231] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"580.284185ms","start":"2026-02-12T03:41:46.488327Z","end":"2026-02-12T03:41:47.068611Z","steps":["trace[535899231] 'read index received' (duration: 92.351424ms)","trace[535899231] 'applied index is now lower than readState.Index' (duration: 487.931581ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T03:41:47.069202Z","caller":"traceutil/trace.go:171","msg":"trace[12020897] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"751.862904ms","start":"2026-02-12T03:41:46.317319Z","end":"2026-02-12T03:41:47.069182Z","steps":["trace[12020897] 'process raft request' (duration: 693.993552ms)","trace[12020897] 'compare' (duration: 54.384935ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T03:41:47.070571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:46.317295Z","time spent":"751.961576ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T03:41:47.071508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.141499ms","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-12T03:41:47.071922Z","caller":"traceutil/trace.go:171","msg":"trace[164122165] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1042; }","duration":"583.618229ms","start":"2026-02-12T03:41:46.488286Z","end":"2026-02-12T03:41:47.071904Z","steps":["trace[164122165] 'agreement among raft nodes before linearized reading' (duration: 583.115928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:47.071981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:46.488268Z","time spent":"583.692271ms","remote":"127.0.0.1:51764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-12T03:41:47.072611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.712234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-12T03:41:47.072668Z","caller":"traceutil/trace.go:171","msg":"trace[1669532946] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1042; }","duration":"458.794086ms","start":"2026-02-12T03:41:46.61386Z","end":"2026-02-12T03:41:47.072654Z","steps":["trace[1669532946] 'agreement among raft nodes before linearized reading' (duration: 458.305276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:47.072749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:41:46.61385Z","time spent":"458.887028ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-12T03:41:47.073091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.951094ms","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-12T03:41:47.073124Z","caller":"traceutil/trace.go:171","msg":"trace[893817342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1042; }","duration":"110.009075ms","start":"2026-02-12T03:41:46.963104Z","end":"2026-02-12T03:41:47.073113Z","steps":["trace[893817342] 'agreement among raft nodes before linearized reading' (duration: 109.906803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:41:47.073149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.346925ms","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-12T03:41:47.07323Z","caller":"traceutil/trace.go:171","msg":"trace[913663074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1042; }","duration":"116.471668ms","start":"2026-02-12T03:41:46.956744Z","end":"2026-02-12T03:41:47.073215Z","steps":["trace[913663074] 'agreement among raft nodes before linearized reading' (duration: 116.302564ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T03:43:11.109522Z","caller":"traceutil/trace.go:171","msg":"trace[746152202] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"107.686135ms","start":"2026-02-12T03:43:11.001822Z","end":"2026-02-12T03:43:11.109509Z","steps":["trace[746152202] 'process raft request' (duration: 107.611783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:45:00.872637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.291467ms","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:535"} {"level":"info","ts":"2026-02-12T03:45:00.872721Z","caller":"traceutil/trace.go:171","msg":"trace[1814841543] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1866; }","duration":"214.42557ms","start":"2026-02-12T03:45:00.658276Z","end":"2026-02-12T03:45:00.872702Z","steps":["trace[1814841543] 'range keys from in-memory index tree' (duration: 214.079973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:45:00.872992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.484187ms","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-12T03:45:00.87302Z","caller":"traceutil/trace.go:171","msg":"trace[300906523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1866; }","duration":"325.58214ms","start":"2026-02-12T03:45:00.547428Z","end":"2026-02-12T03:45:00.873011Z","steps":["trace[300906523] 'range keys from in-memory index tree' (duration: 325.377086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T03:45:00.873046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T03:45:00.547407Z","time spent":"325.631951ms","remote":"127.0.0.1:51556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T03:45:00.873289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.739744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T03:45:00.873401Z","caller":"traceutil/trace.go:171","msg":"trace[272220857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1866; }","duration":"103.894457ms","start":"2026-02-12T03:45:00.769487Z","end":"2026-02-12T03:45:00.873382Z","steps":["trace[272220857] 'range keys from in-memory index tree' (duration: 103.602061ms)"],"step_count":1}