{"level":"warn","ts":"2026-02-22T17:50:03.787976Z","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-22T17:50:03.788063Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.144:2380","--initial-cluster=instance=https://199.204.45.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.144: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-22T17:50:03.788169Z","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-22T17:50:03.788179Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-22T17:50:03.788202Z","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-22T17:50:03.788876Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"]} {"level":"info","ts":"2026-02-22T17:50:03.788967Z","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://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.144: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-22T17:50:03.794991Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.807352ms"} {"level":"info","ts":"2026-02-22T17:50:03.807002Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4594e5f716bad646","cluster-id":"ff2e51b364668606"} {"level":"info","ts":"2026-02-22T17:50:03.807101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=()"} {"level":"info","ts":"2026-02-22T17:50:03.807125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 0"} {"level":"info","ts":"2026-02-22T17:50:03.807137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4594e5f716bad646 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-22T17:50:03.807153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became follower at term 1"} {"level":"info","ts":"2026-02-22T17:50:03.80719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"warn","ts":"2026-02-22T17:50:03.823419Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-22T17:50:03.825511Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-22T17:50:03.826858Z","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-22T17:50:03.82931Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4594e5f716bad646","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-22T17:50:03.829785Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4594e5f716bad646","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-22T17:50:03.829897Z","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-22T17:50:03.83005Z","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-22T17:50:03.830073Z","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-22T17:50:03.831125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 switched to configuration voters=(5013885134571230790)"} {"level":"info","ts":"2026-02-22T17:50:03.83135Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","added-peer-id":"4594e5f716bad646","added-peer-peer-urls":["https://199.204.45.144:2380"]} {"level":"info","ts":"2026-02-22T17:50:03.834544Z","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-22T17:50:03.83462Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-22T17:50:03.834718Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.144:2380"} {"level":"info","ts":"2026-02-22T17:50:03.834891Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4594e5f716bad646","initial-advertise-peer-urls":["https://199.204.45.144:2380"],"listen-peer-urls":["https://199.204.45.144:2380"],"advertise-client-urls":["https://199.204.45.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-22T17:50:03.834953Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-22T17:50:04.007398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 is starting a new election at term 1"} {"level":"info","ts":"2026-02-22T17:50:04.007461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-22T17:50:04.00767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgPreVoteResp from 4594e5f716bad646 at term 1"} {"level":"info","ts":"2026-02-22T17:50:04.007876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became candidate at term 2"} {"level":"info","ts":"2026-02-22T17:50:04.007902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 received MsgVoteResp from 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-22T17:50:04.008001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4594e5f716bad646 became leader at term 2"} {"level":"info","ts":"2026-02-22T17:50:04.008063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4594e5f716bad646 elected leader 4594e5f716bad646 at term 2"} {"level":"info","ts":"2026-02-22T17:50:04.009612Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4594e5f716bad646","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.144:2379]}","request-path":"/0/members/4594e5f716bad646/attributes","cluster-id":"ff2e51b364668606","publish-timeout":"7s"} {"level":"info","ts":"2026-02-22T17:50:04.009821Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:50:04.010067Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:50:04.011895Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-22T17:50:04.009894Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:50:04.013371Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff2e51b364668606","local-member-id":"4594e5f716bad646","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:50:04.013572Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:50:04.013603Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:50:04.014229Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.144:2379"} {"level":"info","ts":"2026-02-22T17:50:04.022288Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-22T17:50:04.022307Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-22T17:50:32.580516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.379423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:50:32.582265Z","caller":"traceutil/trace.go:171","msg":"trace[634499271] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"583.624772ms","start":"2026-02-22T17:50:31.998607Z","end":"2026-02-22T17:50:32.582232Z","steps":["trace[634499271] 'process raft request' (duration: 583.308174ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:32.582342Z","caller":"traceutil/trace.go:171","msg":"trace[1095200730] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"582.149693ms","start":"2026-02-22T17:50:32.000169Z","end":"2026-02-22T17:50:32.582319Z","steps":["trace[1095200730] 'process raft request' (duration: 581.977529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:32.582456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:32.000157Z","time spent":"582.252866ms","remote":"127.0.0.1:37064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4272,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:32.582626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:31.998591Z","time spent":"583.823487ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3600,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:32.582602Z","caller":"traceutil/trace.go:171","msg":"trace[1842839096] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"582.571174ms","start":"2026-02-22T17:50:31.999703Z","end":"2026-02-22T17:50:32.582274Z","steps":["trace[1842839096] 'process raft request' (duration: 582.379339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:32.582866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:31.999689Z","time spent":"583.059798ms","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T17:50:32.583113Z","caller":"traceutil/trace.go:171","msg":"trace[105599426] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"579.522296ms","start":"2026-02-22T17:50:32.003552Z","end":"2026-02-22T17:50:32.583074Z","steps":["trace[105599426] 'process raft request' (duration: 578.676534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:32.583227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:32.003544Z","time spent":"579.622558ms","remote":"127.0.0.1:37058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14294,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:32.583925Z","caller":"traceutil/trace.go:171","msg":"trace[739328816] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"584.806802ms","start":"2026-02-22T17:50:31.997269Z","end":"2026-02-22T17:50:32.582076Z","steps":["trace[739328816] 'process raft request' (duration: 299.076109ms)","trace[739328816] 'compare' (duration: 283.168257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:32.584201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:31.997253Z","time spent":"586.764423ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:32.585194Z","caller":"traceutil/trace.go:171","msg":"trace[761413029] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:502; }","duration":"582.978955ms","start":"2026-02-22T17:50:32.002198Z","end":"2026-02-22T17:50:32.585177Z","steps":["trace[761413029] 'read index received' (duration: 294.216163ms)","trace[761413029] 'applied index is now lower than readState.Index' (duration: 288.761802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:32.585444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.242382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4313"} {"level":"info","ts":"2026-02-22T17:50:32.585502Z","caller":"traceutil/trace.go:171","msg":"trace[655244244] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:492; }","duration":"583.319954ms","start":"2026-02-22T17:50:32.002162Z","end":"2026-02-22T17:50:32.585482Z","steps":["trace[655244244] 'agreement among raft nodes before linearized reading' (duration: 583.196891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:32.585503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.428302ms","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-22T17:50:32.585568Z","caller":"traceutil/trace.go:171","msg":"trace[1674289271] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"539.920561ms","start":"2026-02-22T17:50:32.045637Z","end":"2026-02-22T17:50:32.585558Z","steps":["trace[1674289271] 'process raft request' (duration: 537.214831ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:32.585566Z","caller":"traceutil/trace.go:171","msg":"trace[523569100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"492.535635ms","start":"2026-02-22T17:50:32.093015Z","end":"2026-02-22T17:50:32.585551Z","steps":["trace[523569100] 'agreement among raft nodes before linearized reading' (duration: 492.411962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:32.58561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:32.092999Z","time spent":"492.603286ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:50:32.585622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:32.045606Z","time spent":"539.985712ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317263,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:32.585735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.304913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:50:32.585759Z","caller":"traceutil/trace.go:171","msg":"trace[1681718574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:492; }","duration":"314.355834ms","start":"2026-02-22T17:50:32.271397Z","end":"2026-02-22T17:50:32.585752Z","steps":["trace[1681718574] 'agreement among raft nodes before linearized reading' (duration: 314.309203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:32.585777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:32.271385Z","time spent":"314.389146ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-22T17:50:32.585947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.061926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:50:32.586155Z","caller":"traceutil/trace.go:171","msg":"trace[1180980578] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"312.130897ms","start":"2026-02-22T17:50:32.273836Z","end":"2026-02-22T17:50:32.585967Z","steps":["trace[1180980578] 'agreement among raft nodes before linearized reading' (duration: 312.058655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:32.586173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:32.273827Z","time spent":"312.341942ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:50:32.58553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:32.002154Z","time spent":"583.369815ms","remote":"127.0.0.1:37030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4336,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-22T17:50:32.586362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.276123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-22T17:50:32.586395Z","caller":"traceutil/trace.go:171","msg":"trace[1625331257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"491.348565ms","start":"2026-02-22T17:50:32.095037Z","end":"2026-02-22T17:50:32.586386Z","steps":["trace[1625331257] 'agreement among raft nodes before linearized reading' (duration: 491.281003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:32.586417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:32.095016Z","time spent":"491.396245ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:50:35.107127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200473856189260,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:50:35.417644Z","caller":"traceutil/trace.go:171","msg":"trace[1111821782] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"831.237629ms","start":"2026-02-22T17:50:34.586367Z","end":"2026-02-22T17:50:35.417605Z","steps":["trace[1111821782] 'process raft request' (duration: 830.979182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:35.417824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:34.586356Z","time spent":"831.377873ms","remote":"127.0.0.1:36712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:35.417646Z","caller":"traceutil/trace.go:171","msg":"trace[1642069997] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"811.470277ms","start":"2026-02-22T17:50:34.606142Z","end":"2026-02-22T17:50:35.417612Z","steps":["trace[1642069997] 'read index received' (duration: 811.17936ms)","trace[1642069997] 'applied index is now lower than readState.Index' (duration: 288.247µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:35.417857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.698993ms","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-22T17:50:35.418157Z","caller":"traceutil/trace.go:171","msg":"trace[2051010645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"812.026222ms","start":"2026-02-22T17:50:34.606086Z","end":"2026-02-22T17:50:35.418112Z","steps":["trace[2051010645] 'agreement among raft nodes before linearized reading' (duration: 811.613612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:35.418206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.786579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:35.418253Z","caller":"traceutil/trace.go:171","msg":"trace[1136251821] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"313.860921ms","start":"2026-02-22T17:50:35.104374Z","end":"2026-02-22T17:50:35.418234Z","steps":["trace[1136251821] 'agreement among raft nodes before linearized reading' (duration: 313.8022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:35.418267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:34.606064Z","time spent":"812.165315ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:50:35.418299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:35.104346Z","time spent":"313.945984ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:50:35.418336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.99962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-22T17:50:35.418393Z","caller":"traceutil/trace.go:171","msg":"trace[112901588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"810.104192ms","start":"2026-02-22T17:50:34.608275Z","end":"2026-02-22T17:50:35.418379Z","steps":["trace[112901588] 'agreement among raft nodes before linearized reading' (duration: 809.895037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:35.418431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:34.608265Z","time spent":"810.158983ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:50:35.418642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.227598ms","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-22T17:50:35.418841Z","caller":"traceutil/trace.go:171","msg":"trace[107748361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"812.516074ms","start":"2026-02-22T17:50:34.606295Z","end":"2026-02-22T17:50:35.418811Z","steps":["trace[107748361] 'agreement among raft nodes before linearized reading' (duration: 811.864428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:35.418932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:34.606264Z","time spent":"812.652268ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:50:35.419805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.535369ms","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-22T17:50:35.419902Z","caller":"traceutil/trace.go:171","msg":"trace[1619162527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"811.671103ms","start":"2026-02-22T17:50:34.608209Z","end":"2026-02-22T17:50:35.41988Z","steps":["trace[1619162527] 'agreement among raft nodes before linearized reading' (duration: 810.065751ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:35.419958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:34.608197Z","time spent":"811.747045ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:50:41.720103Z","caller":"traceutil/trace.go:171","msg":"trace[1751263502] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"265.531091ms","start":"2026-02-22T17:50:41.454536Z","end":"2026-02-22T17:50:41.720067Z","steps":["trace[1751263502] 'process raft request' (duration: 265.363737ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:41.72034Z","caller":"traceutil/trace.go:171","msg":"trace[1095211798] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:543; }","duration":"264.561216ms","start":"2026-02-22T17:50:41.455545Z","end":"2026-02-22T17:50:41.720106Z","steps":["trace[1095211798] 'read index received' (duration: 264.538636ms)","trace[1095211798] 'applied index is now lower than readState.Index' (duration: 17.44µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:41.720737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.157051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9jl64\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-22T17:50:41.720806Z","caller":"traceutil/trace.go:171","msg":"trace[584197693] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9jl64; range_end:; response_count:1; response_revision:525; }","duration":"265.266254ms","start":"2026-02-22T17:50:41.455522Z","end":"2026-02-22T17:50:41.720788Z","steps":["trace[584197693] 'agreement among raft nodes before linearized reading' (duration: 264.949776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:41.739305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.435782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:50:41.739358Z","caller":"traceutil/trace.go:171","msg":"trace[2086302781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"230.512765ms","start":"2026-02-22T17:50:41.508833Z","end":"2026-02-22T17:50:41.739346Z","steps":["trace[2086302781] 'agreement among raft nodes before linearized reading' (duration: 230.412992ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:41.739427Z","caller":"traceutil/trace.go:171","msg":"trace[878131894] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"283.687611ms","start":"2026-02-22T17:50:41.455709Z","end":"2026-02-22T17:50:41.739397Z","steps":["trace[878131894] 'process raft request' (duration: 283.417604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:41.739504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.503434ms","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-22T17:50:41.739567Z","caller":"traceutil/trace.go:171","msg":"trace[2000698569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"230.583996ms","start":"2026-02-22T17:50:41.508969Z","end":"2026-02-22T17:50:41.739553Z","steps":["trace[2000698569] 'agreement among raft nodes before linearized reading' (duration: 230.455233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:42.204807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.327175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:50:42.205001Z","caller":"traceutil/trace.go:171","msg":"trace[697105100] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"466.395018ms","start":"2026-02-22T17:50:41.738578Z","end":"2026-02-22T17:50:42.204973Z","steps":["trace[697105100] 'process raft request' (duration: 86.727384ms)","trace[697105100] 'compare' (duration: 378.952816ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:42.205082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:41.738545Z","time spent":"466.502491ms","remote":"127.0.0.1:36718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:42.220663Z","caller":"traceutil/trace.go:171","msg":"trace[402062165] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"476.061548ms","start":"2026-02-22T17:50:41.744567Z","end":"2026-02-22T17:50:42.220629Z","steps":["trace[402062165] 'process raft request' (duration: 475.789681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:42.22088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:41.744556Z","time spent":"476.204492ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:50:42.221062Z","caller":"traceutil/trace.go:171","msg":"trace[531586274] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"476.368906ms","start":"2026-02-22T17:50:41.744642Z","end":"2026-02-22T17:50:42.221011Z","steps":["trace[531586274] 'process raft request' (duration: 476.155771ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:42.220894Z","caller":"traceutil/trace.go:171","msg":"trace[2078366305] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:545; }","duration":"107.832411ms","start":"2026-02-22T17:50:42.113036Z","end":"2026-02-22T17:50:42.220868Z","steps":["trace[2078366305] 'read index received' (duration: 107.364688ms)","trace[2078366305] 'applied index is now lower than readState.Index' (duration: 466.783µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:42.221165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:41.744636Z","time spent":"476.498739ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:50:42.221306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.245541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:42.222122Z","caller":"traceutil/trace.go:171","msg":"trace[1695794151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"109.125564ms","start":"2026-02-22T17:50:42.112968Z","end":"2026-02-22T17:50:42.222093Z","steps":["trace[1695794151] 'agreement among raft nodes before linearized reading' (duration: 108.139819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:43.601019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200473856189315,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:50:44.637674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.166035ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-22T17:50:44.637823Z","caller":"traceutil/trace.go:171","msg":"trace[1584016032] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"1.536879277s","start":"2026-02-22T17:50:43.100922Z","end":"2026-02-22T17:50:44.637801Z","steps":["trace[1584016032] 'read index received' (duration: 757.547501ms)","trace[1584016032] 'applied index is now lower than readState.Index' (duration: 779.329996ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:50:44.638022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.537064873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:50:44.63812Z","caller":"traceutil/trace.go:171","msg":"trace[1556800992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"1.537249047s","start":"2026-02-22T17:50:43.100849Z","end":"2026-02-22T17:50:44.638098Z","steps":["trace[1556800992] 'agreement among raft nodes before linearized reading' (duration: 1.537038121s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:44.638147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.129406ms","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-22T17:50:44.638168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:43.100821Z","time spent":"1.537339139s","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:50:44.638185Z","caller":"traceutil/trace.go:171","msg":"trace[924452159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:529; }","duration":"911.179797ms","start":"2026-02-22T17:50:43.726993Z","end":"2026-02-22T17:50:44.638173Z","steps":["trace[924452159] 'agreement among raft nodes before linearized reading' (duration: 910.977482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:44.638226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:43.726918Z","time spent":"911.29949ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:50:44.638258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.442052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-22T17:50:44.638325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.908244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-22T17:50:44.638459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.019143ms","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-22T17:50:44.6385Z","caller":"traceutil/trace.go:171","msg":"trace[1665282339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"889.114266ms","start":"2026-02-22T17:50:43.749377Z","end":"2026-02-22T17:50:44.638491Z","steps":["trace[1665282339] 'agreement among raft nodes before linearized reading' (duration: 889.019174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:44.638536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:43.749349Z","time spent":"889.178028ms","remote":"127.0.0.1:36814","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-22T17:50:44.638436Z","caller":"traceutil/trace.go:171","msg":"trace[1368540013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"413.084078ms","start":"2026-02-22T17:50:44.225329Z","end":"2026-02-22T17:50:44.638413Z","steps":["trace[1368540013] 'agreement among raft nodes before linearized reading' (duration: 412.75585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:44.638615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:44.225301Z","time spent":"413.303524ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:50:44.638356Z","caller":"traceutil/trace.go:171","msg":"trace[249064496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"412.575635ms","start":"2026-02-22T17:50:44.225757Z","end":"2026-02-22T17:50:44.638333Z","steps":["trace[249064496] 'agreement among raft nodes before linearized reading' (duration: 412.228836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:44.638763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:50:44.225742Z","time spent":"413.007037ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:50:46.874942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.054065ms","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-22T17:50:46.87498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.129912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-22T17:50:46.875037Z","caller":"traceutil/trace.go:171","msg":"trace[739059703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"210.23906ms","start":"2026-02-22T17:50:46.664776Z","end":"2026-02-22T17:50:46.875015Z","steps":["trace[739059703] 'range keys from in-memory index tree' (duration: 209.716546ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:46.875121Z","caller":"traceutil/trace.go:171","msg":"trace[315381069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"192.358457ms","start":"2026-02-22T17:50:46.682729Z","end":"2026-02-22T17:50:46.875088Z","steps":["trace[315381069] 'range keys from in-memory index tree' (duration: 191.879744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:46.875242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.980037ms","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-22T17:50:46.875323Z","caller":"traceutil/trace.go:171","msg":"trace[1155229513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"192.098511ms","start":"2026-02-22T17:50:46.683207Z","end":"2026-02-22T17:50:46.875306Z","steps":["trace[1155229513] 'range keys from in-memory index tree' (duration: 191.745542ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:50:46.875603Z","caller":"traceutil/trace.go:171","msg":"trace[1633204846] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"110.479689ms","start":"2026-02-22T17:50:46.76511Z","end":"2026-02-22T17:50:46.87559Z","steps":["trace[1633204846] 'process raft request' (duration: 108.60053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:50:46.88021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.500311ms","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-22T17:50:46.880256Z","caller":"traceutil/trace.go:171","msg":"trace[1512408934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"214.612463ms","start":"2026-02-22T17:50:46.66563Z","end":"2026-02-22T17:50:46.880242Z","steps":["trace[1512408934] 'range keys from in-memory index tree' (duration: 209.696766ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:03.080215Z","caller":"traceutil/trace.go:171","msg":"trace[1132061691] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"101.097516ms","start":"2026-02-22T17:51:02.979091Z","end":"2026-02-22T17:51:03.080189Z","steps":["trace[1132061691] 'process raft request' (duration: 100.934312ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:05.254757Z","caller":"traceutil/trace.go:171","msg":"trace[1899782929] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:741; }","duration":"282.250324ms","start":"2026-02-22T17:51:04.972472Z","end":"2026-02-22T17:51:05.254722Z","steps":["trace[1899782929] 'read index received' (duration: 281.911425ms)","trace[1899782929] 'applied index is now lower than readState.Index' (duration: 337.719µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:05.254866Z","caller":"traceutil/trace.go:171","msg":"trace[673748094] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"775.23935ms","start":"2026-02-22T17:51:04.479577Z","end":"2026-02-22T17:51:05.254816Z","steps":["trace[673748094] 'process raft request' (duration: 774.961313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:05.255059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.565062ms","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-22T17:51:05.255101Z","caller":"traceutil/trace.go:171","msg":"trace[2017440086] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"282.668474ms","start":"2026-02-22T17:51:04.972421Z","end":"2026-02-22T17:51:05.25509Z","steps":["trace[2017440086] 'agreement among raft nodes before linearized reading' (duration: 282.478619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:05.255084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.428943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-22T17:51:05.255101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:04.479548Z","time spent":"775.437875ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:05.255155Z","caller":"traceutil/trace.go:171","msg":"trace[998692053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"276.548717ms","start":"2026-02-22T17:51:04.978587Z","end":"2026-02-22T17:51:05.255136Z","steps":["trace[998692053] 'agreement among raft nodes before linearized reading' (duration: 276.375832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:05.255301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.745739ms","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-22T17:51:05.255318Z","caller":"traceutil/trace.go:171","msg":"trace[723462412] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"170.81334ms","start":"2026-02-22T17:51:05.084499Z","end":"2026-02-22T17:51:05.255312Z","steps":["trace[723462412] 'agreement among raft nodes before linearized reading' (duration: 170.762889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:05.255417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.539258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:51:05.255455Z","caller":"traceutil/trace.go:171","msg":"trace[915866023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"266.593658ms","start":"2026-02-22T17:51:04.988848Z","end":"2026-02-22T17:51:05.255442Z","steps":["trace[915866023] 'agreement among raft nodes before linearized reading' (duration: 266.436824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:05.672542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.200263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:51:05.673133Z","caller":"traceutil/trace.go:171","msg":"trace[1722190045] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"413.872019ms","start":"2026-02-22T17:51:05.259162Z","end":"2026-02-22T17:51:05.673034Z","steps":["trace[1722190045] 'process raft request' (duration: 174.987538ms)","trace[1722190045] 'compare' (duration: 237.826384ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:05.673817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:05.259138Z","time spent":"414.171607ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:05.688877Z","caller":"traceutil/trace.go:171","msg":"trace[1729206317] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"427.104662ms","start":"2026-02-22T17:51:05.261734Z","end":"2026-02-22T17:51:05.688839Z","steps":["trace[1729206317] 'process raft request' (duration: 427.03177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:05.689058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:05.261706Z","time spent":"427.248916ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:05.689247Z","caller":"traceutil/trace.go:171","msg":"trace[1969821736] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"427.130712ms","start":"2026-02-22T17:51:05.262084Z","end":"2026-02-22T17:51:05.689215Z","steps":["trace[1969821736] 'process raft request' (duration: 426.718192ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:05.689543Z","caller":"traceutil/trace.go:171","msg":"trace[1924449838] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"429.091173ms","start":"2026-02-22T17:51:05.260439Z","end":"2026-02-22T17:51:05.68953Z","steps":["trace[1924449838] 'process raft request' (duration: 427.982374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:05.689595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:05.260424Z","time spent":"429.146515ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:05.689593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:05.262074Z","time spent":"427.42995ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:07.406043Z","caller":"traceutil/trace.go:171","msg":"trace[89154512] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"145.197983ms","start":"2026-02-22T17:51:07.260821Z","end":"2026-02-22T17:51:07.406019Z","steps":["trace[89154512] 'read index received' (duration: 145.008167ms)","trace[89154512] 'applied index is now lower than readState.Index' (duration: 189.166µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:07.406248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.418289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-22T17:51:07.406276Z","caller":"traceutil/trace.go:171","msg":"trace[683519342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"145.468471ms","start":"2026-02-22T17:51:07.2608Z","end":"2026-02-22T17:51:07.406269Z","steps":["trace[683519342] 'agreement among raft nodes before linearized reading' (duration: 145.304696ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:07.406262Z","caller":"traceutil/trace.go:171","msg":"trace[89837329] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"258.416064ms","start":"2026-02-22T17:51:07.147793Z","end":"2026-02-22T17:51:07.406209Z","steps":["trace[89837329] 'process raft request' (duration: 258.065884ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:31.606865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200473856189900,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:51:31.628396Z","caller":"traceutil/trace.go:171","msg":"trace[1018455018] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"522.084224ms","start":"2026-02-22T17:51:31.10628Z","end":"2026-02-22T17:51:31.628364Z","steps":["trace[1018455018] 'read index received' (duration: 521.883268ms)","trace[1018455018] 'applied index is now lower than readState.Index' (duration: 200.026µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:31.628492Z","caller":"traceutil/trace.go:171","msg":"trace[500069222] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"574.533988ms","start":"2026-02-22T17:51:31.05395Z","end":"2026-02-22T17:51:31.628484Z","steps":["trace[500069222] 'process raft request' (duration: 574.280821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:31.628597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:31.053919Z","time spent":"574.59089ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:31.628898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.63056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:51:31.628928Z","caller":"traceutil/trace.go:171","msg":"trace[729502528] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"522.709712ms","start":"2026-02-22T17:51:31.106211Z","end":"2026-02-22T17:51:31.628921Z","steps":["trace[729502528] 'agreement among raft nodes before linearized reading' (duration: 522.65788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:31.628947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:31.106174Z","time spent":"522.768863ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:51:32.048792Z","caller":"traceutil/trace.go:171","msg":"trace[1425732317] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"174.392169ms","start":"2026-02-22T17:51:31.874375Z","end":"2026-02-22T17:51:32.048767Z","steps":["trace[1425732317] 'read index received' (duration: 174.202614ms)","trace[1425732317] 'applied index is now lower than readState.Index' (duration: 188.735µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:32.048844Z","caller":"traceutil/trace.go:171","msg":"trace[229135442] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"414.402368ms","start":"2026-02-22T17:51:31.634409Z","end":"2026-02-22T17:51:32.048811Z","steps":["trace[229135442] 'process raft request' (duration: 414.184302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:32.049047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.802788ms","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-22T17:51:32.049084Z","caller":"traceutil/trace.go:171","msg":"trace[230951918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"161.88533ms","start":"2026-02-22T17:51:31.887188Z","end":"2026-02-22T17:51:32.049074Z","steps":["trace[230951918] 'agreement among raft nodes before linearized reading' (duration: 161.736255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:32.049099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.573261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-22T17:51:32.049082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.681177ms","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-22T17:51:32.049148Z","caller":"traceutil/trace.go:171","msg":"trace[1875202665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"161.679994ms","start":"2026-02-22T17:51:31.887456Z","end":"2026-02-22T17:51:32.049136Z","steps":["trace[1875202665] 'agreement among raft nodes before linearized reading' (duration: 161.53404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:32.049207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.325045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:51:32.049249Z","caller":"traceutil/trace.go:171","msg":"trace[777171063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"122.437278ms","start":"2026-02-22T17:51:31.926799Z","end":"2026-02-22T17:51:32.049236Z","steps":["trace[777171063] 'agreement among raft nodes before linearized reading' (duration: 122.196081ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:32.049255Z","caller":"traceutil/trace.go:171","msg":"trace[1777826068] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"174.849552ms","start":"2026-02-22T17:51:31.874303Z","end":"2026-02-22T17:51:32.049152Z","steps":["trace[1777826068] 'agreement among raft nodes before linearized reading' (duration: 174.577874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:32.049453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:31.634379Z","time spent":"414.556243ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:35.0135Z","caller":"traceutil/trace.go:171","msg":"trace[2059973948] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"874.862303ms","start":"2026-02-22T17:51:34.138605Z","end":"2026-02-22T17:51:35.013467Z","steps":["trace[2059973948] 'process raft request' (duration: 874.666818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:35.014778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:34.138592Z","time spent":"876.059457ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:35.138869Z","caller":"traceutil/trace.go:171","msg":"trace[1744850868] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.000110771s","start":"2026-02-22T17:51:34.138704Z","end":"2026-02-22T17:51:35.138815Z","steps":["trace[1744850868] 'process raft request' (duration: 945.215358ms)","trace[1744850868] 'compare' (duration: 54.536223ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:35.139014Z","caller":"traceutil/trace.go:171","msg":"trace[1528543420] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"998.535437ms","start":"2026-02-22T17:51:34.140458Z","end":"2026-02-22T17:51:35.138993Z","steps":["trace[1528543420] 'process raft request' (duration: 998.442714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:35.139085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:34.138697Z","time spent":"1.000272835s","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:35.13911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:34.140451Z","time spent":"998.607678ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:35.139494Z","caller":"traceutil/trace.go:171","msg":"trace[1294632772] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"1.000246044s","start":"2026-02-22T17:51:34.139188Z","end":"2026-02-22T17:51:35.139434Z","steps":["trace[1294632772] 'process raft request' (duration: 999.515623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:35.139659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:34.139181Z","time spent":"1.000390668s","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:47.209237Z","caller":"traceutil/trace.go:171","msg":"trace[1224179996] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"121.564953ms","start":"2026-02-22T17:51:47.087644Z","end":"2026-02-22T17:51:47.209209Z","steps":["trace[1224179996] 'process raft request' (duration: 121.177072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:48.700547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200473856190126,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:51:49.201012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200473856190126,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:51:49.513155Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.31688964s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T17:51:49.513689Z","caller":"traceutil/trace.go:171","msg":"trace[380921390] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"1.313449094s","start":"2026-02-22T17:51:48.200212Z","end":"2026-02-22T17:51:49.513661Z","steps":["trace[380921390] 'read index received' (duration: 1.313077483s)","trace[380921390] 'applied index is now lower than readState.Index' (duration: 370.27µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:49.513829Z","caller":"traceutil/trace.go:171","msg":"trace[275119034] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.317625811s","start":"2026-02-22T17:51:48.19615Z","end":"2026-02-22T17:51:49.513776Z","steps":["trace[275119034] 'process raft request' (duration: 1.317277311s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:49.514036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313790274s","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-22T17:51:49.514095Z","caller":"traceutil/trace.go:171","msg":"trace[1299981179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"1.313924387s","start":"2026-02-22T17:51:48.200159Z","end":"2026-02-22T17:51:49.514084Z","steps":["trace[1299981179] 'agreement among raft nodes before linearized reading' (duration: 1.3136691s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:49.514108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:48.196123Z","time spent":"1.317859596s","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:49.514143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:48.200132Z","time spent":"1.31400057s","remote":"127.0.0.1:36814","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-22T17:51:49.514308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.21212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:51:49.514468Z","caller":"traceutil/trace.go:171","msg":"trace[1971439020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"300.437206ms","start":"2026-02-22T17:51:49.214003Z","end":"2026-02-22T17:51:49.51444Z","steps":["trace[1971439020] 'agreement among raft nodes before linearized reading' (duration: 300.018935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:49.514515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.464974ms","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-22T17:51:49.514527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:49.213979Z","time spent":"300.537299ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:51:49.514562Z","caller":"traceutil/trace.go:171","msg":"trace[319731706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:893; }","duration":"287.533166ms","start":"2026-02-22T17:51:49.227017Z","end":"2026-02-22T17:51:49.51455Z","steps":["trace[319731706] 'agreement among raft nodes before linearized reading' (duration: 287.276699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:49.514721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.692637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-22T17:51:49.514715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.955735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:51:49.514935Z","caller":"traceutil/trace.go:171","msg":"trace[1992331225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:893; }","duration":"289.951404ms","start":"2026-02-22T17:51:49.224974Z","end":"2026-02-22T17:51:49.514926Z","steps":["trace[1992331225] 'agreement among raft nodes before linearized reading' (duration: 289.682946ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:49.514946Z","caller":"traceutil/trace.go:171","msg":"trace[1771681347] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:893; }","duration":"285.205581ms","start":"2026-02-22T17:51:49.229727Z","end":"2026-02-22T17:51:49.514932Z","steps":["trace[1771681347] 'agreement among raft nodes before linearized reading' (duration: 284.914333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:50.08211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200473856190129,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:51:50.179986Z","caller":"traceutil/trace.go:171","msg":"trace[1323000856] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"660.900639ms","start":"2026-02-22T17:51:49.519061Z","end":"2026-02-22T17:51:50.179961Z","steps":["trace[1323000856] 'process raft request' (duration: 658.531743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:50.180136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:49.519035Z","time spent":"661.003242ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:50.19414Z","caller":"traceutil/trace.go:171","msg":"trace[1791363971] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"673.610774ms","start":"2026-02-22T17:51:49.520497Z","end":"2026-02-22T17:51:50.194108Z","steps":["trace[1791363971] 'process raft request' (duration: 673.534232ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:50.19415Z","caller":"traceutil/trace.go:171","msg":"trace[2073920067] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"675.049604ms","start":"2026-02-22T17:51:49.519057Z","end":"2026-02-22T17:51:50.194107Z","steps":["trace[2073920067] 'process raft request' (duration: 674.795237ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:50.194164Z","caller":"traceutil/trace.go:171","msg":"trace[1892340799] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"674.93851ms","start":"2026-02-22T17:51:49.519188Z","end":"2026-02-22T17:51:50.194127Z","steps":["trace[1892340799] 'process raft request' (duration: 674.816857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:50.194309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:49.52049Z","time spent":"673.726877ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:50.194337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:49.519035Z","time spent":"675.198978ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:51:50.194341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:49.519177Z","time spent":"675.068695ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:51:50.19466Z","caller":"traceutil/trace.go:171","msg":"trace[450168052] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:929; }","duration":"612.796076ms","start":"2026-02-22T17:51:49.581851Z","end":"2026-02-22T17:51:50.194647Z","steps":["trace[450168052] 'read index received' (duration: 596.152791ms)","trace[450168052] 'applied index is now lower than readState.Index' (duration: 16.641805ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:51:50.194875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.019042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:51:50.194917Z","caller":"traceutil/trace.go:171","msg":"trace[1135831961] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:898; }","duration":"613.097094ms","start":"2026-02-22T17:51:49.58181Z","end":"2026-02-22T17:51:50.194907Z","steps":["trace[1135831961] 'agreement among raft nodes before linearized reading' (duration: 613.015392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:50.19494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:49.581786Z","time spent":"613.148786ms","remote":"127.0.0.1:36828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:51:50.195072Z","caller":"traceutil/trace.go:171","msg":"trace[69703662] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"673.87329ms","start":"2026-02-22T17:51:49.521191Z","end":"2026-02-22T17:51:50.195064Z","steps":["trace[69703662] 'process raft request' (duration: 672.875613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:51:50.195112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:51:49.521183Z","time spent":"673.903412ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:52:04.609832Z","caller":"traceutil/trace.go:171","msg":"trace[1537642656] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"296.096024ms","start":"2026-02-22T17:52:04.313704Z","end":"2026-02-22T17:52:04.6098Z","steps":["trace[1537642656] 'process raft request' (duration: 296.048223ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:04.610083Z","caller":"traceutil/trace.go:171","msg":"trace[840807739] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"296.7072ms","start":"2026-02-22T17:52:04.313315Z","end":"2026-02-22T17:52:04.610022Z","steps":["trace[840807739] 'process raft request' (duration: 296.249518ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:04.610192Z","caller":"traceutil/trace.go:171","msg":"trace[468158285] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"296.520815ms","start":"2026-02-22T17:52:04.313659Z","end":"2026-02-22T17:52:04.61018Z","steps":["trace[468158285] 'process raft request' (duration: 296.055553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:06.893919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15440200473856190256,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:52:06.909567Z","caller":"traceutil/trace.go:171","msg":"trace[281706550] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"516.649997ms","start":"2026-02-22T17:52:06.392891Z","end":"2026-02-22T17:52:06.909541Z","steps":["trace[281706550] 'read index received' (duration: 516.340799ms)","trace[281706550] 'applied index is now lower than readState.Index' (duration: 307.918µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:06.909685Z","caller":"traceutil/trace.go:171","msg":"trace[2033333734] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"592.098676ms","start":"2026-02-22T17:52:06.317542Z","end":"2026-02-22T17:52:06.90964Z","steps":["trace[2033333734] 'process raft request' (duration: 591.824309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:06.909871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.909265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-22T17:52:06.909893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:06.317515Z","time spent":"592.266511ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:06.910013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.396446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:52:06.910088Z","caller":"traceutil/trace.go:171","msg":"trace[179762611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:938; }","duration":"295.547309ms","start":"2026-02-22T17:52:06.614521Z","end":"2026-02-22T17:52:06.910069Z","steps":["trace[179762611] 'agreement among raft nodes before linearized reading' (duration: 295.329914ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:06.909946Z","caller":"traceutil/trace.go:171","msg":"trace[1199048160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"517.13083ms","start":"2026-02-22T17:52:06.392803Z","end":"2026-02-22T17:52:06.909934Z","steps":["trace[1199048160] 'agreement among raft nodes before linearized reading' (duration: 516.861083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:06.910213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.286483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-22T17:52:06.91016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:06.392776Z","time spent":"517.368676ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:52:06.910232Z","caller":"traceutil/trace.go:171","msg":"trace[10690871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"295.312814ms","start":"2026-02-22T17:52:06.614914Z","end":"2026-02-22T17:52:06.910227Z","steps":["trace[10690871] 'agreement among raft nodes before linearized reading' (duration: 295.261802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:06.91031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.460938ms","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-22T17:52:06.910328Z","caller":"traceutil/trace.go:171","msg":"trace[2095836180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"295.492158ms","start":"2026-02-22T17:52:06.614832Z","end":"2026-02-22T17:52:06.910324Z","steps":["trace[2095836180] 'agreement among raft nodes before linearized reading' (duration: 295.443367ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:07.093124Z","caller":"traceutil/trace.go:171","msg":"trace[840192346] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"176.782229ms","start":"2026-02-22T17:52:06.916309Z","end":"2026-02-22T17:52:07.093091Z","steps":["trace[840192346] 'process raft request' (duration: 176.725448ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:07.093383Z","caller":"traceutil/trace.go:171","msg":"trace[444264980] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"177.448307ms","start":"2026-02-22T17:52:06.915896Z","end":"2026-02-22T17:52:07.093344Z","steps":["trace[444264980] 'process raft request' (duration: 101.525846ms)","trace[444264980] 'compare' (duration: 75.392637ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:07.093457Z","caller":"traceutil/trace.go:171","msg":"trace[393616507] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"177.288053ms","start":"2026-02-22T17:52:06.916127Z","end":"2026-02-22T17:52:07.093415Z","steps":["trace[393616507] 'process raft request' (duration: 176.857962ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:07.317648Z","caller":"traceutil/trace.go:171","msg":"trace[1314214630] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"183.845666ms","start":"2026-02-22T17:52:07.133773Z","end":"2026-02-22T17:52:07.317619Z","steps":["trace[1314214630] 'process raft request' (duration: 183.705782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:07.6758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.65937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:52:07.676066Z","caller":"traceutil/trace.go:171","msg":"trace[1880577576] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:943; }","duration":"195.066302ms","start":"2026-02-22T17:52:07.480976Z","end":"2026-02-22T17:52:07.676042Z","steps":["trace[1880577576] 'count revisions from in-memory index tree' (duration: 194.508277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:08.303035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.790549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:08.303124Z","caller":"traceutil/trace.go:171","msg":"trace[1339809871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"201.931553ms","start":"2026-02-22T17:52:08.101174Z","end":"2026-02-22T17:52:08.303106Z","steps":["trace[1339809871] 'range keys from in-memory index tree' (duration: 201.711147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:08.303036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.975338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:52:08.303218Z","caller":"traceutil/trace.go:171","msg":"trace[591440412] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:944; }","duration":"102.233325ms","start":"2026-02-22T17:52:08.200959Z","end":"2026-02-22T17:52:08.303192Z","steps":["trace[591440412] 'count revisions from in-memory index tree' (duration: 101.855984ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:09.428701Z","caller":"traceutil/trace.go:171","msg":"trace[295688479] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"507.827255ms","start":"2026-02-22T17:52:08.920839Z","end":"2026-02-22T17:52:09.428666Z","steps":["trace[295688479] 'process raft request' (duration: 507.603569ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:09.42876Z","caller":"traceutil/trace.go:171","msg":"trace[1288233978] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"454.578931ms","start":"2026-02-22T17:52:08.97414Z","end":"2026-02-22T17:52:09.428719Z","steps":["trace[1288233978] 'read index received' (duration: 454.1569ms)","trace[1288233978] 'applied index is now lower than readState.Index' (duration: 417.311µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:09.428939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:08.920791Z","time spent":"508.005519ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:09.429105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.094998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:52:09.429168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.355678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-22T17:52:09.429188Z","caller":"traceutil/trace.go:171","msg":"trace[1346496997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"325.241732ms","start":"2026-02-22T17:52:09.103928Z","end":"2026-02-22T17:52:09.42917Z","steps":["trace[1346496997] 'agreement among raft nodes before linearized reading' (duration: 324.993836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:09.429171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.005562ms","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-22T17:52:09.429208Z","caller":"traceutil/trace.go:171","msg":"trace[2044108343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"327.472501ms","start":"2026-02-22T17:52:09.101725Z","end":"2026-02-22T17:52:09.429197Z","steps":["trace[2044108343] 'agreement among raft nodes before linearized reading' (duration: 327.174123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:09.429232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:09.103909Z","time spent":"325.315495ms","remote":"127.0.0.1:36536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T17:52:09.429251Z","caller":"traceutil/trace.go:171","msg":"trace[1312791009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:945; }","duration":"455.161287ms","start":"2026-02-22T17:52:08.974069Z","end":"2026-02-22T17:52:09.42923Z","steps":["trace[1312791009] 'agreement among raft nodes before linearized reading' (duration: 454.803087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:09.429257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:09.101699Z","time spent":"327.548933ms","remote":"127.0.0.1:36814","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-22T17:52:09.429294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:08.974042Z","time spent":"455.246649ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:52:09.429496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.650726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:52:09.429534Z","caller":"traceutil/trace.go:171","msg":"trace[1629494133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"327.751069ms","start":"2026-02-22T17:52:09.101773Z","end":"2026-02-22T17:52:09.429524Z","steps":["trace[1629494133] 'agreement among raft nodes before linearized reading' (duration: 327.642876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:09.429579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:09.101745Z","time spent":"327.827761ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:52:09.429572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.716882ms","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-22T17:52:09.429619Z","caller":"traceutil/trace.go:171","msg":"trace[1632326897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"326.773883ms","start":"2026-02-22T17:52:09.102831Z","end":"2026-02-22T17:52:09.429605Z","steps":["trace[1632326897] 'agreement among raft nodes before linearized reading' (duration: 326.563837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:09.429666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:09.102823Z","time spent":"326.835374ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T17:52:09.642234Z","caller":"traceutil/trace.go:171","msg":"trace[1837361813] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"207.16534ms","start":"2026-02-22T17:52:09.435039Z","end":"2026-02-22T17:52:09.642204Z","steps":["trace[1837361813] 'process raft request' (duration: 207.119409ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:09.642877Z","caller":"traceutil/trace.go:171","msg":"trace[1397904731] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"208.181838ms","start":"2026-02-22T17:52:09.434679Z","end":"2026-02-22T17:52:09.642861Z","steps":["trace[1397904731] 'process raft request' (duration: 207.253793ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:09.643264Z","caller":"traceutil/trace.go:171","msg":"trace[2093524690] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"208.26739ms","start":"2026-02-22T17:52:09.434985Z","end":"2026-02-22T17:52:09.643252Z","steps":["trace[2093524690] 'process raft request' (duration: 207.13336ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:09.67147Z","caller":"traceutil/trace.go:171","msg":"trace[1860143257] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"164.912606ms","start":"2026-02-22T17:52:09.506541Z","end":"2026-02-22T17:52:09.671453Z","steps":["trace[1860143257] 'process raft request' (duration: 164.814444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:09.916176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.250875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:52:09.916316Z","caller":"traceutil/trace.go:171","msg":"trace[2100214453] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"179.221084ms","start":"2026-02-22T17:52:09.73707Z","end":"2026-02-22T17:52:09.916291Z","steps":["trace[2100214453] 'process raft request' (duration: 32.761344ms)","trace[2100214453] 'compare' (duration: 146.026048ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:10.253532Z","caller":"traceutil/trace.go:171","msg":"trace[779932248] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"152.547871ms","start":"2026-02-22T17:52:10.100959Z","end":"2026-02-22T17:52:10.253507Z","steps":["trace[779932248] 'read index received' (duration: 90.938177ms)","trace[779932248] 'applied index is now lower than readState.Index' (duration: 61.608144ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:10.253568Z","caller":"traceutil/trace.go:171","msg":"trace[798607858] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"332.32775ms","start":"2026-02-22T17:52:09.921199Z","end":"2026-02-22T17:52:10.253527Z","steps":["trace[798607858] 'process raft request' (duration: 270.633383ms)","trace[798607858] 'compare' (duration: 61.229194ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:10.2537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.721705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:10.253732Z","caller":"traceutil/trace.go:171","msg":"trace[902506706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:953; }","duration":"152.823158ms","start":"2026-02-22T17:52:10.1009Z","end":"2026-02-22T17:52:10.253723Z","steps":["trace[902506706] 'agreement among raft nodes before linearized reading' (duration: 152.700004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:10.253724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:09.921172Z","time spent":"332.469533ms","remote":"127.0.0.1:36602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T17:52:11.341665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.099119ms","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-22T17:52:11.341769Z","caller":"traceutil/trace.go:171","msg":"trace[265289156] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:953; }","duration":"680.283664ms","start":"2026-02-22T17:52:10.661461Z","end":"2026-02-22T17:52:11.341745Z","steps":["trace[265289156] 'count revisions from in-memory index tree' (duration: 679.956195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:11.341816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:10.661428Z","time spent":"680.375857ms","remote":"127.0.0.1:36858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:52:11.342053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.712847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:11.342085Z","caller":"traceutil/trace.go:171","msg":"trace[1776600608] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:953; }","duration":"625.776838ms","start":"2026-02-22T17:52:10.716298Z","end":"2026-02-22T17:52:11.342075Z","steps":["trace[1776600608] 'count revisions from in-memory index tree' (duration: 625.643954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:11.342114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:10.716285Z","time spent":"625.820669ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:52:11.889427Z","caller":"traceutil/trace.go:171","msg":"trace[1758924144] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"394.46732ms","start":"2026-02-22T17:52:11.494934Z","end":"2026-02-22T17:52:11.889401Z","steps":["trace[1758924144] 'read index received' (duration: 394.306406ms)","trace[1758924144] 'applied index is now lower than readState.Index' (duration: 159.864µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:11.889484Z","caller":"traceutil/trace.go:171","msg":"trace[1414164640] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"451.785521ms","start":"2026-02-22T17:52:11.437656Z","end":"2026-02-22T17:52:11.889441Z","steps":["trace[1414164640] 'process raft request' (duration: 451.596365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:11.889681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.727298ms","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-22T17:52:11.889724Z","caller":"traceutil/trace.go:171","msg":"trace[1372116496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"394.829811ms","start":"2026-02-22T17:52:11.494883Z","end":"2026-02-22T17:52:11.889713Z","steps":["trace[1372116496] 'agreement among raft nodes before linearized reading' (duration: 394.666127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:11.889758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:11.494863Z","time spent":"394.888932ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:52:11.889762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:11.437642Z","time spent":"451.968036ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:11.889862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.927624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:52:11.889972Z","caller":"traceutil/trace.go:171","msg":"trace[1297941020] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:954; }","duration":"268.104969ms","start":"2026-02-22T17:52:11.621848Z","end":"2026-02-22T17:52:11.889953Z","steps":["trace[1297941020] 'agreement among raft nodes before linearized reading' (duration: 267.932734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:11.890075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.396882ms","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-22T17:52:11.890106Z","caller":"traceutil/trace.go:171","msg":"trace[837801011] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"242.438033ms","start":"2026-02-22T17:52:11.647661Z","end":"2026-02-22T17:52:11.890099Z","steps":["trace[837801011] 'agreement among raft nodes before linearized reading' (duration: 242.346211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:11.890229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.203087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-22T17:52:11.890256Z","caller":"traceutil/trace.go:171","msg":"trace[1385517693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"242.228467ms","start":"2026-02-22T17:52:11.648021Z","end":"2026-02-22T17:52:11.890249Z","steps":["trace[1385517693] 'agreement among raft nodes before linearized reading' (duration: 242.156615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:11.890233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.593978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-22T17:52:11.89033Z","caller":"traceutil/trace.go:171","msg":"trace[1283457012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:954; }","duration":"242.740261ms","start":"2026-02-22T17:52:11.647581Z","end":"2026-02-22T17:52:11.890321Z","steps":["trace[1283457012] 'agreement among raft nodes before linearized reading' (duration: 242.562476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:12.227426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.607496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:52:12.227625Z","caller":"traceutil/trace.go:171","msg":"trace[1392643922] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"108.693072ms","start":"2026-02-22T17:52:12.118864Z","end":"2026-02-22T17:52:12.227557Z","steps":["trace[1392643922] 'read index received' (duration: 24.771µs)","trace[1392643922] 'applied index is now lower than readState.Index' (duration: 108.666531ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:12.227655Z","caller":"traceutil/trace.go:171","msg":"trace[208991821] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"297.118038ms","start":"2026-02-22T17:52:11.930445Z","end":"2026-02-22T17:52:12.227563Z","steps":["trace[208991821] 'process raft request' (duration: 82.272465ms)","trace[208991821] 'compare' (duration: 214.39492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:12.227786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.885237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:12.227818Z","caller":"traceutil/trace.go:171","msg":"trace[500784495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"108.99016ms","start":"2026-02-22T17:52:12.118818Z","end":"2026-02-22T17:52:12.227808Z","steps":["trace[500784495] 'agreement among raft nodes before linearized reading' (duration: 108.851066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.93841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010451294s","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-22T17:52:20.938533Z","caller":"traceutil/trace.go:171","msg":"trace[564684055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1014; }","duration":"1.01067418s","start":"2026-02-22T17:52:19.927829Z","end":"2026-02-22T17:52:20.938503Z","steps":["trace[564684055] 'range keys from in-memory index tree' (duration: 1.010180137s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.938609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:19.927805Z","time spent":"1.010793494s","remote":"127.0.0.1:36814","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-22T17:52:20.938689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.096512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:20.938818Z","caller":"traceutil/trace.go:171","msg":"trace[1344505738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"838.289277ms","start":"2026-02-22T17:52:20.100499Z","end":"2026-02-22T17:52:20.938789Z","steps":["trace[1344505738] 'range keys from in-memory index tree' (duration: 837.991849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.938878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:20.100473Z","time spent":"838.39322ms","remote":"127.0.0.1:36548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T17:52:20.938992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.386658ms","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-22T17:52:20.9391Z","caller":"traceutil/trace.go:171","msg":"trace[790007981] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1014; }","duration":"978.564562ms","start":"2026-02-22T17:52:19.960509Z","end":"2026-02-22T17:52:20.939073Z","steps":["trace[790007981] 'range keys from in-memory index tree' (duration: 978.054458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.939154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:19.960476Z","time spent":"978.665895ms","remote":"127.0.0.1:36814","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-22T17:52:20.939439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.037896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T17:52:20.939545Z","caller":"traceutil/trace.go:171","msg":"trace[1683098352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1014; }","duration":"979.17177ms","start":"2026-02-22T17:52:19.960345Z","end":"2026-02-22T17:52:20.939517Z","steps":["trace[1683098352] 'range keys from in-memory index tree' (duration: 978.85657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.939597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:19.960339Z","time spent":"979.248512ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T17:52:20.939966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.922989ms","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-22T17:52:20.939305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.279113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-22T17:52:20.940075Z","caller":"traceutil/trace.go:171","msg":"trace[462107044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"980.058635ms","start":"2026-02-22T17:52:19.959992Z","end":"2026-02-22T17:52:20.940051Z","steps":["trace[462107044] 'range keys from in-memory index tree' (duration: 979.15774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.940181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:19.959984Z","time spent":"980.174608ms","remote":"127.0.0.1:36814","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-22T17:52:20.940073Z","caller":"traceutil/trace.go:171","msg":"trace[270838339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"981.043273ms","start":"2026-02-22T17:52:19.958992Z","end":"2026-02-22T17:52:20.940035Z","steps":["trace[270838339] 'range keys from in-memory index tree' (duration: 979.374035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.940878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:19.95898Z","time spent":"981.867316ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-22T17:52:20.9457Z","caller":"traceutil/trace.go:171","msg":"trace[276425878] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"797.737371ms","start":"2026-02-22T17:52:20.147933Z","end":"2026-02-22T17:52:20.94567Z","steps":["trace[276425878] 'process raft request' (duration: 797.491834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:20.945939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:20.147905Z","time spent":"797.876375ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:21.254714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.806576ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:52:21.255059Z","caller":"traceutil/trace.go:171","msg":"trace[1930243768] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"309.845038ms","start":"2026-02-22T17:52:20.945175Z","end":"2026-02-22T17:52:21.25502Z","steps":["trace[1930243768] 'process raft request' (duration: 200.59662ms)","trace[1930243768] 'compare' (duration: 108.554549ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:52:21.255281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:20.945138Z","time spent":"310.013863ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:52:21.255336Z","caller":"traceutil/trace.go:171","msg":"trace[1219284072] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1057; }","duration":"154.333092ms","start":"2026-02-22T17:52:21.100971Z","end":"2026-02-22T17:52:21.255304Z","steps":["trace[1219284072] 'read index received' (duration: 44.92237ms)","trace[1219284072] 'applied index is now lower than readState.Index' (duration: 109.407812ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:52:21.255429Z","caller":"traceutil/trace.go:171","msg":"trace[69146204] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"307.371378ms","start":"2026-02-22T17:52:20.948Z","end":"2026-02-22T17:52:21.255371Z","steps":["trace[69146204] 'process raft request' (duration: 307.07471ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:21.255554Z","caller":"traceutil/trace.go:171","msg":"trace[763784039] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"307.491912ms","start":"2026-02-22T17:52:20.948036Z","end":"2026-02-22T17:52:21.255528Z","steps":["trace[763784039] 'process raft request' (duration: 307.156922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:21.255645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:20.948025Z","time spent":"307.575215ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:21.255751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:20.947977Z","time spent":"307.621196ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:52:21.255765Z","caller":"traceutil/trace.go:171","msg":"trace[190520550] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"309.251492ms","start":"2026-02-22T17:52:20.946465Z","end":"2026-02-22T17:52:21.255717Z","steps":["trace[190520550] 'process raft request' (duration: 308.413388ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:52:21.255702Z","caller":"traceutil/trace.go:171","msg":"trace[1779596832] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"307.808451ms","start":"2026-02-22T17:52:20.947517Z","end":"2026-02-22T17:52:21.255325Z","steps":["trace[1779596832] 'process raft request' (duration: 307.461281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:52:21.255981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:20.946445Z","time spent":"309.420316ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:21.256189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:52:20.947502Z","time spent":"308.553192ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:52:21.256074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.067824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:52:21.256643Z","caller":"traceutil/trace.go:171","msg":"trace[1887598572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1020; }","duration":"155.557918ms","start":"2026-02-22T17:52:21.100886Z","end":"2026-02-22T17:52:21.256444Z","steps":["trace[1887598572] 'agreement among raft nodes before linearized reading' (duration: 154.521048ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:55:30.200883Z","caller":"traceutil/trace.go:171","msg":"trace[1840636276] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"100.301522ms","start":"2026-02-22T17:55:30.100543Z","end":"2026-02-22T17:55:30.200844Z","steps":["trace[1840636276] 'read index received' (duration: 99.964255ms)","trace[1840636276] 'applied index is now lower than readState.Index' (duration: 335.837µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:55:30.201088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.499957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:55:30.201008Z","caller":"traceutil/trace.go:171","msg":"trace[1475723554] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"114.440441ms","start":"2026-02-22T17:55:30.086514Z","end":"2026-02-22T17:55:30.200954Z","steps":["trace[1475723554] 'process raft request' (duration: 114.098533ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:55:30.20114Z","caller":"traceutil/trace.go:171","msg":"trace[406588132] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1829; }","duration":"100.613179ms","start":"2026-02-22T17:55:30.100514Z","end":"2026-02-22T17:55:30.201127Z","steps":["trace[406588132] 'agreement among raft nodes before linearized reading' (duration: 100.490846ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:55:36.772699Z","caller":"traceutil/trace.go:171","msg":"trace[1557577669] linearizableReadLoop","detail":"{readStateIndex:1942; appliedIndex:1940; }","duration":"378.216063ms","start":"2026-02-22T17:55:36.394458Z","end":"2026-02-22T17:55:36.772674Z","steps":["trace[1557577669] 'read index received' (duration: 55.154922ms)","trace[1557577669] 'applied index is now lower than readState.Index' (duration: 323.059331ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:55:36.772784Z","caller":"traceutil/trace.go:171","msg":"trace[1091641942] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"381.782706ms","start":"2026-02-22T17:55:36.390954Z","end":"2026-02-22T17:55:36.772737Z","steps":["trace[1091641942] 'process raft request' (duration: 381.565241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:55:36.772981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:55:36.390928Z","time spent":"381.94442ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:55:36.773185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.690764ms","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-22T17:55:36.773246Z","caller":"traceutil/trace.go:171","msg":"trace[1445672432] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1860; }","duration":"378.802666ms","start":"2026-02-22T17:55:36.394428Z","end":"2026-02-22T17:55:36.77323Z","steps":["trace[1445672432] 'agreement among raft nodes before linearized reading' (duration: 378.400507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:55:36.773282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:55:36.394417Z","time spent":"378.854308ms","remote":"127.0.0.1:36738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:55:36.773302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.58085ms","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-22T17:55:36.773377Z","caller":"traceutil/trace.go:171","msg":"trace[1204537879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1860; }","duration":"322.711923ms","start":"2026-02-22T17:55:36.450644Z","end":"2026-02-22T17:55:36.773356Z","steps":["trace[1204537879] 'agreement among raft nodes before linearized reading' (duration: 322.415086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:55:36.773418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.658718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-22T17:55:36.773436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:55:36.450617Z","time spent":"322.810485ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T17:55:36.773448Z","caller":"traceutil/trace.go:171","msg":"trace[345311646] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"333.712028ms","start":"2026-02-22T17:55:36.439727Z","end":"2026-02-22T17:55:36.773439Z","steps":["trace[345311646] 'agreement among raft nodes before linearized reading' (duration: 333.569535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:55:36.773476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:55:36.439714Z","time spent":"333.75774ms","remote":"127.0.0.1:36814","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-22T17:55:36.773343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.970211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-22T17:55:36.773634Z","caller":"traceutil/trace.go:171","msg":"trace[122800649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1860; }","duration":"339.431711ms","start":"2026-02-22T17:55:36.434173Z","end":"2026-02-22T17:55:36.773605Z","steps":["trace[122800649] 'agreement among raft nodes before linearized reading' (duration: 338.779486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:55:36.773734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:55:36.434142Z","time spent":"339.575395ms","remote":"127.0.0.1:36814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:55:36.773823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.451508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:55:36.773936Z","caller":"traceutil/trace.go:171","msg":"trace[971189696] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1860; }","duration":"302.249667ms","start":"2026-02-22T17:55:36.471645Z","end":"2026-02-22T17:55:36.773895Z","steps":["trace[971189696] 'agreement among raft nodes before linearized reading' (duration: 301.318885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:55:36.773982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:55:36.471621Z","time spent":"302.34786ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "}