{"level":"warn","ts":"2026-02-11T02:25:19.678094Z","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-11T02:25:19.678296Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.126:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.126:2380","--initial-cluster=instance=https://199.19.213.126:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.126:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.126: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-11T02:25:19.678392Z","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-11T02:25:19.678402Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.126:2380"]} {"level":"info","ts":"2026-02-11T02:25:19.678449Z","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-11T02:25:19.679096Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"]} {"level":"info","ts":"2026-02-11T02:25:19.679192Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.126:2380"],"listen-peer-urls":["https://199.19.213.126:2380"],"advertise-client-urls":["https://199.19.213.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.126: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-11T02:25:19.687414Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.045747ms"} {"level":"info","ts":"2026-02-11T02:25:19.69742Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e241c9714ab70808","cluster-id":"e3245fbcce84a6b1"} {"level":"info","ts":"2026-02-11T02:25:19.697491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T02:25:19.69751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became follower at term 0"} {"level":"info","ts":"2026-02-11T02:25:19.697521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e241c9714ab70808 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T02:25:19.697528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became follower at term 1"} {"level":"info","ts":"2026-02-11T02:25:19.697602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=(16303533614479902728)"} {"level":"warn","ts":"2026-02-11T02:25:19.707658Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T02:25:19.731447Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T02:25:19.7342Z","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-11T02:25:19.737768Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e241c9714ab70808","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T02:25:19.739775Z","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-11T02:25:19.741357Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e241c9714ab70808","initial-advertise-peer-urls":["https://199.19.213.126:2380"],"listen-peer-urls":["https://199.19.213.126:2380"],"advertise-client-urls":["https://199.19.213.126:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.126:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T02:25:19.74138Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T02:25:19.741583Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e241c9714ab70808","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T02:25:19.742254Z","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-11T02:25:19.742283Z","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-11T02:25:19.74229Z","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-11T02:25:19.742663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 switched to configuration voters=(16303533614479902728)"} {"level":"info","ts":"2026-02-11T02:25:19.7433Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e3245fbcce84a6b1","local-member-id":"e241c9714ab70808","added-peer-id":"e241c9714ab70808","added-peer-peer-urls":["https://199.19.213.126:2380"]} {"level":"info","ts":"2026-02-11T02:25:19.744258Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.126:2380"} {"level":"info","ts":"2026-02-11T02:25:19.744271Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.126:2380"} {"level":"info","ts":"2026-02-11T02:25:20.298128Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T02:25:20.298168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T02:25:20.298186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 received MsgPreVoteResp from e241c9714ab70808 at term 1"} {"level":"info","ts":"2026-02-11T02:25:20.298197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became candidate at term 2"} {"level":"info","ts":"2026-02-11T02:25:20.298202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 received MsgVoteResp from e241c9714ab70808 at term 2"} {"level":"info","ts":"2026-02-11T02:25:20.29821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e241c9714ab70808 became leader at term 2"} {"level":"info","ts":"2026-02-11T02:25:20.298365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e241c9714ab70808 elected leader e241c9714ab70808 at term 2"} {"level":"info","ts":"2026-02-11T02:25:20.299838Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e241c9714ab70808","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.126:2379]}","request-path":"/0/members/e241c9714ab70808/attributes","cluster-id":"e3245fbcce84a6b1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T02:25:20.299954Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T02:25:20.300285Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:25:20.300363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T02:25:20.300542Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T02:25:20.300551Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T02:25:20.302396Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e3245fbcce84a6b1","local-member-id":"e241c9714ab70808","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:25:20.302718Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:25:20.303018Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T02:25:20.304761Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T02:25:20.336941Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.126:2379"} {"level":"info","ts":"2026-02-11T02:25:26.195564Z","caller":"traceutil/trace.go:171","msg":"trace[1160996843] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:253; }","duration":"102.010846ms","start":"2026-02-11T02:25:26.093538Z","end":"2026-02-11T02:25:26.195549Z","steps":["trace[1160996843] 'read index received' (duration: 6.007214ms)","trace[1160996843] 'applied index is now lower than readState.Index' (duration: 96.002942ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:25:26.195676Z","caller":"traceutil/trace.go:171","msg":"trace[812384333] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"110.988041ms","start":"2026-02-11T02:25:26.084681Z","end":"2026-02-11T02:25:26.195669Z","steps":["trace[812384333] 'process raft request' (duration: 14.873446ms)","trace[812384333] 'compare' (duration: 95.903729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:26.195795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.245411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:26.195823Z","caller":"traceutil/trace.go:171","msg":"trace[2024816743] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:249; }","duration":"102.296883ms","start":"2026-02-11T02:25:26.09351Z","end":"2026-02-11T02:25:26.195807Z","steps":["trace[2024816743] 'agreement among raft nodes before linearized reading' (duration: 102.248872ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:26.365521Z","caller":"traceutil/trace.go:171","msg":"trace[195816739] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"118.992133ms","start":"2026-02-11T02:25:26.246506Z","end":"2026-02-11T02:25:26.365498Z","steps":["trace[195816739] 'process raft request' (duration: 72.218291ms)","trace[195816739] 'compare' (duration: 46.013594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:26.366271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.64047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T02:25:26.366308Z","caller":"traceutil/trace.go:171","msg":"trace[1442754141] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:260; }","duration":"112.707202ms","start":"2026-02-11T02:25:26.25359Z","end":"2026-02-11T02:25:26.366297Z","steps":["trace[1442754141] 'agreement among raft nodes before linearized reading' (duration: 112.61908ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:36.144961Z","caller":"traceutil/trace.go:171","msg":"trace[1144510353] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"893.200827ms","start":"2026-02-11T02:25:35.251746Z","end":"2026-02-11T02:25:36.144946Z","steps":["trace[1144510353] 'process raft request' (duration: 893.104585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:36.145311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:35.251736Z","time spent":"893.269089ms","remote":"127.0.0.1:34336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:25:36.524702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.821933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:36.524772Z","caller":"traceutil/trace.go:171","msg":"trace[2016959995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"363.917786ms","start":"2026-02-11T02:25:36.160837Z","end":"2026-02-11T02:25:36.524755Z","steps":["trace[2016959995] 'range keys from in-memory index tree' (duration: 363.776432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:36.524807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:36.160825Z","time spent":"363.974557ms","remote":"127.0.0.1:34152","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-11T02:25:36.524994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.888275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-11T02:25:36.525022Z","caller":"traceutil/trace.go:171","msg":"trace[1735342672] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:367; }","duration":"345.972126ms","start":"2026-02-11T02:25:36.179041Z","end":"2026-02-11T02:25:36.525013Z","steps":["trace[1735342672] 'range keys from in-memory index tree' (duration: 345.773321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:36.525049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:36.17903Z","time spent":"346.012947ms","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4037,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-11T02:25:36.525269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.891973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4729"} {"level":"info","ts":"2026-02-11T02:25:36.525316Z","caller":"traceutil/trace.go:171","msg":"trace[796095038] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:367; }","duration":"327.971685ms","start":"2026-02-11T02:25:36.197335Z","end":"2026-02-11T02:25:36.525307Z","steps":["trace[796095038] 'range keys from in-memory index tree' (duration: 327.79431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:36.525343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:36.197318Z","time spent":"328.018375ms","remote":"127.0.0.1:34336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4753,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-02-11T02:25:51.796829Z","caller":"traceutil/trace.go:171","msg":"trace[404662264] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"147.16605ms","start":"2026-02-11T02:25:51.649636Z","end":"2026-02-11T02:25:51.796802Z","steps":["trace[404662264] 'process raft request' (duration: 147.024466ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:53.106229Z","caller":"traceutil/trace.go:171","msg":"trace[1121534788] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"365.236328ms","start":"2026-02-11T02:25:52.740979Z","end":"2026-02-11T02:25:53.106215Z","steps":["trace[1121534788] 'process raft request' (duration: 365.143916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:53.106321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:52.74097Z","time spent":"365.30403ms","remote":"127.0.0.1:34426","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-11T02:25:53.106599Z","caller":"traceutil/trace.go:171","msg":"trace[562833899] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:492; }","duration":"333.63123ms","start":"2026-02-11T02:25:52.772963Z","end":"2026-02-11T02:25:53.106594Z","steps":["trace[562833899] 'read index received' (duration: 333.62859ms)","trace[562833899] 'applied index is now lower than readState.Index' (duration: 2.29µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:53.106674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.708882ms","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-11T02:25:53.106688Z","caller":"traceutil/trace.go:171","msg":"trace[512811195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:478; }","duration":"333.745233ms","start":"2026-02-11T02:25:52.772939Z","end":"2026-02-11T02:25:53.106684Z","steps":["trace[512811195] 'agreement among raft nodes before linearized reading' (duration: 333.684381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:53.1067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:52.772929Z","time spent":"333.769163ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T02:25:53.477712Z","caller":"traceutil/trace.go:171","msg":"trace[1348037833] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"367.971012ms","start":"2026-02-11T02:25:53.109726Z","end":"2026-02-11T02:25:53.477697Z","steps":["trace[1348037833] 'process raft request' (duration: 367.947531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:53.477794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:53.109716Z","time spent":"368.034754ms","remote":"127.0.0.1:34426","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-11T02:25:53.477937Z","caller":"traceutil/trace.go:171","msg":"trace[835842840] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"701.857949ms","start":"2026-02-11T02:25:52.776074Z","end":"2026-02-11T02:25:53.477932Z","steps":["trace[835842840] 'process raft request' (duration: 669.405551ms)","trace[835842840] 'compare' (duration: 31.870684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:53.477963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:52.776064Z","time spent":"701.885059ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:25:53.478016Z","caller":"traceutil/trace.go:171","msg":"trace[704072573] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"371.398765ms","start":"2026-02-11T02:25:53.106612Z","end":"2026-02-11T02:25:53.47801Z","steps":["trace[704072573] 'read index received' (duration: 338.879735ms)","trace[704072573] 'applied index is now lower than readState.Index' (duration: 32.51848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:53.478073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.682373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T02:25:53.478085Z","caller":"traceutil/trace.go:171","msg":"trace[460107878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"668.717164ms","start":"2026-02-11T02:25:52.809364Z","end":"2026-02-11T02:25:53.478082Z","steps":["trace[460107878] 'agreement among raft nodes before linearized reading' (duration: 668.661383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:53.478098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:52.809353Z","time spent":"668.742465ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:25:53.478178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.147745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:25:53.47819Z","caller":"traceutil/trace.go:171","msg":"trace[33271487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"571.186496ms","start":"2026-02-11T02:25:52.907Z","end":"2026-02-11T02:25:53.478187Z","steps":["trace[33271487] 'agreement among raft nodes before linearized reading' (duration: 571.167585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:53.478203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:52.906988Z","time spent":"571.212196ms","remote":"127.0.0.1:34152","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-11T02:25:53.478283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.163783ms","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-11T02:25:53.478294Z","caller":"traceutil/trace.go:171","msg":"trace[1092956985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"661.185944ms","start":"2026-02-11T02:25:52.817105Z","end":"2026-02-11T02:25:53.478291Z","steps":["trace[1092956985] 'agreement among raft nodes before linearized reading' (duration: 661.150514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:25:53.478306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:52.8171Z","time spent":"661.203355ms","remote":"127.0.0.1:34426","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":"info","ts":"2026-02-11T02:25:55.657203Z","caller":"traceutil/trace.go:171","msg":"trace[596366423] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:500; }","duration":"122.399616ms","start":"2026-02-11T02:25:55.534782Z","end":"2026-02-11T02:25:55.657182Z","steps":["trace[596366423] 'read index received' (duration: 118.379529ms)","trace[596366423] 'applied index is now lower than readState.Index' (duration: 4.017367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:25:55.657349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.55308ms","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-11T02:25:55.65737Z","caller":"traceutil/trace.go:171","msg":"trace[666944372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:486; }","duration":"122.611521ms","start":"2026-02-11T02:25:55.534751Z","end":"2026-02-11T02:25:55.657363Z","steps":["trace[666944372] 'agreement among raft nodes before linearized reading' (duration: 122.501398ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:25:55.657433Z","caller":"traceutil/trace.go:171","msg":"trace[1583104397] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"123.124912ms","start":"2026-02-11T02:25:55.534284Z","end":"2026-02-11T02:25:55.657409Z","steps":["trace[1583104397] 'process raft request' (duration: 118.935681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:00.58194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.095005077s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T02:26:00.582019Z","caller":"traceutil/trace.go:171","msg":"trace[230247518] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"1.279721115s","start":"2026-02-11T02:25:59.302285Z","end":"2026-02-11T02:26:00.582006Z","steps":["trace[230247518] 'read index received' (duration: 184.545715ms)","trace[230247518] 'applied index is now lower than readState.Index' (duration: 1.095174429s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:00.582257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.279965371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ll9n7\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-11T02:26:00.582288Z","caller":"traceutil/trace.go:171","msg":"trace[856829768] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ll9n7; range_end:; response_count:1; response_revision:496; }","duration":"1.280037742s","start":"2026-02-11T02:25:59.302242Z","end":"2026-02-11T02:26:00.582279Z","steps":["trace[856829768] 'agreement among raft nodes before linearized reading' (duration: 1.279854738s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:00.582321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:59.302231Z","time spent":"1.280082644s","remote":"127.0.0.1:34336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-ll9n7\" "} {"level":"warn","ts":"2026-02-11T02:26:00.582413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055770996s","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-11T02:26:00.582508Z","caller":"traceutil/trace.go:171","msg":"trace[1044678320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"1.055909369s","start":"2026-02-11T02:25:59.526582Z","end":"2026-02-11T02:26:00.582491Z","steps":["trace[1044678320] 'agreement among raft nodes before linearized reading' (duration: 1.055751205s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:00.582557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:59.526564Z","time spent":"1.0559736s","remote":"127.0.0.1:34426","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-11T02:26:00.582908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.746373ms","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-11T02:26:00.582953Z","caller":"traceutil/trace.go:171","msg":"trace[1375939870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"881.798514ms","start":"2026-02-11T02:25:59.701131Z","end":"2026-02-11T02:26:00.58293Z","steps":["trace[1375939870] 'agreement among raft nodes before linearized reading' (duration: 881.695011ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:00.582961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.234794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-11T02:26:00.582978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:59.701121Z","time spent":"881.851655ms","remote":"127.0.0.1:34426","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":"info","ts":"2026-02-11T02:26:00.583002Z","caller":"traceutil/trace.go:171","msg":"trace[1840985623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"912.301206ms","start":"2026-02-11T02:25:59.670691Z","end":"2026-02-11T02:26:00.582992Z","steps":["trace[1840985623] 'agreement among raft nodes before linearized reading' (duration: 912.202583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:00.583031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:25:59.670679Z","time spent":"912.346056ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T02:26:03.69954Z","caller":"traceutil/trace.go:171","msg":"trace[1489450594] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"302.564875ms","start":"2026-02-11T02:26:03.396959Z","end":"2026-02-11T02:26:03.699524Z","steps":["trace[1489450594] 'process raft request' (duration: 238.070268ms)","trace[1489450594] 'compare' (duration: 64.160899ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:03.699625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:03.396946Z","time spent":"302.635896ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T02:26:03.699723Z","caller":"traceutil/trace.go:171","msg":"trace[270665705] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"217.048375ms","start":"2026-02-11T02:26:03.482668Z","end":"2026-02-11T02:26:03.699716Z","steps":["trace[270665705] 'process raft request' (duration: 216.656895ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:03.700036Z","caller":"traceutil/trace.go:171","msg":"trace[892613894] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"281.183173ms","start":"2026-02-11T02:26:03.418846Z","end":"2026-02-11T02:26:03.700029Z","steps":["trace[892613894] 'read index received' (duration: 216.193424ms)","trace[892613894] 'applied index is now lower than readState.Index' (duration: 64.989039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:03.700145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.294206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-lrngv\" ","response":"range_response_count:1 size:4822"} {"level":"info","ts":"2026-02-11T02:26:03.700163Z","caller":"traceutil/trace.go:171","msg":"trace[2093323028] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-lrngv; range_end:; response_count:1; response_revision:529; }","duration":"281.338506ms","start":"2026-02-11T02:26:03.41882Z","end":"2026-02-11T02:26:03.700158Z","steps":["trace[2093323028] 'agreement among raft nodes before linearized reading' (duration: 281.233524ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:03.700342Z","caller":"traceutil/trace.go:171","msg":"trace[66269831] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"274.105733ms","start":"2026-02-11T02:26:03.42623Z","end":"2026-02-11T02:26:03.700336Z","steps":["trace[66269831] 'process raft request' (duration: 273.069327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:06.248079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.399432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:06.248432Z","caller":"traceutil/trace.go:171","msg":"trace[672263729] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"250.308501ms","start":"2026-02-11T02:26:05.998041Z","end":"2026-02-11T02:26:06.24835Z","steps":["trace[672263729] 'read index received' (duration: 27.791µs)","trace[672263729] 'applied index is now lower than readState.Index' (duration: 250.27935ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:06.248694Z","caller":"traceutil/trace.go:171","msg":"trace[1268311935] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"383.914945ms","start":"2026-02-11T02:26:05.864764Z","end":"2026-02-11T02:26:06.248679Z","steps":["trace[1268311935] 'process raft request' (duration: 32.835128ms)","trace[1268311935] 'compare' (duration: 350.289739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:06.248875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:05.864749Z","time spent":"384.09195ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:26:06.249427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.391597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-11T02:26:06.249618Z","caller":"traceutil/trace.go:171","msg":"trace[2104463087] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:570; }","duration":"251.619803ms","start":"2026-02-11T02:26:05.997983Z","end":"2026-02-11T02:26:06.249603Z","steps":["trace[2104463087] 'agreement among raft nodes before linearized reading' (duration: 251.248714ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:07.2395Z","caller":"traceutil/trace.go:171","msg":"trace[1148533689] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"213.770505ms","start":"2026-02-11T02:26:07.025695Z","end":"2026-02-11T02:26:07.239465Z","steps":["trace[1148533689] 'process raft request' (duration: 207.498955ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:12.848483Z","caller":"traceutil/trace.go:171","msg":"trace[1243764203] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"103.768959ms","start":"2026-02-11T02:26:12.744707Z","end":"2026-02-11T02:26:12.848476Z","steps":["trace[1243764203] 'process raft request' (duration: 35.158453ms)","trace[1243764203] 'compare' (duration: 68.283238ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:12.848456Z","caller":"traceutil/trace.go:171","msg":"trace[1991084468] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"101.524905ms","start":"2026-02-11T02:26:12.746914Z","end":"2026-02-11T02:26:12.848439Z","steps":["trace[1991084468] 'process raft request' (duration: 101.351701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:14.555049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.588908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:14.555201Z","caller":"traceutil/trace.go:171","msg":"trace[1953908014] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"652.234676ms","start":"2026-02-11T02:26:13.902952Z","end":"2026-02-11T02:26:14.555187Z","steps":["trace[1953908014] 'process raft request' (duration: 233.460843ms)","trace[1953908014] 'compare' (duration: 418.513366ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:14.555244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:13.902936Z","time spent":"652.288277ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:14.555302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:13.955772Z","time spent":"599.529292ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T02:26:17.93722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578884395980641943,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:26:18.082917Z","caller":"traceutil/trace.go:171","msg":"trace[1217344591] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"649.387946ms","start":"2026-02-11T02:26:17.433502Z","end":"2026-02-11T02:26:18.08289Z","steps":["trace[1217344591] 'process raft request' (duration: 649.299534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:18.083016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:17.433491Z","time spent":"649.473458ms","remote":"127.0.0.1:34426","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-11T02:26:18.08962Z","caller":"traceutil/trace.go:171","msg":"trace[317579393] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"652.624135ms","start":"2026-02-11T02:26:17.436986Z","end":"2026-02-11T02:26:18.089611Z","steps":["trace[317579393] 'read index received' (duration: 646.2924ms)","trace[317579393] 'applied index is now lower than readState.Index' (duration: 6.331265ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:18.089675Z","caller":"traceutil/trace.go:171","msg":"trace[2083865019] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"640.660482ms","start":"2026-02-11T02:26:17.449009Z","end":"2026-02-11T02:26:18.08967Z","steps":["trace[2083865019] 'process raft request' (duration: 640.530969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:18.089719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:17.448999Z","time spent":"640.688862ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-11T02:26:18.089928Z","caller":"traceutil/trace.go:171","msg":"trace[864981584] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"592.001497ms","start":"2026-02-11T02:26:17.497919Z","end":"2026-02-11T02:26:18.08992Z","steps":["trace[864981584] 'process raft request' (duration: 591.663209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:18.089982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:17.497904Z","time spent":"592.045299ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:18.090117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.129388ms","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-11T02:26:18.090137Z","caller":"traceutil/trace.go:171","msg":"trace[1605671352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"653.183349ms","start":"2026-02-11T02:26:17.436947Z","end":"2026-02-11T02:26:18.09013Z","steps":["trace[1605671352] 'agreement among raft nodes before linearized reading' (duration: 653.112298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:18.090154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:17.43694Z","time spent":"653.20967ms","remote":"127.0.0.1:34426","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-11T02:26:18.09047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.334558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-45x5h\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-11T02:26:18.090506Z","caller":"traceutil/trace.go:171","msg":"trace[534799778] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-45x5h; range_end:; response_count:1; response_revision:675; }","duration":"641.390209ms","start":"2026-02-11T02:26:17.44911Z","end":"2026-02-11T02:26:18.0905Z","steps":["trace[534799778] 'agreement among raft nodes before linearized reading' (duration: 641.141093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:18.090525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:17.449095Z","time spent":"641.42651ms","remote":"127.0.0.1:34336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4848,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-45x5h\" "} {"level":"warn","ts":"2026-02-11T02:26:18.596921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578884395980641948,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:26:18.646923Z","caller":"traceutil/trace.go:171","msg":"trace[53659058] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"552.382524ms","start":"2026-02-11T02:26:18.094514Z","end":"2026-02-11T02:26:18.646897Z","steps":["trace[53659058] 'process raft request' (duration: 552.23263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:18.64703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:18.094501Z","time spent":"552.471647ms","remote":"127.0.0.1:34426","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-11T02:26:18.962743Z","caller":"traceutil/trace.go:171","msg":"trace[870334676] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:695; }","duration":"866.352434ms","start":"2026-02-11T02:26:18.096374Z","end":"2026-02-11T02:26:18.962726Z","steps":["trace[870334676] 'read index received' (duration: 550.461277ms)","trace[870334676] 'applied index is now lower than readState.Index' (duration: 315.888927ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:18.96283Z","caller":"traceutil/trace.go:171","msg":"trace[1982436514] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"568.50563ms","start":"2026-02-11T02:26:18.394317Z","end":"2026-02-11T02:26:18.962823Z","steps":["trace[1982436514] 'process raft request' (duration: 479.114505ms)","trace[1982436514] 'compare' (duration: 89.037987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:18.962889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:18.394296Z","time spent":"568.550081ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:26:18.963093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.713553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ll9n7\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-11T02:26:18.963113Z","caller":"traceutil/trace.go:171","msg":"trace[301009001] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ll9n7; range_end:; response_count:1; response_revision:677; }","duration":"866.751204ms","start":"2026-02-11T02:26:18.096355Z","end":"2026-02-11T02:26:18.963107Z","steps":["trace[301009001] 'agreement among raft nodes before linearized reading' (duration: 866.646972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:18.963131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:18.096336Z","time spent":"866.790665ms","remote":"127.0.0.1:34336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18274,"request content":"key:\"/registry/pods/kube-system/cilium-ll9n7\" "} {"level":"warn","ts":"2026-02-11T02:26:18.963224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.695578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:18.96324Z","caller":"traceutil/trace.go:171","msg":"trace[1881985230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"459.7605ms","start":"2026-02-11T02:26:18.503474Z","end":"2026-02-11T02:26:18.963235Z","steps":["trace[1881985230] 'agreement among raft nodes before linearized reading' (duration: 459.735959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:18.963254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:18.503459Z","time spent":"459.791721ms","remote":"127.0.0.1:34152","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-11T02:26:18.963744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.93923ms","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-11T02:26:18.963765Z","caller":"traceutil/trace.go:171","msg":"trace[782653739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:677; }","duration":"189.064613ms","start":"2026-02-11T02:26:18.774694Z","end":"2026-02-11T02:26:18.963759Z","steps":["trace[782653739] 'agreement among raft nodes before linearized reading' (duration: 189.012142ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:19.115469Z","caller":"traceutil/trace.go:171","msg":"trace[1581565601] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"145.3981ms","start":"2026-02-11T02:26:18.970049Z","end":"2026-02-11T02:26:19.115447Z","steps":["trace[1581565601] 'read index received' (duration: 114.150213ms)","trace[1581565601] 'applied index is now lower than readState.Index' (duration: 31.246687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:19.115566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.500943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:19.115595Z","caller":"traceutil/trace.go:171","msg":"trace[1631861041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:678; }","duration":"145.569215ms","start":"2026-02-11T02:26:18.970017Z","end":"2026-02-11T02:26:19.115586Z","steps":["trace[1631861041] 'agreement among raft nodes before linearized reading' (duration: 145.509313ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:19.115696Z","caller":"traceutil/trace.go:171","msg":"trace[471686768] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"145.916593ms","start":"2026-02-11T02:26:18.969758Z","end":"2026-02-11T02:26:19.115675Z","steps":["trace[471686768] 'process raft request' (duration: 114.496961ms)","trace[471686768] 'compare' (duration: 31.099964ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:19.1308Z","caller":"traceutil/trace.go:171","msg":"trace[1611642182] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"158.690597ms","start":"2026-02-11T02:26:18.972095Z","end":"2026-02-11T02:26:19.130785Z","steps":["trace[1611642182] 'process raft request' (duration: 158.4455ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:19.131072Z","caller":"traceutil/trace.go:171","msg":"trace[1785214462] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"158.263897ms","start":"2026-02-11T02:26:18.972803Z","end":"2026-02-11T02:26:19.131067Z","steps":["trace[1785214462] 'process raft request' (duration: 157.811055ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:19.131046Z","caller":"traceutil/trace.go:171","msg":"trace[1895095843] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"150.981768ms","start":"2026-02-11T02:26:18.980056Z","end":"2026-02-11T02:26:19.131037Z","steps":["trace[1895095843] 'process raft request' (duration: 150.582098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:21.573459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.191316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T02:26:21.573509Z","caller":"traceutil/trace.go:171","msg":"trace[1150866217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:686; }","duration":"455.282889ms","start":"2026-02-11T02:26:21.118214Z","end":"2026-02-11T02:26:21.573496Z","steps":["trace[1150866217] 'range keys from in-memory index tree' (duration: 455.091764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:21.573533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.118202Z","time spent":"455.324391ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:26:21.573692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.124154ms","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-11T02:26:21.573712Z","caller":"traceutil/trace.go:171","msg":"trace[1225542887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"437.159105ms","start":"2026-02-11T02:26:21.136546Z","end":"2026-02-11T02:26:21.573705Z","steps":["trace[1225542887] 'range keys from in-memory index tree' (duration: 437.068742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:21.573728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.136537Z","time spent":"437.187116ms","remote":"127.0.0.1:34426","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-11T02:26:21.574009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.780973ms","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-11T02:26:21.574036Z","caller":"traceutil/trace.go:171","msg":"trace[2139461100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"429.840454ms","start":"2026-02-11T02:26:21.144187Z","end":"2026-02-11T02:26:21.574027Z","steps":["trace[2139461100] 'range keys from in-memory index tree' (duration: 429.69777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:21.574054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.144177Z","time spent":"429.873305ms","remote":"127.0.0.1:34426","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":"info","ts":"2026-02-11T02:26:22.551827Z","caller":"traceutil/trace.go:171","msg":"trace[753251152] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"970.989723ms","start":"2026-02-11T02:26:21.580814Z","end":"2026-02-11T02:26:22.551804Z","steps":["trace[753251152] 'process raft request' (duration: 970.903811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:22.551922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.580802Z","time spent":"971.067775ms","remote":"127.0.0.1:34426","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-11T02:26:24.55392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.225939183s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:24.554027Z","caller":"traceutil/trace.go:171","msg":"trace[171742053] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"2.457584497s","start":"2026-02-11T02:26:22.09643Z","end":"2026-02-11T02:26:24.554015Z","steps":["trace[171742053] 'read index received' (duration: 455.6935ms)","trace[171742053] 'applied index is now lower than readState.Index' (duration: 2.001890337s)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:24.554174Z","caller":"traceutil/trace.go:171","msg":"trace[2046885732] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"2.973103206s","start":"2026-02-11T02:26:21.581062Z","end":"2026-02-11T02:26:24.554165Z","steps":["trace[2046885732] 'process raft request' (duration: 1.746861555s)","trace[2046885732] 'compare' (duration: 1.225289887s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:24.554214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.581052Z","time spent":"2.973140606s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:24.554321Z","caller":"traceutil/trace.go:171","msg":"trace[311088947] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"2.973208448s","start":"2026-02-11T02:26:21.581107Z","end":"2026-02-11T02:26:24.554316Z","steps":["trace[311088947] 'process raft request' (duration: 2.97287333s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:24.554351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:21.581103Z","time spent":"2.973232489s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:24.554576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.45814249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T02:26:24.554597Z","caller":"traceutil/trace.go:171","msg":"trace[1389796744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:689; }","duration":"2.458190401s","start":"2026-02-11T02:26:22.0964Z","end":"2026-02-11T02:26:24.55459Z","steps":["trace[1389796744] 'agreement among raft nodes before linearized reading' (duration: 2.458022028s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:24.554616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:22.096387Z","time spent":"2.458224872s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:26:24.554736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854508108s","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-11T02:26:24.554753Z","caller":"traceutil/trace.go:171","msg":"trace[1406865316] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.854560019s","start":"2026-02-11T02:26:22.700188Z","end":"2026-02-11T02:26:24.554748Z","steps":["trace[1406865316] 'agreement among raft nodes before linearized reading' (duration: 1.854510508s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:24.55477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:22.70017Z","time spent":"1.85459691s","remote":"127.0.0.1:34426","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-11T02:26:24.554969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.160761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.126\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T02:26:24.554987Z","caller":"traceutil/trace.go:171","msg":"trace[2121762345] range","detail":"{range_begin:/registry/masterleases/199.19.213.126; range_end:; response_count:1; response_revision:689; }","duration":"671.195191ms","start":"2026-02-11T02:26:23.883786Z","end":"2026-02-11T02:26:24.554982Z","steps":["trace[2121762345] 'agreement among raft nodes before linearized reading' (duration: 671.14204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:24.555003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:23.883775Z","time spent":"671.224842ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.126\" "} {"level":"warn","ts":"2026-02-11T02:26:24.555107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.053317084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:24.555125Z","caller":"traceutil/trace.go:171","msg":"trace[1534993357] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"1.053355385s","start":"2026-02-11T02:26:23.501764Z","end":"2026-02-11T02:26:24.55512Z","steps":["trace[1534993357] 'agreement among raft nodes before linearized reading' (duration: 1.053326845s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:24.55514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:23.50175Z","time spent":"1.053386846s","remote":"127.0.0.1:34158","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-11T02:26:24.555221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643556588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:24.555238Z","caller":"traceutil/trace.go:171","msg":"trace[451524631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"1.643591169s","start":"2026-02-11T02:26:22.911642Z","end":"2026-02-11T02:26:24.555233Z","steps":["trace[451524631] 'agreement among raft nodes before linearized reading' (duration: 1.643567819s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:24.555253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:22.911631Z","time spent":"1.64361899s","remote":"127.0.0.1:34152","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-11T02:26:24.555355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.703362686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:24.555372Z","caller":"traceutil/trace.go:171","msg":"trace[1900952972] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:689; }","duration":"1.703410248s","start":"2026-02-11T02:26:22.851957Z","end":"2026-02-11T02:26:24.555367Z","steps":["trace[1900952972] 'agreement among raft nodes before linearized reading' (duration: 1.703381747s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:24.55539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:22.85194Z","time spent":"1.703445668s","remote":"127.0.0.1:34468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:26:25.061435Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578884395980641978,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:26:25.562551Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578884395980641978,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:26:26.062654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578884395980641978,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:26:26.155769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.5951596s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T02:26:26.308989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.569307ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-11T02:26:26.309062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.560522Z","time spent":"1.748536186s","remote":"127.0.0.1:34194","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T02:26:26.569513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000649477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T02:26:26.569581Z","caller":"traceutil/trace.go:171","msg":"trace[2136386628] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000737419s","start":"2026-02-11T02:26:24.568833Z","end":"2026-02-11T02:26:26.56957Z","steps":["trace[2136386628] 'agreement among raft nodes before linearized reading' (duration: 2.000662667s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.569626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.568824Z","time spent":"2.00079566s","remote":"127.0.0.1:34152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 02:26:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T02:26:26.802802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.917982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:26.803019Z","caller":"traceutil/trace.go:171","msg":"trace[987357513] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"492.436452ms","start":"2026-02-11T02:26:26.310567Z","end":"2026-02-11T02:26:26.803003Z","steps":["trace[987357513] 'process raft request' (duration: 492.398701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.803076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:26.310554Z","time spent":"492.494373ms","remote":"127.0.0.1:34194","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-11T02:26:26.803373Z","caller":"traceutil/trace.go:171","msg":"trace[153169027] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"2.239714526s","start":"2026-02-11T02:26:24.563648Z","end":"2026-02-11T02:26:26.803362Z","steps":["trace[153169027] 'process raft request' (duration: 2.080161528s)","trace[153169027] 'compare' (duration: 158.660637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:26.80344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.563636Z","time spent":"2.239759717s","remote":"127.0.0.1:34426","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-11T02:26:26.80355Z","caller":"traceutil/trace.go:171","msg":"trace[168505434] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"2.237230336s","start":"2026-02-11T02:26:24.566311Z","end":"2026-02-11T02:26:26.803542Z","steps":["trace[168505434] 'process raft request' (duration: 2.236561949s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.803594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.566297Z","time spent":"2.237272486s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:26.803717Z","caller":"traceutil/trace.go:171","msg":"trace[1151042136] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"2.097072004s","start":"2026-02-11T02:26:24.706637Z","end":"2026-02-11T02:26:26.803709Z","steps":["trace[1151042136] 'process raft request' (duration: 2.096290554s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.803769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.706623Z","time spent":"2.097121206s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:26.803873Z","caller":"traceutil/trace.go:171","msg":"trace[391633590] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:710; }","duration":"2.243200972s","start":"2026-02-11T02:26:24.560663Z","end":"2026-02-11T02:26:26.803864Z","steps":["trace[391633590] 'read index received' (duration: 1.597752473s)","trace[391633590] 'applied index is now lower than readState.Index' (duration: 645.447529ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:26.804129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.243461789s","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-11T02:26:26.804155Z","caller":"traceutil/trace.go:171","msg":"trace[1515357026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"2.24349961s","start":"2026-02-11T02:26:24.560647Z","end":"2026-02-11T02:26:26.804146Z","steps":["trace[1515357026] 'agreement among raft nodes before linearized reading' (duration: 2.243402807s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.804177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.56064Z","time spent":"2.24353165s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T02:26:26.804292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.234502748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:26.804315Z","caller":"traceutil/trace.go:171","msg":"trace[246778415] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:693; }","duration":"2.23455374s","start":"2026-02-11T02:26:24.569755Z","end":"2026-02-11T02:26:26.804308Z","steps":["trace[246778415] 'agreement among raft nodes before linearized reading' (duration: 2.234516499s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.804337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.569748Z","time spent":"2.23458234s","remote":"127.0.0.1:34440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:26:26.804562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.178055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:26.804589Z","caller":"traceutil/trace.go:171","msg":"trace[1851390366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"235.218665ms","start":"2026-02-11T02:26:26.569363Z","end":"2026-02-11T02:26:26.804582Z","steps":["trace[1851390366] 'agreement among raft nodes before linearized reading' (duration: 235.177064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.804739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.187163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T02:26:26.804761Z","caller":"traceutil/trace.go:171","msg":"trace[1564961850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"239.236135ms","start":"2026-02-11T02:26:26.565518Z","end":"2026-02-11T02:26:26.804754Z","steps":["trace[1564961850] 'agreement among raft nodes before linearized reading' (duration: 239.171713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.804896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.828563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-11T02:26:26.804919Z","caller":"traceutil/trace.go:171","msg":"trace[1911092869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"242.849974ms","start":"2026-02-11T02:26:26.562061Z","end":"2026-02-11T02:26:26.804911Z","steps":["trace[1911092869] 'agreement among raft nodes before linearized reading' (duration: 242.787582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.80503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.949639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:26.805053Z","caller":"traceutil/trace.go:171","msg":"trace[4517482] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:693; }","duration":"840.991061ms","start":"2026-02-11T02:26:25.964055Z","end":"2026-02-11T02:26:26.805046Z","steps":["trace[4517482] 'agreement among raft nodes before linearized reading' (duration: 840.95474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.805075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:25.964041Z","time spent":"841.027092ms","remote":"127.0.0.1:34306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:26:26.805196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621211749s","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-11T02:26:26.805219Z","caller":"traceutil/trace.go:171","msg":"trace[909757073] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:693; }","duration":"1.62125711s","start":"2026-02-11T02:26:25.183955Z","end":"2026-02-11T02:26:26.805212Z","steps":["trace[909757073] 'agreement among raft nodes before linearized reading' (duration: 1.621221069s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.805243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:25.183943Z","time spent":"1.621294181s","remote":"127.0.0.1:34526","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-11T02:26:26.805379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.974739619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:26.805402Z","caller":"traceutil/trace.go:171","msg":"trace[2056283632] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:693; }","duration":"1.974790381s","start":"2026-02-11T02:26:24.830605Z","end":"2026-02-11T02:26:26.805395Z","steps":["trace[2056283632] 'agreement among raft nodes before linearized reading' (duration: 1.97475389s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.805424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.830587Z","time spent":"1.974830861s","remote":"127.0.0.1:34290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:26:26.805542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.134076921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:26:26.805564Z","caller":"traceutil/trace.go:171","msg":"trace[859432167] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:693; }","duration":"2.134123053s","start":"2026-02-11T02:26:24.671434Z","end":"2026-02-11T02:26:26.805558Z","steps":["trace[859432167] 'agreement among raft nodes before linearized reading' (duration: 2.134086892s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.805589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:24.671421Z","time spent":"2.134159553s","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-02-11T02:26:26.979049Z","caller":"traceutil/trace.go:171","msg":"trace[1252874661] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"162.48061ms","start":"2026-02-11T02:26:26.816549Z","end":"2026-02-11T02:26:26.979029Z","steps":["trace[1252874661] 'process raft request' (duration: 162.447679ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:26.979305Z","caller":"traceutil/trace.go:171","msg":"trace[16421762] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"162.820657ms","start":"2026-02-11T02:26:26.816466Z","end":"2026-02-11T02:26:26.979287Z","steps":["trace[16421762] 'process raft request' (duration: 162.403977ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:26.979387Z","caller":"traceutil/trace.go:171","msg":"trace[1118476595] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"162.857039ms","start":"2026-02-11T02:26:26.816517Z","end":"2026-02-11T02:26:26.979374Z","steps":["trace[1118476595] 'process raft request' (duration: 162.446489ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:26.979554Z","caller":"traceutil/trace.go:171","msg":"trace[1386857962] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"163.133216ms","start":"2026-02-11T02:26:26.816413Z","end":"2026-02-11T02:26:26.979546Z","steps":["trace[1386857962] 'read index received' (duration: 121.34913ms)","trace[1386857962] 'applied index is now lower than readState.Index' (duration: 41.783096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:26.979652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.221998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-11T02:26:26.979678Z","caller":"traceutil/trace.go:171","msg":"trace[1266386296] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:696; }","duration":"163.277499ms","start":"2026-02-11T02:26:26.816393Z","end":"2026-02-11T02:26:26.979671Z","steps":["trace[1266386296] 'agreement among raft nodes before linearized reading' (duration: 163.213147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:26.979836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.137667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:26.979862Z","caller":"traceutil/trace.go:171","msg":"trace[474353612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"163.174847ms","start":"2026-02-11T02:26:26.816679Z","end":"2026-02-11T02:26:26.979853Z","steps":["trace[474353612] 'agreement among raft nodes before linearized reading' (duration: 163.133186ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:28.311772Z","caller":"traceutil/trace.go:171","msg":"trace[1369779244] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"402.221276ms","start":"2026-02-11T02:26:27.909536Z","end":"2026-02-11T02:26:28.311757Z","steps":["trace[1369779244] 'process raft request' (duration: 402.087082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:28.311894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:27.909514Z","time spent":"402.310668ms","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4726,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:28.752105Z","caller":"traceutil/trace.go:171","msg":"trace[619434649] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"251.945577ms","start":"2026-02-11T02:26:28.500143Z","end":"2026-02-11T02:26:28.752088Z","steps":["trace[619434649] 'read index received' (duration: 218.230329ms)","trace[619434649] 'applied index is now lower than readState.Index' (duration: 33.714558ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:28.752186Z","caller":"traceutil/trace.go:171","msg":"trace[174386173] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"332.689379ms","start":"2026-02-11T02:26:28.41949Z","end":"2026-02-11T02:26:28.752179Z","steps":["trace[174386173] 'process raft request' (duration: 298.890539ms)","trace[174386173] 'compare' (duration: 33.625435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:28.752254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:28.419474Z","time spent":"332.72769ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:28.752405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.260104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:28.752425Z","caller":"traceutil/trace.go:171","msg":"trace[2091710267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"252.302306ms","start":"2026-02-11T02:26:28.500117Z","end":"2026-02-11T02:26:28.752419Z","steps":["trace[2091710267] 'agreement among raft nodes before linearized reading' (duration: 252.251865ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:29.259927Z","caller":"traceutil/trace.go:171","msg":"trace[312433666] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:723; }","duration":"447.710674ms","start":"2026-02-11T02:26:28.8122Z","end":"2026-02-11T02:26:29.25991Z","steps":["trace[312433666] 'read index received' (duration: 447.57936ms)","trace[312433666] 'applied index is now lower than readState.Index' (duration: 130.674µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:29.260171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.270555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:26:29.260161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.653903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:26:29.260198Z","caller":"traceutil/trace.go:171","msg":"trace[1881232645] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"140.323896ms","start":"2026-02-11T02:26:29.119866Z","end":"2026-02-11T02:26:29.26019Z","steps":["trace[1881232645] 'agreement among raft nodes before linearized reading' (duration: 140.263265ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:29.260212Z","caller":"traceutil/trace.go:171","msg":"trace[2075751749] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:701; }","duration":"445.729485ms","start":"2026-02-11T02:26:28.81447Z","end":"2026-02-11T02:26:29.2602Z","steps":["trace[2075751749] 'agreement among raft nodes before linearized reading' (duration: 445.629522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.260243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:28.814465Z","time spent":"445.771066ms","remote":"127.0.0.1:34626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-02-11T02:26:29.260333Z","caller":"traceutil/trace.go:171","msg":"trace[1899078720] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"448.571105ms","start":"2026-02-11T02:26:28.811755Z","end":"2026-02-11T02:26:29.260326Z","steps":["trace[1899078720] 'process raft request' (duration: 448.068942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.260389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:28.811745Z","time spent":"448.605495ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:26:29.260407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.636598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.260432Z","caller":"traceutil/trace.go:171","msg":"trace[919433848] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"154.702409ms","start":"2026-02-11T02:26:29.105721Z","end":"2026-02-11T02:26:29.260423Z","steps":["trace[919433848] 'agreement among raft nodes before linearized reading' (duration: 154.665498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.260523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.320558ms","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-11T02:26:29.260536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.78876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.260545Z","caller":"traceutil/trace.go:171","msg":"trace[685834300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:701; }","duration":"448.351008ms","start":"2026-02-11T02:26:28.812186Z","end":"2026-02-11T02:26:29.260537Z","steps":["trace[685834300] 'agreement among raft nodes before linearized reading' (duration: 448.277997ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:29.260556Z","caller":"traceutil/trace.go:171","msg":"trace[1036544362] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"154.815851ms","start":"2026-02-11T02:26:29.105734Z","end":"2026-02-11T02:26:29.26055Z","steps":["trace[1036544362] 'agreement among raft nodes before linearized reading' (duration: 154.786461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.260566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:28.81218Z","time spent":"448.380169ms","remote":"127.0.0.1:34426","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-11T02:26:29.260669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.943104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:26:29.260671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.172625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.260687Z","caller":"traceutil/trace.go:171","msg":"trace[715512775] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"154.985526ms","start":"2026-02-11T02:26:29.105696Z","end":"2026-02-11T02:26:29.260682Z","steps":["trace[715512775] 'agreement among raft nodes before linearized reading' (duration: 154.955915ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:29.260691Z","caller":"traceutil/trace.go:171","msg":"trace[603333736] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"132.208996ms","start":"2026-02-11T02:26:29.128476Z","end":"2026-02-11T02:26:29.260685Z","steps":["trace[603333736] 'agreement among raft nodes before linearized reading' (duration: 132.175555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.260792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.435251ms","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":"warn","ts":"2026-02-11T02:26:29.260812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.786683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T02:26:29.260831Z","caller":"traceutil/trace.go:171","msg":"trace[314214089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:701; }","duration":"273.806003ms","start":"2026-02-11T02:26:28.987019Z","end":"2026-02-11T02:26:29.260825Z","steps":["trace[314214089] 'agreement among raft nodes before linearized reading' (duration: 273.729471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.260836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.508243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.260854Z","caller":"traceutil/trace.go:171","msg":"trace[1159019149] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"132.536484ms","start":"2026-02-11T02:26:29.128313Z","end":"2026-02-11T02:26:29.26085Z","steps":["trace[1159019149] 'agreement among raft nodes before linearized reading' (duration: 132.511013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.260933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.939231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.260957Z","caller":"traceutil/trace.go:171","msg":"trace[1963696925] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"140.977911ms","start":"2026-02-11T02:26:29.119967Z","end":"2026-02-11T02:26:29.260945Z","steps":["trace[1963696925] 'agreement among raft nodes before linearized reading' (duration: 140.951601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.260964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.965417ms","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-11T02:26:29.260984Z","caller":"traceutil/trace.go:171","msg":"trace[1179009539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:701; }","duration":"273.984157ms","start":"2026-02-11T02:26:28.986994Z","end":"2026-02-11T02:26:29.260978Z","steps":["trace[1179009539] 'agreement among raft nodes before linearized reading' (duration: 273.908495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.261032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.073523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.26105Z","caller":"traceutil/trace.go:171","msg":"trace[1098231502] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:701; }","duration":"141.092424ms","start":"2026-02-11T02:26:29.119952Z","end":"2026-02-11T02:26:29.261045Z","steps":["trace[1098231502] 'agreement among raft nodes before linearized reading' (duration: 141.066173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.261084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.133732ms","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-11T02:26:29.261102Z","caller":"traceutil/trace.go:171","msg":"trace[254599663] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:701; }","duration":"274.179572ms","start":"2026-02-11T02:26:28.986918Z","end":"2026-02-11T02:26:29.261097Z","steps":["trace[254599663] 'agreement among raft nodes before linearized reading' (duration: 274.131401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.261205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.763493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:26:29.261222Z","caller":"traceutil/trace.go:171","msg":"trace[924689690] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:701; }","duration":"350.812024ms","start":"2026-02-11T02:26:28.910405Z","end":"2026-02-11T02:26:29.261217Z","steps":["trace[924689690] 'agreement among raft nodes before linearized reading' (duration: 350.784973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.261239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:28.910386Z","time spent":"350.847475ms","remote":"127.0.0.1:34594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":2,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-02-11T02:26:29.260809Z","caller":"traceutil/trace.go:171","msg":"trace[2126370280] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:701; }","duration":"132.459422ms","start":"2026-02-11T02:26:29.128344Z","end":"2026-02-11T02:26:29.260804Z","steps":["trace[2126370280] 'agreement among raft nodes before linearized reading' (duration: 132.433211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.622054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.389261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:26:29.622263Z","caller":"traceutil/trace.go:171","msg":"trace[998753069] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:724; }","duration":"121.350929ms","start":"2026-02-11T02:26:29.500899Z","end":"2026-02-11T02:26:29.62225Z","steps":["trace[998753069] 'read index received' (duration: 58.678821ms)","trace[998753069] 'applied index is now lower than readState.Index' (duration: 62.671127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:29.622322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.42047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:29.622343Z","caller":"traceutil/trace.go:171","msg":"trace[537318850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"121.466742ms","start":"2026-02-11T02:26:29.500869Z","end":"2026-02-11T02:26:29.622335Z","steps":["trace[537318850] 'agreement among raft nodes before linearized reading' (duration: 121.41619ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:29.622591Z","caller":"traceutil/trace.go:171","msg":"trace[1041465431] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"349.517722ms","start":"2026-02-11T02:26:29.273064Z","end":"2026-02-11T02:26:29.622582Z","steps":["trace[1041465431] 'process raft request' (duration: 106.472634ms)","trace[1041465431] 'compare' (duration: 242.107464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:29.622637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.273052Z","time spent":"349.559893ms","remote":"127.0.0.1:34426","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-11T02:26:29.625196Z","caller":"traceutil/trace.go:171","msg":"trace[1983526210] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"350.877734ms","start":"2026-02-11T02:26:29.274297Z","end":"2026-02-11T02:26:29.625175Z","steps":["trace[1983526210] 'process raft request' (duration: 347.924452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.625313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.274293Z","time spent":"350.950576ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:29.62544Z","caller":"traceutil/trace.go:171","msg":"trace[2047154003] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"351.172802ms","start":"2026-02-11T02:26:29.27426Z","end":"2026-02-11T02:26:29.625433Z","steps":["trace[2047154003] 'process raft request' (duration: 347.880121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.625476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.274251Z","time spent":"351.206182ms","remote":"127.0.0.1:34426","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-11T02:26:29.625554Z","caller":"traceutil/trace.go:171","msg":"trace[1939633604] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"351.278995ms","start":"2026-02-11T02:26:29.274269Z","end":"2026-02-11T02:26:29.625548Z","steps":["trace[1939633604] 'process raft request' (duration: 347.909512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:29.625584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:29.274252Z","time spent":"351.315665ms","remote":"127.0.0.1:34426","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-11T02:26:29.989898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.019616ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T02:26:41.586636Z","caller":"traceutil/trace.go:171","msg":"trace[1638626375] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"277.991817ms","start":"2026-02-11T02:26:41.308622Z","end":"2026-02-11T02:26:41.586613Z","steps":["trace[1638626375] 'process raft request' (duration: 277.483594ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:41.828151Z","caller":"traceutil/trace.go:171","msg":"trace[631799634] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:766; }","duration":"128.926985ms","start":"2026-02-11T02:26:41.699203Z","end":"2026-02-11T02:26:41.82813Z","steps":["trace[631799634] 'read index received' (duration: 42.876693ms)","trace[631799634] 'applied index is now lower than readState.Index' (duration: 86.049482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:41.828627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.376667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T02:26:41.83019Z","caller":"traceutil/trace.go:171","msg":"trace[1867990824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:742; }","duration":"130.979266ms","start":"2026-02-11T02:26:41.69919Z","end":"2026-02-11T02:26:41.83017Z","steps":["trace[1867990824] 'agreement among raft nodes before linearized reading' (duration: 129.214362ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:41.82896Z","caller":"traceutil/trace.go:171","msg":"trace[2103256126] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"130.993076ms","start":"2026-02-11T02:26:41.697948Z","end":"2026-02-11T02:26:41.828941Z","steps":["trace[2103256126] 'process raft request' (duration: 130.043422ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:41.828992Z","caller":"traceutil/trace.go:171","msg":"trace[242616882] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"128.429933ms","start":"2026-02-11T02:26:41.700556Z","end":"2026-02-11T02:26:41.828986Z","steps":["trace[242616882] 'process raft request' (duration: 127.536801ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:43.817676Z","caller":"traceutil/trace.go:171","msg":"trace[1970987992] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"223.550999ms","start":"2026-02-11T02:26:43.594103Z","end":"2026-02-11T02:26:43.817654Z","steps":["trace[1970987992] 'process raft request' (duration: 223.259152ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:44.170626Z","caller":"traceutil/trace.go:171","msg":"trace[407574067] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"336.595105ms","start":"2026-02-11T02:26:43.834006Z","end":"2026-02-11T02:26:44.170601Z","steps":["trace[407574067] 'read index received' (duration: 238.125647ms)","trace[407574067] 'applied index is now lower than readState.Index' (duration: 98.468188ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:44.170889Z","caller":"traceutil/trace.go:171","msg":"trace[78241888] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"347.243676ms","start":"2026-02-11T02:26:43.823568Z","end":"2026-02-11T02:26:44.170811Z","steps":["trace[78241888] 'process raft request' (duration: 248.618955ms)","trace[78241888] 'compare' (duration: 98.080528ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:44.171155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:43.823542Z","time spent":"347.408199ms","remote":"127.0.0.1:34426","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-11T02:26:44.171415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.384884ms","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-11T02:26:44.171561Z","caller":"traceutil/trace.go:171","msg":"trace[1546472153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:745; }","duration":"337.556767ms","start":"2026-02-11T02:26:43.833985Z","end":"2026-02-11T02:26:44.171541Z","steps":["trace[1546472153] 'agreement among raft nodes before linearized reading' (duration: 337.109836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:44.171721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:43.833976Z","time spent":"337.729681ms","remote":"127.0.0.1:34426","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-11T02:26:44.172159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.580919ms","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-11T02:26:44.172311Z","caller":"traceutil/trace.go:171","msg":"trace[445222895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"337.741352ms","start":"2026-02-11T02:26:43.834555Z","end":"2026-02-11T02:26:44.172297Z","steps":["trace[445222895] 'agreement among raft nodes before linearized reading' (duration: 337.527497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:44.172471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:43.83455Z","time spent":"337.902096ms","remote":"127.0.0.1:34426","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-11T02:26:44.19262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.323936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.126\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-11T02:26:44.193726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.0665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T02:26:44.192676Z","caller":"traceutil/trace.go:171","msg":"trace[942744222] range","detail":"{range_begin:/registry/masterleases/199.19.213.126; range_end:; response_count:1; response_revision:745; }","duration":"307.410689ms","start":"2026-02-11T02:26:43.885251Z","end":"2026-02-11T02:26:44.192662Z","steps":["trace[942744222] 'agreement among raft nodes before linearized reading' (duration: 307.253155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:44.195902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:43.885237Z","time spent":"310.510944ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.126\" "} {"level":"info","ts":"2026-02-11T02:26:44.193779Z","caller":"traceutil/trace.go:171","msg":"trace[4034846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:745; }","duration":"338.151652ms","start":"2026-02-11T02:26:43.855618Z","end":"2026-02-11T02:26:44.193769Z","steps":["trace[4034846] 'agreement among raft nodes before linearized reading' (duration: 338.015329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:44.196199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:43.85558Z","time spent":"340.505001ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T02:26:44.437084Z","caller":"traceutil/trace.go:171","msg":"trace[1780125730] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"238.70046ms","start":"2026-02-11T02:26:44.19836Z","end":"2026-02-11T02:26:44.437061Z","steps":["trace[1780125730] 'process raft request' (duration: 238.524806ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:44.437233Z","caller":"traceutil/trace.go:171","msg":"trace[2123815916] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"229.956316ms","start":"2026-02-11T02:26:44.207261Z","end":"2026-02-11T02:26:44.437217Z","steps":["trace[2123815916] 'process raft request' (duration: 229.763491ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:44.438034Z","caller":"traceutil/trace.go:171","msg":"trace[674331439] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"229.899215ms","start":"2026-02-11T02:26:44.208127Z","end":"2026-02-11T02:26:44.438026Z","steps":["trace[674331439] 'process raft request' (duration: 229.038774ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:44.658606Z","caller":"traceutil/trace.go:171","msg":"trace[1215911930] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"157.785564ms","start":"2026-02-11T02:26:44.500796Z","end":"2026-02-11T02:26:44.658582Z","steps":["trace[1215911930] 'read index received' (duration: 136.262876ms)","trace[1215911930] 'applied index is now lower than readState.Index' (duration: 21.521828ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:44.65862Z","caller":"traceutil/trace.go:171","msg":"trace[2119501106] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"402.443882ms","start":"2026-02-11T02:26:44.256153Z","end":"2026-02-11T02:26:44.658597Z","steps":["trace[2119501106] 'process raft request' (duration: 380.859213ms)","trace[2119501106] 'compare' (duration: 21.479996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:44.658733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.937637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T02:26:44.658753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:44.256135Z","time spent":"402.545654ms","remote":"127.0.0.1:34194","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-11T02:26:44.658764Z","caller":"traceutil/trace.go:171","msg":"trace[624259453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"158.015659ms","start":"2026-02-11T02:26:44.500739Z","end":"2026-02-11T02:26:44.658755Z","steps":["trace[624259453] 'agreement among raft nodes before linearized reading' (duration: 157.929597ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:46.607499Z","caller":"traceutil/trace.go:171","msg":"trace[1438712596] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"144.552329ms","start":"2026-02-11T02:26:46.462926Z","end":"2026-02-11T02:26:46.607479Z","steps":["trace[1438712596] 'process raft request' (duration: 79.269736ms)","trace[1438712596] 'compare' (duration: 64.514534ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:46.607818Z","caller":"traceutil/trace.go:171","msg":"trace[932518487] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"103.950572ms","start":"2026-02-11T02:26:46.502933Z","end":"2026-02-11T02:26:46.606884Z","steps":["trace[932518487] 'read index received' (duration: 39.140551ms)","trace[932518487] 'applied index is now lower than readState.Index' (duration: 64.8091ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:46.607906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.955166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:46.607934Z","caller":"traceutil/trace.go:171","msg":"trace[1733638255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"105.011068ms","start":"2026-02-11T02:26:46.502914Z","end":"2026-02-11T02:26:46.607925Z","steps":["trace[1733638255] 'agreement among raft nodes before linearized reading' (duration: 104.951397ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:58.670138Z","caller":"traceutil/trace.go:171","msg":"trace[2056053092] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"169.8271ms","start":"2026-02-11T02:26:58.500295Z","end":"2026-02-11T02:26:58.670122Z","steps":["trace[2056053092] 'read index received' (duration: 169.697356ms)","trace[2056053092] 'applied index is now lower than readState.Index' (duration: 129.334µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:58.670203Z","caller":"traceutil/trace.go:171","msg":"trace[1778414148] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"358.924153ms","start":"2026-02-11T02:26:58.311274Z","end":"2026-02-11T02:26:58.670198Z","steps":["trace[1778414148] 'process raft request' (duration: 358.759708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:58.670267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:58.311264Z","time spent":"358.953113ms","remote":"127.0.0.1:34426","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-11T02:26:58.670352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.056835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:58.670367Z","caller":"traceutil/trace.go:171","msg":"trace[1442196631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:837; }","duration":"170.070556ms","start":"2026-02-11T02:26:58.500292Z","end":"2026-02-11T02:26:58.670362Z","steps":["trace[1442196631] 'agreement among raft nodes before linearized reading' (duration: 170.044435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:58.67056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.574174ms","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-11T02:26:58.670573Z","caller":"traceutil/trace.go:171","msg":"trace[1113396631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:837; }","duration":"143.610456ms","start":"2026-02-11T02:26:58.526959Z","end":"2026-02-11T02:26:58.67057Z","steps":["trace[1113396631] 'agreement among raft nodes before linearized reading' (duration: 143.544714ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:58.889602Z","caller":"traceutil/trace.go:171","msg":"trace[403258310] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"212.217111ms","start":"2026-02-11T02:26:58.677367Z","end":"2026-02-11T02:26:58.889585Z","steps":["trace[403258310] 'process raft request' (duration: 212.109879ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:26:59.107402Z","caller":"traceutil/trace.go:171","msg":"trace[2005501622] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"428.800409ms","start":"2026-02-11T02:26:58.678587Z","end":"2026-02-11T02:26:59.107387Z","steps":["trace[2005501622] 'process raft request' (duration: 428.773169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:26:59.107501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:58.678573Z","time spent":"428.876202ms","remote":"127.0.0.1:34426","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-11T02:26:59.10758Z","caller":"traceutil/trace.go:171","msg":"trace[675927033] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"430.07162ms","start":"2026-02-11T02:26:58.677503Z","end":"2026-02-11T02:26:59.107575Z","steps":["trace[675927033] 'process raft request' (duration: 405.431775ms)","trace[675927033] 'compare' (duration: 24.343538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:59.107603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:58.677498Z","time spent":"430.090671ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:26:59.759699Z","caller":"traceutil/trace.go:171","msg":"trace[962959278] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"256.811625ms","start":"2026-02-11T02:26:59.502875Z","end":"2026-02-11T02:26:59.759686Z","steps":["trace[962959278] 'read index received' (duration: 239.648083ms)","trace[962959278] 'applied index is now lower than readState.Index' (duration: 17.162522ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:26:59.759756Z","caller":"traceutil/trace.go:171","msg":"trace[926190767] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"647.056239ms","start":"2026-02-11T02:26:59.112693Z","end":"2026-02-11T02:26:59.759749Z","steps":["trace[926190767] 'process raft request' (duration: 629.820195ms)","trace[926190767] 'compare' (duration: 17.069979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:26:59.759816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:26:59.112681Z","time spent":"647.087979ms","remote":"127.0.0.1:34426","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-11T02:26:59.759803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.923798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:26:59.759851Z","caller":"traceutil/trace.go:171","msg":"trace[662375136] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"257.007501ms","start":"2026-02-11T02:26:59.50283Z","end":"2026-02-11T02:26:59.759838Z","steps":["trace[662375136] 'agreement among raft nodes before linearized reading' (duration: 256.906108ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:01.064826Z","caller":"traceutil/trace.go:171","msg":"trace[1962657888] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:874; }","duration":"171.998024ms","start":"2026-02-11T02:27:00.892809Z","end":"2026-02-11T02:27:01.064807Z","steps":["trace[1962657888] 'read index received' (duration: 171.777258ms)","trace[1962657888] 'applied index is now lower than readState.Index' (duration: 220.096µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:01.064979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.130327ms","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-11T02:27:01.064999Z","caller":"traceutil/trace.go:171","msg":"trace[257814797] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:843; }","duration":"172.223949ms","start":"2026-02-11T02:27:00.892769Z","end":"2026-02-11T02:27:01.064993Z","steps":["trace[257814797] 'agreement among raft nodes before linearized reading' (duration: 172.104176ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:01.065051Z","caller":"traceutil/trace.go:171","msg":"trace[1646506975] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"385.136087ms","start":"2026-02-11T02:27:00.67989Z","end":"2026-02-11T02:27:01.065026Z","steps":["trace[1646506975] 'process raft request' (duration: 384.721987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:01.06514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:00.67988Z","time spent":"385.21282ms","remote":"127.0.0.1:34426","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-11T02:27:01.258363Z","caller":"traceutil/trace.go:171","msg":"trace[1575306409] linearizableReadLoop","detail":"{readStateIndex:876; appliedIndex:875; }","duration":"147.973323ms","start":"2026-02-11T02:27:01.110374Z","end":"2026-02-11T02:27:01.258347Z","steps":["trace[1575306409] 'read index received' (duration: 69.679391ms)","trace[1575306409] 'applied index is now lower than readState.Index' (duration: 78.293282ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:01.25855Z","caller":"traceutil/trace.go:171","msg":"trace[859496976] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"189.253428ms","start":"2026-02-11T02:27:01.069289Z","end":"2026-02-11T02:27:01.258542Z","steps":["trace[859496976] 'process raft request' (duration: 110.781841ms)","trace[859496976] 'compare' (duration: 78.101618ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:01.258715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.331302ms","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-11T02:27:01.258738Z","caller":"traceutil/trace.go:171","msg":"trace[1008570216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:844; }","duration":"148.384203ms","start":"2026-02-11T02:27:01.110346Z","end":"2026-02-11T02:27:01.25873Z","steps":["trace[1008570216] 'agreement among raft nodes before linearized reading' (duration: 148.302961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:01.258942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.925753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T02:27:01.258961Z","caller":"traceutil/trace.go:171","msg":"trace[234129603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:844; }","duration":"147.955183ms","start":"2026-02-11T02:27:01.111001Z","end":"2026-02-11T02:27:01.258956Z","steps":["trace[234129603] 'agreement among raft nodes before linearized reading' (duration: 147.904852ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:04.421094Z","caller":"traceutil/trace.go:171","msg":"trace[1842468705] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"384.345618ms","start":"2026-02-11T02:27:04.036733Z","end":"2026-02-11T02:27:04.421078Z","steps":["trace[1842468705] 'process raft request' (duration: 384.270616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:04.421242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:04.036725Z","time spent":"384.465791ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:27:05.205643Z","caller":"traceutil/trace.go:171","msg":"trace[89603957] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"115.811834ms","start":"2026-02-11T02:27:05.089815Z","end":"2026-02-11T02:27:05.205627Z","steps":["trace[89603957] 'process raft request' (duration: 115.685891ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:11.502864Z","caller":"traceutil/trace.go:171","msg":"trace[2047775878] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:904; }","duration":"201.568809ms","start":"2026-02-11T02:27:11.301284Z","end":"2026-02-11T02:27:11.502853Z","steps":["trace[2047775878] 'read index received' (duration: 201.463566ms)","trace[2047775878] 'applied index is now lower than readState.Index' (duration: 104.753µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:11.503018Z","caller":"traceutil/trace.go:171","msg":"trace[1426252397] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"281.568233ms","start":"2026-02-11T02:27:11.221444Z","end":"2026-02-11T02:27:11.503013Z","steps":["trace[1426252397] 'process raft request' (duration: 281.340248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:11.503142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.849275ms","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-11T02:27:11.50316Z","caller":"traceutil/trace.go:171","msg":"trace[1655673346] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:871; }","duration":"201.894977ms","start":"2026-02-11T02:27:11.301258Z","end":"2026-02-11T02:27:11.503153Z","steps":["trace[1655673346] 'agreement among raft nodes before linearized reading' (duration: 201.834765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:11.503255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.336001ms","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-11T02:27:11.503274Z","caller":"traceutil/trace.go:171","msg":"trace[1139794598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:871; }","duration":"194.366782ms","start":"2026-02-11T02:27:11.308903Z","end":"2026-02-11T02:27:11.50327Z","steps":["trace[1139794598] 'agreement among raft nodes before linearized reading' (duration: 194.31244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:11.503428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.290052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T02:27:11.503441Z","caller":"traceutil/trace.go:171","msg":"trace[1873500328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:871; }","duration":"190.312523ms","start":"2026-02-11T02:27:11.313125Z","end":"2026-02-11T02:27:11.503437Z","steps":["trace[1873500328] 'agreement among raft nodes before linearized reading' (duration: 190.276802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:12.531916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.353939ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T02:27:12.532064Z","caller":"traceutil/trace.go:171","msg":"trace[1772031969] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"1.025607294s","start":"2026-02-11T02:27:11.506369Z","end":"2026-02-11T02:27:12.531976Z","steps":["trace[1772031969] 'process raft request' (duration: 621.136562ms)","trace[1772031969] 'compare' (duration: 404.076272ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:12.532109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:11.506362Z","time spent":"1.025728127s","remote":"127.0.0.1:34426","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-11T02:27:12.948682Z","caller":"traceutil/trace.go:171","msg":"trace[521170972] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:906; }","duration":"471.873406ms","start":"2026-02-11T02:27:12.476795Z","end":"2026-02-11T02:27:12.948668Z","steps":["trace[521170972] 'read index received' (duration: 386.080201ms)","trace[521170972] 'applied index is now lower than readState.Index' (duration: 85.792675ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:12.948823Z","caller":"traceutil/trace.go:171","msg":"trace[1998469429] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"1.441616519s","start":"2026-02-11T02:27:11.5072Z","end":"2026-02-11T02:27:12.948817Z","steps":["trace[1998469429] 'process raft request' (duration: 1.355737751s)","trace[1998469429] 'compare' (duration: 85.641762ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:12.948882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:11.507194Z","time spent":"1.44164617s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:27:12.94897Z","caller":"traceutil/trace.go:171","msg":"trace[565765032] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"1.434263269s","start":"2026-02-11T02:27:11.514702Z","end":"2026-02-11T02:27:12.948966Z","steps":["trace[565765032] 'process raft request' (duration: 1.433943761s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:12.948994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:11.514685Z","time spent":"1.434295389s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T02:27:12.949092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.298617ms","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-11T02:27:12.949107Z","caller":"traceutil/trace.go:171","msg":"trace[444651562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:874; }","duration":"472.348188ms","start":"2026-02-11T02:27:12.476755Z","end":"2026-02-11T02:27:12.949103Z","steps":["trace[444651562] 'agreement among raft nodes before linearized reading' (duration: 472.290747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:12.94912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:12.476738Z","time spent":"472.379539ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:27:12.949186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.284982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:12.949198Z","caller":"traceutil/trace.go:171","msg":"trace[1403629640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:874; }","duration":"447.320224ms","start":"2026-02-11T02:27:12.501874Z","end":"2026-02-11T02:27:12.949194Z","steps":["trace[1403629640] 'agreement among raft nodes before linearized reading' (duration: 447.301803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:12.949211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:12.501837Z","time spent":"447.371765ms","remote":"127.0.0.1:34152","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-11T02:27:18.867455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578884395980642492,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:27:19.368418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578884395980642492,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T02:27:19.600713Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.236452578s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T02:27:19.601053Z","caller":"traceutil/trace.go:171","msg":"trace[1305495651] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"1.549008252s","start":"2026-02-11T02:27:18.05202Z","end":"2026-02-11T02:27:19.601029Z","steps":["trace[1305495651] 'process raft request' (duration: 1.548844028s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:19.601193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:18.052005Z","time spent":"1.549114294s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:27:19.664826Z","caller":"traceutil/trace.go:171","msg":"trace[367298621] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"1.121931129s","start":"2026-02-11T02:27:18.542859Z","end":"2026-02-11T02:27:19.66479Z","steps":["trace[367298621] 'process raft request' (duration: 1.121625361s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:19.665004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:18.542845Z","time spent":"1.122105004s","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:27:20.03665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.060948ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-11T02:27:20.03681Z","caller":"traceutil/trace.go:171","msg":"trace[69235600] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:933; }","duration":"1.669914989s","start":"2026-02-11T02:27:18.366876Z","end":"2026-02-11T02:27:20.036791Z","steps":["trace[69235600] 'read index received' (duration: 1.235525655s)","trace[69235600] 'applied index is now lower than readState.Index' (duration: 434.388714ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:20.037056Z","caller":"traceutil/trace.go:171","msg":"trace[1794779449] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"367.176846ms","start":"2026-02-11T02:27:19.669855Z","end":"2026-02-11T02:27:20.037032Z","steps":["trace[1794779449] 'process raft request' (duration: 366.864208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.037168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:19.669835Z","time spent":"367.275739ms","remote":"127.0.0.1:34236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":800,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:27:20.03892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029594384s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T02:27:20.038953Z","caller":"traceutil/trace.go:171","msg":"trace[1260906837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"1.029666686s","start":"2026-02-11T02:27:19.009279Z","end":"2026-02-11T02:27:20.038945Z","steps":["trace[1260906837] 'agreement among raft nodes before linearized reading' (duration: 1.027779149s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.038974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:19.009262Z","time spent":"1.029707186s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T02:27:20.039114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.672237187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4740"} {"level":"info","ts":"2026-02-11T02:27:20.039151Z","caller":"traceutil/trace.go:171","msg":"trace[1208715643] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:900; }","duration":"1.672296428s","start":"2026-02-11T02:27:18.366849Z","end":"2026-02-11T02:27:20.039146Z","steps":["trace[1208715643] 'agreement among raft nodes before linearized reading' (duration: 1.670134394s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.039164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:18.366836Z","time spent":"1.672324929s","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4764,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-11T02:27:20.039241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537947164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:20.039253Z","caller":"traceutil/trace.go:171","msg":"trace[683386461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"1.537982194s","start":"2026-02-11T02:27:18.501267Z","end":"2026-02-11T02:27:20.03925Z","steps":["trace[683386461] 'agreement among raft nodes before linearized reading' (duration: 1.535764909s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.039264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:18.501257Z","time spent":"1.538005396s","remote":"127.0.0.1:34152","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-11T02:27:20.039363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.078579ms","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-11T02:27:20.039376Z","caller":"traceutil/trace.go:171","msg":"trace[1924226316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"489.12071ms","start":"2026-02-11T02:27:19.550252Z","end":"2026-02-11T02:27:20.039372Z","steps":["trace[1924226316] 'agreement among raft nodes before linearized reading' (duration: 486.791713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.039388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:19.550235Z","time spent":"489.150821ms","remote":"127.0.0.1:34426","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-11T02:27:20.03969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074459453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T02:27:20.039705Z","caller":"traceutil/trace.go:171","msg":"trace[491173582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"1.074507975s","start":"2026-02-11T02:27:18.965193Z","end":"2026-02-11T02:27:20.039701Z","steps":["trace[491173582] 'agreement among raft nodes before linearized reading' (duration: 1.071880299s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.03972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:18.96518Z","time spent":"1.074537095s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T02:27:20.039782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070440373s","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-11T02:27:20.039795Z","caller":"traceutil/trace.go:171","msg":"trace[447338051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"1.070463263s","start":"2026-02-11T02:27:18.969327Z","end":"2026-02-11T02:27:20.03979Z","steps":["trace[447338051] 'agreement among raft nodes before linearized reading' (duration: 1.067742625s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.039811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:18.969321Z","time spent":"1.070485114s","remote":"127.0.0.1:34426","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-11T02:27:20.039991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.281850801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T02:27:20.040011Z","caller":"traceutil/trace.go:171","msg":"trace[1185580398] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:900; }","duration":"1.281904733s","start":"2026-02-11T02:27:18.758099Z","end":"2026-02-11T02:27:20.040004Z","steps":["trace[1185580398] 'agreement among raft nodes before linearized reading' (duration: 1.278978729s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.040025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:18.758081Z","time spent":"1.281938883s","remote":"127.0.0.1:34516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:27:20.041052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456973054s","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-11T02:27:20.041071Z","caller":"traceutil/trace.go:171","msg":"trace[45395371] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"1.457020905s","start":"2026-02-11T02:27:18.584045Z","end":"2026-02-11T02:27:20.041066Z","steps":["trace[45395371] 'agreement among raft nodes before linearized reading' (duration: 1.453037176s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:20.041088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:18.58403Z","time spent":"1.457054886s","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T02:27:22.230522Z","caller":"traceutil/trace.go:171","msg":"trace[931554390] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:951; }","duration":"151.511422ms","start":"2026-02-11T02:27:22.078995Z","end":"2026-02-11T02:27:22.230507Z","steps":["trace[931554390] 'read index received' (duration: 53.184057ms)","trace[931554390] 'applied index is now lower than readState.Index' (duration: 98.326595ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:22.230589Z","caller":"traceutil/trace.go:171","msg":"trace[1125460186] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"147.894973ms","start":"2026-02-11T02:27:22.082688Z","end":"2026-02-11T02:27:22.230583Z","steps":["trace[1125460186] 'process raft request' (duration: 147.799281ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:22.230572Z","caller":"traceutil/trace.go:171","msg":"trace[705740755] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"152.065547ms","start":"2026-02-11T02:27:22.078478Z","end":"2026-02-11T02:27:22.230543Z","steps":["trace[705740755] 'process raft request' (duration: 151.923553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:22.230802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.79359ms","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-11T02:27:22.230821Z","caller":"traceutil/trace.go:171","msg":"trace[293218406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:918; }","duration":"151.832501ms","start":"2026-02-11T02:27:22.078983Z","end":"2026-02-11T02:27:22.230816Z","steps":["trace[293218406] 'agreement among raft nodes before linearized reading' (duration: 151.742589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:22.230891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.712983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-11T02:27:22.230903Z","caller":"traceutil/trace.go:171","msg":"trace[1967531459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:918; }","duration":"136.750864ms","start":"2026-02-11T02:27:22.094147Z","end":"2026-02-11T02:27:22.230898Z","steps":["trace[1967531459] 'agreement among raft nodes before linearized reading' (duration: 136.715643ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:32.892211Z","caller":"traceutil/trace.go:171","msg":"trace[1590527354] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"298.664806ms","start":"2026-02-11T02:27:32.593526Z","end":"2026-02-11T02:27:32.892191Z","steps":["trace[1590527354] 'process raft request' (duration: 298.611565ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:27:32.893286Z","caller":"traceutil/trace.go:171","msg":"trace[1021944520] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"398.715005ms","start":"2026-02-11T02:27:32.494556Z","end":"2026-02-11T02:27:32.893271Z","steps":["trace[1021944520] 'process raft request' (duration: 375.662459ms)","trace[1021944520] 'compare' (duration: 20.998765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:32.89351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:32.494529Z","time spent":"398.796457ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T02:27:32.893616Z","caller":"traceutil/trace.go:171","msg":"trace[1472143589] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"376.764667ms","start":"2026-02-11T02:27:32.516841Z","end":"2026-02-11T02:27:32.893606Z","steps":["trace[1472143589] 'read index received' (duration: 353.382033ms)","trace[1472143589] 'applied index is now lower than readState.Index' (duration: 23.381604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T02:27:32.89388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.036374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-11T02:27:32.893913Z","caller":"traceutil/trace.go:171","msg":"trace[1073425624] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:970; }","duration":"377.114326ms","start":"2026-02-11T02:27:32.516788Z","end":"2026-02-11T02:27:32.893903Z","steps":["trace[1073425624] 'agreement among raft nodes before linearized reading' (duration: 377.002623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:32.893941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:32.516765Z","time spent":"377.169227ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-11T02:27:32.895012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.189597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:32.895061Z","caller":"traceutil/trace.go:171","msg":"trace[1966639714] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:970; }","duration":"363.27698ms","start":"2026-02-11T02:27:32.531771Z","end":"2026-02-11T02:27:32.895048Z","steps":["trace[1966639714] 'agreement among raft nodes before linearized reading' (duration: 363.189287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:32.895093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:32.531751Z","time spent":"363.33303ms","remote":"127.0.0.1:44832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-11T02:27:33.809185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.882954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T02:27:33.809357Z","caller":"traceutil/trace.go:171","msg":"trace[1663894632] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:974; }","duration":"114.103559ms","start":"2026-02-11T02:27:33.695229Z","end":"2026-02-11T02:27:33.809333Z","steps":["trace[1663894632] 'range keys from in-memory index tree' (duration: 113.832652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:34.388942Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":578884395980642651,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T02:27:34.502977Z","caller":"traceutil/trace.go:171","msg":"trace[460456218] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"614.903171ms","start":"2026-02-11T02:27:33.888057Z","end":"2026-02-11T02:27:34.50296Z","steps":["trace[460456218] 'read index received' (duration: 614.685336ms)","trace[460456218] 'applied index is now lower than readState.Index' (duration: 216.745µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T02:27:34.503173Z","caller":"traceutil/trace.go:171","msg":"trace[1334317091] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"687.72322ms","start":"2026-02-11T02:27:33.815439Z","end":"2026-02-11T02:27:34.503162Z","steps":["trace[1334317091] 'process raft request' (duration: 687.369041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:34.504066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:33.8154Z","time spent":"688.597102ms","remote":"127.0.0.1:34426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T02:27:34.50433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.269504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.126\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-11T02:27:34.504355Z","caller":"traceutil/trace.go:171","msg":"trace[1142684748] range","detail":"{range_begin:/registry/masterleases/199.19.213.126; range_end:; response_count:1; response_revision:975; }","duration":"616.337116ms","start":"2026-02-11T02:27:33.888011Z","end":"2026-02-11T02:27:34.504348Z","steps":["trace[1142684748] 'agreement among raft nodes before linearized reading' (duration: 616.170362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:27:34.504373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T02:27:33.887995Z","time spent":"616.374648ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.126\" "} {"level":"warn","ts":"2026-02-11T02:27:34.504511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.799681ms","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-11T02:27:34.504528Z","caller":"traceutil/trace.go:171","msg":"trace[36065947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:975; }","duration":"236.841592ms","start":"2026-02-11T02:27:34.267681Z","end":"2026-02-11T02:27:34.504523Z","steps":["trace[36065947] 'agreement among raft nodes before linearized reading' (duration: 236.791521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T02:30:46.84296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.405275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-02-11T02:30:46.843345Z","caller":"traceutil/trace.go:171","msg":"trace[576174246] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1803; }","duration":"283.824116ms","start":"2026-02-11T02:30:46.559502Z","end":"2026-02-11T02:30:46.843326Z","steps":["trace[576174246] 'range keys from in-memory index tree' (duration: 283.252571ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T02:30:53.625125Z","caller":"traceutil/trace.go:171","msg":"trace[1125827980] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"280.299899ms","start":"2026-02-11T02:30:53.344801Z","end":"2026-02-11T02:30:53.625101Z","steps":["trace[1125827980] 'process raft request' (duration: 280.107936ms)"],"step_count":1}