{"level":"warn","ts":"2026-02-20T18:26:01.475882Z","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-20T18:26:01.47602Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-20T18:26:01.47613Z","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-20T18:26:01.476147Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-20T18:26:01.476179Z","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-20T18:26:01.477448Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-02-20T18:26:01.477566Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-20T18:26:01.482565Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.598052ms"} {"level":"info","ts":"2026-02-20T18:26:01.490271Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-02-20T18:26:01.490366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T18:26:01.4904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-02-20T18:26:01.49042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T18:26:01.490434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-02-20T18:26:01.490481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-02-20T18:26:01.498751Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T18:26:01.5001Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T18:26:01.503281Z","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-20T18:26:01.505596Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T18:26:01.505954Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T18:26:01.506202Z","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-20T18:26:01.506245Z","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-20T18:26:01.506277Z","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-20T18:26:01.50689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-02-20T18:26:01.509544Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-02-20T18:26:01.512557Z","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-20T18:26:01.512816Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-20T18:26:01.512859Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T18:26:01.512892Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-02-20T18:26:01.512917Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T18:26:02.091557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T18:26:02.091625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T18:26:02.091683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-02-20T18:26:02.091703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-02-20T18:26:02.091712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-20T18:26:02.091726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-02-20T18:26:02.091739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-02-20T18:26:02.117192Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:26:02.118065Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:26:02.118073Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T18:26:02.118348Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:26:02.118584Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T18:26:02.118652Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T18:26:02.119439Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:26:02.119618Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:26:02.119691Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:26:02.123239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T18:26:02.123403Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-02-20T18:26:10.883415Z","caller":"traceutil/trace.go:171","msg":"trace[1712096718] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"140.866802ms","start":"2026-02-20T18:26:10.742507Z","end":"2026-02-20T18:26:10.883373Z","steps":["trace[1712096718] 'process raft request' (duration: 93.361039ms)","trace[1712096718] 'compare' (duration: 47.230918ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:10.883626Z","caller":"traceutil/trace.go:171","msg":"trace[513281636] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"139.872903ms","start":"2026-02-20T18:26:10.743713Z","end":"2026-02-20T18:26:10.883586Z","steps":["trace[513281636] 'process raft request' (duration: 139.570098ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:10.883762Z","caller":"traceutil/trace.go:171","msg":"trace[1840582604] linearizableReadLoop","detail":"{readStateIndex:327; appliedIndex:322; }","duration":"118.076221ms","start":"2026-02-20T18:26:10.765661Z","end":"2026-02-20T18:26:10.883737Z","steps":["trace[1840582604] 'read index received' (duration: 14.143968ms)","trace[1840582604] 'applied index is now lower than readState.Index' (duration: 103.931133ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:10.883878Z","caller":"traceutil/trace.go:171","msg":"trace[1011191943] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"140.096657ms","start":"2026-02-20T18:26:10.743773Z","end":"2026-02-20T18:26:10.883869Z","steps":["trace[1011191943] 'process raft request' (duration: 139.561798ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:10.884047Z","caller":"traceutil/trace.go:171","msg":"trace[1754947586] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"139.433625ms","start":"2026-02-20T18:26:10.744598Z","end":"2026-02-20T18:26:10.884031Z","steps":["trace[1754947586] 'process raft request' (duration: 139.006518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:10.884094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.416546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-20T18:26:10.884292Z","caller":"traceutil/trace.go:171","msg":"trace[272215983] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:316; }","duration":"118.598871ms","start":"2026-02-20T18:26:10.765611Z","end":"2026-02-20T18:26:10.88421Z","steps":["trace[272215983] 'agreement among raft nodes before linearized reading' (duration: 118.372706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:20.144434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933904736,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:26:20.559159Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.448789752s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:26:20.559742Z","caller":"traceutil/trace.go:171","msg":"trace[254056668] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"915.998907ms","start":"2026-02-20T18:26:19.643715Z","end":"2026-02-20T18:26:20.559714Z","steps":["trace[254056668] 'read index received' (duration: 915.745032ms)","trace[254056668] 'applied index is now lower than readState.Index' (duration: 252.095µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:20.559836Z","caller":"traceutil/trace.go:171","msg":"trace[1114077692] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.449527184s","start":"2026-02-20T18:26:19.110262Z","end":"2026-02-20T18:26:20.559789Z","steps":["trace[1114077692] 'process raft request' (duration: 1.449218439s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:20.559911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.176369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:26:20.559949Z","caller":"traceutil/trace.go:171","msg":"trace[25558073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:379; }","duration":"916.26929ms","start":"2026-02-20T18:26:19.643669Z","end":"2026-02-20T18:26:20.559938Z","steps":["trace[25558073] 'agreement among raft nodes before linearized reading' (duration: 916.179259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:20.559984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:19.643641Z","time spent":"916.334452ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:26:20.560504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:19.110243Z","time spent":"1.449672297s","remote":"127.0.0.1:58650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:26:21.060516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933904739,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:26:21.083524Z","caller":"traceutil/trace.go:171","msg":"trace[1800367821] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"518.608706ms","start":"2026-02-20T18:26:20.56489Z","end":"2026-02-20T18:26:21.083498Z","steps":["trace[1800367821] 'process raft request' (duration: 518.136258ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:21.0836Z","caller":"traceutil/trace.go:171","msg":"trace[1900591161] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:393; }","duration":"523.746367ms","start":"2026-02-20T18:26:20.559819Z","end":"2026-02-20T18:26:21.083566Z","steps":["trace[1900591161] 'read index received' (duration: 513.118441ms)","trace[1900591161] 'applied index is now lower than readState.Index' (duration: 10.626086ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:26:21.08375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:20.564866Z","time spent":"518.780449ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:26:21.083801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382790123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:26:21.083862Z","caller":"traceutil/trace.go:171","msg":"trace[1870960231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:381; }","duration":"1.382884205s","start":"2026-02-20T18:26:19.700957Z","end":"2026-02-20T18:26:21.083841Z","steps":["trace[1870960231] 'agreement among raft nodes before linearized reading' (duration: 1.382719993s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:21.083916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:19.700944Z","time spent":"1.382959326s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:26:21.084086Z","caller":"traceutil/trace.go:171","msg":"trace[778521014] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"516.199803ms","start":"2026-02-20T18:26:20.56787Z","end":"2026-02-20T18:26:21.08407Z","steps":["trace[778521014] 'process raft request' (duration: 515.301058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:21.084181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:20.567855Z","time spent":"516.268215ms","remote":"127.0.0.1:58660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:26:21.084249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035820228s","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-20T18:26:21.084291Z","caller":"traceutil/trace.go:171","msg":"trace[580742365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:381; }","duration":"1.0359075s","start":"2026-02-20T18:26:20.048373Z","end":"2026-02-20T18:26:21.08428Z","steps":["trace[580742365] 'agreement among raft nodes before linearized reading' (duration: 1.035822898s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:21.084322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:20.048337Z","time spent":"1.035979311s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:26:21.084406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164853032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T18:26:21.084439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.716831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:26:21.084494Z","caller":"traceutil/trace.go:171","msg":"trace[563654754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:381; }","duration":"704.782373ms","start":"2026-02-20T18:26:20.379684Z","end":"2026-02-20T18:26:21.084466Z","steps":["trace[563654754] 'agreement among raft nodes before linearized reading' (duration: 704.726582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:21.084528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:20.379662Z","time spent":"704.855114ms","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:26:21.084844Z","caller":"traceutil/trace.go:171","msg":"trace[1483404665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:381; }","duration":"1.164944872s","start":"2026-02-20T18:26:19.919493Z","end":"2026-02-20T18:26:21.084438Z","steps":["trace[1483404665] 'agreement among raft nodes before linearized reading' (duration: 1.164867791s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:21.084902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:19.919459Z","time spent":"1.165431041s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:26:35.525252Z","caller":"traceutil/trace.go:171","msg":"trace[1102017840] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"362.63438ms","start":"2026-02-20T18:26:35.162577Z","end":"2026-02-20T18:26:35.525211Z","steps":["trace[1102017840] 'read index received' (duration: 362.183542ms)","trace[1102017840] 'applied index is now lower than readState.Index' (duration: 449.308µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:26:35.525392Z","caller":"traceutil/trace.go:171","msg":"trace[202685346] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"364.363821ms","start":"2026-02-20T18:26:35.160971Z","end":"2026-02-20T18:26:35.525335Z","steps":["trace[202685346] 'process raft request' (duration: 364.007674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:35.525587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.972606ms","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-20T18:26:35.525642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:35.16094Z","time spent":"364.590916ms","remote":"127.0.0.1:58380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4258,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:26:35.525681Z","caller":"traceutil/trace.go:171","msg":"trace[1946003876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"363.136469ms","start":"2026-02-20T18:26:35.162526Z","end":"2026-02-20T18:26:35.525662Z","steps":["trace[1946003876] 'agreement among raft nodes before linearized reading' (duration: 362.907775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:35.525727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:35.162504Z","time spent":"363.21408ms","remote":"127.0.0.1:58478","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-20T18:26:35.525694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.775398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-20T18:26:35.525712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.563593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-20T18:26:35.525823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.913972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:26:35.525648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.028502ms","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-20T18:26:35.525933Z","caller":"traceutil/trace.go:171","msg":"trace[2134797054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"341.402218ms","start":"2026-02-20T18:26:35.184521Z","end":"2026-02-20T18:26:35.525923Z","steps":["trace[2134797054] 'agreement among raft nodes before linearized reading' (duration: 340.944371ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:35.526549Z","caller":"traceutil/trace.go:171","msg":"trace[1647218024] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:476; }","duration":"300.806356ms","start":"2026-02-20T18:26:35.225054Z","end":"2026-02-20T18:26:35.52586Z","steps":["trace[1647218024] 'agreement among raft nodes before linearized reading' (duration: 300.45048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:35.526652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:35.22504Z","time spent":"301.59114ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.200\" "} {"level":"warn","ts":"2026-02-20T18:26:35.526694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:35.184491Z","time spent":"342.179342ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T18:26:35.525866Z","caller":"traceutil/trace.go:171","msg":"trace[1167241967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"337.018102ms","start":"2026-02-20T18:26:35.188822Z","end":"2026-02-20T18:26:35.52584Z","steps":["trace[1167241967] 'agreement among raft nodes before linearized reading' (duration: 336.708807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:35.52693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:35.188791Z","time spent":"338.124811ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:26:35.527276Z","caller":"traceutil/trace.go:171","msg":"trace[2091117648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"340.119466ms","start":"2026-02-20T18:26:35.185785Z","end":"2026-02-20T18:26:35.525904Z","steps":["trace[2091117648] 'agreement among raft nodes before linearized reading' (duration: 339.892662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:35.527375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:35.185766Z","time spent":"341.586061ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:26:42.896574Z","caller":"traceutil/trace.go:171","msg":"trace[1036887468] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"592.486142ms","start":"2026-02-20T18:26:42.304051Z","end":"2026-02-20T18:26:42.896537Z","steps":["trace[1036887468] 'process raft request' (duration: 592.157917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:42.896843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:42.303925Z","time spent":"592.754967ms","remote":"127.0.0.1:58392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:26:43.798126Z","caller":"traceutil/trace.go:171","msg":"trace[320461718] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"171.098191ms","start":"2026-02-20T18:26:43.626991Z","end":"2026-02-20T18:26:43.798089Z","steps":["trace[320461718] 'process raft request' (duration: 170.867617ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:45.231585Z","caller":"traceutil/trace.go:171","msg":"trace[1377761637] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"819.2506ms","start":"2026-02-20T18:26:44.412249Z","end":"2026-02-20T18:26:45.231499Z","steps":["trace[1377761637] 'process raft request' (duration: 818.951025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:45.231842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:44.412215Z","time spent":"819.492283ms","remote":"127.0.0.1:58478","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-20T18:26:45.433888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.031327ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-20T18:26:50.679573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.461211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-20T18:26:50.67971Z","caller":"traceutil/trace.go:171","msg":"trace[961540929] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:576; }","duration":"133.671154ms","start":"2026-02-20T18:26:50.546007Z","end":"2026-02-20T18:26:50.679678Z","steps":["trace[961540929] 'range keys from in-memory index tree' (duration: 133.138235ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:55.842686Z","caller":"traceutil/trace.go:171","msg":"trace[1179384594] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"137.889419ms","start":"2026-02-20T18:26:55.704778Z","end":"2026-02-20T18:26:55.842668Z","steps":["trace[1179384594] 'process raft request' (duration: 137.637245ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:55.842522Z","caller":"traceutil/trace.go:171","msg":"trace[2040398389] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"139.612799ms","start":"2026-02-20T18:26:55.702876Z","end":"2026-02-20T18:26:55.842489Z","steps":["trace[2040398389] 'process raft request' (duration: 139.13264ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:58.279419Z","caller":"traceutil/trace.go:171","msg":"trace[1562706247] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"233.386534ms","start":"2026-02-20T18:26:58.046014Z","end":"2026-02-20T18:26:58.2794Z","steps":["trace[1562706247] 'process raft request' (duration: 233.347803ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:58.279515Z","caller":"traceutil/trace.go:171","msg":"trace[237235502] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"427.987917ms","start":"2026-02-20T18:26:57.851496Z","end":"2026-02-20T18:26:58.279484Z","steps":["trace[237235502] 'process raft request' (duration: 419.526828ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:26:58.279674Z","caller":"traceutil/trace.go:171","msg":"trace[1672273021] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:695; }","duration":"421.425252ms","start":"2026-02-20T18:26:57.858238Z","end":"2026-02-20T18:26:58.279663Z","steps":["trace[1672273021] 'read index received' (duration: 6.563486ms)","trace[1672273021] 'applied index is now lower than readState.Index' (duration: 414.860836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:26:58.27969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:57.851483Z","time spent":"428.103269ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:26:58.279814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.568144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:26:58.279839Z","caller":"traceutil/trace.go:171","msg":"trace[79775825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"421.642646ms","start":"2026-02-20T18:26:57.858189Z","end":"2026-02-20T18:26:58.279831Z","steps":["trace[79775825] 'agreement among raft nodes before linearized reading' (duration: 421.515374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:26:58.27986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:26:57.858167Z","time spent":"421.686096ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:27:04.705918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.030434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:27:04.706005Z","caller":"traceutil/trace.go:171","msg":"trace[1916753011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:693; }","duration":"401.229037ms","start":"2026-02-20T18:27:04.304755Z","end":"2026-02-20T18:27:04.705984Z","steps":["trace[1916753011] 'range keys from in-memory index tree' (duration: 400.768289ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:04.706045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:04.304733Z","time spent":"401.301289ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:27:04.706361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.346496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:27:04.706391Z","caller":"traceutil/trace.go:171","msg":"trace[1400771749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"297.427406ms","start":"2026-02-20T18:27:04.408956Z","end":"2026-02-20T18:27:04.706384Z","steps":["trace[1400771749] 'range keys from in-memory index tree' (duration: 297.233533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:05.407649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:05.908439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:06.409223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:06.524544Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.814328943s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:27:06.524962Z","caller":"traceutil/trace.go:171","msg":"trace[1430441104] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"1.814811281s","start":"2026-02-20T18:27:04.710119Z","end":"2026-02-20T18:27:06.52493Z","steps":["trace[1430441104] 'process raft request' (duration: 1.814638308s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.525147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:04.710096Z","time spent":"1.814950974s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:27:06.749367Z","caller":"traceutil/trace.go:171","msg":"trace[1329416602] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"2.038736528s","start":"2026-02-20T18:27:04.710588Z","end":"2026-02-20T18:27:06.749325Z","steps":["trace[1329416602] 'process raft request' (duration: 2.002998622s)","trace[1329416602] 'compare' (duration: 35.542043ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:06.749548Z","caller":"traceutil/trace.go:171","msg":"trace[1333482725] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:719; }","duration":"1.84265223s","start":"2026-02-20T18:27:04.90687Z","end":"2026-02-20T18:27:06.749522Z","steps":["trace[1333482725] 'read index received' (duration: 1.617978268s)","trace[1333482725] 'applied index is now lower than readState.Index' (duration: 224.672902ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:06.749694Z","caller":"traceutil/trace.go:171","msg":"trace[629076079] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"796.413598ms","start":"2026-02-20T18:27:05.953268Z","end":"2026-02-20T18:27:06.749681Z","steps":["trace[629076079] 'process raft request' (duration: 795.99929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.749579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:04.710583Z","time spent":"2.038903812s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:06.749847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:05.953236Z","time spent":"796.48924ms","remote":"127.0.0.1:58478","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-20T18:27:06.749892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.694720805s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:06.749944Z","caller":"traceutil/trace.go:171","msg":"trace[1096618370] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:696; }","duration":"1.694800707s","start":"2026-02-20T18:27:05.055129Z","end":"2026-02-20T18:27:06.74993Z","steps":["trace[1096618370] 'agreement among raft nodes before linearized reading' (duration: 1.694723215s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.749982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:05.05511Z","time spent":"1.694864147s","remote":"127.0.0.1:58492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:27:06.75005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268731753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:27:06.750102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.03362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:27:06.750086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.843150498s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:06.750141Z","caller":"traceutil/trace.go:171","msg":"trace[1294853513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"1.268871186s","start":"2026-02-20T18:27:05.481246Z","end":"2026-02-20T18:27:06.750118Z","steps":["trace[1294853513] 'agreement among raft nodes before linearized reading' (duration: 1.268700593s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:06.750151Z","caller":"traceutil/trace.go:171","msg":"trace[2029978837] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:696; }","duration":"866.123932ms","start":"2026-02-20T18:27:05.884014Z","end":"2026-02-20T18:27:06.750138Z","steps":["trace[2029978837] 'agreement among raft nodes before linearized reading' (duration: 865.97945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.750198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:05.88399Z","time spent":"866.200663ms","remote":"127.0.0.1:58478","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-20T18:27:06.750196Z","caller":"traceutil/trace.go:171","msg":"trace[1062796945] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:696; }","duration":"1.843342442s","start":"2026-02-20T18:27:04.90683Z","end":"2026-02-20T18:27:06.750172Z","steps":["trace[1062796945] 'agreement among raft nodes before linearized reading' (duration: 1.843064237s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.750211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:05.481222Z","time spent":"1.268973007s","remote":"127.0.0.1:58196","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-20T18:27:06.750283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523472371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-20T18:27:06.750209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.595442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:27:06.750327Z","caller":"traceutil/trace.go:171","msg":"trace[463269770] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:696; }","duration":"1.523573603s","start":"2026-02-20T18:27:05.226741Z","end":"2026-02-20T18:27:06.750314Z","steps":["trace[463269770] 'agreement among raft nodes before linearized reading' (duration: 1.5234214s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.750353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:05.226696Z","time spent":"1.523650724s","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.200\" "} {"level":"info","ts":"2026-02-20T18:27:06.750354Z","caller":"traceutil/trace.go:171","msg":"trace[935023044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:696; }","duration":"727.805565ms","start":"2026-02-20T18:27:06.022526Z","end":"2026-02-20T18:27:06.750331Z","steps":["trace[935023044] 'agreement among raft nodes before linearized reading' (duration: 727.46297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.750406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:06.0225Z","time spent":"727.895117ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:27:06.750385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464509417s","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-20T18:27:06.750281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:04.906805Z","time spent":"1.843463094s","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:27:06.750475Z","caller":"traceutil/trace.go:171","msg":"trace[1027568601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:696; }","duration":"1.464644939s","start":"2026-02-20T18:27:05.285809Z","end":"2026-02-20T18:27:06.750453Z","steps":["trace[1027568601] 'agreement among raft nodes before linearized reading' (duration: 1.464368065s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.750512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:05.28579Z","time spent":"1.46471501s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T18:27:06.904649Z","caller":"traceutil/trace.go:171","msg":"trace[1726087213] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"149.484911ms","start":"2026-02-20T18:27:06.755128Z","end":"2026-02-20T18:27:06.904613Z","steps":["trace[1726087213] 'process raft request' (duration: 149.280078ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:06.90495Z","caller":"traceutil/trace.go:171","msg":"trace[1906436362] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:722; }","duration":"144.286491ms","start":"2026-02-20T18:27:06.760644Z","end":"2026-02-20T18:27:06.904931Z","steps":["trace[1906436362] 'read index received' (duration: 7.772796ms)","trace[1906436362] 'applied index is now lower than readState.Index' (duration: 136.512895ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:06.904967Z","caller":"traceutil/trace.go:171","msg":"trace[401138214] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"149.396481ms","start":"2026-02-20T18:27:06.75554Z","end":"2026-02-20T18:27:06.904936Z","steps":["trace[401138214] 'process raft request' (duration: 148.998483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:06.905065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.402942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:06.905074Z","caller":"traceutil/trace.go:171","msg":"trace[1588693640] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"148.724639ms","start":"2026-02-20T18:27:06.756333Z","end":"2026-02-20T18:27:06.905058Z","steps":["trace[1588693640] 'process raft request' (duration: 148.449553ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:06.905108Z","caller":"traceutil/trace.go:171","msg":"trace[1578473756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"144.483665ms","start":"2026-02-20T18:27:06.76061Z","end":"2026-02-20T18:27:06.905094Z","steps":["trace[1578473756] 'agreement among raft nodes before linearized reading' (duration: 144.380514ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:06.925893Z","caller":"traceutil/trace.go:171","msg":"trace[1651000006] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"156.379673ms","start":"2026-02-20T18:27:06.76949Z","end":"2026-02-20T18:27:06.925869Z","steps":["trace[1651000006] 'process raft request' (duration: 156.222489ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:08.625797Z","caller":"traceutil/trace.go:171","msg":"trace[1268213264] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"143.727562ms","start":"2026-02-20T18:27:08.482043Z","end":"2026-02-20T18:27:08.625771Z","steps":["trace[1268213264] 'read index received' (duration: 143.477697ms)","trace[1268213264] 'applied index is now lower than readState.Index' (duration: 248.385µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:08.626129Z","caller":"traceutil/trace.go:171","msg":"trace[2145511733] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"248.373447ms","start":"2026-02-20T18:27:08.377743Z","end":"2026-02-20T18:27:08.626116Z","steps":["trace[2145511733] 'process raft request' (duration: 247.798736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:08.626435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.364933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:08.626503Z","caller":"traceutil/trace.go:171","msg":"trace[736402763] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:701; }","duration":"144.484174ms","start":"2026-02-20T18:27:08.482004Z","end":"2026-02-20T18:27:08.626488Z","steps":["trace[736402763] 'agreement among raft nodes before linearized reading' (duration: 144.344122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:08.631273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.565424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:27:08.63149Z","caller":"traceutil/trace.go:171","msg":"trace[1563602746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"100.640946ms","start":"2026-02-20T18:27:08.530664Z","end":"2026-02-20T18:27:08.631305Z","steps":["trace[1563602746] 'agreement among raft nodes before linearized reading' (duration: 100.492832ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:08.865957Z","caller":"traceutil/trace.go:171","msg":"trace[423360642] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"106.177362ms","start":"2026-02-20T18:27:08.759748Z","end":"2026-02-20T18:27:08.865925Z","steps":["trace[423360642] 'process raft request' (duration: 105.992929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:33.502713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905581,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:27:33.735634Z","caller":"traceutil/trace.go:171","msg":"trace[1219396527] linearizableReadLoop","detail":"{readStateIndex:807; appliedIndex:806; }","duration":"733.270517ms","start":"2026-02-20T18:27:33.002328Z","end":"2026-02-20T18:27:33.735599Z","steps":["trace[1219396527] 'read index received' (duration: 733.091242ms)","trace[1219396527] 'applied index is now lower than readState.Index' (duration: 177.624µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:33.735683Z","caller":"traceutil/trace.go:171","msg":"trace[400919581] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"960.104133ms","start":"2026-02-20T18:27:32.775541Z","end":"2026-02-20T18:27:33.735645Z","steps":["trace[400919581] 'process raft request' (duration: 959.854498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:33.735875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:32.775514Z","time spent":"960.256707ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:33.735994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.595253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T18:27:33.736052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.479664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:33.73608Z","caller":"traceutil/trace.go:171","msg":"trace[1282878282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:774; }","duration":"733.781506ms","start":"2026-02-20T18:27:33.002253Z","end":"2026-02-20T18:27:33.736035Z","steps":["trace[1282878282] 'agreement among raft nodes before linearized reading' (duration: 733.528602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:33.73615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.012701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-20T18:27:33.736155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:33.002224Z","time spent":"733.894648ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:27:33.736177Z","caller":"traceutil/trace.go:171","msg":"trace[914507403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"547.054202ms","start":"2026-02-20T18:27:33.189117Z","end":"2026-02-20T18:27:33.736171Z","steps":["trace[914507403] 'agreement among raft nodes before linearized reading' (duration: 546.98595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:33.736002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.010698ms","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-20T18:27:33.736085Z","caller":"traceutil/trace.go:171","msg":"trace[1895519943] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:774; }","duration":"270.527885ms","start":"2026-02-20T18:27:33.465545Z","end":"2026-02-20T18:27:33.736073Z","steps":["trace[1895519943] 'agreement among raft nodes before linearized reading' (duration: 270.482044ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:33.736294Z","caller":"traceutil/trace.go:171","msg":"trace[459469023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:774; }","duration":"595.347335ms","start":"2026-02-20T18:27:33.14093Z","end":"2026-02-20T18:27:33.736277Z","steps":["trace[459469023] 'agreement among raft nodes before linearized reading' (duration: 594.998108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:33.736329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:33.140904Z","time spent":"595.417035ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:27:33.736345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.397413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-20T18:27:33.736416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:33.189105Z","time spent":"547.101702ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T18:27:33.736457Z","caller":"traceutil/trace.go:171","msg":"trace[1628122829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:774; }","duration":"550.565656ms","start":"2026-02-20T18:27:33.185868Z","end":"2026-02-20T18:27:33.736433Z","steps":["trace[1628122829] 'agreement among raft nodes before linearized reading' (duration: 550.244261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:33.736516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:33.185853Z","time spent":"550.652188ms","remote":"127.0.0.1:58478","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-20T18:27:35.612046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.230039Z","time spent":"381.997158ms","remote":"127.0.0.1:58240","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T18:27:36.243121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:36.743576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:37.244317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:37.584843Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.837915864s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:27:37.821804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.629919ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:27:37.8219Z","caller":"traceutil/trace.go:171","msg":"trace[1090939557] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"2.07933327s","start":"2026-02-20T18:27:35.742547Z","end":"2026-02-20T18:27:37.82188Z","steps":["trace[1090939557] 'read index received' (duration: 1.842545979s)","trace[1090939557] 'applied index is now lower than readState.Index' (duration: 236.784471ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:37.821966Z","caller":"traceutil/trace.go:171","msg":"trace[499513268] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"2.20851301s","start":"2026-02-20T18:27:35.613443Z","end":"2026-02-20T18:27:37.821956Z","steps":["trace[499513268] 'process raft request' (duration: 1.971636658s)","trace[499513268] 'compare' (duration: 235.988535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:37.822019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.613391Z","time spent":"2.208602442s","remote":"127.0.0.1:58240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:37.8224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.079808368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:27:37.822532Z","caller":"traceutil/trace.go:171","msg":"trace[393536045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"2.080026122s","start":"2026-02-20T18:27:35.742475Z","end":"2026-02-20T18:27:37.822501Z","steps":["trace[393536045] 'agreement among raft nodes before linearized reading' (duration: 2.079538703s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:37.822591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.742447Z","time spent":"2.080130725s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T18:27:38.194789Z","caller":"traceutil/trace.go:171","msg":"trace[150442351] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"658.065234ms","start":"2026-02-20T18:27:37.536655Z","end":"2026-02-20T18:27:38.19472Z","steps":["trace[150442351] 'process raft request' (duration: 622.525927ms)","trace[150442351] 'compare' (duration: 35.110919ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:38.19482Z","caller":"traceutil/trace.go:171","msg":"trace[1227090700] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:813; }","duration":"372.856697ms","start":"2026-02-20T18:27:37.821923Z","end":"2026-02-20T18:27:38.194779Z","steps":["trace[1227090700] 'read index received' (duration: 337.394051ms)","trace[1227090700] 'applied index is now lower than readState.Index' (duration: 35.460846ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:38.194998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:37.536615Z","time spent":"658.265218ms","remote":"127.0.0.1:58478","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-20T18:27:38.195162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.733044763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:38.195214Z","caller":"traceutil/trace.go:171","msg":"trace[2029422976] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:781; }","duration":"1.733159845s","start":"2026-02-20T18:27:36.462041Z","end":"2026-02-20T18:27:38.195201Z","steps":["trace[2029422976] 'agreement among raft nodes before linearized reading' (duration: 1.73292598s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.195255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:36.462025Z","time spent":"1.733218046s","remote":"127.0.0.1:58576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:27:38.195321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.979828ms","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-20T18:27:38.195428Z","caller":"traceutil/trace.go:171","msg":"trace[1387076975] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:781; }","duration":"368.177201ms","start":"2026-02-20T18:27:37.82722Z","end":"2026-02-20T18:27:38.195397Z","steps":["trace[1387076975] 'agreement among raft nodes before linearized reading' (duration: 367.894426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.195478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:37.827188Z","time spent":"368.277573ms","remote":"127.0.0.1:58376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-20T18:27:38.195509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.404014416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:27:38.195554Z","caller":"traceutil/trace.go:171","msg":"trace[1949708949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"2.404127298s","start":"2026-02-20T18:27:35.791414Z","end":"2026-02-20T18:27:38.195542Z","steps":["trace[1949708949] 'agreement among raft nodes before linearized reading' (duration: 2.403599239s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.195606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.711037025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:27:38.195649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.791381Z","time spent":"2.40419926s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:27:38.195661Z","caller":"traceutil/trace.go:171","msg":"trace[1073843140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"1.711143107s","start":"2026-02-20T18:27:36.484504Z","end":"2026-02-20T18:27:38.195647Z","steps":["trace[1073843140] 'agreement among raft nodes before linearized reading' (duration: 1.710439464s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.195694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:36.484474Z","time spent":"1.711213459s","remote":"127.0.0.1:58198","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-20T18:27:38.19575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.404305412s","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-20T18:27:38.195792Z","caller":"traceutil/trace.go:171","msg":"trace[1846719177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"2.404397064s","start":"2026-02-20T18:27:35.791384Z","end":"2026-02-20T18:27:38.195781Z","steps":["trace[1846719177] 'agreement among raft nodes before linearized reading' (duration: 2.40364243s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.195802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958610226s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:27:38.195822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.79134Z","time spent":"2.404474944s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T18:27:38.195834Z","caller":"traceutil/trace.go:171","msg":"trace[1122891998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"1.958697897s","start":"2026-02-20T18:27:36.237125Z","end":"2026-02-20T18:27:38.195823Z","steps":["trace[1122891998] 'agreement among raft nodes before linearized reading' (duration: 1.957856091s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.195862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:36.237095Z","time spent":"1.958760529s","remote":"127.0.0.1:58196","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-20T18:27:38.196021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.436470387s","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-20T18:27:38.196059Z","caller":"traceutil/trace.go:171","msg":"trace[1443512479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"2.436561518s","start":"2026-02-20T18:27:35.759488Z","end":"2026-02-20T18:27:38.19605Z","steps":["trace[1443512479] 'agreement among raft nodes before linearized reading' (duration: 2.43555136s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.196087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.759465Z","time spent":"2.436616059s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:27:38.196111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.404334422s","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-20T18:27:38.19615Z","caller":"traceutil/trace.go:171","msg":"trace[123448375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"2.404386763s","start":"2026-02-20T18:27:35.791753Z","end":"2026-02-20T18:27:38.19614Z","steps":["trace[123448375] 'agreement among raft nodes before linearized reading' (duration: 2.403250313s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.196179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.791743Z","time spent":"2.404428983s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:27:38.196217Z","caller":"traceutil/trace.go:171","msg":"trace[1254874179] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"367.279444ms","start":"2026-02-20T18:27:37.828927Z","end":"2026-02-20T18:27:38.196207Z","steps":["trace[1254874179] 'process raft request' (duration: 365.712535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.196372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:37.8289Z","time spent":"367.356975ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:38.196026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.235799305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:38.196622Z","caller":"traceutil/trace.go:171","msg":"trace[1986309681] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:781; }","duration":"2.236450347s","start":"2026-02-20T18:27:35.960157Z","end":"2026-02-20T18:27:38.196607Z","steps":["trace[1986309681] 'agreement among raft nodes before linearized reading' (duration: 2.234836308s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:38.196671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:35.960123Z","time spent":"2.236533178s","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:27:48.904136Z","caller":"traceutil/trace.go:171","msg":"trace[1608778728] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"276.485026ms","start":"2026-02-20T18:27:48.627609Z","end":"2026-02-20T18:27:48.904094Z","steps":["trace[1608778728] 'process raft request' (duration: 231.408552ms)","trace[1608778728] 'compare' (duration: 44.85829ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:48.905008Z","caller":"traceutil/trace.go:171","msg":"trace[1423433802] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"258.652706ms","start":"2026-02-20T18:27:48.646316Z","end":"2026-02-20T18:27:48.904969Z","steps":["trace[1423433802] 'process raft request' (duration: 258.582005ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:48.905042Z","caller":"traceutil/trace.go:171","msg":"trace[1550467183] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"258.926431ms","start":"2026-02-20T18:27:48.646082Z","end":"2026-02-20T18:27:48.905009Z","steps":["trace[1550467183] 'process raft request' (duration: 258.617206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:52.876815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:53.377454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:53.878066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:54.379214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905800,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:27:54.453851Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.18303382s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:27:54.454359Z","caller":"traceutil/trace.go:171","msg":"trace[1598572396] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:909; }","duration":"2.078553526s","start":"2026-02-20T18:27:52.375769Z","end":"2026-02-20T18:27:54.454322Z","steps":["trace[1598572396] 'read index received' (duration: 2.078262611s)","trace[1598572396] 'applied index is now lower than readState.Index' (duration: 289.515µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:54.454444Z","caller":"traceutil/trace.go:171","msg":"trace[1866158973] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"2.183750623s","start":"2026-02-20T18:27:52.270662Z","end":"2026-02-20T18:27:54.454413Z","steps":["trace[1866158973] 'process raft request' (duration: 2.183437247s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.454595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:52.270621Z","time spent":"2.183896025s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:27:54.454644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.073032244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:27:54.454699Z","caller":"traceutil/trace.go:171","msg":"trace[1938464337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:871; }","duration":"2.073139636s","start":"2026-02-20T18:27:52.381541Z","end":"2026-02-20T18:27:54.454681Z","steps":["trace[1938464337] 'agreement among raft nodes before linearized reading' (duration: 2.072920862s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.454683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.078876922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:27:54.454752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:52.381531Z","time spent":"2.073207759s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:27:54.454777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.165218ms","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-20T18:27:54.454815Z","caller":"traceutil/trace.go:171","msg":"trace[1616500866] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:871; }","duration":"671.23395ms","start":"2026-02-20T18:27:53.783565Z","end":"2026-02-20T18:27:54.454799Z","steps":["trace[1616500866] 'agreement among raft nodes before linearized reading' (duration: 671.195689ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:54.454803Z","caller":"traceutil/trace.go:171","msg":"trace[865242746] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:871; }","duration":"2.079064636s","start":"2026-02-20T18:27:52.375699Z","end":"2026-02-20T18:27:54.454763Z","steps":["trace[865242746] 'agreement among raft nodes before linearized reading' (duration: 2.0787494s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.454843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:53.783528Z","time spent":"671.309581ms","remote":"127.0.0.1:58344","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-20T18:27:54.454875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:52.375675Z","time spent":"2.079172589s","remote":"127.0.0.1:58478","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-20T18:27:54.454926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.058916214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:27:54.454951Z","caller":"traceutil/trace.go:171","msg":"trace[1884576181] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:871; }","duration":"2.058967335s","start":"2026-02-20T18:27:52.395976Z","end":"2026-02-20T18:27:54.454944Z","steps":["trace[1884576181] 'agreement among raft nodes before linearized reading' (duration: 2.058906434s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.454966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:52.395965Z","time spent":"2.058997116s","remote":"127.0.0.1:58376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:27:54.454707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97006804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:27:54.455062Z","caller":"traceutil/trace.go:171","msg":"trace[848293620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:871; }","duration":"1.970479448s","start":"2026-02-20T18:27:52.484577Z","end":"2026-02-20T18:27:54.455057Z","steps":["trace[848293620] 'agreement among raft nodes before linearized reading' (duration: 1.97007715s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.455066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.072547276s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-20T18:27:54.455078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:52.484557Z","time spent":"1.970517139s","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:27:54.4551Z","caller":"traceutil/trace.go:171","msg":"trace[1233279955] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:871; }","duration":"2.072613318s","start":"2026-02-20T18:27:52.382477Z","end":"2026-02-20T18:27:54.455091Z","steps":["trace[1233279955] 'agreement among raft nodes before linearized reading' (duration: 2.072522916s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.455123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.037369344s","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-20T18:27:54.455131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:52.382458Z","time spent":"2.072665699s","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:27:54.455143Z","caller":"traceutil/trace.go:171","msg":"trace[1798473752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:871; }","duration":"2.037401276s","start":"2026-02-20T18:27:52.417736Z","end":"2026-02-20T18:27:54.455138Z","steps":["trace[1798473752] 'agreement among raft nodes before linearized reading' (duration: 2.037313624s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.455158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:52.41773Z","time spent":"2.037425546s","remote":"127.0.0.1:58478","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-20T18:28:00.801151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.627256ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:28:07.039079Z","caller":"traceutil/trace.go:171","msg":"trace[476592370] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"429.374493ms","start":"2026-02-20T18:28:06.60968Z","end":"2026-02-20T18:28:07.039054Z","steps":["trace[476592370] 'process raft request' (duration: 429.17888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:07.039243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:06.609663Z","time spent":"429.495026ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:07.751787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.931106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:07.751883Z","caller":"traceutil/trace.go:171","msg":"trace[1539696241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:909; }","duration":"269.092459ms","start":"2026-02-20T18:28:07.482767Z","end":"2026-02-20T18:28:07.751859Z","steps":["trace[1539696241] 'range keys from in-memory index tree' (duration: 268.830813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:09.108069Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905916,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:28:09.323853Z","caller":"traceutil/trace.go:171","msg":"trace[1684731090] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"768.264364ms","start":"2026-02-20T18:28:08.555526Z","end":"2026-02-20T18:28:09.32379Z","steps":["trace[1684731090] 'process raft request' (duration: 768.07387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:09.324093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.555509Z","time spent":"768.421046ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:09.477451Z","caller":"traceutil/trace.go:171","msg":"trace[187545601] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"921.715534ms","start":"2026-02-20T18:28:08.555711Z","end":"2026-02-20T18:28:09.477426Z","steps":["trace[187545601] 'process raft request' (duration: 870.846613ms)","trace[187545601] 'compare' (duration: 50.580365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:09.477599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.555702Z","time spent":"921.813625ms","remote":"127.0.0.1:58478","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-20T18:28:09.477789Z","caller":"traceutil/trace.go:171","msg":"trace[867195366] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"594.029941ms","start":"2026-02-20T18:28:08.883751Z","end":"2026-02-20T18:28:09.477781Z","steps":["trace[867195366] 'process raft request' (duration: 593.609583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:09.477846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.883712Z","time spent":"594.110803ms","remote":"127.0.0.1:58478","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-20T18:28:09.477718Z","caller":"traceutil/trace.go:171","msg":"trace[1573614664] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:951; }","duration":"869.786373ms","start":"2026-02-20T18:28:08.607876Z","end":"2026-02-20T18:28:09.477663Z","steps":["trace[1573614664] 'read index received' (duration: 715.651061ms)","trace[1573614664] 'applied index is now lower than readState.Index' (duration: 154.132552ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:09.478078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.19774ms","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-20T18:28:09.478103Z","caller":"traceutil/trace.go:171","msg":"trace[1771073779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:912; }","duration":"870.264901ms","start":"2026-02-20T18:28:08.607831Z","end":"2026-02-20T18:28:09.478096Z","steps":["trace[1771073779] 'agreement among raft nodes before linearized reading' (duration: 870.17163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:09.478149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.607816Z","time spent":"870.323852ms","remote":"127.0.0.1:58478","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-20T18:28:09.478223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.976168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:09.478268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.708623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T18:28:09.478267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"853.417711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:28:09.478306Z","caller":"traceutil/trace.go:171","msg":"trace[889643398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:912; }","duration":"434.803345ms","start":"2026-02-20T18:28:09.043493Z","end":"2026-02-20T18:28:09.478296Z","steps":["trace[889643398] 'agreement among raft nodes before linearized reading' (duration: 434.656101ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:09.478313Z","caller":"traceutil/trace.go:171","msg":"trace[595600808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:912; }","duration":"853.470062ms","start":"2026-02-20T18:28:08.62483Z","end":"2026-02-20T18:28:09.4783Z","steps":["trace[595600808] 'agreement among raft nodes before linearized reading' (duration: 853.303388ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:09.47832Z","caller":"traceutil/trace.go:171","msg":"trace[1771856181] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:912; }","duration":"776.107081ms","start":"2026-02-20T18:28:08.702186Z","end":"2026-02-20T18:28:09.478293Z","steps":["trace[1771856181] 'agreement among raft nodes before linearized reading' (duration: 775.956787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:09.478364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:09.043473Z","time spent":"434.885926ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:28:09.47836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.624804Z","time spent":"853.540673ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:28:09.47839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.702177Z","time spent":"776.198352ms","remote":"127.0.0.1:42540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:28:09.47838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.093785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:09.478517Z","caller":"traceutil/trace.go:171","msg":"trace[21117842] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:912; }","duration":"145.316929ms","start":"2026-02-20T18:28:09.333172Z","end":"2026-02-20T18:28:09.478489Z","steps":["trace[21117842] 'agreement among raft nodes before linearized reading' (duration: 145.033224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:10.859392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.171158ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:28:11.686467Z","caller":"traceutil/trace.go:171","msg":"trace[1515110744] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"112.463921ms","start":"2026-02-20T18:28:11.573971Z","end":"2026-02-20T18:28:11.686435Z","steps":["trace[1515110744] 'process raft request' (duration: 112.38954ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:11.686781Z","caller":"traceutil/trace.go:171","msg":"trace[1347481046] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"112.926049ms","start":"2026-02-20T18:28:11.573787Z","end":"2026-02-20T18:28:11.686713Z","steps":["trace[1347481046] 'process raft request' (duration: 109.467975ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:11.686852Z","caller":"traceutil/trace.go:171","msg":"trace[874992538] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"113.004791ms","start":"2026-02-20T18:28:11.573818Z","end":"2026-02-20T18:28:11.686823Z","steps":["trace[874992538] 'process raft request' (duration: 112.467342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:16.20398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933905992,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:28:16.372975Z","caller":"traceutil/trace.go:171","msg":"trace[463630394] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"669.044241ms","start":"2026-02-20T18:28:15.703898Z","end":"2026-02-20T18:28:16.372942Z","steps":["trace[463630394] 'read index received' (duration: 668.893948ms)","trace[463630394] 'applied index is now lower than readState.Index' (duration: 148.503µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:16.373061Z","caller":"traceutil/trace.go:171","msg":"trace[2117006225] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"708.465124ms","start":"2026-02-20T18:28:15.664564Z","end":"2026-02-20T18:28:16.373029Z","steps":["trace[2117006225] 'process raft request' (duration: 708.183449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:16.373194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:15.664535Z","time spent":"708.589376ms","remote":"127.0.0.1:58478","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-20T18:28:16.373281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.526451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T18:28:16.373245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.731742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:16.373298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.398897ms","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-20T18:28:16.373326Z","caller":"traceutil/trace.go:171","msg":"trace[1583894601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:949; }","duration":"668.608333ms","start":"2026-02-20T18:28:15.704703Z","end":"2026-02-20T18:28:16.373312Z","steps":["trace[1583894601] 'agreement among raft nodes before linearized reading' (duration: 668.420489ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:16.37335Z","caller":"traceutil/trace.go:171","msg":"trace[987677354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"136.928286ms","start":"2026-02-20T18:28:16.2364Z","end":"2026-02-20T18:28:16.373328Z","steps":["trace[987677354] 'agreement among raft nodes before linearized reading' (duration: 136.744643ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:16.37335Z","caller":"traceutil/trace.go:171","msg":"trace[1460214003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:949; }","duration":"669.493489ms","start":"2026-02-20T18:28:15.703841Z","end":"2026-02-20T18:28:16.373334Z","steps":["trace[1460214003] 'agreement among raft nodes before linearized reading' (duration: 669.270245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:16.373365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:15.704696Z","time spent":"668.660703ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:28:16.373461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:15.703817Z","time spent":"669.575551ms","remote":"127.0.0.1:58478","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-20T18:28:16.373475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.170875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:28:16.373564Z","caller":"traceutil/trace.go:171","msg":"trace[708252566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:949; }","duration":"668.388259ms","start":"2026-02-20T18:28:15.70515Z","end":"2026-02-20T18:28:16.373539Z","steps":["trace[708252566] 'agreement among raft nodes before linearized reading' (duration: 668.032542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:16.373602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:15.705126Z","time spent":"668.46974ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:28:16.790006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.088438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:16.790159Z","caller":"traceutil/trace.go:171","msg":"trace[1068831544] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"410.39519ms","start":"2026-02-20T18:28:16.379738Z","end":"2026-02-20T18:28:16.790133Z","steps":["trace[1068831544] 'process raft request' (duration: 271.994537ms)","trace[1068831544] 'compare' (duration: 137.865493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:16.790235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:16.379701Z","time spent":"410.501973ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:16.825833Z","caller":"traceutil/trace.go:171","msg":"trace[618889219] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:993; }","duration":"341.870706ms","start":"2026-02-20T18:28:16.483932Z","end":"2026-02-20T18:28:16.825802Z","steps":["trace[618889219] 'read index received' (duration: 167.82022ms)","trace[618889219] 'applied index is now lower than readState.Index' (duration: 174.049316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:16.825988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.04006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:16.82597Z","caller":"traceutil/trace.go:171","msg":"trace[857017255] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"446.039044ms","start":"2026-02-20T18:28:16.379891Z","end":"2026-02-20T18:28:16.82593Z","steps":["trace[857017255] 'process raft request' (duration: 445.705778ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:16.826028Z","caller":"traceutil/trace.go:171","msg":"trace[935703169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"342.135271ms","start":"2026-02-20T18:28:16.483879Z","end":"2026-02-20T18:28:16.826014Z","steps":["trace[935703169] 'agreement among raft nodes before linearized reading' (duration: 342.023149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:16.826068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:16.483855Z","time spent":"342.204744ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:28:16.826082Z","caller":"traceutil/trace.go:171","msg":"trace[441576879] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"446.010192ms","start":"2026-02-20T18:28:16.380026Z","end":"2026-02-20T18:28:16.826037Z","steps":["trace[441576879] 'process raft request' (duration: 445.733147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:16.826167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:16.37986Z","time spent":"446.208047ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:16.826329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:16.380009Z","time spent":"446.187397ms","remote":"127.0.0.1:58478","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-20T18:28:17.137469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.047608ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:17.137599Z","caller":"traceutil/trace.go:171","msg":"trace[160145145] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"252.395783ms","start":"2026-02-20T18:28:16.885186Z","end":"2026-02-20T18:28:17.137582Z","steps":["trace[160145145] 'compare' (duration: 243.846674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:18.790502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.046237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:18.790709Z","caller":"traceutil/trace.go:171","msg":"trace[450680739] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1000; }","duration":"412.081813ms","start":"2026-02-20T18:28:18.378613Z","end":"2026-02-20T18:28:18.790695Z","steps":["trace[450680739] 'read index received' (duration: 99.800536ms)","trace[450680739] 'applied index is now lower than readState.Index' (duration: 312.280357ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:18.790762Z","caller":"traceutil/trace.go:171","msg":"trace[1984112133] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"698.614141ms","start":"2026-02-20T18:28:18.092141Z","end":"2026-02-20T18:28:18.790755Z","steps":["trace[1984112133] 'process raft request' (duration: 119.240137ms)","trace[1984112133] 'compare' (duration: 578.849604ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:18.790799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.092094Z","time spent":"698.686333ms","remote":"127.0.0.1:58290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:28:18.790812Z","caller":"traceutil/trace.go:171","msg":"trace[502856863] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"693.068998ms","start":"2026-02-20T18:28:18.097713Z","end":"2026-02-20T18:28:18.790782Z","steps":["trace[502856863] 'process raft request' (duration: 692.890004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:18.790966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.338308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:28:18.790991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.097695Z","time spent":"693.19887ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:18.791008Z","caller":"traceutil/trace.go:171","msg":"trace[1413863178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:958; }","duration":"412.40088ms","start":"2026-02-20T18:28:18.378596Z","end":"2026-02-20T18:28:18.790997Z","steps":["trace[1413863178] 'agreement among raft nodes before linearized reading' (duration: 412.184386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:18.791078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.378535Z","time spent":"412.534382ms","remote":"127.0.0.1:58478","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-20T18:28:19.345935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.150134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:19.346112Z","caller":"traceutil/trace.go:171","msg":"trace[1984838099] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"550.987175ms","start":"2026-02-20T18:28:18.795113Z","end":"2026-02-20T18:28:19.3461Z","steps":["trace[1984838099] 'process raft request' (duration: 550.904813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:19.346163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.795099Z","time spent":"551.038746ms","remote":"127.0.0.1:58478","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-20T18:28:19.346294Z","caller":"traceutil/trace.go:171","msg":"trace[622107759] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"860.572331ms","start":"2026-02-20T18:28:18.485715Z","end":"2026-02-20T18:28:19.346288Z","steps":["trace[622107759] 'process raft request' (duration: 569.00277ms)","trace[622107759] 'compare' (duration: 290.860249ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:19.346323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.485691Z","time spent":"860.618994ms","remote":"127.0.0.1:58290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:28:19.346372Z","caller":"traceutil/trace.go:171","msg":"trace[1420130103] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"555.613571ms","start":"2026-02-20T18:28:18.790729Z","end":"2026-02-20T18:28:19.346343Z","steps":["trace[1420130103] 'read index received' (duration: 264.001528ms)","trace[1420130103] 'applied index is now lower than readState.Index' (duration: 291.609783ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:19.346589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.788349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:28:19.346637Z","caller":"traceutil/trace.go:171","msg":"trace[644394507] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:960; }","duration":"410.89431ms","start":"2026-02-20T18:28:18.93573Z","end":"2026-02-20T18:28:19.346624Z","steps":["trace[644394507] 'agreement among raft nodes before linearized reading' (duration: 410.792268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:19.34664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.898082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-20T18:28:19.346653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.383173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-20T18:28:19.346621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.751105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:19.346644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.290251ms","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-20T18:28:19.346677Z","caller":"traceutil/trace.go:171","msg":"trace[572450636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:960; }","duration":"551.970553ms","start":"2026-02-20T18:28:18.794697Z","end":"2026-02-20T18:28:19.346667Z","steps":["trace[572450636] 'agreement among raft nodes before linearized reading' (duration: 551.8151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:19.346678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.935706Z","time spent":"410.962022ms","remote":"127.0.0.1:42532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:28:19.346714Z","caller":"traceutil/trace.go:171","msg":"trace[66168818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"515.422084ms","start":"2026-02-20T18:28:18.831276Z","end":"2026-02-20T18:28:19.346698Z","steps":["trace[66168818] 'agreement among raft nodes before linearized reading' (duration: 515.254881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:19.346718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.794679Z","time spent":"552.027665ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:28:19.346718Z","caller":"traceutil/trace.go:171","msg":"trace[1534300008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:960; }","duration":"860.892468ms","start":"2026-02-20T18:28:18.485799Z","end":"2026-02-20T18:28:19.346692Z","steps":["trace[1534300008] 'agreement among raft nodes before linearized reading' (duration: 860.702975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:19.346746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.831243Z","time spent":"515.496955ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T18:28:19.34668Z","caller":"traceutil/trace.go:171","msg":"trace[1605833514] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:960; }","duration":"515.444794ms","start":"2026-02-20T18:28:18.831226Z","end":"2026-02-20T18:28:19.346671Z","steps":["trace[1605833514] 'agreement among raft nodes before linearized reading' (duration: 515.349922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:19.34678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.831206Z","time spent":"515.568546ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:28:19.346767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:18.485779Z","time spent":"860.9788ms","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:28:19.483624Z","caller":"traceutil/trace.go:171","msg":"trace[47733484] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"132.163648ms","start":"2026-02-20T18:28:19.351434Z","end":"2026-02-20T18:28:19.483598Z","steps":["trace[47733484] 'process raft request' (duration: 132.104317ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:19.483715Z","caller":"traceutil/trace.go:171","msg":"trace[156829118] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"133.054784ms","start":"2026-02-20T18:28:19.35063Z","end":"2026-02-20T18:28:19.483685Z","steps":["trace[156829118] 'process raft request' (duration: 132.771278ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:19.483562Z","caller":"traceutil/trace.go:171","msg":"trace[1040747984] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"132.932101ms","start":"2026-02-20T18:28:19.350568Z","end":"2026-02-20T18:28:19.4835Z","steps":["trace[1040747984] 'process raft request' (duration: 95.912373ms)","trace[1040747984] 'compare' (duration: 36.711022ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:21.36593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.558441ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:28:21.53373Z","caller":"traceutil/trace.go:171","msg":"trace[504448599] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"152.321833ms","start":"2026-02-20T18:28:21.38138Z","end":"2026-02-20T18:28:21.533702Z","steps":["trace[504448599] 'read index received' (duration: 152.015678ms)","trace[504448599] 'applied index is now lower than readState.Index' (duration: 304.975µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:21.533867Z","caller":"traceutil/trace.go:171","msg":"trace[1700913499] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"163.42786ms","start":"2026-02-20T18:28:21.370413Z","end":"2026-02-20T18:28:21.533841Z","steps":["trace[1700913499] 'process raft request' (duration: 163.084443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:21.534171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.75542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:28:21.534279Z","caller":"traceutil/trace.go:171","msg":"trace[276902611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:968; }","duration":"152.941404ms","start":"2026-02-20T18:28:21.381312Z","end":"2026-02-20T18:28:21.534254Z","steps":["trace[276902611] 'agreement among raft nodes before linearized reading' (duration: 152.601868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:22.047912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.263879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:22.048025Z","caller":"traceutil/trace.go:171","msg":"trace[693023001] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"509.583486ms","start":"2026-02-20T18:28:21.538421Z","end":"2026-02-20T18:28:22.048004Z","steps":["trace[693023001] 'process raft request' (duration: 149.129964ms)","trace[693023001] 'compare' (duration: 360.030914ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:22.048085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:21.538407Z","time spent":"509.650327ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:22.140286Z","caller":"traceutil/trace.go:171","msg":"trace[1756337539] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1014; }","duration":"440.192505ms","start":"2026-02-20T18:28:21.700067Z","end":"2026-02-20T18:28:22.140259Z","steps":["trace[1756337539] 'read index received' (duration: 439.986682ms)","trace[1756337539] 'applied index is now lower than readState.Index' (duration: 204.583µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:22.140588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.514671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:28:22.140642Z","caller":"traceutil/trace.go:171","msg":"trace[825217630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:971; }","duration":"440.631943ms","start":"2026-02-20T18:28:21.699997Z","end":"2026-02-20T18:28:22.140629Z","steps":["trace[825217630] 'agreement among raft nodes before linearized reading' (duration: 440.462ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:22.140637Z","caller":"traceutil/trace.go:171","msg":"trace[1583687208] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"601.931872ms","start":"2026-02-20T18:28:21.538661Z","end":"2026-02-20T18:28:22.140593Z","steps":["trace[1583687208] 'process raft request' (duration: 601.420683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:22.140678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:21.699971Z","time spent":"440.699154ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T18:28:22.140705Z","caller":"traceutil/trace.go:171","msg":"trace[304411620] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"601.950533ms","start":"2026-02-20T18:28:21.538707Z","end":"2026-02-20T18:28:22.140658Z","steps":["trace[304411620] 'process raft request' (duration: 601.505905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:22.140813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:21.538651Z","time spent":"602.067285ms","remote":"127.0.0.1:58478","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-20T18:28:22.140939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:21.538685Z","time spent":"602.126226ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:23.017949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.401459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:23.018034Z","caller":"traceutil/trace.go:171","msg":"trace[1858347155] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"533.812645ms","start":"2026-02-20T18:28:22.484205Z","end":"2026-02-20T18:28:23.018018Z","steps":["trace[1858347155] 'read index received' (duration: 63.441µs)","trace[1858347155] 'applied index is now lower than readState.Index' (duration: 533.747734ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:23.018104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.898387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:23.018123Z","caller":"traceutil/trace.go:171","msg":"trace[1111715938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"533.954888ms","start":"2026-02-20T18:28:22.484161Z","end":"2026-02-20T18:28:23.018116Z","steps":["trace[1111715938] 'agreement among raft nodes before linearized reading' (duration: 533.894327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:23.018145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:22.48412Z","time spent":"534.019269ms","remote":"127.0.0.1:58198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:28:23.018418Z","caller":"traceutil/trace.go:171","msg":"trace[574448012] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"874.004632ms","start":"2026-02-20T18:28:22.144379Z","end":"2026-02-20T18:28:23.018384Z","steps":["trace[574448012] 'process raft request' (duration: 63.075183ms)","trace[574448012] 'compare' (duration: 810.128975ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:23.018569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:22.144349Z","time spent":"874.136694ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:24.471092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.410532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:28:24.471175Z","caller":"traceutil/trace.go:171","msg":"trace[1791387024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"325.528403ms","start":"2026-02-20T18:28:24.145628Z","end":"2026-02-20T18:28:24.471157Z","steps":["trace[1791387024] 'range keys from in-memory index tree' (duration: 325.255209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:24.471225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:24.145621Z","time spent":"325.594955ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:28:24.471297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.524352ms","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-20T18:28:24.471392Z","caller":"traceutil/trace.go:171","msg":"trace[58096582] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"326.614293ms","start":"2026-02-20T18:28:24.144754Z","end":"2026-02-20T18:28:24.471368Z","steps":["trace[58096582] 'range keys from in-memory index tree' (duration: 326.373819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:24.471437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:24.144682Z","time spent":"326.746225ms","remote":"127.0.0.1:58478","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-20T18:28:24.471556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.965109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:28:24.472015Z","caller":"traceutil/trace.go:171","msg":"trace[66266050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"420.549659ms","start":"2026-02-20T18:28:24.051444Z","end":"2026-02-20T18:28:24.471993Z","steps":["trace[66266050] 'range keys from in-memory index tree' (duration: 419.703275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:24.472697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:24.05141Z","time spent":"420.875147ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:28:24.818287Z","caller":"traceutil/trace.go:171","msg":"trace[392017085] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"342.499259ms","start":"2026-02-20T18:28:24.475758Z","end":"2026-02-20T18:28:24.818257Z","steps":["trace[392017085] 'process raft request' (duration: 342.278925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:24.818472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:24.475732Z","time spent":"342.640312ms","remote":"127.0.0.1:58478","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-20T18:28:25.003993Z","caller":"traceutil/trace.go:171","msg":"trace[1307721356] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"526.961069ms","start":"2026-02-20T18:28:24.477004Z","end":"2026-02-20T18:28:25.003965Z","steps":["trace[1307721356] 'process raft request' (duration: 526.626983ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:25.004152Z","caller":"traceutil/trace.go:171","msg":"trace[1471955139] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"527.00817ms","start":"2026-02-20T18:28:24.477119Z","end":"2026-02-20T18:28:25.004127Z","steps":["trace[1471955139] 'process raft request' (duration: 526.757415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:25.004292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:24.477093Z","time spent":"527.119371ms","remote":"127.0.0.1:58478","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-20T18:28:25.004292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:24.476993Z","time spent":"527.122971ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:29.870716Z","caller":"traceutil/trace.go:171","msg":"trace[1898945809] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"198.44497ms","start":"2026-02-20T18:28:29.672243Z","end":"2026-02-20T18:28:29.870688Z","steps":["trace[1898945809] 'process raft request' (duration: 198.218636ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:30.703016Z","caller":"traceutil/trace.go:171","msg":"trace[766541106] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"753.578464ms","start":"2026-02-20T18:28:29.949395Z","end":"2026-02-20T18:28:30.702973Z","steps":["trace[766541106] 'process raft request' (duration: 753.301508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:30.703435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:29.949366Z","time spent":"753.762876ms","remote":"127.0.0.1:58478","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-20T18:28:30.864435Z","caller":"traceutil/trace.go:171","msg":"trace[1172773860] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"908.762648ms","start":"2026-02-20T18:28:29.955638Z","end":"2026-02-20T18:28:30.864401Z","steps":["trace[1172773860] 'process raft request' (duration: 849.641949ms)","trace[1172773860] 'compare' (duration: 58.826584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:30.864628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:29.955618Z","time spent":"908.90012ms","remote":"127.0.0.1:58478","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-20T18:28:31.203909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.661465ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:28:31.204048Z","caller":"traceutil/trace.go:171","msg":"trace[1406595671] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1052; }","duration":"769.663391ms","start":"2026-02-20T18:28:30.434358Z","end":"2026-02-20T18:28:31.204021Z","steps":["trace[1406595671] 'read index received' (duration: 268.761607ms)","trace[1406595671] 'applied index is now lower than readState.Index' (duration: 500.899334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:31.204248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.858276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:31.204328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.114545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:28:31.204382Z","caller":"traceutil/trace.go:171","msg":"trace[127512779] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"354.211477ms","start":"2026-02-20T18:28:30.850157Z","end":"2026-02-20T18:28:31.204369Z","steps":["trace[127512779] 'agreement among raft nodes before linearized reading' (duration: 354.020173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:31.204437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:30.850128Z","time spent":"354.296678ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:28:31.20443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.238241ms","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":"warn","ts":"2026-02-20T18:28:31.204457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.49364ms","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-20T18:28:31.204485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.026886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-20T18:28:31.204521Z","caller":"traceutil/trace.go:171","msg":"trace[1838730151] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1007; }","duration":"173.377514ms","start":"2026-02-20T18:28:31.031126Z","end":"2026-02-20T18:28:31.204503Z","steps":["trace[1838730151] 'agreement among raft nodes before linearized reading' (duration: 173.14588ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:31.204539Z","caller":"traceutil/trace.go:171","msg":"trace[1548958502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1007; }","duration":"149.629872ms","start":"2026-02-20T18:28:31.054895Z","end":"2026-02-20T18:28:31.204525Z","steps":["trace[1548958502] 'agreement among raft nodes before linearized reading' (duration: 149.47548ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:31.20433Z","caller":"traceutil/trace.go:171","msg":"trace[278309002] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1007; }","duration":"770.011269ms","start":"2026-02-20T18:28:30.434298Z","end":"2026-02-20T18:28:31.204309Z","steps":["trace[278309002] 'agreement among raft nodes before linearized reading' (duration: 769.852236ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:31.20458Z","caller":"traceutil/trace.go:171","msg":"trace[1126311752] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1007; }","duration":"302.168539ms","start":"2026-02-20T18:28:30.902381Z","end":"2026-02-20T18:28:31.20455Z","steps":["trace[1126311752] 'agreement among raft nodes before linearized reading' (duration: 301.939175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:31.204637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:30.902353Z","time spent":"302.2738ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-20T18:28:31.204709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.772061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:28:31.204813Z","caller":"traceutil/trace.go:171","msg":"trace[202889150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1007; }","duration":"173.995454ms","start":"2026-02-20T18:28:31.030796Z","end":"2026-02-20T18:28:31.204792Z","steps":["trace[202889150] 'agreement among raft nodes before linearized reading' (duration: 173.534996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:31.204766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:30.434279Z","time spent":"770.360325ms","remote":"127.0.0.1:58420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:28:31.447883Z","caller":"traceutil/trace.go:171","msg":"trace[997596332] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"238.352693ms","start":"2026-02-20T18:28:31.209496Z","end":"2026-02-20T18:28:31.447848Z","steps":["trace[997596332] 'process raft request' (duration: 238.110038ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:31.4482Z","caller":"traceutil/trace.go:171","msg":"trace[1179620570] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"238.319631ms","start":"2026-02-20T18:28:31.209845Z","end":"2026-02-20T18:28:31.448165Z","steps":["trace[1179620570] 'process raft request' (duration: 237.956065ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:31.448263Z","caller":"traceutil/trace.go:171","msg":"trace[364269747] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"237.331053ms","start":"2026-02-20T18:28:31.210903Z","end":"2026-02-20T18:28:31.448234Z","steps":["trace[364269747] 'process raft request' (duration: 237.266322ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:31.448322Z","caller":"traceutil/trace.go:171","msg":"trace[1895200029] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"237.849833ms","start":"2026-02-20T18:28:31.210447Z","end":"2026-02-20T18:28:31.448297Z","steps":["trace[1895200029] 'process raft request' (duration: 237.610569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:31.922537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.6992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:31.922627Z","caller":"traceutil/trace.go:171","msg":"trace[1494570155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"437.851253ms","start":"2026-02-20T18:28:31.484753Z","end":"2026-02-20T18:28:31.922604Z","steps":["trace[1494570155] 'range keys from in-memory index tree' (duration: 437.592557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:31.922673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:31.484728Z","time spent":"437.935924ms","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:28:32.906216Z","caller":"traceutil/trace.go:171","msg":"trace[1115231469] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"118.745709ms","start":"2026-02-20T18:28:32.787443Z","end":"2026-02-20T18:28:32.906188Z","steps":["trace[1115231469] 'process raft request' (duration: 118.582076ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:34.027981Z","caller":"traceutil/trace.go:171","msg":"trace[231298844] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"190.248277ms","start":"2026-02-20T18:28:33.837709Z","end":"2026-02-20T18:28:34.027958Z","steps":["trace[231298844] 'read index received' (duration: 189.854609ms)","trace[231298844] 'applied index is now lower than readState.Index' (duration: 392.648µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:34.028144Z","caller":"traceutil/trace.go:171","msg":"trace[677887591] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"206.051733ms","start":"2026-02-20T18:28:33.822039Z","end":"2026-02-20T18:28:34.028091Z","steps":["trace[677887591] 'process raft request' (duration: 205.585564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:34.028356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.599954ms","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-20T18:28:34.028397Z","caller":"traceutil/trace.go:171","msg":"trace[727022381] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1033; }","duration":"190.705206ms","start":"2026-02-20T18:28:33.83768Z","end":"2026-02-20T18:28:34.028385Z","steps":["trace[727022381] 'agreement among raft nodes before linearized reading' (duration: 190.472272ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:54.306547Z","caller":"traceutil/trace.go:171","msg":"trace[83940037] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"104.616636ms","start":"2026-02-20T18:28:54.201904Z","end":"2026-02-20T18:28:54.306521Z","steps":["trace[83940037] 'process raft request' (duration: 104.475733ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:05.387448Z","caller":"traceutil/trace.go:171","msg":"trace[995874344] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"132.80468ms","start":"2026-02-20T18:29:05.254614Z","end":"2026-02-20T18:29:05.387418Z","steps":["trace[995874344] 'process raft request' (duration: 61.970202ms)","trace[995874344] 'compare' (duration: 70.627874ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:11.245761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.336804ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:29:11.581625Z","caller":"traceutil/trace.go:171","msg":"trace[1407202240] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"203.972104ms","start":"2026-02-20T18:29:11.377601Z","end":"2026-02-20T18:29:11.581573Z","steps":["trace[1407202240] 'process raft request' (duration: 199.055922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:12.420284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.532446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:12.420411Z","caller":"traceutil/trace.go:171","msg":"trace[2111085325] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1242; }","duration":"809.35629ms","start":"2026-02-20T18:29:11.611035Z","end":"2026-02-20T18:29:12.420392Z","steps":["trace[2111085325] 'read index received' (duration: 11.580916ms)","trace[2111085325] 'applied index is now lower than readState.Index' (duration: 797.773604ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:12.420483Z","caller":"traceutil/trace.go:171","msg":"trace[448369614] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"831.734126ms","start":"2026-02-20T18:29:11.5887Z","end":"2026-02-20T18:29:12.420434Z","steps":["trace[448369614] 'process raft request' (duration: 208.944415ms)","trace[448369614] 'compare' (duration: 622.321423ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:12.420659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.588641Z","time spent":"831.927899ms","remote":"127.0.0.1:58478","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-20T18:29:12.420757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.601265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:29:12.420791Z","caller":"traceutil/trace.go:171","msg":"trace[342807445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"709.657726ms","start":"2026-02-20T18:29:11.71112Z","end":"2026-02-20T18:29:12.420778Z","steps":["trace[342807445] 'agreement among raft nodes before linearized reading' (duration: 709.577025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:12.420834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.711108Z","time spent":"709.719677ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:12.420668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.626185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-20T18:29:12.420892Z","caller":"traceutil/trace.go:171","msg":"trace[663336870] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1189; }","duration":"809.844449ms","start":"2026-02-20T18:29:11.61103Z","end":"2026-02-20T18:29:12.420874Z","steps":["trace[663336870] 'agreement among raft nodes before linearized reading' (duration: 809.498733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:12.420934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.610988Z","time spent":"809.93353ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-20T18:29:12.421131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.802748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:29:12.421173Z","caller":"traceutil/trace.go:171","msg":"trace[1687690583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1189; }","duration":"709.89724ms","start":"2026-02-20T18:29:11.711267Z","end":"2026-02-20T18:29:12.421164Z","steps":["trace[1687690583] 'agreement among raft nodes before linearized reading' (duration: 709.802057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:12.421202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.711229Z","time spent":"709.967913ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:12.421141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.89057ms","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-20T18:29:12.421325Z","caller":"traceutil/trace.go:171","msg":"trace[1442346939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"568.113464ms","start":"2026-02-20T18:29:11.853196Z","end":"2026-02-20T18:29:12.42131Z","steps":["trace[1442346939] 'agreement among raft nodes before linearized reading' (duration: 567.829357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:12.421364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.853172Z","time spent":"568.182135ms","remote":"127.0.0.1:58478","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-20T18:29:12.421825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.652273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:29:12.421865Z","caller":"traceutil/trace.go:171","msg":"trace[1729659716] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1189; }","duration":"533.733074ms","start":"2026-02-20T18:29:11.888123Z","end":"2026-02-20T18:29:12.421856Z","steps":["trace[1729659716] 'agreement among raft nodes before linearized reading' (duration: 533.054182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:12.421895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.888105Z","time spent":"533.784045ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-20T18:29:12.981303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.988307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:12.981473Z","caller":"traceutil/trace.go:171","msg":"trace[1528636214] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"553.027993ms","start":"2026-02-20T18:29:12.428413Z","end":"2026-02-20T18:29:12.981441Z","steps":["trace[1528636214] 'process raft request' (duration: 122.800672ms)","trace[1528636214] 'compare' (duration: 429.844203ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:12.981543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.42838Z","time spent":"553.132446ms","remote":"127.0.0.1:58478","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-20T18:29:13.032865Z","caller":"traceutil/trace.go:171","msg":"trace[486142925] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1244; }","duration":"573.982424ms","start":"2026-02-20T18:29:12.458864Z","end":"2026-02-20T18:29:13.032846Z","steps":["trace[486142925] 'read index received' (duration: 92.372008ms)","trace[486142925] 'applied index is now lower than readState.Index' (duration: 481.609315ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:13.032884Z","caller":"traceutil/trace.go:171","msg":"trace[971710082] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"604.016632ms","start":"2026-02-20T18:29:12.428838Z","end":"2026-02-20T18:29:13.032855Z","steps":["trace[971710082] 'process raft request' (duration: 603.820398ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:13.03296Z","caller":"traceutil/trace.go:171","msg":"trace[1517331296] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"603.786967ms","start":"2026-02-20T18:29:12.42916Z","end":"2026-02-20T18:29:13.032947Z","steps":["trace[1517331296] 'process raft request' (duration: 603.620014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:13.033213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.428798Z","time spent":"604.150274ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:13.033235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.429135Z","time spent":"603.867309ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:13.033283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.749143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:13.033239Z","caller":"traceutil/trace.go:171","msg":"trace[774006665] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"600.844253ms","start":"2026-02-20T18:29:12.432158Z","end":"2026-02-20T18:29:13.033003Z","steps":["trace[774006665] 'process raft request' (duration: 600.660979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:13.033247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.366081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:29:13.033529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.432147Z","time spent":"601.223171ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:13.033309Z","caller":"traceutil/trace.go:171","msg":"trace[1039779626] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"549.797874ms","start":"2026-02-20T18:29:12.483504Z","end":"2026-02-20T18:29:13.033302Z","steps":["trace[1039779626] 'agreement among raft nodes before linearized reading' (duration: 549.764253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:13.033741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.483471Z","time spent":"550.256002ms","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:29:13.033544Z","caller":"traceutil/trace.go:171","msg":"trace[370181815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"574.675907ms","start":"2026-02-20T18:29:12.458828Z","end":"2026-02-20T18:29:13.033504Z","steps":["trace[370181815] 'agreement among raft nodes before linearized reading' (duration: 574.136357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:13.03409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.458813Z","time spent":"575.220767ms","remote":"127.0.0.1:58478","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-20T18:29:13.032905Z","caller":"traceutil/trace.go:171","msg":"trace[1359302912] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"602.89675ms","start":"2026-02-20T18:29:12.429983Z","end":"2026-02-20T18:29:13.032879Z","steps":["trace[1359302912] 'process raft request' (duration: 602.819479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:13.034314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.42997Z","time spent":"604.232926ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:13.418125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.771657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:13.418387Z","caller":"traceutil/trace.go:171","msg":"trace[575805250] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"378.462408ms","start":"2026-02-20T18:29:13.03988Z","end":"2026-02-20T18:29:13.418342Z","steps":["trace[575805250] 'process raft request' (duration: 173.323653ms)","trace[575805250] 'compare' (duration: 204.503223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:13.418497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:13.039846Z","time spent":"378.60285ms","remote":"127.0.0.1:58478","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-20T18:29:13.639665Z","caller":"traceutil/trace.go:171","msg":"trace[1112858360] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"162.149016ms","start":"2026-02-20T18:29:13.477471Z","end":"2026-02-20T18:29:13.63962Z","steps":["trace[1112858360] 'process raft request' (duration: 161.85264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.151887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.874888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:14.151994Z","caller":"traceutil/trace.go:171","msg":"trace[1035725143] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"507.724241ms","start":"2026-02-20T18:29:13.64425Z","end":"2026-02-20T18:29:14.151974Z","steps":["trace[1035725143] 'process raft request' (duration: 201.68359ms)","trace[1035725143] 'compare' (duration: 305.757496ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:14.152051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:13.644232Z","time spent":"507.793083ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:14.576539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.716733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:14.576906Z","caller":"traceutil/trace.go:171","msg":"trace[1907567545] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"350.424576ms","start":"2026-02-20T18:29:14.226409Z","end":"2026-02-20T18:29:14.576834Z","steps":["trace[1907567545] 'process raft request' (duration: 72.307605ms)","trace[1907567545] 'compare' (duration: 277.311036ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:14.577148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.226398Z","time spent":"350.634209ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:15.183674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.62668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-20T18:29:15.183738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.806713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:29:15.183761Z","caller":"traceutil/trace.go:171","msg":"trace[1310829314] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1200; }","duration":"144.759563ms","start":"2026-02-20T18:29:15.038983Z","end":"2026-02-20T18:29:15.183742Z","steps":["trace[1310829314] 'range keys from in-memory index tree' (duration: 144.426976ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:15.183844Z","caller":"traceutil/trace.go:171","msg":"trace[1819199777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1200; }","duration":"144.990396ms","start":"2026-02-20T18:29:15.038833Z","end":"2026-02-20T18:29:15.183823Z","steps":["trace[1819199777] 'range keys from in-memory index tree' (duration: 144.573858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.183898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.932936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:29:15.183993Z","caller":"traceutil/trace.go:171","msg":"trace[548354950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1200; }","duration":"145.108639ms","start":"2026-02-20T18:29:15.03886Z","end":"2026-02-20T18:29:15.183969Z","steps":["trace[548354950] 'range keys from in-memory index tree' (duration: 144.682111ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:17.178755Z","caller":"traceutil/trace.go:171","msg":"trace[1058457574] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"174.029936ms","start":"2026-02-20T18:29:17.00468Z","end":"2026-02-20T18:29:17.17871Z","steps":["trace[1058457574] 'process raft request' (duration: 173.822172ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:17.502664Z","caller":"traceutil/trace.go:171","msg":"trace[618947211] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"225.973892ms","start":"2026-02-20T18:29:17.276664Z","end":"2026-02-20T18:29:17.502638Z","steps":["trace[618947211] 'process raft request' (duration: 225.736158ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:17.502883Z","caller":"traceutil/trace.go:171","msg":"trace[1578805255] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"225.943071ms","start":"2026-02-20T18:29:17.27691Z","end":"2026-02-20T18:29:17.502853Z","steps":["trace[1578805255] 'process raft request' (duration: 225.683216ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:18.604173Z","caller":"traceutil/trace.go:171","msg":"trace[468629222] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"119.32489ms","start":"2026-02-20T18:29:18.484801Z","end":"2026-02-20T18:29:18.604126Z","steps":["trace[468629222] 'process raft request' (duration: 119.077514ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:18.858969Z","caller":"traceutil/trace.go:171","msg":"trace[1817710821] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"208.322793ms","start":"2026-02-20T18:29:18.650594Z","end":"2026-02-20T18:29:18.858917Z","steps":["trace[1817710821] 'process raft request' (duration: 196.518884ms)","trace[1817710821] 'compare' (duration: 11.443483ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:35.749552Z","caller":"traceutil/trace.go:171","msg":"trace[816229974] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"234.511794ms","start":"2026-02-20T18:29:35.515016Z","end":"2026-02-20T18:29:35.749527Z","steps":["trace[816229974] 'read index received' (duration: 234.263279ms)","trace[816229974] 'applied index is now lower than readState.Index' (duration: 247.325µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:35.750132Z","caller":"traceutil/trace.go:171","msg":"trace[666031074] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"246.451038ms","start":"2026-02-20T18:29:35.503667Z","end":"2026-02-20T18:29:35.750118Z","steps":["trace[666031074] 'process raft request' (duration: 245.694184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:35.750516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.471632ms","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-20T18:29:35.750683Z","caller":"traceutil/trace.go:171","msg":"trace[2122187084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1283; }","duration":"235.675426ms","start":"2026-02-20T18:29:35.514988Z","end":"2026-02-20T18:29:35.750664Z","steps":["trace[2122187084] 'agreement among raft nodes before linearized reading' (duration: 235.34476ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:35.860782Z","caller":"traceutil/trace.go:171","msg":"trace[1322025649] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"104.785785ms","start":"2026-02-20T18:29:35.75597Z","end":"2026-02-20T18:29:35.860756Z","steps":["trace[1322025649] 'process raft request' (duration: 104.546699ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:35.860844Z","caller":"traceutil/trace.go:171","msg":"trace[1417202232] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"105.039959ms","start":"2026-02-20T18:29:35.755767Z","end":"2026-02-20T18:29:35.860807Z","steps":["trace[1417202232] 'process raft request' (duration: 37.810345ms)","trace[1417202232] 'compare' (duration: 66.740405ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:35.860972Z","caller":"traceutil/trace.go:171","msg":"trace[964696014] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"105.10711ms","start":"2026-02-20T18:29:35.755808Z","end":"2026-02-20T18:29:35.860915Z","steps":["trace[964696014] 'process raft request' (duration: 104.662051ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:38.856455Z","caller":"traceutil/trace.go:171","msg":"trace[1823839959] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"184.634814ms","start":"2026-02-20T18:29:38.671786Z","end":"2026-02-20T18:29:38.856421Z","steps":["trace[1823839959] 'process raft request' (duration: 184.464791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.089702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.137228ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:40.089861Z","caller":"traceutil/trace.go:171","msg":"trace[1453924178] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1298; }","duration":"330.319352ms","start":"2026-02-20T18:29:39.759516Z","end":"2026-02-20T18:29:40.089836Z","steps":["trace[1453924178] 'range keys from in-memory index tree' (duration: 330.111398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.089855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.879386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:29:40.089938Z","caller":"traceutil/trace.go:171","msg":"trace[87351889] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1298; }","duration":"209.002749ms","start":"2026-02-20T18:29:39.880914Z","end":"2026-02-20T18:29:40.089916Z","steps":["trace[87351889] 'range keys from in-memory index tree' (duration: 208.670993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.089597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.132241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:29:40.090271Z","caller":"traceutil/trace.go:171","msg":"trace[1490754427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1298; }","duration":"314.864704ms","start":"2026-02-20T18:29:39.775384Z","end":"2026-02-20T18:29:40.090249Z","steps":["trace[1490754427] 'range keys from in-memory index tree' (duration: 313.970286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.090321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:39.775361Z","time spent":"314.947185ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:40.090149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.425691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:29:40.090472Z","caller":"traceutil/trace.go:171","msg":"trace[342709400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1298; }","duration":"213.809307ms","start":"2026-02-20T18:29:39.876633Z","end":"2026-02-20T18:29:40.090443Z","steps":["trace[342709400] 'range keys from in-memory index tree' (duration: 212.703288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.090566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.632309ms","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-20T18:29:40.090573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.786855ms","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-20T18:29:40.090657Z","caller":"traceutil/trace.go:171","msg":"trace[1512398895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1298; }","duration":"210.755912ms","start":"2026-02-20T18:29:39.879859Z","end":"2026-02-20T18:29:40.090615Z","steps":["trace[1512398895] 'range keys from in-memory index tree' (duration: 209.379906ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:40.090791Z","caller":"traceutil/trace.go:171","msg":"trace[1433705097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1298; }","duration":"298.724523ms","start":"2026-02-20T18:29:39.79195Z","end":"2026-02-20T18:29:40.090675Z","steps":["trace[1433705097] 'range keys from in-memory index tree' (duration: 297.568701ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:43.301475Z","caller":"traceutil/trace.go:171","msg":"trace[867837421] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"112.971657ms","start":"2026-02-20T18:29:43.18848Z","end":"2026-02-20T18:29:43.301452Z","steps":["trace[867837421] 'process raft request' (duration: 112.808314ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:43.334133Z","caller":"traceutil/trace.go:171","msg":"trace[1713080958] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"133.116543ms","start":"2026-02-20T18:29:43.200999Z","end":"2026-02-20T18:29:43.334116Z","steps":["trace[1713080958] 'process raft request' (duration: 133.033801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.132552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.652294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:44.132686Z","caller":"traceutil/trace.go:171","msg":"trace[235581841] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"200.473809ms","start":"2026-02-20T18:29:43.932188Z","end":"2026-02-20T18:29:44.132662Z","steps":["trace[235581841] 'process raft request' (duration: 52.49871ms)","trace[235581841] 'compare' (duration: 147.495041ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:44.80301Z","caller":"traceutil/trace.go:171","msg":"trace[858296866] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"123.540435ms","start":"2026-02-20T18:29:44.67944Z","end":"2026-02-20T18:29:44.802981Z","steps":["trace[858296866] 'process raft request' (duration: 123.342931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.780088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823085794933906930,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:29:48.820673Z","caller":"traceutil/trace.go:171","msg":"trace[1948630632] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"547.116956ms","start":"2026-02-20T18:29:48.273512Z","end":"2026-02-20T18:29:48.820629Z","steps":["trace[1948630632] 'process raft request' (duration: 546.893972ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:48.820935Z","caller":"traceutil/trace.go:171","msg":"trace[1359186055] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"541.267257ms","start":"2026-02-20T18:29:48.279644Z","end":"2026-02-20T18:29:48.820911Z","steps":["trace[1359186055] 'read index received' (duration: 540.757698ms)","trace[1359186055] 'applied index is now lower than readState.Index' (duration: 508.119µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:48.820884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.273495Z","time spent":"547.28937ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:48.821162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.496432ms","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-20T18:29:48.821208Z","caller":"traceutil/trace.go:171","msg":"trace[743090690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1330; }","duration":"541.588463ms","start":"2026-02-20T18:29:48.279609Z","end":"2026-02-20T18:29:48.821197Z","steps":["trace[743090690] 'agreement among raft nodes before linearized reading' (duration: 541.42855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.821381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.279593Z","time spent":"541.773236ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:48.821525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.674538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:29:48.821585Z","caller":"traceutil/trace.go:171","msg":"trace[2002217884] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1330; }","duration":"248.798151ms","start":"2026-02-20T18:29:48.572775Z","end":"2026-02-20T18:29:48.821573Z","steps":["trace[2002217884] 'agreement among raft nodes before linearized reading' (duration: 248.663818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.821389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.619785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-20T18:29:48.821609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.279136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:29:48.821636Z","caller":"traceutil/trace.go:171","msg":"trace[1378269066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1330; }","duration":"524.879471ms","start":"2026-02-20T18:29:48.296745Z","end":"2026-02-20T18:29:48.821625Z","steps":["trace[1378269066] 'agreement among raft nodes before linearized reading' (duration: 524.420093ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:48.821664Z","caller":"traceutil/trace.go:171","msg":"trace[2022219667] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1330; }","duration":"497.349297ms","start":"2026-02-20T18:29:48.324296Z","end":"2026-02-20T18:29:48.821646Z","steps":["trace[2022219667] 'agreement among raft nodes before linearized reading' (duration: 497.190164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.821719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.324285Z","time spent":"497.418629ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-20T18:29:48.82186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.425396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-02-20T18:29:48.821892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.842643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:48.821921Z","caller":"traceutil/trace.go:171","msg":"trace[1694668293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"337.903214ms","start":"2026-02-20T18:29:48.484009Z","end":"2026-02-20T18:29:48.821912Z","steps":["trace[1694668293] 'agreement among raft nodes before linearized reading' (duration: 337.856813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.821951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.483992Z","time spent":"337.954404ms","remote":"127.0.0.1:58196","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-20T18:29:48.822181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.803329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:29:48.822227Z","caller":"traceutil/trace.go:171","msg":"trace[1954934020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1330; }","duration":"524.879171ms","start":"2026-02-20T18:29:48.297334Z","end":"2026-02-20T18:29:48.822213Z","steps":["trace[1954934020] 'agreement among raft nodes before linearized reading' (duration: 524.765359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.822257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.297318Z","time spent":"524.932312ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:29:48.822297Z","caller":"traceutil/trace.go:171","msg":"trace[582009317] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1330; }","duration":"515.479676ms","start":"2026-02-20T18:29:48.306402Z","end":"2026-02-20T18:29:48.821882Z","steps":["trace[582009317] 'agreement among raft nodes before linearized reading' (duration: 515.354003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.822413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.306391Z","time spent":"516.011997ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-20T18:29:48.822511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.296738Z","time spent":"524.927711ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T18:29:49.274474Z","caller":"traceutil/trace.go:171","msg":"trace[1852170426] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"131.697337ms","start":"2026-02-20T18:29:49.142753Z","end":"2026-02-20T18:29:49.274451Z","steps":["trace[1852170426] 'process raft request' (duration: 131.541664ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:55.093237Z","caller":"traceutil/trace.go:171","msg":"trace[563137776] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1420; }","duration":"147.580443ms","start":"2026-02-20T18:29:54.94563Z","end":"2026-02-20T18:29:55.09321Z","steps":["trace[563137776] 'read index received' (duration: 123.104366ms)","trace[563137776] 'applied index is now lower than readState.Index' (duration: 24.475037ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:55.093452Z","caller":"traceutil/trace.go:171","msg":"trace[2020359861] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"206.668966ms","start":"2026-02-20T18:29:54.886682Z","end":"2026-02-20T18:29:55.093351Z","steps":["trace[2020359861] 'process raft request' (duration: 182.223379ms)","trace[2020359861] 'compare' (duration: 24.180192ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:55.093575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.91625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:29:55.093665Z","caller":"traceutil/trace.go:171","msg":"trace[1238224578] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1358; }","duration":"148.071682ms","start":"2026-02-20T18:29:54.945574Z","end":"2026-02-20T18:29:55.093646Z","steps":["trace[1238224578] 'agreement among raft nodes before linearized reading' (duration: 147.780247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.094187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.025637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:29:55.094227Z","caller":"traceutil/trace.go:171","msg":"trace[1527959635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"143.096039ms","start":"2026-02-20T18:29:54.95112Z","end":"2026-02-20T18:29:55.094216Z","steps":["trace[1527959635] 'agreement among raft nodes before linearized reading' (duration: 142.960797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.094505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.172261ms","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-20T18:29:55.094551Z","caller":"traceutil/trace.go:171","msg":"trace[1735512522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"143.251273ms","start":"2026-02-20T18:29:54.951291Z","end":"2026-02-20T18:29:55.094542Z","steps":["trace[1735512522] 'agreement among raft nodes before linearized reading' (duration: 143.145971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.09476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.543108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:29:55.094839Z","caller":"traceutil/trace.go:171","msg":"trace[1372045070] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1358; }","duration":"143.6452ms","start":"2026-02-20T18:29:54.951172Z","end":"2026-02-20T18:29:55.094817Z","steps":["trace[1372045070] 'agreement among raft nodes before linearized reading' (duration: 143.416056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.094841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.566348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:29:55.094936Z","caller":"traceutil/trace.go:171","msg":"trace[251713250] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1358; }","duration":"143.710241ms","start":"2026-02-20T18:29:54.9512Z","end":"2026-02-20T18:29:55.09491Z","steps":["trace[251713250] 'agreement among raft nodes before linearized reading' (duration: 143.360574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.094956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.376135ms","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-20T18:29:55.095013Z","caller":"traceutil/trace.go:171","msg":"trace[1791174630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1358; }","duration":"143.489077ms","start":"2026-02-20T18:29:54.951506Z","end":"2026-02-20T18:29:55.094996Z","steps":["trace[1791174630] 'agreement among raft nodes before linearized reading' (duration: 142.718332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.449656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.45989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-20T18:29:55.449735Z","caller":"traceutil/trace.go:171","msg":"trace[953644116] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:1363; }","duration":"214.587113ms","start":"2026-02-20T18:29:55.235127Z","end":"2026-02-20T18:29:55.449714Z","steps":["trace[953644116] 'range keys from in-memory index tree' (duration: 214.283727ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:55.829374Z","caller":"traceutil/trace.go:171","msg":"trace[688585960] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"314.283442ms","start":"2026-02-20T18:29:55.515062Z","end":"2026-02-20T18:29:55.829345Z","steps":["trace[688585960] 'process raft request' (duration: 314.077818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.829608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.515045Z","time spent":"314.448856ms","remote":"127.0.0.1:58240","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-20T18:29:59.026285Z","caller":"traceutil/trace.go:171","msg":"trace[2124252508] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"105.147591ms","start":"2026-02-20T18:29:58.921109Z","end":"2026-02-20T18:29:59.026257Z","steps":["trace[2124252508] 'read index received' (duration: 104.876417ms)","trace[2124252508] 'applied index is now lower than readState.Index' (duration: 269.894µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:59.026581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.454737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:29:59.026643Z","caller":"traceutil/trace.go:171","msg":"trace[59251649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"105.566239ms","start":"2026-02-20T18:29:58.921061Z","end":"2026-02-20T18:29:59.026628Z","steps":["trace[59251649] 'agreement among raft nodes before linearized reading' (duration: 105.328575ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:59.026344Z","caller":"traceutil/trace.go:171","msg":"trace[308063917] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"136.567498ms","start":"2026-02-20T18:29:58.889739Z","end":"2026-02-20T18:29:59.026307Z","steps":["trace[308063917] 'process raft request' (duration: 136.320824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.026993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.440259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-20T18:29:59.027261Z","caller":"traceutil/trace.go:171","msg":"trace[1304562600] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1371; }","duration":"103.727215ms","start":"2026-02-20T18:29:58.923508Z","end":"2026-02-20T18:29:59.027235Z","steps":["trace[1304562600] 'agreement among raft nodes before linearized reading' (duration: 103.375129ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:59.317115Z","caller":"traceutil/trace.go:171","msg":"trace[1380420805] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"285.990995ms","start":"2026-02-20T18:29:59.031079Z","end":"2026-02-20T18:29:59.31707Z","steps":["trace[1380420805] 'process raft request' (duration: 199.97315ms)","trace[1380420805] 'compare' (duration: 85.605987ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:59.317584Z","caller":"traceutil/trace.go:171","msg":"trace[1480214896] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"284.163441ms","start":"2026-02-20T18:29:59.033339Z","end":"2026-02-20T18:29:59.317502Z","steps":["trace[1480214896] 'process raft request' (duration: 283.656291ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:59.3176Z","caller":"traceutil/trace.go:171","msg":"trace[300440068] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1435; }","duration":"168.365592ms","start":"2026-02-20T18:29:59.148995Z","end":"2026-02-20T18:29:59.317361Z","steps":["trace[300440068] 'read index received' (duration: 81.899618ms)","trace[300440068] 'applied index is now lower than readState.Index' (duration: 86.463534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:59.317903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.903552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:29:59.317988Z","caller":"traceutil/trace.go:171","msg":"trace[1843105899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1373; }","duration":"169.100425ms","start":"2026-02-20T18:29:59.148868Z","end":"2026-02-20T18:29:59.317969Z","steps":["trace[1843105899] 'agreement among raft nodes before linearized reading' (duration: 168.758989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.318203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.544771ms","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-20T18:29:59.31828Z","caller":"traceutil/trace.go:171","msg":"trace[1096227747] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1373; }","duration":"163.666023ms","start":"2026-02-20T18:29:59.154595Z","end":"2026-02-20T18:29:59.318261Z","steps":["trace[1096227747] 'agreement among raft nodes before linearized reading' (duration: 163.444929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.318378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.155437ms","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-20T18:29:59.318421Z","caller":"traceutil/trace.go:171","msg":"trace[279084727] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"160.252649ms","start":"2026-02-20T18:29:59.158156Z","end":"2026-02-20T18:29:59.318409Z","steps":["trace[279084727] 'agreement among raft nodes before linearized reading' (duration: 160.155307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.318475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.016211ms","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-20T18:29:59.318635Z","caller":"traceutil/trace.go:171","msg":"trace[1660023762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1373; }","duration":"163.278897ms","start":"2026-02-20T18:29:59.155294Z","end":"2026-02-20T18:29:59.318573Z","steps":["trace[1660023762] 'agreement among raft nodes before linearized reading' (duration: 162.840108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.318976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.976822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T18:29:59.319093Z","caller":"traceutil/trace.go:171","msg":"trace[1599455488] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1373; }","duration":"162.120305ms","start":"2026-02-20T18:29:59.156957Z","end":"2026-02-20T18:29:59.319078Z","steps":["trace[1599455488] 'agreement among raft nodes before linearized reading' (duration: 161.898861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.833681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.579772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.833901Z","caller":"traceutil/trace.go:171","msg":"trace[684213691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1379; }","duration":"349.074483ms","start":"2026-02-20T18:29:59.484805Z","end":"2026-02-20T18:29:59.83388Z","steps":["trace[684213691] 'range keys from in-memory index tree' (duration: 348.373889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.833951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.484756Z","time spent":"349.185574ms","remote":"127.0.0.1:58196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:30:01.545448Z","caller":"traceutil/trace.go:171","msg":"trace[43159529] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"107.912293ms","start":"2026-02-20T18:30:01.437468Z","end":"2026-02-20T18:30:01.54538Z","steps":["trace[43159529] 'process raft request' (duration: 59.55641ms)","trace[43159529] 'compare' (duration: 48.134219ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:01.545645Z","caller":"traceutil/trace.go:171","msg":"trace[690853869] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"107.938913ms","start":"2026-02-20T18:30:01.437688Z","end":"2026-02-20T18:30:01.545627Z","steps":["trace[690853869] 'process raft request' (duration: 107.644628ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:01.545904Z","caller":"traceutil/trace.go:171","msg":"trace[1545795422] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"107.699779ms","start":"2026-02-20T18:30:01.438191Z","end":"2026-02-20T18:30:01.545891Z","steps":["trace[1545795422] 'process raft request' (duration: 107.372583ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:01.546205Z","caller":"traceutil/trace.go:171","msg":"trace[586303583] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"107.088647ms","start":"2026-02-20T18:30:01.439088Z","end":"2026-02-20T18:30:01.546177Z","steps":["trace[586303583] 'process raft request' (duration: 106.745761ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:13.803291Z","caller":"traceutil/trace.go:171","msg":"trace[797744484] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"142.871455ms","start":"2026-02-20T18:30:13.660381Z","end":"2026-02-20T18:30:13.803253Z","steps":["trace[797744484] 'process raft request' (duration: 110.914689ms)","trace[797744484] 'compare' (duration: 31.535907ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:19.615147Z","caller":"traceutil/trace.go:171","msg":"trace[60460153] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"120.563219ms","start":"2026-02-20T18:30:19.494542Z","end":"2026-02-20T18:30:19.615105Z","steps":["trace[60460153] 'process raft request' (duration: 93.559204ms)","trace[60460153] 'compare' (duration: 26.618477ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:20.287871Z","caller":"traceutil/trace.go:171","msg":"trace[881470303] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"125.056913ms","start":"2026-02-20T18:30:20.162777Z","end":"2026-02-20T18:30:20.287834Z","steps":["trace[881470303] 'process raft request' (duration: 124.870849ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:42.019018Z","caller":"traceutil/trace.go:171","msg":"trace[390580998] linearizableReadLoop","detail":"{readStateIndex:1621; appliedIndex:1620; }","duration":"138.832459ms","start":"2026-02-20T18:30:41.880155Z","end":"2026-02-20T18:30:42.018988Z","steps":["trace[390580998] 'read index received' (duration: 138.581694ms)","trace[390580998] 'applied index is now lower than readState.Index' (duration: 250.115µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:42.019101Z","caller":"traceutil/trace.go:171","msg":"trace[153511429] transaction","detail":"{read_only:false; response_revision:1548; number_of_response:1; }","duration":"139.264948ms","start":"2026-02-20T18:30:41.879785Z","end":"2026-02-20T18:30:42.01905Z","steps":["trace[153511429] 'process raft request' (duration: 139.023754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:42.01938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.162996ms","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-20T18:30:42.019452Z","caller":"traceutil/trace.go:171","msg":"trace[200518950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1548; }","duration":"139.40256ms","start":"2026-02-20T18:30:41.880028Z","end":"2026-02-20T18:30:42.01943Z","steps":["trace[200518950] 'agreement among raft nodes before linearized reading' (duration: 139.087534ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:44.035109Z","caller":"traceutil/trace.go:171","msg":"trace[944447019] transaction","detail":"{read_only:false; response_revision:1554; number_of_response:1; }","duration":"135.297524ms","start":"2026-02-20T18:30:43.899787Z","end":"2026-02-20T18:30:44.035085Z","steps":["trace[944447019] 'process raft request' (duration: 128.16492ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:44.602494Z","caller":"traceutil/trace.go:171","msg":"trace[44211876] linearizableReadLoop","detail":"{readStateIndex:1632; appliedIndex:1631; }","duration":"118.06195ms","start":"2026-02-20T18:30:44.484404Z","end":"2026-02-20T18:30:44.602466Z","steps":["trace[44211876] 'read index received' (duration: 117.908407ms)","trace[44211876] 'applied index is now lower than readState.Index' (duration: 152.513µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:44.602642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.219433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:44.602678Z","caller":"traceutil/trace.go:171","msg":"trace[716040070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1559; }","duration":"118.343825ms","start":"2026-02-20T18:30:44.484323Z","end":"2026-02-20T18:30:44.602667Z","steps":["trace[716040070] 'agreement among raft nodes before linearized reading' (duration: 118.259353ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:44.602815Z","caller":"traceutil/trace.go:171","msg":"trace[1328524801] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"165.750643ms","start":"2026-02-20T18:30:44.437044Z","end":"2026-02-20T18:30:44.602795Z","steps":["trace[1328524801] 'process raft request' (duration: 165.250964ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:44.732554Z","caller":"traceutil/trace.go:171","msg":"trace[3186797] linearizableReadLoop","detail":"{readStateIndex:1633; appliedIndex:1632; }","duration":"128.920404ms","start":"2026-02-20T18:30:44.603614Z","end":"2026-02-20T18:30:44.732534Z","steps":["trace[3186797] 'read index received' (duration: 65.324673ms)","trace[3186797] 'applied index is now lower than readState.Index' (duration: 63.594581ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:44.732725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.096617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:44.732764Z","caller":"traceutil/trace.go:171","msg":"trace[341654193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1560; }","duration":"129.194369ms","start":"2026-02-20T18:30:44.603558Z","end":"2026-02-20T18:30:44.732752Z","steps":["trace[341654193] 'agreement among raft nodes before linearized reading' (duration: 129.088417ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:44.73273Z","caller":"traceutil/trace.go:171","msg":"trace[212015739] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"134.114171ms","start":"2026-02-20T18:30:44.598558Z","end":"2026-02-20T18:30:44.732672Z","steps":["trace[212015739] 'process raft request' (duration: 70.46463ms)","trace[212015739] 'compare' (duration: 63.423927ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:06.35358Z","caller":"traceutil/trace.go:171","msg":"trace[906273910] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"109.920997ms","start":"2026-02-20T18:31:06.243616Z","end":"2026-02-20T18:31:06.353537Z","steps":["trace[906273910] 'process raft request' (duration: 51.512013ms)","trace[906273910] 'compare' (duration: 58.038957ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:06.353571Z","caller":"traceutil/trace.go:171","msg":"trace[1398685367] linearizableReadLoop","detail":"{readStateIndex:1729; appliedIndex:1727; }","duration":"103.911296ms","start":"2026-02-20T18:31:06.249535Z","end":"2026-02-20T18:31:06.353447Z","steps":["trace[1398685367] 'read index received' (duration: 45.608554ms)","trace[1398685367] 'applied index is now lower than readState.Index' (duration: 58.301411ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:06.353651Z","caller":"traceutil/trace.go:171","msg":"trace[180503631] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"108.231956ms","start":"2026-02-20T18:31:06.245375Z","end":"2026-02-20T18:31:06.353607Z","steps":["trace[180503631] 'process raft request' (duration: 107.987312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:06.353848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.267182ms","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-20T18:31:06.353911Z","caller":"traceutil/trace.go:171","msg":"trace[184415837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1651; }","duration":"104.387534ms","start":"2026-02-20T18:31:06.249509Z","end":"2026-02-20T18:31:06.353896Z","steps":["trace[184415837] 'agreement among raft nodes before linearized reading' (duration: 104.144969ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:34.578085Z","caller":"traceutil/trace.go:171","msg":"trace[1762791024] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"158.972318ms","start":"2026-02-20T18:31:34.419087Z","end":"2026-02-20T18:31:34.578059Z","steps":["trace[1762791024] 'process raft request' (duration: 137.856093ms)","trace[1762791024] 'compare' (duration: 20.817718ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:39.033823Z","caller":"traceutil/trace.go:171","msg":"trace[1623355480] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"137.040469ms","start":"2026-02-20T18:31:38.896761Z","end":"2026-02-20T18:31:39.033802Z","steps":["trace[1623355480] 'process raft request' (duration: 136.885046ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:50.874941Z","caller":"traceutil/trace.go:171","msg":"trace[1588339420] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"119.260165ms","start":"2026-02-20T18:31:50.755648Z","end":"2026-02-20T18:31:50.874908Z","steps":["trace[1588339420] 'process raft request' (duration: 119.140333ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:50.875073Z","caller":"traceutil/trace.go:171","msg":"trace[1635621073] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"119.877348ms","start":"2026-02-20T18:31:50.755067Z","end":"2026-02-20T18:31:50.874945Z","steps":["trace[1635621073] 'process raft request' (duration: 109.387443ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:53.592066Z","caller":"traceutil/trace.go:171","msg":"trace[603042207] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"707.616951ms","start":"2026-02-20T18:31:52.884414Z","end":"2026-02-20T18:31:53.592031Z","steps":["trace[603042207] 'process raft request' (duration: 706.885566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:53.592486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:52.884395Z","time spent":"707.920686ms","remote":"127.0.0.1:58478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:31:53.614658Z","caller":"traceutil/trace.go:171","msg":"trace[459670325] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1953; }","duration":"129.261614ms","start":"2026-02-20T18:31:53.485364Z","end":"2026-02-20T18:31:53.614625Z","steps":["trace[459670325] 'read index received' (duration: 106.04336ms)","trace[459670325] 'applied index is now lower than readState.Index' (duration: 23.216614ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:53.614905Z","caller":"traceutil/trace.go:171","msg":"trace[572560042] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"730.050138ms","start":"2026-02-20T18:31:52.884811Z","end":"2026-02-20T18:31:53.614861Z","steps":["trace[572560042] 'process raft request' (duration: 729.723842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:53.614932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.571699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:53.615118Z","caller":"traceutil/trace.go:171","msg":"trace[1651550499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1867; }","duration":"129.781223ms","start":"2026-02-20T18:31:53.485297Z","end":"2026-02-20T18:31:53.615078Z","steps":["trace[1651550499] 'agreement among raft nodes before linearized reading' (duration: 129.579529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:53.61522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:31:52.884795Z","time spent":"730.248271ms","remote":"127.0.0.1:58478","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-20T18:32:10.985459Z","caller":"traceutil/trace.go:171","msg":"trace[1168794069] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"107.615708ms","start":"2026-02-20T18:32:10.877816Z","end":"2026-02-20T18:32:10.985432Z","steps":["trace[1168794069] 'process raft request' (duration: 107.423495ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:10.985454Z","caller":"traceutil/trace.go:171","msg":"trace[1149765628] linearizableReadLoop","detail":"{readStateIndex:2039; appliedIndex:2038; }","duration":"107.18569ms","start":"2026-02-20T18:32:10.878243Z","end":"2026-02-20T18:32:10.985429Z","steps":["trace[1149765628] 'read index received' (duration: 107.012617ms)","trace[1149765628] 'applied index is now lower than readState.Index' (duration: 171.543µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:32:10.985643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.378904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-20T18:32:10.986233Z","caller":"traceutil/trace.go:171","msg":"trace[937447171] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1947; }","duration":"107.470146ms","start":"2026-02-20T18:32:10.878238Z","end":"2026-02-20T18:32:10.985708Z","steps":["trace[937447171] 'agreement among raft nodes before linearized reading' (duration: 107.283202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:32:10.986276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.902377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:32:10.986325Z","caller":"traceutil/trace.go:171","msg":"trace[1498058216] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1947; }","duration":"106.534868ms","start":"2026-02-20T18:32:10.879773Z","end":"2026-02-20T18:32:10.986308Z","steps":["trace[1498058216] 'agreement among raft nodes before linearized reading' (duration: 105.875745ms)"],"step_count":1}