{"level":"warn","ts":"2026-02-28T21:33:24.951193Z","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-28T21:33:24.951264Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-28T21:33:24.951322Z","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-28T21:33:24.951329Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-28T21:33:24.951344Z","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-28T21:33:24.951852Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-02-28T21:33:24.951918Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-28T21:33:24.956194Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.102435ms"} {"level":"info","ts":"2026-02-28T21:33:24.967009Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-02-28T21:33:24.96714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-02-28T21:33:24.967209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-02-28T21:33:24.967269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-28T21:33:24.96729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-02-28T21:33:24.967377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-02-28T21:33:24.97766Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-28T21:33:24.978888Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-28T21:33:24.979907Z","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-28T21:33:24.981582Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-28T21:33:24.984881Z","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-28T21:33:24.985724Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-28T21:33:24.985828Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-28T21:33:24.985775Z","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-28T21:33:24.985867Z","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-28T21:33:24.98585Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-28T21:33:24.985912Z","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-28T21:33:24.98616Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-28T21:33:24.986197Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-28T21:33:24.986567Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-02-28T21:33:24.986696Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-28T21:33:25.76764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-02-28T21:33:25.767713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-28T21:33:25.767787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-02-28T21:33:25.767817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-02-28T21:33:25.767839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-28T21:33:25.767868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-02-28T21:33:25.767888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-28T21:33:25.768733Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T21:33:25.769607Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-02-28T21:33:25.769732Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T21:33:25.770268Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T21:33:25.770429Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T21:33:25.770473Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-28T21:33:25.771814Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-28T21:33:25.771945Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-28T21:33:25.773466Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-02-28T21:33:25.785922Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-28T21:33:25.785963Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-28T21:33:30.426182Z","caller":"traceutil/trace.go:171","msg":"trace[637757483] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"791.365338ms","start":"2026-02-28T21:33:29.634792Z","end":"2026-02-28T21:33:30.426157Z","steps":["trace[637757483] 'process raft request' (duration: 791.217665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:33:30.426794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:33:29.634763Z","time spent":"791.534711ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:33:30.707935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.554548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-28T21:33:30.70816Z","caller":"traceutil/trace.go:171","msg":"trace[1398500653] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"273.705645ms","start":"2026-02-28T21:33:30.43442Z","end":"2026-02-28T21:33:30.708126Z","steps":["trace[1398500653] 'process raft request' (duration: 166.729897ms)","trace[1398500653] 'compare' (duration: 106.429256ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:33:56.216672Z","caller":"traceutil/trace.go:171","msg":"trace[176795174] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"101.372019ms","start":"2026-02-28T21:33:56.115263Z","end":"2026-02-28T21:33:56.216635Z","steps":["trace[176795174] 'process raft request' (duration: 101.142443ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:01.534622Z","caller":"traceutil/trace.go:171","msg":"trace[1100590725] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"302.341585ms","start":"2026-02-28T21:34:01.232254Z","end":"2026-02-28T21:34:01.534596Z","steps":["trace[1100590725] 'read index received' (duration: 302.162511ms)","trace[1100590725] 'applied index is now lower than readState.Index' (duration: 177.704µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:34:01.534756Z","caller":"traceutil/trace.go:171","msg":"trace[1286806231] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"543.55448ms","start":"2026-02-28T21:34:00.991091Z","end":"2026-02-28T21:34:01.534646Z","steps":["trace[1286806231] 'process raft request' (duration: 543.351856ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:01.534809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.53125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-28T21:34:01.534903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.306177ms","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-28T21:34:01.534952Z","caller":"traceutil/trace.go:171","msg":"trace[339349370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"302.723164ms","start":"2026-02-28T21:34:01.23221Z","end":"2026-02-28T21:34:01.534933Z","steps":["trace[339349370] 'agreement among raft nodes before linearized reading' (duration: 302.54398ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:01.534964Z","caller":"traceutil/trace.go:171","msg":"trace[1401557854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"179.428769ms","start":"2026-02-28T21:34:01.355519Z","end":"2026-02-28T21:34:01.534948Z","steps":["trace[1401557854] 'agreement among raft nodes before linearized reading' (duration: 179.323847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:01.534996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:01.232185Z","time spent":"302.801206ms","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T21:34:01.534992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:00.991073Z","time spent":"543.793405ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:34:02.206761Z","caller":"traceutil/trace.go:171","msg":"trace[1337742375] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"355.87729ms","start":"2026-02-28T21:34:01.850858Z","end":"2026-02-28T21:34:02.206735Z","steps":["trace[1337742375] 'process raft request' (duration: 355.720076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:02.206918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:01.850831Z","time spent":"356.007593ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:34:02.42607Z","caller":"traceutil/trace.go:171","msg":"trace[1108429602] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"241.700356ms","start":"2026-02-28T21:34:02.184337Z","end":"2026-02-28T21:34:02.426037Z","steps":["trace[1108429602] 'process raft request' (duration: 241.495181ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:02.750386Z","caller":"traceutil/trace.go:171","msg":"trace[291866719] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"252.832762ms","start":"2026-02-28T21:34:02.497509Z","end":"2026-02-28T21:34:02.750342Z","steps":["trace[291866719] 'process raft request' (duration: 229.858133ms)","trace[291866719] 'compare' (duration: 22.606171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:34:03.41974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.285907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:34:03.419879Z","caller":"traceutil/trace.go:171","msg":"trace[1305933595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"189.481902ms","start":"2026-02-28T21:34:03.230371Z","end":"2026-02-28T21:34:03.419852Z","steps":["trace[1305933595] 'range keys from in-memory index tree' (duration: 189.207776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:03.420129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.005324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-28T21:34:03.420214Z","caller":"traceutil/trace.go:171","msg":"trace[1181249464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"532.170017ms","start":"2026-02-28T21:34:02.888024Z","end":"2026-02-28T21:34:03.420194Z","steps":["trace[1181249464] 'range keys from in-memory index tree' (duration: 531.831449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:03.420258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:02.887994Z","time spent":"532.25468ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-28T21:34:03.859564Z","caller":"traceutil/trace.go:171","msg":"trace[725818015] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"320.246257ms","start":"2026-02-28T21:34:03.539289Z","end":"2026-02-28T21:34:03.859535Z","steps":["trace[725818015] 'read index received' (duration: 320.024532ms)","trace[725818015] 'applied index is now lower than readState.Index' (duration: 220.015µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:34:03.859698Z","caller":"traceutil/trace.go:171","msg":"trace[15599957] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"433.978972ms","start":"2026-02-28T21:34:03.42568Z","end":"2026-02-28T21:34:03.859659Z","steps":["trace[15599957] 'process raft request' (duration: 433.673555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:03.859921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.611256ms","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-28T21:34:03.859925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:03.425655Z","time spent":"434.166376ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:34:03.859917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.489308ms","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-28T21:34:03.859982Z","caller":"traceutil/trace.go:171","msg":"trace[432890915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:533; }","duration":"320.750849ms","start":"2026-02-28T21:34:03.539213Z","end":"2026-02-28T21:34:03.859964Z","steps":["trace[432890915] 'agreement among raft nodes before linearized reading' (duration: 320.508854ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:03.860027Z","caller":"traceutil/trace.go:171","msg":"trace[2124865533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:533; }","duration":"276.652622ms","start":"2026-02-28T21:34:03.583356Z","end":"2026-02-28T21:34:03.860009Z","steps":["trace[2124865533] 'agreement among raft nodes before linearized reading' (duration: 276.482158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:03.860034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:03.539188Z","time spent":"320.835802ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-28T21:34:23.526969Z","caller":"traceutil/trace.go:171","msg":"trace[1123300886] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"185.802363ms","start":"2026-02-28T21:34:23.341135Z","end":"2026-02-28T21:34:23.526938Z","steps":["trace[1123300886] 'process raft request' (duration: 185.615789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:24.239168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.989777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:34:24.239255Z","caller":"traceutil/trace.go:171","msg":"trace[687167821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"290.135611ms","start":"2026-02-28T21:34:23.9491Z","end":"2026-02-28T21:34:24.239235Z","steps":["trace[687167821] 'range keys from in-memory index tree' (duration: 289.834394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:24.239509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.355064ms","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-28T21:34:24.239585Z","caller":"traceutil/trace.go:171","msg":"trace[137398533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:719; }","duration":"240.447246ms","start":"2026-02-28T21:34:23.999119Z","end":"2026-02-28T21:34:24.239566Z","steps":["trace[137398533] 'range keys from in-memory index tree' (duration: 240.18341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:24.239547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.763048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-28T21:34:24.239672Z","caller":"traceutil/trace.go:171","msg":"trace[2120892993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:719; }","duration":"275.962072ms","start":"2026-02-28T21:34:23.963681Z","end":"2026-02-28T21:34:24.239643Z","steps":["trace[2120892993] 'range keys from in-memory index tree' (duration: 275.349468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:24.239692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.493379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-28T21:34:24.239498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.338081ms","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-28T21:34:24.239756Z","caller":"traceutil/trace.go:171","msg":"trace[1625505073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"238.651003ms","start":"2026-02-28T21:34:24.001087Z","end":"2026-02-28T21:34:24.239738Z","steps":["trace[1625505073] 'range keys from in-memory index tree' (duration: 237.964006ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:24.239912Z","caller":"traceutil/trace.go:171","msg":"trace[28238401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:719; }","duration":"242.911415ms","start":"2026-02-28T21:34:23.996965Z","end":"2026-02-28T21:34:24.239876Z","steps":["trace[28238401] 'range keys from in-memory index tree' (duration: 242.003394ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:24.463842Z","caller":"traceutil/trace.go:171","msg":"trace[1586096101] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"232.420025ms","start":"2026-02-28T21:34:24.231379Z","end":"2026-02-28T21:34:24.463799Z","steps":["trace[1586096101] 'read index received' (duration: 172.503006ms)","trace[1586096101] 'applied index is now lower than readState.Index' (duration: 59.903829ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:34:24.463941Z","caller":"traceutil/trace.go:171","msg":"trace[1633098447] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"219.559757ms","start":"2026-02-28T21:34:24.244347Z","end":"2026-02-28T21:34:24.463907Z","steps":["trace[1633098447] 'process raft request' (duration: 219.299131ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:24.464316Z","caller":"traceutil/trace.go:171","msg":"trace[283669574] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"218.906542ms","start":"2026-02-28T21:34:24.245372Z","end":"2026-02-28T21:34:24.464279Z","steps":["trace[283669574] 'process raft request' (duration: 218.478192ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:24.464713Z","caller":"traceutil/trace.go:171","msg":"trace[242674452] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"219.113877ms","start":"2026-02-28T21:34:24.245586Z","end":"2026-02-28T21:34:24.4647Z","steps":["trace[242674452] 'process raft request' (duration: 218.573074ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:24.464781Z","caller":"traceutil/trace.go:171","msg":"trace[854370158] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"217.371455ms","start":"2026-02-28T21:34:24.247397Z","end":"2026-02-28T21:34:24.464768Z","steps":["trace[854370158] 'process raft request' (duration: 217.255282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:24.464445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.030239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:34:24.46512Z","caller":"traceutil/trace.go:171","msg":"trace[606321698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"233.721316ms","start":"2026-02-28T21:34:24.231362Z","end":"2026-02-28T21:34:24.465083Z","steps":["trace[606321698] 'agreement among raft nodes before linearized reading' (duration: 232.525467ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:54.755223Z","caller":"traceutil/trace.go:171","msg":"trace[1613068032] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"137.040489ms","start":"2026-02-28T21:34:54.618148Z","end":"2026-02-28T21:34:54.755189Z","steps":["trace[1613068032] 'process raft request' (duration: 136.776013ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:54.75612Z","caller":"traceutil/trace.go:171","msg":"trace[1096414720] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"135.557303ms","start":"2026-02-28T21:34:54.620534Z","end":"2026-02-28T21:34:54.756091Z","steps":["trace[1096414720] 'process raft request' (duration: 135.478921ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:54.756339Z","caller":"traceutil/trace.go:171","msg":"trace[1349168281] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"135.40097ms","start":"2026-02-28T21:34:54.620911Z","end":"2026-02-28T21:34:54.756312Z","steps":["trace[1349168281] 'process raft request' (duration: 135.142493ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:34:54.756453Z","caller":"traceutil/trace.go:171","msg":"trace[321493353] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"137.657804ms","start":"2026-02-28T21:34:54.618784Z","end":"2026-02-28T21:34:54.756442Z","steps":["trace[321493353] 'process raft request' (duration: 137.061459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:34:58.545666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540974055812638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T21:34:59.046347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540974055812638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T21:34:59.546736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540974055812638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T21:35:00.047084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540974055812638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-28T21:35:00.05767Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.274715202s","expected-duration":"1s"} {"level":"info","ts":"2026-02-28T21:35:00.058198Z","caller":"traceutil/trace.go:171","msg":"trace[1643358370] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"2.275417568s","start":"2026-02-28T21:34:57.782751Z","end":"2026-02-28T21:35:00.058168Z","steps":["trace[1643358370] 'process raft request' (duration: 2.275245234s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.058358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:57.782721Z","time spent":"2.275538722s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:00.148782Z","caller":"traceutil/trace.go:171","msg":"trace[1609353907] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:852; }","duration":"2.104175593s","start":"2026-02-28T21:34:58.044583Z","end":"2026-02-28T21:35:00.148759Z","steps":["trace[1609353907] 'read index received' (duration: 2.014072824s)","trace[1609353907] 'applied index is now lower than readState.Index' (duration: 90.101529ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:00.148924Z","caller":"traceutil/trace.go:171","msg":"trace[875843947] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"1.106639047s","start":"2026-02-28T21:34:59.042232Z","end":"2026-02-28T21:35:00.148871Z","steps":["trace[875843947] 'process raft request' (duration: 1.096534497s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.149043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.10442949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:00.149133Z","caller":"traceutil/trace.go:171","msg":"trace[1838108499] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:821; }","duration":"2.104595774s","start":"2026-02-28T21:34:58.044515Z","end":"2026-02-28T21:35:00.149111Z","steps":["trace[1838108499] 'agreement among raft nodes before linearized reading' (duration: 2.104391308s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.149179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:58.044488Z","time spent":"2.104683266s","remote":"127.0.0.1:52522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-28T21:35:00.149232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:59.042211Z","time spent":"1.106855934s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:00.149353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307329726s","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-28T21:35:00.149403Z","caller":"traceutil/trace.go:171","msg":"trace[1966185011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:821; }","duration":"1.30752306s","start":"2026-02-28T21:34:58.841869Z","end":"2026-02-28T21:35:00.149392Z","steps":["trace[1966185011] 'agreement among raft nodes before linearized reading' (duration: 1.307262754s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.149438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:58.841861Z","time spent":"1.307570971s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-28T21:35:00.149583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.474917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T21:35:00.150538Z","caller":"traceutil/trace.go:171","msg":"trace[1655383916] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:821; }","duration":"394.423048ms","start":"2026-02-28T21:34:59.756062Z","end":"2026-02-28T21:35:00.150485Z","steps":["trace[1655383916] 'agreement among raft nodes before linearized reading' (duration: 393.486526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.150679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:59.756046Z","time spent":"394.576363ms","remote":"127.0.0.1:52470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-02-28T21:35:00.149654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.004276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T21:35:00.150863Z","caller":"traceutil/trace.go:171","msg":"trace[164369713] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:821; }","duration":"490.244345ms","start":"2026-02-28T21:34:59.66059Z","end":"2026-02-28T21:35:00.150834Z","steps":["trace[164369713] 'agreement among raft nodes before linearized reading' (duration: 489.027416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.150945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:59.660566Z","time spent":"490.356288ms","remote":"127.0.0.1:52610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-28T21:35:00.149646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372379437s","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-28T21:35:00.151054Z","caller":"traceutil/trace.go:171","msg":"trace[561110978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:821; }","duration":"1.373835791s","start":"2026-02-28T21:34:58.777189Z","end":"2026-02-28T21:35:00.151025Z","steps":["trace[561110978] 'agreement among raft nodes before linearized reading' (duration: 1.372202293s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.14971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.759021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T21:35:00.151126Z","caller":"traceutil/trace.go:171","msg":"trace[1541081242] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:821; }","duration":"981.235006ms","start":"2026-02-28T21:34:59.169878Z","end":"2026-02-28T21:35:00.151113Z","steps":["trace[1541081242] 'agreement among raft nodes before linearized reading' (duration: 979.803712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.151137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:58.777164Z","time spent":"1.373951004s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T21:35:00.151181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:59.169851Z","time spent":"981.315358ms","remote":"127.0.0.1:52658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-28T21:35:00.149775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308979785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-28T21:35:00.151343Z","caller":"traceutil/trace.go:171","msg":"trace[1258857077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:821; }","duration":"1.310543771s","start":"2026-02-28T21:34:58.840788Z","end":"2026-02-28T21:35:00.151332Z","steps":["trace[1258857077] 'agreement among raft nodes before linearized reading' (duration: 1.308920123s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.151387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:58.840715Z","time spent":"1.310660573s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-28T21:35:00.14985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309129757s","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-28T21:35:00.151478Z","caller":"traceutil/trace.go:171","msg":"trace[1118191245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:821; }","duration":"1.310773967s","start":"2026-02-28T21:34:58.840692Z","end":"2026-02-28T21:35:00.151466Z","steps":["trace[1118191245] 'agreement among raft nodes before linearized reading' (duration: 1.309110448s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.151526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:58.840679Z","time spent":"1.310834379s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-28T21:35:00.149862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580523012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T21:35:00.151592Z","caller":"traceutil/trace.go:171","msg":"trace[1344981606] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:821; }","duration":"1.582271023s","start":"2026-02-28T21:34:58.569295Z","end":"2026-02-28T21:35:00.151566Z","steps":["trace[1344981606] 'agreement among raft nodes before linearized reading' (duration: 1.580525712s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.149982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461489692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-28T21:35:00.151669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:58.569272Z","time spent":"1.582372555s","remote":"127.0.0.1:52784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-02-28T21:35:00.151711Z","caller":"traceutil/trace.go:171","msg":"trace[672898498] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:821; }","duration":"1.463276145s","start":"2026-02-28T21:34:58.688422Z","end":"2026-02-28T21:35:00.151698Z","steps":["trace[672898498] 'agreement among raft nodes before linearized reading' (duration: 1.46137672s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.151755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:58.688396Z","time spent":"1.463347447s","remote":"127.0.0.1:52252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"warn","ts":"2026-02-28T21:35:00.150016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.919382255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:00.152013Z","caller":"traceutil/trace.go:171","msg":"trace[756800435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:821; }","duration":"1.921405394s","start":"2026-02-28T21:34:58.230588Z","end":"2026-02-28T21:35:00.151994Z","steps":["trace[756800435] 'agreement among raft nodes before linearized reading' (duration: 1.919405786s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.15208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:34:58.230567Z","time spent":"1.921495895s","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T21:35:00.459297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.384536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T21:35:00.459608Z","caller":"traceutil/trace.go:171","msg":"trace[1155960403] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"301.941272ms","start":"2026-02-28T21:35:00.157646Z","end":"2026-02-28T21:35:00.459587Z","steps":["trace[1155960403] 'process raft request' (duration: 301.851959ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:00.459779Z","caller":"traceutil/trace.go:171","msg":"trace[1043333320] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"305.402716ms","start":"2026-02-28T21:35:00.154356Z","end":"2026-02-28T21:35:00.459758Z","steps":["trace[1043333320] 'process raft request' (duration: 136.458386ms)","trace[1043333320] 'compare' (duration: 168.11561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:00.459786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:00.157623Z","time spent":"302.068125ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:00.459896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:00.154335Z","time spent":"305.491957ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:00.459869Z","caller":"traceutil/trace.go:171","msg":"trace[1691746237] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"304.214256ms","start":"2026-02-28T21:35:00.155615Z","end":"2026-02-28T21:35:00.459829Z","steps":["trace[1691746237] 'process raft request' (duration: 303.790796ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:00.460046Z","caller":"traceutil/trace.go:171","msg":"trace[1573362482] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"304.247697ms","start":"2026-02-28T21:35:00.155762Z","end":"2026-02-28T21:35:00.46001Z","steps":["trace[1573362482] 'process raft request' (duration: 303.698954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:00.460082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:00.155589Z","time spent":"304.388152ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:00.460953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:00.155722Z","time spent":"304.443812ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:01.289155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.794659ms","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-28T21:35:01.289554Z","caller":"traceutil/trace.go:171","msg":"trace[1194134778] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:825; }","duration":"268.221919ms","start":"2026-02-28T21:35:01.021306Z","end":"2026-02-28T21:35:01.289528Z","steps":["trace[1194134778] 'range keys from in-memory index tree' (duration: 267.768218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:01.290418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.870636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T21:35:01.29054Z","caller":"traceutil/trace.go:171","msg":"trace[691177102] linearizableReadLoop","detail":"{readStateIndex:859; appliedIndex:854; }","duration":"1.091122387s","start":"2026-02-28T21:35:00.199397Z","end":"2026-02-28T21:35:01.290519Z","steps":["trace[691177102] 'read index received' (duration: 91.43119ms)","trace[691177102] 'applied index is now lower than readState.Index' (duration: 999.689337ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:01.290675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091285451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:01.290717Z","caller":"traceutil/trace.go:171","msg":"trace[55374986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:826; }","duration":"1.091358514s","start":"2026-02-28T21:35:00.199342Z","end":"2026-02-28T21:35:01.290701Z","steps":["trace[55374986] 'agreement among raft nodes before linearized reading' (duration: 1.091238421s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:01.290754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:00.199318Z","time spent":"1.091428976s","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-28T21:35:01.290776Z","caller":"traceutil/trace.go:171","msg":"trace[1662929284] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"1.098800091s","start":"2026-02-28T21:35:00.19175Z","end":"2026-02-28T21:35:01.29055Z","steps":["trace[1662929284] 'process raft request' (duration: 595.647888ms)","trace[1662929284] 'compare' (duration: 501.907924ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:01.290994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:00.191731Z","time spent":"1.099144379s","remote":"127.0.0.1:52252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:02.729551Z","caller":"traceutil/trace.go:171","msg":"trace[1066915435] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"255.878595ms","start":"2026-02-28T21:35:02.473627Z","end":"2026-02-28T21:35:02.729506Z","steps":["trace[1066915435] 'process raft request' (duration: 240.830235ms)","trace[1066915435] 'compare' (duration: 14.738732ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:02.729618Z","caller":"traceutil/trace.go:171","msg":"trace[422897070] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"255.733161ms","start":"2026-02-28T21:35:02.473857Z","end":"2026-02-28T21:35:02.72959Z","steps":["trace[422897070] 'process raft request' (duration: 255.488875ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:02.729508Z","caller":"traceutil/trace.go:171","msg":"trace[912540035] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"255.601798ms","start":"2026-02-28T21:35:02.473874Z","end":"2026-02-28T21:35:02.729476Z","steps":["trace[912540035] 'process raft request' (duration: 255.524236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:03.977707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.926933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:03.977817Z","caller":"traceutil/trace.go:171","msg":"trace[1412612119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:831; }","duration":"746.142098ms","start":"2026-02-28T21:35:03.231652Z","end":"2026-02-28T21:35:03.977794Z","steps":["trace[1412612119] 'range keys from in-memory index tree' (duration: 745.820171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:03.977875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:03.231624Z","time spent":"746.240531ms","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T21:35:04.355725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.55516ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-28T21:35:04.355965Z","caller":"traceutil/trace.go:171","msg":"trace[164198715] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"201.030816ms","start":"2026-02-28T21:35:04.154903Z","end":"2026-02-28T21:35:04.355934Z","steps":["trace[164198715] 'read index received' (duration: 84.245921ms)","trace[164198715] 'applied index is now lower than readState.Index' (duration: 116.782305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:04.356239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.321423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-28T21:35:04.356304Z","caller":"traceutil/trace.go:171","msg":"trace[593432391] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:832; }","duration":"201.446286ms","start":"2026-02-28T21:35:04.154841Z","end":"2026-02-28T21:35:04.356288Z","steps":["trace[593432391] 'agreement among raft nodes before linearized reading' (duration: 201.19871ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:04.61353Z","caller":"traceutil/trace.go:171","msg":"trace[222085200] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"115.779492ms","start":"2026-02-28T21:35:04.497721Z","end":"2026-02-28T21:35:04.613501Z","steps":["trace[222085200] 'process raft request' (duration: 21.332199ms)","trace[222085200] 'compare' (duration: 94.285579ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:07.00053Z","caller":"traceutil/trace.go:171","msg":"trace[494037860] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"102.426783ms","start":"2026-02-28T21:35:06.898069Z","end":"2026-02-28T21:35:07.000496Z","steps":["trace[494037860] 'process raft request' (duration: 102.125786ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:07.039203Z","caller":"traceutil/trace.go:171","msg":"trace[1222427545] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"141.002143ms","start":"2026-02-28T21:35:06.898171Z","end":"2026-02-28T21:35:07.039173Z","steps":["trace[1222427545] 'process raft request' (duration: 140.843849ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:14.442375Z","caller":"traceutil/trace.go:171","msg":"trace[1592147012] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"311.650874ms","start":"2026-02-28T21:35:14.130697Z","end":"2026-02-28T21:35:14.442348Z","steps":["trace[1592147012] 'process raft request' (duration: 311.365117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:14.442556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:14.130669Z","time spent":"311.790467ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":32870,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-28T21:35:14.465136Z","caller":"traceutil/trace.go:171","msg":"trace[646310469] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:895; }","duration":"235.436437ms","start":"2026-02-28T21:35:14.229671Z","end":"2026-02-28T21:35:14.465107Z","steps":["trace[646310469] 'read index received' (duration: 212.493629ms)","trace[646310469] 'applied index is now lower than readState.Index' (duration: 22.941698ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:14.465185Z","caller":"traceutil/trace.go:171","msg":"trace[2140299923] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"303.197093ms","start":"2026-02-28T21:35:14.161958Z","end":"2026-02-28T21:35:14.465155Z","steps":["trace[2140299923] 'process raft request' (duration: 303.014808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:14.465285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.59715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:14.465381Z","caller":"traceutil/trace.go:171","msg":"trace[759057260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"235.709523ms","start":"2026-02-28T21:35:14.22961Z","end":"2026-02-28T21:35:14.465319Z","steps":["trace[759057260] 'agreement among raft nodes before linearized reading' (duration: 235.622281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:14.465571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:14.161923Z","time spent":"303.337395ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:14.494015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.422549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:14.494081Z","caller":"traceutil/trace.go:171","msg":"trace[912658157] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:863; }","duration":"140.521682ms","start":"2026-02-28T21:35:14.353543Z","end":"2026-02-28T21:35:14.494064Z","steps":["trace[912658157] 'agreement among raft nodes before linearized reading' (duration: 140.373828ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:14.646998Z","caller":"traceutil/trace.go:171","msg":"trace[578156833] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"148.938313ms","start":"2026-02-28T21:35:14.498031Z","end":"2026-02-28T21:35:14.646969Z","steps":["trace[578156833] 'process raft request' (duration: 148.790469ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:14.689103Z","caller":"traceutil/trace.go:171","msg":"trace[891455534] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"190.728809ms","start":"2026-02-28T21:35:14.49834Z","end":"2026-02-28T21:35:14.689069Z","steps":["trace[891455534] 'process raft request' (duration: 190.561055ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:14.802826Z","caller":"traceutil/trace.go:171","msg":"trace[1437668878] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"151.172416ms","start":"2026-02-28T21:35:14.651619Z","end":"2026-02-28T21:35:14.802791Z","steps":["trace[1437668878] 'process raft request' (duration: 150.970991ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:14.802856Z","caller":"traceutil/trace.go:171","msg":"trace[452045875] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"150.819087ms","start":"2026-02-28T21:35:14.652006Z","end":"2026-02-28T21:35:14.802826Z","steps":["trace[452045875] 'process raft request' (duration: 150.710444ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:14.802812Z","caller":"traceutil/trace.go:171","msg":"trace[138840631] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:900; }","duration":"144.088107ms","start":"2026-02-28T21:35:14.6587Z","end":"2026-02-28T21:35:14.802788Z","steps":["trace[138840631] 'read index received' (duration: 30.194911ms)","trace[138840631] 'applied index is now lower than readState.Index' (duration: 113.892126ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:14.802983Z","caller":"traceutil/trace.go:171","msg":"trace[787077185] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"150.829577ms","start":"2026-02-28T21:35:14.652091Z","end":"2026-02-28T21:35:14.802921Z","steps":["trace[787077185] 'process raft request' (duration: 150.664783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:14.803304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.525818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T21:35:14.803449Z","caller":"traceutil/trace.go:171","msg":"trace[641272907] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:868; }","duration":"144.781544ms","start":"2026-02-28T21:35:14.658648Z","end":"2026-02-28T21:35:14.80343Z","steps":["trace[641272907] 'agreement among raft nodes before linearized reading' (duration: 144.24209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:14.803522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.702542ms","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-28T21:35:14.803718Z","caller":"traceutil/trace.go:171","msg":"trace[641835075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:868; }","duration":"144.954358ms","start":"2026-02-28T21:35:14.658747Z","end":"2026-02-28T21:35:14.803702Z","steps":["trace[641835075] 'agreement among raft nodes before linearized reading' (duration: 144.548018ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:15.029695Z","caller":"traceutil/trace.go:171","msg":"trace[87859472] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"183.538898ms","start":"2026-02-28T21:35:14.846125Z","end":"2026-02-28T21:35:15.029664Z","steps":["trace[87859472] 'process raft request' (duration: 163.022539ms)","trace[87859472] 'compare' (duration: 20.301434ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:15.426043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.816321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T21:35:15.426156Z","caller":"traceutil/trace.go:171","msg":"trace[1857804200] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:899; }","duration":"104.967684ms","start":"2026-02-28T21:35:15.321164Z","end":"2026-02-28T21:35:15.426132Z","steps":["trace[1857804200] 'agreement among raft nodes before linearized reading' (duration: 104.583645ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:16.555202Z","caller":"traceutil/trace.go:171","msg":"trace[2140590216] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"143.616615ms","start":"2026-02-28T21:35:16.411553Z","end":"2026-02-28T21:35:16.55517Z","steps":["trace[2140590216] 'process raft request' (duration: 143.442111ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:17.288586Z","caller":"traceutil/trace.go:171","msg":"trace[1515855345] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"210.139783ms","start":"2026-02-28T21:35:17.078422Z","end":"2026-02-28T21:35:17.288562Z","steps":["trace[1515855345] 'read index received' (duration: 209.957739ms)","trace[1515855345] 'applied index is now lower than readState.Index' (duration: 180.814µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:17.28863Z","caller":"traceutil/trace.go:171","msg":"trace[1302855496] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"245.798153ms","start":"2026-02-28T21:35:17.04281Z","end":"2026-02-28T21:35:17.288608Z","steps":["trace[1302855496] 'process raft request' (duration: 245.590159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:17.288866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.41397ms","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-28T21:35:17.28891Z","caller":"traceutil/trace.go:171","msg":"trace[407993721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"210.515372ms","start":"2026-02-28T21:35:17.078383Z","end":"2026-02-28T21:35:17.288899Z","steps":["trace[407993721] 'agreement among raft nodes before linearized reading' (duration: 210.310467ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:17.609786Z","caller":"traceutil/trace.go:171","msg":"trace[698686159] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"316.627573ms","start":"2026-02-28T21:35:17.293128Z","end":"2026-02-28T21:35:17.609755Z","steps":["trace[698686159] 'process raft request' (duration: 316.253514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:17.610288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:17.293093Z","time spent":"316.846539ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:20.099689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.581039ms","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-28T21:35:20.09976Z","caller":"traceutil/trace.go:171","msg":"trace[1523711703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"485.726432ms","start":"2026-02-28T21:35:19.614017Z","end":"2026-02-28T21:35:20.099743Z","steps":["trace[1523711703] 'range keys from in-memory index tree' (duration: 485.404164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:20.099795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:19.613982Z","time spent":"485.806034ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-28T21:35:20.294569Z","caller":"traceutil/trace.go:171","msg":"trace[266023495] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"191.337815ms","start":"2026-02-28T21:35:20.103193Z","end":"2026-02-28T21:35:20.294531Z","steps":["trace[266023495] 'process raft request' (duration: 191.172991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.359664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323650616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T21:35:22.359767Z","caller":"traceutil/trace.go:171","msg":"trace[2029690923] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:928; }","duration":"1.3238288s","start":"2026-02-28T21:35:21.035914Z","end":"2026-02-28T21:35:22.359743Z","steps":["trace[2029690923] 'count revisions from in-memory index tree' (duration: 1.323544013s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.359824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:21.035884Z","time spent":"1.323928742s","remote":"127.0.0.1:55690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":2,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-02-28T21:35:22.360272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130097459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:22.360364Z","caller":"traceutil/trace.go:171","msg":"trace[1464591636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"1.130251642s","start":"2026-02-28T21:35:21.230089Z","end":"2026-02-28T21:35:22.36034Z","steps":["trace[1464591636] 'range keys from in-memory index tree' (duration: 1.129946446s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.36041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:21.230059Z","time spent":"1.130342445s","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T21:35:22.360427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617172095s","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-28T21:35:22.360466Z","caller":"traceutil/trace.go:171","msg":"trace[1369262267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"1.617291418s","start":"2026-02-28T21:35:20.743164Z","end":"2026-02-28T21:35:22.360455Z","steps":["trace[1369262267] 'range keys from in-memory index tree' (duration: 1.616996022s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.360498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:20.743136Z","time spent":"1.61735467s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-28T21:35:22.360822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503966201s","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-28T21:35:22.360853Z","caller":"traceutil/trace.go:171","msg":"trace[550568077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"1.504058094s","start":"2026-02-28T21:35:20.856786Z","end":"2026-02-28T21:35:22.360844Z","steps":["trace[550568077] 'range keys from in-memory index tree' (duration: 1.503831099s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.360886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:20.856754Z","time spent":"1.504125685s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T21:35:22.360897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.762883ms","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-28T21:35:22.360937Z","caller":"traceutil/trace.go:171","msg":"trace[572862641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"973.842264ms","start":"2026-02-28T21:35:21.387082Z","end":"2026-02-28T21:35:22.360924Z","steps":["trace[572862641] 'range keys from in-memory index tree' (duration: 973.593109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.36097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:21.387058Z","time spent":"973.898956ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-28T21:35:22.360965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.842304ms","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-28T21:35:22.361065Z","caller":"traceutil/trace.go:171","msg":"trace[517129616] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:928; }","duration":"910.989907ms","start":"2026-02-28T21:35:21.450046Z","end":"2026-02-28T21:35:22.361036Z","steps":["trace[517129616] 'count revisions from in-memory index tree' (duration: 910.683271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.361116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:21.450032Z","time spent":"911.07127ms","remote":"127.0.0.1:52650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-28T21:35:22.361094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339648437s","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-28T21:35:22.361175Z","caller":"traceutil/trace.go:171","msg":"trace[52217633] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:928; }","duration":"1.339732179s","start":"2026-02-28T21:35:21.021433Z","end":"2026-02-28T21:35:22.361166Z","steps":["trace[52217633] 'range keys from in-memory index tree' (duration: 1.339639477s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.361526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.487466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:22.36157Z","caller":"traceutil/trace.go:171","msg":"trace[1732446184] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:928; }","duration":"288.569008ms","start":"2026-02-28T21:35:22.072986Z","end":"2026-02-28T21:35:22.361555Z","steps":["trace[1732446184] 'count revisions from in-memory index tree' (duration: 288.432045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.361656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.060699954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-28T21:35:22.361706Z","caller":"traceutil/trace.go:171","msg":"trace[329157059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"1.060802227s","start":"2026-02-28T21:35:21.300887Z","end":"2026-02-28T21:35:22.361689Z","steps":["trace[329157059] 'range keys from in-memory index tree' (duration: 1.060556072s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.361749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:21.300858Z","time spent":"1.060878968s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-28T21:35:22.738519Z","caller":"traceutil/trace.go:171","msg":"trace[715709958] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"371.531429ms","start":"2026-02-28T21:35:22.366959Z","end":"2026-02-28T21:35:22.738491Z","steps":["trace[715709958] 'process raft request' (duration: 371.377706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.738704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:22.366932Z","time spent":"371.675672ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:22.746579Z","caller":"traceutil/trace.go:171","msg":"trace[2093367446] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:968; }","duration":"373.990015ms","start":"2026-02-28T21:35:22.372577Z","end":"2026-02-28T21:35:22.746567Z","steps":["trace[2093367446] 'read index received' (duration: 366.071976ms)","trace[2093367446] 'applied index is now lower than readState.Index' (duration: 7.916989ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:22.746669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.071317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:22.746698Z","caller":"traceutil/trace.go:171","msg":"trace[188759409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"374.128808ms","start":"2026-02-28T21:35:22.372561Z","end":"2026-02-28T21:35:22.74669Z","steps":["trace[188759409] 'agreement among raft nodes before linearized reading' (duration: 374.048626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.746723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:22.372553Z","time spent":"374.165218ms","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-28T21:35:22.746971Z","caller":"traceutil/trace.go:171","msg":"trace[120942500] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"379.314075ms","start":"2026-02-28T21:35:22.367622Z","end":"2026-02-28T21:35:22.746936Z","steps":["trace[120942500] 'process raft request' (duration: 378.818634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.747015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:22.367595Z","time spent":"379.397117ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:22.747105Z","caller":"traceutil/trace.go:171","msg":"trace[902991407] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"378.930777ms","start":"2026-02-28T21:35:22.368169Z","end":"2026-02-28T21:35:22.747099Z","steps":["trace[902991407] 'process raft request' (duration: 378.322013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.747144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:22.368156Z","time spent":"378.967287ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:22.747275Z","caller":"traceutil/trace.go:171","msg":"trace[340558631] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"378.869446ms","start":"2026-02-28T21:35:22.368399Z","end":"2026-02-28T21:35:22.747268Z","steps":["trace[340558631] 'process raft request' (duration: 378.117999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.747318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:22.368389Z","time spent":"378.907966ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:22.747413Z","caller":"traceutil/trace.go:171","msg":"trace[189725243] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"378.62248ms","start":"2026-02-28T21:35:22.368783Z","end":"2026-02-28T21:35:22.747406Z","steps":["trace[189725243] 'process raft request' (duration: 377.76127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:22.747453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:22.368755Z","time spent":"378.680691ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:32.901805Z","caller":"traceutil/trace.go:171","msg":"trace[673696106] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"103.088735ms","start":"2026-02-28T21:35:32.79867Z","end":"2026-02-28T21:35:32.901759Z","steps":["trace[673696106] 'process raft request' (duration: 39.645565ms)","trace[673696106] 'compare' (duration: 63.102822ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:32.901983Z","caller":"traceutil/trace.go:171","msg":"trace[1398631547] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"102.033441ms","start":"2026-02-28T21:35:32.799909Z","end":"2026-02-28T21:35:32.901942Z","steps":["trace[1398631547] 'process raft request' (duration: 101.901228ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:32.902145Z","caller":"traceutil/trace.go:171","msg":"trace[1281482140] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"100.803704ms","start":"2026-02-28T21:35:32.80114Z","end":"2026-02-28T21:35:32.901944Z","steps":["trace[1281482140] 'process raft request' (duration: 100.733672ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:38.540195Z","caller":"traceutil/trace.go:171","msg":"trace[1920868278] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"309.988713ms","start":"2026-02-28T21:35:38.230177Z","end":"2026-02-28T21:35:38.540165Z","steps":["trace[1920868278] 'read index received' (duration: 309.753158ms)","trace[1920868278] 'applied index is now lower than readState.Index' (duration: 231.865µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:38.540248Z","caller":"traceutil/trace.go:171","msg":"trace[2100023322] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"650.451891ms","start":"2026-02-28T21:35:37.889754Z","end":"2026-02-28T21:35:38.540206Z","steps":["trace[2100023322] 'process raft request' (duration: 650.240276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:38.540412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:37.889709Z","time spent":"650.612594ms","remote":"127.0.0.1:52404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5310,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:38.540453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.236138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-mxng7\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-02-28T21:35:38.540498Z","caller":"traceutil/trace.go:171","msg":"trace[255929243] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-mxng7; range_end:; response_count:1; response_revision:987; }","duration":"310.352731ms","start":"2026-02-28T21:35:38.230129Z","end":"2026-02-28T21:35:38.540481Z","steps":["trace[255929243] 'agreement among raft nodes before linearized reading' (duration: 310.146566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:38.540565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:38.230108Z","time spent":"310.446192ms","remote":"127.0.0.1:52412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11085,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-mxng7\" "} {"level":"info","ts":"2026-02-28T21:35:39.149487Z","caller":"traceutil/trace.go:171","msg":"trace[692519783] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"216.083844ms","start":"2026-02-28T21:35:38.933371Z","end":"2026-02-28T21:35:39.149455Z","steps":["trace[692519783] 'read index received' (duration: 182.065597ms)","trace[692519783] 'applied index is now lower than readState.Index' (duration: 34.016887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:39.149939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.803337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-28T21:35:39.149994Z","caller":"traceutil/trace.go:171","msg":"trace[35058924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"215.955491ms","start":"2026-02-28T21:35:38.934027Z","end":"2026-02-28T21:35:39.149983Z","steps":["trace[35058924] 'agreement among raft nodes before linearized reading' (duration: 215.728466ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:39.149926Z","caller":"traceutil/trace.go:171","msg":"trace[1462881299] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"217.824563ms","start":"2026-02-28T21:35:38.931968Z","end":"2026-02-28T21:35:39.149792Z","steps":["trace[1462881299] 'process raft request' (duration: 183.427347ms)","trace[1462881299] 'compare' (duration: 33.934535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:39.149979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.511554ms","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-28T21:35:39.150064Z","caller":"traceutil/trace.go:171","msg":"trace[202289592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"216.698919ms","start":"2026-02-28T21:35:38.933343Z","end":"2026-02-28T21:35:39.150042Z","steps":["trace[202289592] 'agreement among raft nodes before linearized reading' (duration: 216.315349ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:41.053723Z","caller":"traceutil/trace.go:171","msg":"trace[1756827728] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"216.40178ms","start":"2026-02-28T21:35:40.837297Z","end":"2026-02-28T21:35:41.053699Z","steps":["trace[1756827728] 'read index received' (duration: 216.287317ms)","trace[1756827728] 'applied index is now lower than readState.Index' (duration: 113.073µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:41.053784Z","caller":"traceutil/trace.go:171","msg":"trace[1438740867] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"220.967654ms","start":"2026-02-28T21:35:40.832773Z","end":"2026-02-28T21:35:41.053741Z","steps":["trace[1438740867] 'process raft request' (duration: 220.78137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:41.054023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.686177ms","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-28T21:35:41.054071Z","caller":"traceutil/trace.go:171","msg":"trace[914765369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"216.8293ms","start":"2026-02-28T21:35:40.83723Z","end":"2026-02-28T21:35:41.05406Z","steps":["trace[914765369] 'agreement among raft nodes before linearized reading' (duration: 216.563394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:41.654953Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540974055813072,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-28T21:35:42.024401Z","caller":"traceutil/trace.go:171","msg":"trace[722571713] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"870.33009ms","start":"2026-02-28T21:35:41.154039Z","end":"2026-02-28T21:35:42.024369Z","steps":["trace[722571713] 'read index received' (duration: 870.040054ms)","trace[722571713] 'applied index is now lower than readState.Index' (duration: 288.426µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:42.024464Z","caller":"traceutil/trace.go:171","msg":"trace[514928650] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"895.421928ms","start":"2026-02-28T21:35:41.129001Z","end":"2026-02-28T21:35:42.024423Z","steps":["trace[514928650] 'process raft request' (duration: 895.166162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:42.024665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:41.128961Z","time spent":"895.59415ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:42.024724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.06896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-28T21:35:42.024707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.636308ms","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-28T21:35:42.024774Z","caller":"traceutil/trace.go:171","msg":"trace[1929900838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"829.139741ms","start":"2026-02-28T21:35:41.195622Z","end":"2026-02-28T21:35:42.024762Z","steps":["trace[1929900838] 'agreement among raft nodes before linearized reading' (duration: 828.926256ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:42.024806Z","caller":"traceutil/trace.go:171","msg":"trace[1857857695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"870.817272ms","start":"2026-02-28T21:35:41.153965Z","end":"2026-02-28T21:35:42.024783Z","steps":["trace[1857857695] 'agreement among raft nodes before linearized reading' (duration: 870.482834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:42.024878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:41.153922Z","time spent":"870.945924ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-28T21:35:42.02491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.551302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-28T21:35:42.024717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.526476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-28T21:35:42.024947Z","caller":"traceutil/trace.go:171","msg":"trace[783074648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"818.615984ms","start":"2026-02-28T21:35:41.206322Z","end":"2026-02-28T21:35:42.024938Z","steps":["trace[783074648] 'agreement among raft nodes before linearized reading' (duration: 818.527662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:42.024981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:41.206308Z","time spent":"818.667605ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-28T21:35:42.02498Z","caller":"traceutil/trace.go:171","msg":"trace[198993447] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1001; }","duration":"526.857743ms","start":"2026-02-28T21:35:41.498097Z","end":"2026-02-28T21:35:42.024955Z","steps":["trace[198993447] 'agreement among raft nodes before linearized reading' (duration: 526.521076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:42.024822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:41.19556Z","time spent":"829.250344ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-28T21:35:42.025035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:41.498071Z","time spent":"526.951406ms","remote":"127.0.0.1:36330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-28T21:35:42.024818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.60981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:42.025106Z","caller":"traceutil/trace.go:171","msg":"trace[1043643933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1001; }","duration":"793.952507ms","start":"2026-02-28T21:35:41.231139Z","end":"2026-02-28T21:35:42.025092Z","steps":["trace[1043643933] 'agreement among raft nodes before linearized reading' (duration: 793.657221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:42.025157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:41.231107Z","time spent":"794.03955ms","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-28T21:35:42.325977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.218073ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T21:35:42.326185Z","caller":"traceutil/trace.go:171","msg":"trace[1136461653] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"295.29402ms","start":"2026-02-28T21:35:42.030876Z","end":"2026-02-28T21:35:42.32617Z","steps":["trace[1136461653] 'process raft request' (duration: 295.260449ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:42.326292Z","caller":"traceutil/trace.go:171","msg":"trace[1544184160] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"295.534945ms","start":"2026-02-28T21:35:42.030749Z","end":"2026-02-28T21:35:42.326284Z","steps":["trace[1544184160] 'process raft request' (duration: 103.900633ms)","trace[1544184160] 'compare' (duration: 191.017159ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:42.326342Z","caller":"traceutil/trace.go:171","msg":"trace[1177516701] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"295.520255ms","start":"2026-02-28T21:35:42.030817Z","end":"2026-02-28T21:35:42.326337Z","steps":["trace[1177516701] 'process raft request' (duration: 295.248899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:43.609753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540974055813082,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-28T21:35:43.85778Z","caller":"traceutil/trace.go:171","msg":"trace[1693882064] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"748.044512ms","start":"2026-02-28T21:35:43.109709Z","end":"2026-02-28T21:35:43.857754Z","steps":["trace[1693882064] 'read index received' (duration: 747.788247ms)","trace[1693882064] 'applied index is now lower than readState.Index' (duration: 254.985µs)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:43.858007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.776959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:43.858065Z","caller":"traceutil/trace.go:171","msg":"trace[1830425938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"607.897981ms","start":"2026-02-28T21:35:43.250154Z","end":"2026-02-28T21:35:43.858052Z","steps":["trace[1830425938] 'agreement among raft nodes before linearized reading' (duration: 607.786598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:43.85811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.39581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-28T21:35:43.858127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:43.250137Z","time spent":"607.979943ms","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-28T21:35:43.858183Z","caller":"traceutil/trace.go:171","msg":"trace[262367420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1006; }","duration":"748.481012ms","start":"2026-02-28T21:35:43.109661Z","end":"2026-02-28T21:35:43.858142Z","steps":["trace[262367420] 'agreement among raft nodes before linearized reading' (duration: 748.272367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:43.858225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:43.109642Z","time spent":"748.574974ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-28T21:35:43.858188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.613873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:43.858306Z","caller":"traceutil/trace.go:171","msg":"trace[1641352949] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1006; }","duration":"168.813267ms","start":"2026-02-28T21:35:43.68947Z","end":"2026-02-28T21:35:43.858283Z","steps":["trace[1641352949] 'agreement among raft nodes before linearized reading' (duration: 168.577162ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:43.857869Z","caller":"traceutil/trace.go:171","msg":"trace[159658506] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"796.092796ms","start":"2026-02-28T21:35:43.061753Z","end":"2026-02-28T21:35:43.857846Z","steps":["trace[159658506] 'process raft request' (duration: 795.82888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:43.858813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:43.061734Z","time spent":"796.764201ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:44.589767Z","caller":"traceutil/trace.go:171","msg":"trace[358166743] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"259.912621ms","start":"2026-02-28T21:35:44.329827Z","end":"2026-02-28T21:35:44.589739Z","steps":["trace[358166743] 'read index received' (duration: 230.514769ms)","trace[358166743] 'applied index is now lower than readState.Index' (duration: 29.395922ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:44.590172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.334172ms","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-28T21:35:44.590223Z","caller":"traceutil/trace.go:171","msg":"trace[1771165048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"260.452275ms","start":"2026-02-28T21:35:44.329759Z","end":"2026-02-28T21:35:44.590211Z","steps":["trace[1771165048] 'agreement among raft nodes before linearized reading' (duration: 260.124207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:44.590203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.196637ms","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-28T21:35:44.59031Z","caller":"traceutil/trace.go:171","msg":"trace[1396683244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"259.36897ms","start":"2026-02-28T21:35:44.330914Z","end":"2026-02-28T21:35:44.590283Z","steps":["trace[1396683244] 'agreement among raft nodes before linearized reading' (duration: 259.037913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:44.590409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.926965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:44.590474Z","caller":"traceutil/trace.go:171","msg":"trace[1654448346] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1007; }","duration":"116.076118ms","start":"2026-02-28T21:35:44.474382Z","end":"2026-02-28T21:35:44.590458Z","steps":["trace[1654448346] 'agreement among raft nodes before linearized reading' (duration: 115.943905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:44.590746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.995879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-28T21:35:44.590824Z","caller":"traceutil/trace.go:171","msg":"trace[562756487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"258.281375ms","start":"2026-02-28T21:35:44.332523Z","end":"2026-02-28T21:35:44.590805Z","steps":["trace[562756487] 'agreement among raft nodes before linearized reading' (duration: 257.810795ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:45.025526Z","caller":"traceutil/trace.go:171","msg":"trace[1684182462] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"103.246959ms","start":"2026-02-28T21:35:44.922228Z","end":"2026-02-28T21:35:45.025475Z","steps":["trace[1684182462] 'process raft request' (duration: 41.475896ms)","trace[1684182462] 'compare' (duration: 61.564269ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:46.016753Z","caller":"traceutil/trace.go:171","msg":"trace[667511438] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"148.836567ms","start":"2026-02-28T21:35:45.867871Z","end":"2026-02-28T21:35:46.016707Z","steps":["trace[667511438] 'process raft request' (duration: 148.52165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:46.388255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.578558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-28T21:35:46.388679Z","caller":"traceutil/trace.go:171","msg":"trace[1638560530] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"367.604582ms","start":"2026-02-28T21:35:46.021044Z","end":"2026-02-28T21:35:46.388648Z","steps":["trace[1638560530] 'process raft request' (duration: 102.546073ms)","trace[1638560530] 'compare' (duration: 264.357953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:46.388743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:46.021019Z","time spent":"367.698094ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:46.796996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.296175ms","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-28T21:35:46.797101Z","caller":"traceutil/trace.go:171","msg":"trace[1412640858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"156.493429ms","start":"2026-02-28T21:35:46.640586Z","end":"2026-02-28T21:35:46.79708Z","steps":["trace[1412640858] 'range keys from in-memory index tree' (duration: 155.974817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:46.797076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.604414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-28T21:35:46.7972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.684206ms","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-28T21:35:46.797339Z","caller":"traceutil/trace.go:171","msg":"trace[652406748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1019; }","duration":"141.860789ms","start":"2026-02-28T21:35:46.655454Z","end":"2026-02-28T21:35:46.797315Z","steps":["trace[652406748] 'range keys from in-memory index tree' (duration: 141.236895ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:46.797177Z","caller":"traceutil/trace.go:171","msg":"trace[1219797030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1019; }","duration":"141.770697ms","start":"2026-02-28T21:35:46.65538Z","end":"2026-02-28T21:35:46.79715Z","steps":["trace[1219797030] 'range keys from in-memory index tree' (duration: 141.369118ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:48.528236Z","caller":"traceutil/trace.go:171","msg":"trace[1402919744] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"132.10663ms","start":"2026-02-28T21:35:48.396099Z","end":"2026-02-28T21:35:48.528206Z","steps":["trace[1402919744] 'process raft request' (duration: 131.924316ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:51.13894Z","caller":"traceutil/trace.go:171","msg":"trace[137303383] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"323.409424ms","start":"2026-02-28T21:35:50.815505Z","end":"2026-02-28T21:35:51.138914Z","steps":["trace[137303383] 'read index received' (duration: 323.170219ms)","trace[137303383] 'applied index is now lower than readState.Index' (duration: 238.415µs)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:35:51.139001Z","caller":"traceutil/trace.go:171","msg":"trace[2085372568] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"600.897093ms","start":"2026-02-28T21:35:50.53807Z","end":"2026-02-28T21:35:51.138967Z","steps":["trace[2085372568] 'process raft request' (duration: 600.669258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:51.139187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:50.538038Z","time spent":"601.053447ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:51.139268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.645479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-28T21:35:51.139268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.70166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-28T21:35:51.139315Z","caller":"traceutil/trace.go:171","msg":"trace[1753794111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1045; }","duration":"323.755891ms","start":"2026-02-28T21:35:50.815543Z","end":"2026-02-28T21:35:51.139299Z","steps":["trace[1753794111] 'agreement among raft nodes before linearized reading' (duration: 323.584448ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:51.139336Z","caller":"traceutil/trace.go:171","msg":"trace[451631433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1045; }","duration":"323.875384ms","start":"2026-02-28T21:35:50.815447Z","end":"2026-02-28T21:35:51.139323Z","steps":["trace[451631433] 'agreement among raft nodes before linearized reading' (duration: 323.631068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:51.139346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:50.815513Z","time spent":"323.826313ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-28T21:35:51.139326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.093501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-28T21:35:51.139372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:50.815422Z","time spent":"323.941716ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-28T21:35:51.139415Z","caller":"traceutil/trace.go:171","msg":"trace[768765140] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1045; }","duration":"321.222744ms","start":"2026-02-28T21:35:50.818171Z","end":"2026-02-28T21:35:51.139394Z","steps":["trace[768765140] 'agreement among raft nodes before linearized reading' (duration: 320.973489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:51.139463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:50.81816Z","time spent":"321.293906ms","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-28T21:35:51.73061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540974055813166,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-28T21:35:52.186308Z","caller":"traceutil/trace.go:171","msg":"trace[1159354763] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"1.042154497s","start":"2026-02-28T21:35:51.144125Z","end":"2026-02-28T21:35:52.186279Z","steps":["trace[1159354763] 'process raft request' (duration: 1.042029394s)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:35:52.186388Z","caller":"traceutil/trace.go:171","msg":"trace[1728511800] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"1.042604076s","start":"2026-02-28T21:35:51.143747Z","end":"2026-02-28T21:35:52.186351Z","steps":["trace[1728511800] 'process raft request' (duration: 943.821029ms)","trace[1728511800] 'compare' (duration: 97.623492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:52.186513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:51.144089Z","time spent":"1.04231509s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:52.186605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:51.143717Z","time spent":"1.04274657s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-28T21:35:52.18666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.432032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-28T21:35:52.186713Z","caller":"traceutil/trace.go:171","msg":"trace[1234945316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1048; }","duration":"956.483933ms","start":"2026-02-28T21:35:51.230213Z","end":"2026-02-28T21:35:52.186697Z","steps":["trace[1234945316] 'agreement among raft nodes before linearized reading' (duration: 956.403261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:52.186747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:51.230148Z","time spent":"956.591476ms","remote":"127.0.0.1:52218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-28T21:35:52.186713Z","caller":"traceutil/trace.go:171","msg":"trace[1990938738] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.042218398s","start":"2026-02-28T21:35:51.14434Z","end":"2026-02-28T21:35:52.186559Z","steps":["trace[1990938738] 'process raft request' (duration: 1.041890251s)"],"step_count":1} {"level":"warn","ts":"2026-02-28T21:35:52.187068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-28T21:35:51.144312Z","time spent":"1.042648447s","remote":"127.0.0.1:52482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-28T21:35:52.186506Z","caller":"traceutil/trace.go:171","msg":"trace[450035514] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1090; }","duration":"956.256958ms","start":"2026-02-28T21:35:51.230229Z","end":"2026-02-28T21:35:52.186486Z","steps":["trace[450035514] 'read index received' (duration: 857.335847ms)","trace[450035514] 'applied index is now lower than readState.Index' (duration: 98.919581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-28T21:35:52.187979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.230181ms","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-28T21:35:52.188016Z","caller":"traceutil/trace.go:171","msg":"trace[536619625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1048; }","duration":"147.322313ms","start":"2026-02-28T21:35:52.040681Z","end":"2026-02-28T21:35:52.188003Z","steps":["trace[536619625] 'agreement among raft nodes before linearized reading' (duration: 147.152389ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:36:34.618354Z","caller":"traceutil/trace.go:171","msg":"trace[1676189357] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"114.709522ms","start":"2026-02-28T21:36:34.503616Z","end":"2026-02-28T21:36:34.618325Z","steps":["trace[1676189357] 'process raft request' (duration: 106.359979ms)"],"step_count":1} {"level":"info","ts":"2026-02-28T21:36:40.882313Z","caller":"traceutil/trace.go:171","msg":"trace[379026243] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"150.68422ms","start":"2026-02-28T21:36:40.731597Z","end":"2026-02-28T21:36:40.882282Z","steps":["trace[379026243] 'process raft request' (duration: 112.119725ms)","trace[379026243] 'compare' (duration: 38.423842ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:39:09.630298Z","caller":"traceutil/trace.go:171","msg":"trace[785074143] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"233.599946ms","start":"2026-02-28T21:39:09.396644Z","end":"2026-02-28T21:39:09.630243Z","steps":["trace[785074143] 'process raft request' (duration: 193.490878ms)","trace[785074143] 'compare' (duration: 39.849362ms)"],"step_count":2} {"level":"info","ts":"2026-02-28T21:39:09.630526Z","caller":"traceutil/trace.go:171","msg":"trace[695227589] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"233.134876ms","start":"2026-02-28T21:39:09.397337Z","end":"2026-02-28T21:39:09.630472Z","steps":["trace[695227589] 'process raft request' (duration: 232.838709ms)"],"step_count":1}