{"level":"warn","ts":"2026-02-26T20:59:51.105481Z","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-26T20:59:51.105576Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-26T20:59:51.105661Z","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-26T20:59:51.105679Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-02-26T20:59:51.105838Z","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-26T20:59:51.106785Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-02-26T20:59:51.106919Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-26T20:59:51.110246Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.037312ms"} {"level":"info","ts":"2026-02-26T20:59:51.116232Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-02-26T20:59:51.116513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T20:59:51.11655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-02-26T20:59:51.116628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T20:59:51.116689Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-02-26T20:59:51.116783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-02-26T20:59:51.121441Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T20:59:51.122895Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T20:59:51.123955Z","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-26T20:59:51.125771Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T20:59:51.125895Z","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-26T20:59:51.125953Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T20:59:51.126083Z","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-26T20:59:51.126429Z","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-26T20:59:51.126855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-02-26T20:59:51.127023Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-02-26T20:59:51.129446Z","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-26T20:59:51.129649Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-02-26T20:59:51.129706Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-02-26T20:59:51.129837Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T20:59:51.129888Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T20:59:51.717778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T20:59:51.717836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T20:59:51.717873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-02-26T20:59:51.717893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-02-26T20:59:51.717904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-02-26T20:59:51.71792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-02-26T20:59:51.717933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-02-26T20:59:51.71886Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:59:51.719117Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T20:59:51.719165Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T20:59:51.719189Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T20:59:51.719388Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T20:59:51.719544Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T20:59:51.719747Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:59:51.719821Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:59:51.719854Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T20:59:51.722868Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-02-26T20:59:51.725562Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T21:00:00.61263Z","caller":"traceutil/trace.go:171","msg":"trace[1592411752] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"260.450305ms","start":"2026-02-26T21:00:00.352163Z","end":"2026-02-26T21:00:00.612614Z","steps":["trace[1592411752] 'process raft request' (duration: 211.552112ms)","trace[1592411752] 'compare' (duration: 48.809071ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:00:00.612829Z","caller":"traceutil/trace.go:171","msg":"trace[297067697] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"259.83814ms","start":"2026-02-26T21:00:00.352971Z","end":"2026-02-26T21:00:00.612809Z","steps":["trace[297067697] 'process raft request' (duration: 259.616365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:00.926121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.701719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:00:00.926264Z","caller":"traceutil/trace.go:171","msg":"trace[908656310] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:346; }","duration":"133.554177ms","start":"2026-02-26T21:00:00.792698Z","end":"2026-02-26T21:00:00.926253Z","steps":["trace[908656310] 'read index received' (duration: 53.087493ms)","trace[908656310] 'applied index is now lower than readState.Index' (duration: 80.466054ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:00.92646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.772462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:00:00.926507Z","caller":"traceutil/trace.go:171","msg":"trace[1706431535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:338; }","duration":"133.857744ms","start":"2026-02-26T21:00:00.792643Z","end":"2026-02-26T21:00:00.926501Z","steps":["trace[1706431535] 'agreement among raft nodes before linearized reading' (duration: 133.640859ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:00.926598Z","caller":"traceutil/trace.go:171","msg":"trace[165323328] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"293.323048ms","start":"2026-02-26T21:00:00.633251Z","end":"2026-02-26T21:00:00.926574Z","steps":["trace[165323328] 'process raft request' (duration: 292.958559ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:00.926616Z","caller":"traceutil/trace.go:171","msg":"trace[1269717939] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"295.298493ms","start":"2026-02-26T21:00:00.631294Z","end":"2026-02-26T21:00:00.926592Z","steps":["trace[1269717939] 'process raft request' (duration: 118.677252ms)","trace[1269717939] 'compare' (duration: 175.588897ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:00:15.25741Z","caller":"traceutil/trace.go:171","msg":"trace[205147504] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"240.493641ms","start":"2026-02-26T21:00:15.016895Z","end":"2026-02-26T21:00:15.257388Z","steps":["trace[205147504] 'read index received' (duration: 240.372038ms)","trace[205147504] 'applied index is now lower than readState.Index' (duration: 120.513µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:00:15.257533Z","caller":"traceutil/trace.go:171","msg":"trace[113085800] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"275.485404ms","start":"2026-02-26T21:00:14.982015Z","end":"2026-02-26T21:00:15.257501Z","steps":["trace[113085800] 'process raft request' (duration: 275.247188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:15.257625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.599443ms","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-26T21:00:15.257718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.765418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:00:15.257826Z","caller":"traceutil/trace.go:171","msg":"trace[752455918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"240.955013ms","start":"2026-02-26T21:00:15.016845Z","end":"2026-02-26T21:00:15.2578Z","steps":["trace[752455918] 'agreement among raft nodes before linearized reading' (duration: 240.676756ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:15.257836Z","caller":"traceutil/trace.go:171","msg":"trace[1501974239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:415; }","duration":"182.836809ms","start":"2026-02-26T21:00:15.074964Z","end":"2026-02-26T21:00:15.257801Z","steps":["trace[1501974239] 'agreement among raft nodes before linearized reading' (duration: 182.563372ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:15.473168Z","caller":"traceutil/trace.go:171","msg":"trace[237153754] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"211.568273ms","start":"2026-02-26T21:00:15.261583Z","end":"2026-02-26T21:00:15.473151Z","steps":["trace[237153754] 'process raft request' (duration: 211.461271ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:15.47404Z","caller":"traceutil/trace.go:171","msg":"trace[479942548] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"212.259759ms","start":"2026-02-26T21:00:15.261772Z","end":"2026-02-26T21:00:15.474031Z","steps":["trace[479942548] 'process raft request' (duration: 212.217808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:20.485232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.891975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-26T21:00:20.48532Z","caller":"traceutil/trace.go:171","msg":"trace[1456184189] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:460; }","duration":"139.035068ms","start":"2026-02-26T21:00:20.346264Z","end":"2026-02-26T21:00:20.485299Z","steps":["trace[1456184189] 'range keys from in-memory index tree' (duration: 138.815123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:20.485428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.12699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"warn","ts":"2026-02-26T21:00:20.485487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.582075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-02-26T21:00:20.485583Z","caller":"traceutil/trace.go:171","msg":"trace[1546886900] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:460; }","duration":"185.692827ms","start":"2026-02-26T21:00:20.299867Z","end":"2026-02-26T21:00:20.48556Z","steps":["trace[1546886900] 'range keys from in-memory index tree' (duration: 185.242126ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:20.48552Z","caller":"traceutil/trace.go:171","msg":"trace[191798972] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:460; }","duration":"139.271054ms","start":"2026-02-26T21:00:20.346227Z","end":"2026-02-26T21:00:20.485498Z","steps":["trace[191798972] 'range keys from in-memory index tree' (duration: 138.943235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:21.604769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.00724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:00:21.604846Z","caller":"traceutil/trace.go:171","msg":"trace[1778194402] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"108.128262ms","start":"2026-02-26T21:00:21.496698Z","end":"2026-02-26T21:00:21.604826Z","steps":["trace[1778194402] 'range keys from in-memory index tree' (duration: 107.915118ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:25.17523Z","caller":"traceutil/trace.go:171","msg":"trace[1356543562] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"533.791277ms","start":"2026-02-26T21:00:24.641394Z","end":"2026-02-26T21:00:25.175186Z","steps":["trace[1356543562] 'read index received' (duration: 494.282447ms)","trace[1356543562] 'applied index is now lower than readState.Index' (duration: 39.50514ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:00:25.17529Z","caller":"traceutil/trace.go:171","msg":"trace[622986969] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"584.289118ms","start":"2026-02-26T21:00:24.590965Z","end":"2026-02-26T21:00:25.175254Z","steps":["trace[622986969] 'process raft request' (duration: 544.775398ms)","trace[622986969] 'compare' (duration: 39.124761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:25.175421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:24.590951Z","time spent":"584.40028ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:00:25.175486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.030983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:00:25.175532Z","caller":"traceutil/trace.go:171","msg":"trace[140865412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"534.159095ms","start":"2026-02-26T21:00:24.641359Z","end":"2026-02-26T21:00:25.175518Z","steps":["trace[140865412] 'agreement among raft nodes before linearized reading' (duration: 533.981021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:25.175568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:24.641343Z","time spent":"534.214978ms","remote":"127.0.0.1:40730","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-26T21:00:25.479877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.700579ms","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-26T21:00:25.479945Z","caller":"traceutil/trace.go:171","msg":"trace[716975473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:473; }","duration":"101.783061ms","start":"2026-02-26T21:00:25.378146Z","end":"2026-02-26T21:00:25.479929Z","steps":["trace[716975473] 'range keys from in-memory index tree' (duration: 101.491124ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:29.68407Z","caller":"traceutil/trace.go:171","msg":"trace[158859360] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"566.199988ms","start":"2026-02-26T21:00:29.117854Z","end":"2026-02-26T21:00:29.684054Z","steps":["trace[158859360] 'process raft request' (duration: 566.112576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:29.684175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:29.11783Z","time spent":"566.291779ms","remote":"127.0.0.1:40916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:00:29.684181Z","caller":"traceutil/trace.go:171","msg":"trace[783317755] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:502; }","duration":"184.801705ms","start":"2026-02-26T21:00:29.49935Z","end":"2026-02-26T21:00:29.684152Z","steps":["trace[783317755] 'read index received' (duration: 184.788055ms)","trace[783317755] 'applied index is now lower than readState.Index' (duration: 11.21µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:29.684489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.121202ms","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-26T21:00:29.684531Z","caller":"traceutil/trace.go:171","msg":"trace[1898098387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"185.199875ms","start":"2026-02-26T21:00:29.49932Z","end":"2026-02-26T21:00:29.684519Z","steps":["trace[1898098387] 'agreement among raft nodes before linearized reading' (duration: 184.978089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:30.053358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.848385ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T21:00:30.05364Z","caller":"traceutil/trace.go:171","msg":"trace[354694217] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:502; }","duration":"369.355464ms","start":"2026-02-26T21:00:29.684266Z","end":"2026-02-26T21:00:30.053621Z","steps":["trace[354694217] 'read index received' (duration: 65.250461ms)","trace[354694217] 'applied index is now lower than readState.Index' (duration: 304.103833ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:30.053731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.834106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:00:30.053762Z","caller":"traceutil/trace.go:171","msg":"trace[264520690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"406.902508ms","start":"2026-02-26T21:00:29.646848Z","end":"2026-02-26T21:00:30.05375Z","steps":["trace[264520690] 'agreement among raft nodes before linearized reading' (duration: 406.836796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:30.053797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:29.646834Z","time spent":"406.955209ms","remote":"127.0.0.1:40730","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-26T21:00:30.054002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.046091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:00:30.054058Z","caller":"traceutil/trace.go:171","msg":"trace[1875743617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"407.116833ms","start":"2026-02-26T21:00:29.646926Z","end":"2026-02-26T21:00:30.054043Z","steps":["trace[1875743617] 'agreement among raft nodes before linearized reading' (duration: 406.937049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:30.054086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:29.646924Z","time spent":"407.157014ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T21:00:30.054205Z","caller":"traceutil/trace.go:171","msg":"trace[274557140] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"365.294308ms","start":"2026-02-26T21:00:29.688899Z","end":"2026-02-26T21:00:30.054193Z","steps":["trace[274557140] 'process raft request' (duration: 364.56629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:30.054513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:29.688875Z","time spent":"365.360669ms","remote":"127.0.0.1:40990","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-26T21:00:30.490993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.044595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T21:00:30.491143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.186538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:00:30.491191Z","caller":"traceutil/trace.go:171","msg":"trace[2032209315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"844.25007ms","start":"2026-02-26T21:00:29.64691Z","end":"2026-02-26T21:00:30.49116Z","steps":["trace[2032209315] 'agreement among raft nodes before linearized reading' (duration: 407.127633ms)","trace[2032209315] 'range keys from in-memory index tree' (duration: 436.84175ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:30.491223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:29.646906Z","time spent":"844.310891ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T21:00:30.491214Z","caller":"traceutil/trace.go:171","msg":"trace[1500542941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"844.28825ms","start":"2026-02-26T21:00:29.646906Z","end":"2026-02-26T21:00:30.491194Z","steps":["trace[1500542941] 'agreement among raft nodes before linearized reading' (duration: 407.115632ms)","trace[1500542941] 'range keys from in-memory index tree' (duration: 436.970743ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:30.491294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:29.646899Z","time spent":"844.384694ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:00:30.491362Z","caller":"traceutil/trace.go:171","msg":"trace[950822891] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"433.469891ms","start":"2026-02-26T21:00:30.057856Z","end":"2026-02-26T21:00:30.491326Z","steps":["trace[950822891] 'process raft request' (duration: 396.268805ms)","trace[950822891] 'compare' (duration: 36.94907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:30.491518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:30.057844Z","time spent":"433.596693ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:00:30.491607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.745238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:00:30.491661Z","caller":"traceutil/trace.go:171","msg":"trace[1553464954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"436.81735ms","start":"2026-02-26T21:00:30.054831Z","end":"2026-02-26T21:00:30.491648Z","steps":["trace[1553464954] 'range keys from in-memory index tree' (duration: 436.087472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:30.491703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:30.054819Z","time spent":"436.874021ms","remote":"127.0.0.1:40722","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-26T21:00:30.842854Z","caller":"traceutil/trace.go:171","msg":"trace[1637057397] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"346.732667ms","start":"2026-02-26T21:00:30.496068Z","end":"2026-02-26T21:00:30.842801Z","steps":["trace[1637057397] 'process raft request' (duration: 300.457937ms)","trace[1637057397] 'compare' (duration: 46.053885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:30.843038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:30.496062Z","time spent":"346.87431ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:00:33.189999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.622969ms","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-26T21:00:33.190033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.386284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:00:33.190087Z","caller":"traceutil/trace.go:171","msg":"trace[1855510549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"342.770713ms","start":"2026-02-26T21:00:32.847296Z","end":"2026-02-26T21:00:33.190067Z","steps":["trace[1855510549] 'range keys from in-memory index tree' (duration: 342.471506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:33.190072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131206447s","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-26T21:00:33.190209Z","caller":"traceutil/trace.go:171","msg":"trace[1076268177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:490; }","duration":"1.131411991s","start":"2026-02-26T21:00:32.058762Z","end":"2026-02-26T21:00:33.190174Z","steps":["trace[1076268177] 'range keys from in-memory index tree' (duration: 1.13093311s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:33.190231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549268408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:00:33.190275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:32.058732Z","time spent":"1.131526444s","remote":"127.0.0.1:40990","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-26T21:00:33.190284Z","caller":"traceutil/trace.go:171","msg":"trace[1508427765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"1.549356611s","start":"2026-02-26T21:00:31.640912Z","end":"2026-02-26T21:00:33.190269Z","steps":["trace[1508427765] 'range keys from in-memory index tree' (duration: 1.549194927s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:33.190329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:31.640893Z","time spent":"1.549428133s","remote":"127.0.0.1:40730","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-26T21:00:33.190128Z","caller":"traceutil/trace.go:171","msg":"trace[1156501250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:490; }","duration":"670.535438ms","start":"2026-02-26T21:00:32.519568Z","end":"2026-02-26T21:00:33.190103Z","steps":["trace[1156501250] 'range keys from in-memory index tree' (duration: 670.17933ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:33.190394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:32.519547Z","time spent":"670.825585ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:00:33.190144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:32.847263Z","time spent":"342.871925ms","remote":"127.0.0.1:40990","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-26T21:00:33.190871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.4515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:00:33.190918Z","caller":"traceutil/trace.go:171","msg":"trace[1014709567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"695.501211ms","start":"2026-02-26T21:00:32.495404Z","end":"2026-02-26T21:00:33.190906Z","steps":["trace[1014709567] 'range keys from in-memory index tree' (duration: 694.241412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:33.19096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:32.495357Z","time spent":"695.589463ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-26T21:00:33.416032Z","caller":"traceutil/trace.go:171","msg":"trace[1854575411] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"220.832672ms","start":"2026-02-26T21:00:33.195174Z","end":"2026-02-26T21:00:33.416007Z","steps":["trace[1854575411] 'process raft request' (duration: 220.693238ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:33.454243Z","caller":"traceutil/trace.go:171","msg":"trace[1573544697] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"258.528798ms","start":"2026-02-26T21:00:33.195687Z","end":"2026-02-26T21:00:33.454216Z","steps":["trace[1573544697] 'process raft request' (duration: 258.335543ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:33.454538Z","caller":"traceutil/trace.go:171","msg":"trace[396320485] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"258.653621ms","start":"2026-02-26T21:00:33.195813Z","end":"2026-02-26T21:00:33.454466Z","steps":["trace[396320485] 'process raft request' (duration: 258.361644ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:33.454655Z","caller":"traceutil/trace.go:171","msg":"trace[2083669539] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:507; }","duration":"254.318988ms","start":"2026-02-26T21:00:33.20031Z","end":"2026-02-26T21:00:33.454629Z","steps":["trace[2083669539] 'read index received' (duration: 215.618258ms)","trace[2083669539] 'applied index is now lower than readState.Index' (duration: 38.69854ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:00:33.454706Z","caller":"traceutil/trace.go:171","msg":"trace[1642538981] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"258.022027ms","start":"2026-02-26T21:00:33.196673Z","end":"2026-02-26T21:00:33.454695Z","steps":["trace[1642538981] 'process raft request' (duration: 257.731389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:33.454823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.490072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:00:33.454863Z","caller":"traceutil/trace.go:171","msg":"trace[894813669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"254.580145ms","start":"2026-02-26T21:00:33.200273Z","end":"2026-02-26T21:00:33.454854Z","steps":["trace[894813669] 'agreement among raft nodes before linearized reading' (duration: 254.454652ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:44.221149Z","caller":"traceutil/trace.go:171","msg":"trace[1014144235] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"222.024597ms","start":"2026-02-26T21:00:43.9991Z","end":"2026-02-26T21:00:44.221124Z","steps":["trace[1014144235] 'process raft request' (duration: 221.916244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:44.611944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.667364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T21:00:44.612024Z","caller":"traceutil/trace.go:171","msg":"trace[185364904] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:617; }","duration":"119.800746ms","start":"2026-02-26T21:00:44.492205Z","end":"2026-02-26T21:00:44.612005Z","steps":["trace[185364904] 'range keys from in-memory index tree' (duration: 119.479839ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:45.370393Z","caller":"traceutil/trace.go:171","msg":"trace[894453314] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"113.566469ms","start":"2026-02-26T21:00:45.256803Z","end":"2026-02-26T21:00:45.37037Z","steps":["trace[894453314] 'process raft request' (duration: 113.475777ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:45.370401Z","caller":"traceutil/trace.go:171","msg":"trace[1934770694] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"114.179564ms","start":"2026-02-26T21:00:45.25618Z","end":"2026-02-26T21:00:45.37036Z","steps":["trace[1934770694] 'process raft request' (duration: 113.908147ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:45.370604Z","caller":"traceutil/trace.go:171","msg":"trace[1277506501] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"110.130407ms","start":"2026-02-26T21:00:45.260449Z","end":"2026-02-26T21:00:45.370579Z","steps":["trace[1277506501] 'process raft request' (duration: 109.877971ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:50.815178Z","caller":"traceutil/trace.go:171","msg":"trace[33944414] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"173.730119ms","start":"2026-02-26T21:00:50.641409Z","end":"2026-02-26T21:00:50.815139Z","steps":["trace[33944414] 'read index received' (duration: 172.975781ms)","trace[33944414] 'applied index is now lower than readState.Index' (duration: 750.738µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:00:50.815265Z","caller":"traceutil/trace.go:171","msg":"trace[1977483147] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"318.862249ms","start":"2026-02-26T21:00:50.496349Z","end":"2026-02-26T21:00:50.815212Z","steps":["trace[1977483147] 'process raft request' (duration: 318.156061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:50.815363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.934624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:00:50.815404Z","caller":"traceutil/trace.go:171","msg":"trace[656563212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"174.037056ms","start":"2026-02-26T21:00:50.641357Z","end":"2026-02-26T21:00:50.815394Z","steps":["trace[656563212] 'agreement among raft nodes before linearized reading' (duration: 173.917013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:50.815434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:50.496329Z","time spent":"319.033602ms","remote":"127.0.0.1:40810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:00:51.746213Z","caller":"traceutil/trace.go:171","msg":"trace[249452748] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"637.296245ms","start":"2026-02-26T21:00:51.108874Z","end":"2026-02-26T21:00:51.74617Z","steps":["trace[249452748] 'process raft request' (duration: 637.07368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:51.746366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:51.108853Z","time spent":"637.429809ms","remote":"127.0.0.1:40990","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-26T21:00:51.746407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.075082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:00:51.746171Z","caller":"traceutil/trace.go:171","msg":"trace[971193402] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"177.839437ms","start":"2026-02-26T21:00:51.5683Z","end":"2026-02-26T21:00:51.746139Z","steps":["trace[971193402] 'read index received' (duration: 177.54264ms)","trace[971193402] 'applied index is now lower than readState.Index' (duration: 295.337µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:00:51.746468Z","caller":"traceutil/trace.go:171","msg":"trace[711317359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"178.188094ms","start":"2026-02-26T21:00:51.568266Z","end":"2026-02-26T21:00:51.746454Z","steps":["trace[711317359] 'agreement among raft nodes before linearized reading' (duration: 177.990649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:51.746701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.624843ms","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-26T21:00:51.746745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.699404ms","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-26T21:00:51.746767Z","caller":"traceutil/trace.go:171","msg":"trace[1592792183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:673; }","duration":"172.732835ms","start":"2026-02-26T21:00:51.574027Z","end":"2026-02-26T21:00:51.74676Z","steps":["trace[1592792183] 'agreement among raft nodes before linearized reading' (duration: 172.635723ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:51.746765Z","caller":"traceutil/trace.go:171","msg":"trace[1175576786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:673; }","duration":"175.718086ms","start":"2026-02-26T21:00:51.571029Z","end":"2026-02-26T21:00:51.746747Z","steps":["trace[1175576786] 'agreement among raft nodes before linearized reading' (duration: 175.528781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:51.746796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.482057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:00:51.746829Z","caller":"traceutil/trace.go:171","msg":"trace[1997353515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"174.548869ms","start":"2026-02-26T21:00:51.572271Z","end":"2026-02-26T21:00:51.746819Z","steps":["trace[1997353515] 'agreement among raft nodes before linearized reading' (duration: 174.442726ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:52.052918Z","caller":"traceutil/trace.go:171","msg":"trace[771735168] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"303.090415ms","start":"2026-02-26T21:00:51.749804Z","end":"2026-02-26T21:00:52.052895Z","steps":["trace[771735168] 'process raft request' (duration: 285.155638ms)","trace[771735168] 'compare' (duration: 17.827453ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:52.053053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:51.749777Z","time spent":"303.207907ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:00:52.110155Z","caller":"traceutil/trace.go:171","msg":"trace[2036482909] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"359.697139ms","start":"2026-02-26T21:00:51.750438Z","end":"2026-02-26T21:00:52.110135Z","steps":["trace[2036482909] 'process raft request' (duration: 359.468393ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:52.110201Z","caller":"traceutil/trace.go:171","msg":"trace[1375315568] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"359.115094ms","start":"2026-02-26T21:00:51.751056Z","end":"2026-02-26T21:00:52.110171Z","steps":["trace[1375315568] 'process raft request' (duration: 358.994912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:52.110258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:51.750419Z","time spent":"359.78569ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:00:52.110142Z","caller":"traceutil/trace.go:171","msg":"trace[1026538006] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"358.967481ms","start":"2026-02-26T21:00:51.751149Z","end":"2026-02-26T21:00:52.110117Z","steps":["trace[1026538006] 'process raft request' (duration: 358.93529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:52.110346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:51.751045Z","time spent":"359.226608ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:00:52.110371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:51.751129Z","time spent":"359.223318ms","remote":"127.0.0.1:40990","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-26T21:00:54.557839Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871035142427071,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:00:55.034801Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.281180089s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:00:55.035284Z","caller":"traceutil/trace.go:171","msg":"trace[2117308738] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"977.866941ms","start":"2026-02-26T21:00:54.057393Z","end":"2026-02-26T21:00:55.03526Z","steps":["trace[2117308738] 'read index received' (duration: 977.739418ms)","trace[2117308738] 'applied index is now lower than readState.Index' (duration: 126.293µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:00:55.035378Z","caller":"traceutil/trace.go:171","msg":"trace[1715487449] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"1.281878925s","start":"2026-02-26T21:00:53.753471Z","end":"2026-02-26T21:00:55.03535Z","steps":["trace[1715487449] 'process raft request' (duration: 1.281631299s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:55.035532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:53.753451Z","time spent":"1.281989158s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:00:55.035553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.139787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:00:55.035593Z","caller":"traceutil/trace.go:171","msg":"trace[822191941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:678; }","duration":"978.228289ms","start":"2026-02-26T21:00:54.057354Z","end":"2026-02-26T21:00:55.035582Z","steps":["trace[822191941] 'agreement among raft nodes before linearized reading' (duration: 978.068446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:55.035635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:54.057338Z","time spent":"978.28855ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T21:00:55.53568Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871035142427074,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T21:00:55.758646Z","caller":"traceutil/trace.go:171","msg":"trace[89911794] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"1.143533917s","start":"2026-02-26T21:00:54.615091Z","end":"2026-02-26T21:00:55.758625Z","steps":["trace[89911794] 'process raft request' (duration: 1.143403544s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:55.758814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:54.615065Z","time spent":"1.143670211s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:00:55.760293Z","caller":"traceutil/trace.go:171","msg":"trace[882743201] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:701; }","duration":"724.874807ms","start":"2026-02-26T21:00:55.035379Z","end":"2026-02-26T21:00:55.760254Z","steps":["trace[882743201] 'read index received' (duration: 723.150576ms)","trace[882743201] 'applied index is now lower than readState.Index' (duration: 1.723251ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:00:55.760426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.646479551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:00:55.760463Z","caller":"traceutil/trace.go:171","msg":"trace[1825364679] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"721.742344ms","start":"2026-02-26T21:00:55.038711Z","end":"2026-02-26T21:00:55.760454Z","steps":["trace[1825364679] 'process raft request' (duration: 721.465828ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:55.76047Z","caller":"traceutil/trace.go:171","msg":"trace[298124805] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:680; }","duration":"1.646544052s","start":"2026-02-26T21:00:54.113915Z","end":"2026-02-26T21:00:55.760459Z","steps":["trace[298124805] 'agreement among raft nodes before linearized reading' (duration: 1.64644497s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:55.760511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:54.113905Z","time spent":"1.646586984s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:00:55.760516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:55.038692Z","time spent":"721.795175ms","remote":"127.0.0.1:40990","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-26T21:00:55.760426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267820892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T21:00:55.760588Z","caller":"traceutil/trace.go:171","msg":"trace[490934667] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:680; }","duration":"1.268020247s","start":"2026-02-26T21:00:54.492558Z","end":"2026-02-26T21:00:55.760578Z","steps":["trace[490934667] 'agreement among raft nodes before linearized reading' (duration: 1.267749651s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:55.760558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.340688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:00:55.760616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:54.49254Z","time spent":"1.268068098s","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"info","ts":"2026-02-26T21:00:55.760653Z","caller":"traceutil/trace.go:171","msg":"trace[59514277] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:680; }","duration":"276.499262ms","start":"2026-02-26T21:00:55.484131Z","end":"2026-02-26T21:00:55.76063Z","steps":["trace[59514277] 'agreement among raft nodes before linearized reading' (duration: 276.339479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:55.760645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119461506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T21:00:55.760684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.646715777s","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-26T21:00:55.760722Z","caller":"traceutil/trace.go:171","msg":"trace[1425997785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"1.119574929s","start":"2026-02-26T21:00:54.641129Z","end":"2026-02-26T21:00:55.760704Z","steps":["trace[1425997785] 'agreement among raft nodes before linearized reading' (duration: 1.119453846s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:00:55.760755Z","caller":"traceutil/trace.go:171","msg":"trace[1536293842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"1.64682782s","start":"2026-02-26T21:00:54.113911Z","end":"2026-02-26T21:00:55.760739Z","steps":["trace[1536293842] 'agreement among raft nodes before linearized reading' (duration: 1.646591344s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:55.760762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:54.641111Z","time spent":"1.11964305s","remote":"127.0.0.1:40730","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-26T21:00:55.760792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:54.113897Z","time spent":"1.646889091s","remote":"127.0.0.1:40990","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-26T21:00:55.760668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647188248s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:00:55.760871Z","caller":"traceutil/trace.go:171","msg":"trace[1860053270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:680; }","duration":"1.647444864s","start":"2026-02-26T21:00:54.113414Z","end":"2026-02-26T21:00:55.760859Z","steps":["trace[1860053270] 'agreement among raft nodes before linearized reading' (duration: 1.647012634s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:00:55.760906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:00:54.113391Z","time spent":"1.647503435s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:01:14.002884Z","caller":"traceutil/trace.go:171","msg":"trace[1573634884] linearizableReadLoop","detail":"{readStateIndex:764; appliedIndex:763; }","duration":"160.569306ms","start":"2026-02-26T21:01:13.84229Z","end":"2026-02-26T21:01:14.002859Z","steps":["trace[1573634884] 'read index received' (duration: 160.410932ms)","trace[1573634884] 'applied index is now lower than readState.Index' (duration: 156.774µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:14.003007Z","caller":"traceutil/trace.go:171","msg":"trace[1648670375] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"162.801279ms","start":"2026-02-26T21:01:13.840179Z","end":"2026-02-26T21:01:14.00298Z","steps":["trace[1648670375] 'process raft request' (duration: 162.530423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:14.00334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.964985ms","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-26T21:01:14.003403Z","caller":"traceutil/trace.go:171","msg":"trace[1369123024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:736; }","duration":"161.112498ms","start":"2026-02-26T21:01:13.842272Z","end":"2026-02-26T21:01:14.003384Z","steps":["trace[1369123024] 'agreement among raft nodes before linearized reading' (duration: 160.724339ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:14.034511Z","caller":"traceutil/trace.go:171","msg":"trace[2125241764] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"191.064291ms","start":"2026-02-26T21:01:13.843392Z","end":"2026-02-26T21:01:14.034457Z","steps":["trace[2125241764] 'process raft request' (duration: 190.748563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:14.034574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.65351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:01:14.034694Z","caller":"traceutil/trace.go:171","msg":"trace[1199217021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:737; }","duration":"187.835515ms","start":"2026-02-26T21:01:13.846836Z","end":"2026-02-26T21:01:14.034671Z","steps":["trace[1199217021] 'agreement among raft nodes before linearized reading' (duration: 187.521807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:15.511612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01776628s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T21:01:15.511722Z","caller":"traceutil/trace.go:171","msg":"trace[955670971] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:739; }","duration":"1.017911752s","start":"2026-02-26T21:01:14.493786Z","end":"2026-02-26T21:01:15.511698Z","steps":["trace[955670971] 'range keys from in-memory index tree' (duration: 1.017596975s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:15.511778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:14.493769Z","time spent":"1.017994095s","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-02-26T21:01:15.511931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.243202ms","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-26T21:01:15.511987Z","caller":"traceutil/trace.go:171","msg":"trace[1165908940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:739; }","duration":"328.334664ms","start":"2026-02-26T21:01:15.183638Z","end":"2026-02-26T21:01:15.511972Z","steps":["trace[1165908940] 'range keys from in-memory index tree' (duration: 328.061117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:15.51202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:15.183616Z","time spent":"328.396106ms","remote":"127.0.0.1:40990","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-26T21:01:15.511987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.229715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:15.512141Z","caller":"traceutil/trace.go:171","msg":"trace[544113498] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:739; }","duration":"669.359418ms","start":"2026-02-26T21:01:14.842714Z","end":"2026-02-26T21:01:15.512073Z","steps":["trace[544113498] 'count revisions from in-memory index tree' (duration: 669.140403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:15.512263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:14.84265Z","time spent":"669.548842ms","remote":"127.0.0.1:40950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:01:15.790458Z","caller":"traceutil/trace.go:171","msg":"trace[1004040273] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:767; }","duration":"148.466139ms","start":"2026-02-26T21:01:15.641961Z","end":"2026-02-26T21:01:15.790427Z","steps":["trace[1004040273] 'read index received' (duration: 44.992389ms)","trace[1004040273] 'applied index is now lower than readState.Index' (duration: 103.47234ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:15.790522Z","caller":"traceutil/trace.go:171","msg":"trace[218043234] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"273.934401ms","start":"2026-02-26T21:01:15.516557Z","end":"2026-02-26T21:01:15.790491Z","steps":["trace[218043234] 'process raft request' (duration: 230.295584ms)","trace[218043234] 'compare' (duration: 43.33383ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:15.790669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.685974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:15.790666Z","caller":"traceutil/trace.go:171","msg":"trace[1104917731] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"102.032355ms","start":"2026-02-26T21:01:15.688604Z","end":"2026-02-26T21:01:15.790636Z","steps":["trace[1104917731] 'process raft request' (duration: 101.758388ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:15.790752Z","caller":"traceutil/trace.go:171","msg":"trace[2069090513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:741; }","duration":"148.793576ms","start":"2026-02-26T21:01:15.641925Z","end":"2026-02-26T21:01:15.790719Z","steps":["trace[2069090513] 'agreement among raft nodes before linearized reading' (duration: 148.657213ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:16.110948Z","caller":"traceutil/trace.go:171","msg":"trace[1581762650] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"102.211589ms","start":"2026-02-26T21:01:16.008703Z","end":"2026-02-26T21:01:16.110915Z","steps":["trace[1581762650] 'read index received' (duration: 101.83902ms)","trace[1581762650] 'applied index is now lower than readState.Index' (duration: 370.659µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:16.111083Z","caller":"traceutil/trace.go:171","msg":"trace[436730100] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"163.507227ms","start":"2026-02-26T21:01:15.947533Z","end":"2026-02-26T21:01:16.11104Z","steps":["trace[436730100] 'process raft request' (duration: 163.155858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:16.111405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.65229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:01:16.111526Z","caller":"traceutil/trace.go:171","msg":"trace[784992686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:742; }","duration":"102.801044ms","start":"2026-02-26T21:01:16.008696Z","end":"2026-02-26T21:01:16.111497Z","steps":["trace[784992686] 'agreement among raft nodes before linearized reading' (duration: 102.384284ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.09787Z","caller":"traceutil/trace.go:171","msg":"trace[1998345989] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"300.476112ms","start":"2026-02-26T21:01:17.797366Z","end":"2026-02-26T21:01:18.097842Z","steps":["trace[1998345989] 'read index received' (duration: 300.226896ms)","trace[1998345989] 'applied index is now lower than readState.Index' (duration: 247.646µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:18.097916Z","caller":"traceutil/trace.go:171","msg":"trace[1520860768] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"301.418234ms","start":"2026-02-26T21:01:17.796475Z","end":"2026-02-26T21:01:18.097894Z","steps":["trace[1520860768] 'process raft request' (duration: 301.197669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:18.098048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:17.796446Z","time spent":"301.526197ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:18.09803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.760478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:01:18.098164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.771598ms","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-26T21:01:18.098185Z","caller":"traceutil/trace.go:171","msg":"trace[58825092] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:747; }","duration":"167.937812ms","start":"2026-02-26T21:01:17.93021Z","end":"2026-02-26T21:01:18.098148Z","steps":["trace[58825092] 'agreement among raft nodes before linearized reading' (duration: 167.774558ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.098242Z","caller":"traceutil/trace.go:171","msg":"trace[1828776514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"300.904692ms","start":"2026-02-26T21:01:17.797319Z","end":"2026-02-26T21:01:18.098224Z","steps":["trace[1828776514] 'agreement among raft nodes before linearized reading' (duration: 300.656916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:18.098289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:17.797298Z","time spent":"300.983024ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T21:01:18.299945Z","caller":"traceutil/trace.go:171","msg":"trace[1580736740] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"123.67068ms","start":"2026-02-26T21:01:18.176251Z","end":"2026-02-26T21:01:18.299921Z","steps":["trace[1580736740] 'process raft request' (duration: 123.606968ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.299962Z","caller":"traceutil/trace.go:171","msg":"trace[827141909] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"123.739911ms","start":"2026-02-26T21:01:18.176192Z","end":"2026-02-26T21:01:18.299932Z","steps":["trace[827141909] 'process raft request' (duration: 123.534266ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:18.300367Z","caller":"traceutil/trace.go:171","msg":"trace[181498681] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"124.172201ms","start":"2026-02-26T21:01:18.176151Z","end":"2026-02-26T21:01:18.300323Z","steps":["trace[181498681] 'process raft request' (duration: 123.741021ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:27.945247Z","caller":"traceutil/trace.go:171","msg":"trace[403236902] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"111.640163ms","start":"2026-02-26T21:01:27.833587Z","end":"2026-02-26T21:01:27.945227Z","steps":["trace[403236902] 'process raft request' (duration: 111.569532ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.492479Z","caller":"traceutil/trace.go:171","msg":"trace[882179275] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"102.978756ms","start":"2026-02-26T21:01:28.389477Z","end":"2026-02-26T21:01:28.492456Z","steps":["trace[882179275] 'process raft request' (duration: 97.483456ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.652477Z","caller":"traceutil/trace.go:171","msg":"trace[872735467] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"151.223354ms","start":"2026-02-26T21:01:28.501223Z","end":"2026-02-26T21:01:28.652446Z","steps":["trace[872735467] 'process raft request' (duration: 150.231091ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.652491Z","caller":"traceutil/trace.go:171","msg":"trace[244856130] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"151.183013ms","start":"2026-02-26T21:01:28.501278Z","end":"2026-02-26T21:01:28.652461Z","steps":["trace[244856130] 'process raft request' (duration: 151.074531ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.652587Z","caller":"traceutil/trace.go:171","msg":"trace[769628321] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"148.96942ms","start":"2026-02-26T21:01:28.503569Z","end":"2026-02-26T21:01:28.652538Z","steps":["trace[769628321] 'process raft request' (duration: 148.821316ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.652701Z","caller":"traceutil/trace.go:171","msg":"trace[974869325] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"147.356731ms","start":"2026-02-26T21:01:28.505335Z","end":"2026-02-26T21:01:28.652691Z","steps":["trace[974869325] 'process raft request' (duration: 147.158787ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.652802Z","caller":"traceutil/trace.go:171","msg":"trace[1295334835] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"148.560252ms","start":"2026-02-26T21:01:28.504233Z","end":"2026-02-26T21:01:28.652793Z","steps":["trace[1295334835] 'process raft request' (duration: 148.208823ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.652797Z","caller":"traceutil/trace.go:171","msg":"trace[506424036] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"103.514299ms","start":"2026-02-26T21:01:28.549261Z","end":"2026-02-26T21:01:28.652775Z","steps":["trace[506424036] 'process raft request' (duration: 103.408907ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.652881Z","caller":"traceutil/trace.go:171","msg":"trace[1842606574] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"146.019042ms","start":"2026-02-26T21:01:28.506855Z","end":"2026-02-26T21:01:28.652874Z","steps":["trace[1842606574] 'process raft request' (duration: 145.664412ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.653121Z","caller":"traceutil/trace.go:171","msg":"trace[1352314521] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:844; }","duration":"145.050457ms","start":"2026-02-26T21:01:28.508055Z","end":"2026-02-26T21:01:28.653105Z","steps":["trace[1352314521] 'read index received' (duration: 37.007189ms)","trace[1352314521] 'applied index is now lower than readState.Index' (duration: 108.042148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:28.653354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.269222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"warn","ts":"2026-02-26T21:01:28.653461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.212205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-dxjsc\" ","response":"range_response_count:1 size:9695"} {"level":"info","ts":"2026-02-26T21:01:28.653485Z","caller":"traceutil/trace.go:171","msg":"trace[1575117478] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:819; }","duration":"145.381345ms","start":"2026-02-26T21:01:28.508032Z","end":"2026-02-26T21:01:28.653413Z","steps":["trace[1575117478] 'agreement among raft nodes before linearized reading' (duration: 145.117069ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.653512Z","caller":"traceutil/trace.go:171","msg":"trace[272108501] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-dxjsc; range_end:; response_count:1; response_revision:819; }","duration":"101.332698ms","start":"2026-02-26T21:01:28.552167Z","end":"2026-02-26T21:01:28.653499Z","steps":["trace[272108501] 'agreement among raft nodes before linearized reading' (duration: 101.145464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.653515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.751998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363678"} {"level":"info","ts":"2026-02-26T21:01:28.653556Z","caller":"traceutil/trace.go:171","msg":"trace[3526216] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:819; }","duration":"109.83958ms","start":"2026-02-26T21:01:28.543704Z","end":"2026-02-26T21:01:28.653544Z","steps":["trace[3526216] 'agreement among raft nodes before linearized reading' (duration: 109.489902ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.800629Z","caller":"traceutil/trace.go:171","msg":"trace[492022081] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"136.416671ms","start":"2026-02-26T21:01:28.664191Z","end":"2026-02-26T21:01:28.800608Z","steps":["trace[492022081] 'process raft request' (duration: 104.756799ms)","trace[492022081] 'compare' (duration: 31.517779ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:28.800949Z","caller":"traceutil/trace.go:171","msg":"trace[980024447] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"134.872185ms","start":"2026-02-26T21:01:28.666061Z","end":"2026-02-26T21:01:28.800933Z","steps":["trace[980024447] 'process raft request' (duration: 134.495996ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.801338Z","caller":"traceutil/trace.go:171","msg":"trace[1650085349] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"134.742403ms","start":"2026-02-26T21:01:28.666569Z","end":"2026-02-26T21:01:28.801312Z","steps":["trace[1650085349] 'process raft request' (duration: 134.23152ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.945547Z","caller":"traceutil/trace.go:171","msg":"trace[1997558486] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"123.170257ms","start":"2026-02-26T21:01:28.822337Z","end":"2026-02-26T21:01:28.945507Z","steps":["trace[1997558486] 'process raft request' (duration: 122.525452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.94561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.672943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9373"} {"level":"info","ts":"2026-02-26T21:01:28.94569Z","caller":"traceutil/trace.go:171","msg":"trace[1643796482] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:826; }","duration":"132.784455ms","start":"2026-02-26T21:01:28.812882Z","end":"2026-02-26T21:01:28.945667Z","steps":["trace[1643796482] 'agreement among raft nodes before linearized reading' (duration: 132.57903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.945739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.841871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-02-26T21:01:28.945769Z","caller":"traceutil/trace.go:171","msg":"trace[1436615995] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:826; }","duration":"125.902552ms","start":"2026-02-26T21:01:28.819858Z","end":"2026-02-26T21:01:28.945761Z","steps":["trace[1436615995] 'agreement among raft nodes before linearized reading' (duration: 125.837741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:28.945757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.240407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-02-26T21:01:28.945848Z","caller":"traceutil/trace.go:171","msg":"trace[1305068088] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:826; }","duration":"125.35314ms","start":"2026-02-26T21:01:28.820474Z","end":"2026-02-26T21:01:28.945827Z","steps":["trace[1305068088] 'agreement among raft nodes before linearized reading' (duration: 125.122214ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:28.979542Z","caller":"traceutil/trace.go:171","msg":"trace[1992052621] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"115.489674ms","start":"2026-02-26T21:01:28.86403Z","end":"2026-02-26T21:01:28.97952Z","steps":["trace[1992052621] 'process raft request' (duration: 115.329171ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:30.320612Z","caller":"traceutil/trace.go:171","msg":"trace[1067525055] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"122.895121ms","start":"2026-02-26T21:01:30.197699Z","end":"2026-02-26T21:01:30.320594Z","steps":["trace[1067525055] 'process raft request' (duration: 122.776818ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:30.507534Z","caller":"traceutil/trace.go:171","msg":"trace[1446163628] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"130.110013ms","start":"2026-02-26T21:01:30.377402Z","end":"2026-02-26T21:01:30.507512Z","steps":["trace[1446163628] 'read index received' (duration: 122.730927ms)","trace[1446163628] 'applied index is now lower than readState.Index' (duration: 7.378026ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:30.507561Z","caller":"traceutil/trace.go:171","msg":"trace[1176434483] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"146.674466ms","start":"2026-02-26T21:01:30.360858Z","end":"2026-02-26T21:01:30.507532Z","steps":["trace[1176434483] 'process raft request' (duration: 139.28926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:30.507759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.334158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T21:01:30.507777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.432912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:01:30.507808Z","caller":"traceutil/trace.go:171","msg":"trace[1615356490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:832; }","duration":"130.43742ms","start":"2026-02-26T21:01:30.377356Z","end":"2026-02-26T21:01:30.507794Z","steps":["trace[1615356490] 'agreement among raft nodes before linearized reading' (duration: 130.236416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:30.5078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.399148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:01:30.507854Z","caller":"traceutil/trace.go:171","msg":"trace[1607937432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"127.47323ms","start":"2026-02-26T21:01:30.380366Z","end":"2026-02-26T21:01:30.507839Z","steps":["trace[1607937432] 'agreement among raft nodes before linearized reading' (duration: 127.307706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:30.507896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.492484ms","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-26T21:01:30.507922Z","caller":"traceutil/trace.go:171","msg":"trace[1784301231] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:832; }","duration":"128.533925ms","start":"2026-02-26T21:01:30.379381Z","end":"2026-02-26T21:01:30.507915Z","steps":["trace[1784301231] 'agreement among raft nodes before linearized reading' (duration: 128.455033ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:30.507816Z","caller":"traceutil/trace.go:171","msg":"trace[1878026321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:832; }","duration":"128.523615ms","start":"2026-02-26T21:01:30.379282Z","end":"2026-02-26T21:01:30.507805Z","steps":["trace[1878026321] 'agreement among raft nodes before linearized reading' (duration: 128.351881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:32.556968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.539444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:32.557038Z","caller":"traceutil/trace.go:171","msg":"trace[631124964] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:837; }","duration":"104.656057ms","start":"2026-02-26T21:01:32.452363Z","end":"2026-02-26T21:01:32.557019Z","steps":["trace[631124964] 'count revisions from in-memory index tree' (duration: 104.423771ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:32.669143Z","caller":"traceutil/trace.go:171","msg":"trace[661627171] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"102.113896ms","start":"2026-02-26T21:01:32.567012Z","end":"2026-02-26T21:01:32.669126Z","steps":["trace[661627171] 'process raft request' (duration: 101.509262ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:32.90141Z","caller":"traceutil/trace.go:171","msg":"trace[71454120] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"228.51774ms","start":"2026-02-26T21:01:32.67287Z","end":"2026-02-26T21:01:32.901388Z","steps":["trace[71454120] 'process raft request' (duration: 228.370467ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:32.902042Z","caller":"traceutil/trace.go:171","msg":"trace[636768838] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"228.970631ms","start":"2026-02-26T21:01:32.673052Z","end":"2026-02-26T21:01:32.902022Z","steps":["trace[636768838] 'process raft request' (duration: 228.92684ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:32.902135Z","caller":"traceutil/trace.go:171","msg":"trace[49871382] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"229.197236ms","start":"2026-02-26T21:01:32.672926Z","end":"2026-02-26T21:01:32.902123Z","steps":["trace[49871382] 'process raft request' (duration: 228.945261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:34.738222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.489848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:01:34.738393Z","caller":"traceutil/trace.go:171","msg":"trace[717697055] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:877; }","duration":"176.791242ms","start":"2026-02-26T21:01:34.561544Z","end":"2026-02-26T21:01:34.738335Z","steps":["trace[717697055] 'read index received' (duration: 47.10571ms)","trace[717697055] 'applied index is now lower than readState.Index' (duration: 129.681901ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:34.738511Z","caller":"traceutil/trace.go:171","msg":"trace[91502848] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"227.518937ms","start":"2026-02-26T21:01:34.51098Z","end":"2026-02-26T21:01:34.738499Z","steps":["trace[91502848] 'process raft request' (duration: 97.656291ms)","trace[91502848] 'compare' (duration: 129.329014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:34.73887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.304873ms","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-26T21:01:34.738967Z","caller":"traceutil/trace.go:171","msg":"trace[1010938767] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:843; }","duration":"177.459317ms","start":"2026-02-26T21:01:34.561487Z","end":"2026-02-26T21:01:34.738946Z","steps":["trace[1010938767] 'agreement among raft nodes before linearized reading' (duration: 177.218621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:35.075867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.912109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:01:35.07596Z","caller":"traceutil/trace.go:171","msg":"trace[191663771] linearizableReadLoop","detail":"{readStateIndex:879; appliedIndex:878; }","duration":"333.251161ms","start":"2026-02-26T21:01:34.742694Z","end":"2026-02-26T21:01:35.075945Z","steps":["trace[191663771] 'read index received' (duration: 99.110736ms)","trace[191663771] 'applied index is now lower than readState.Index' (duration: 234.139405ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:35.076058Z","caller":"traceutil/trace.go:171","msg":"trace[1893531931] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"334.373266ms","start":"2026-02-26T21:01:34.741671Z","end":"2026-02-26T21:01:35.076044Z","steps":["trace[1893531931] 'process raft request' (duration: 100.213351ms)","trace[1893531931] 'compare' (duration: 233.663233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:35.076141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:34.74166Z","time spent":"334.440288ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:35.076226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.42959ms","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-26T21:01:35.076281Z","caller":"traceutil/trace.go:171","msg":"trace[187462701] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:844; }","duration":"170.530002ms","start":"2026-02-26T21:01:34.905738Z","end":"2026-02-26T21:01:35.076268Z","steps":["trace[187462701] 'agreement among raft nodes before linearized reading' (duration: 170.382569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:35.076384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.693602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-26T21:01:35.076413Z","caller":"traceutil/trace.go:171","msg":"trace[545845814] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:844; }","duration":"333.735323ms","start":"2026-02-26T21:01:34.742671Z","end":"2026-02-26T21:01:35.076406Z","steps":["trace[545845814] 'agreement among raft nodes before linearized reading' (duration: 333.688671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:35.076431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:34.742662Z","time spent":"333.763033ms","remote":"127.0.0.1:40992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-26T21:01:35.076581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.406901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:01:35.076609Z","caller":"traceutil/trace.go:171","msg":"trace[1684240811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:844; }","duration":"170.463242ms","start":"2026-02-26T21:01:34.906138Z","end":"2026-02-26T21:01:35.076601Z","steps":["trace[1684240811] 'agreement among raft nodes before linearized reading' (duration: 170.398051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:35.076754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.935572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:01:35.076812Z","caller":"traceutil/trace.go:171","msg":"trace[1175634365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:844; }","duration":"171.019295ms","start":"2026-02-26T21:01:34.905778Z","end":"2026-02-26T21:01:35.076797Z","steps":["trace[1175634365] 'agreement among raft nodes before linearized reading' (duration: 170.856401ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:35.397619Z","caller":"traceutil/trace.go:171","msg":"trace[897512064] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"316.976533ms","start":"2026-02-26T21:01:35.080618Z","end":"2026-02-26T21:01:35.397594Z","steps":["trace[897512064] 'process raft request' (duration: 316.82373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:35.397806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:35.080602Z","time spent":"317.109006ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:35.398314Z","caller":"traceutil/trace.go:171","msg":"trace[201748551] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"317.534356ms","start":"2026-02-26T21:01:35.080761Z","end":"2026-02-26T21:01:35.398295Z","steps":["trace[201748551] 'process raft request' (duration: 317.431963ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:35.3984Z","caller":"traceutil/trace.go:171","msg":"trace[604116871] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"317.596298ms","start":"2026-02-26T21:01:35.080789Z","end":"2026-02-26T21:01:35.398385Z","steps":["trace[604116871] 'process raft request' (duration: 317.481615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:35.398425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:35.08074Z","time spent":"317.628839ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:35.398506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:35.080776Z","time spent":"317.665559ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:48.703556Z","caller":"traceutil/trace.go:171","msg":"trace[1555888321] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"194.261339ms","start":"2026-02-26T21:01:48.509278Z","end":"2026-02-26T21:01:48.703539Z","steps":["trace[1555888321] 'process raft request' (duration: 194.158706ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:48.996794Z","caller":"traceutil/trace.go:171","msg":"trace[1597032811] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:918; }","duration":"355.469202ms","start":"2026-02-26T21:01:48.641304Z","end":"2026-02-26T21:01:48.996773Z","steps":["trace[1597032811] 'read index received' (duration: 62.155658ms)","trace[1597032811] 'applied index is now lower than readState.Index' (duration: 293.312804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:48.996897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.605225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:48.996926Z","caller":"traceutil/trace.go:171","msg":"trace[1306407677] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"355.686178ms","start":"2026-02-26T21:01:48.641229Z","end":"2026-02-26T21:01:48.996915Z","steps":["trace[1306407677] 'agreement among raft nodes before linearized reading' (duration: 355.621446ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:48.996919Z","caller":"traceutil/trace.go:171","msg":"trace[1367574686] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"436.193631ms","start":"2026-02-26T21:01:48.560708Z","end":"2026-02-26T21:01:48.996901Z","steps":["trace[1367574686] 'process raft request' (duration: 373.285335ms)","trace[1367574686] 'compare' (duration: 62.693201ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:48.997009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:48.560685Z","time spent":"436.273744ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:48.997013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:48.641211Z","time spent":"355.747929ms","remote":"127.0.0.1:40730","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-26T21:01:52.302332Z","caller":"traceutil/trace.go:171","msg":"trace[856190376] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"164.325068ms","start":"2026-02-26T21:01:52.137982Z","end":"2026-02-26T21:01:52.302307Z","steps":["trace[856190376] 'read index received' (duration: 160.648239ms)","trace[856190376] 'applied index is now lower than readState.Index' (duration: 3.675809ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:52.302413Z","caller":"traceutil/trace.go:171","msg":"trace[849667612] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"209.347898ms","start":"2026-02-26T21:01:52.093027Z","end":"2026-02-26T21:01:52.302375Z","steps":["trace[849667612] 'process raft request' (duration: 205.597238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:52.302622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.623824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5184"} {"level":"info","ts":"2026-02-26T21:01:52.30269Z","caller":"traceutil/trace.go:171","msg":"trace[1472873379] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:904; }","duration":"164.740937ms","start":"2026-02-26T21:01:52.137931Z","end":"2026-02-26T21:01:52.302672Z","steps":["trace[1472873379] 'agreement among raft nodes before linearized reading' (duration: 164.461441ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:53.715578Z","caller":"traceutil/trace.go:171","msg":"trace[300069896] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"140.081201ms","start":"2026-02-26T21:01:53.575466Z","end":"2026-02-26T21:01:53.715548Z","steps":["trace[300069896] 'process raft request' (duration: 53.470711ms)","trace[300069896] 'compare' (duration: 86.370484ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:53.715819Z","caller":"traceutil/trace.go:171","msg":"trace[921452427] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"139.690961ms","start":"2026-02-26T21:01:53.576106Z","end":"2026-02-26T21:01:53.715797Z","steps":["trace[921452427] 'process raft request' (duration: 139.314793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:54.644251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.280761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-26T21:01:54.644333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.842687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:01:54.644395Z","caller":"traceutil/trace.go:171","msg":"trace[209052828] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:917; }","duration":"136.465445ms","start":"2026-02-26T21:01:54.507909Z","end":"2026-02-26T21:01:54.644375Z","steps":["trace[209052828] 'range keys from in-memory index tree' (duration: 136.110596ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:54.64444Z","caller":"traceutil/trace.go:171","msg":"trace[198430582] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:917; }","duration":"756.999931ms","start":"2026-02-26T21:01:53.887401Z","end":"2026-02-26T21:01:54.644401Z","steps":["trace[198430582] 'count revisions from in-memory index tree' (duration: 756.746684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:54.64432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.004532ms","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":"warn","ts":"2026-02-26T21:01:54.644511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:53.887355Z","time spent":"757.138614ms","remote":"127.0.0.1:50428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:01:54.644549Z","caller":"traceutil/trace.go:171","msg":"trace[692113022] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:917; }","duration":"281.225087ms","start":"2026-02-26T21:01:54.363285Z","end":"2026-02-26T21:01:54.64451Z","steps":["trace[692113022] 'count revisions from in-memory index tree' (duration: 280.922351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:55.34215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.243951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:01:55.342252Z","caller":"traceutil/trace.go:171","msg":"trace[557003327] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"560.802154ms","start":"2026-02-26T21:01:54.781434Z","end":"2026-02-26T21:01:55.342236Z","steps":["trace[557003327] 'read index received' (duration: 368.331217ms)","trace[557003327] 'applied index is now lower than readState.Index' (duration: 192.469287ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:55.342396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.162115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:55.342458Z","caller":"traceutil/trace.go:171","msg":"trace[2133755111] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:918; }","duration":"318.266118ms","start":"2026-02-26T21:01:55.02418Z","end":"2026-02-26T21:01:55.342446Z","steps":["trace[2133755111] 'agreement among raft nodes before linearized reading' (duration: 318.145995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:55.342521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:55.024165Z","time spent":"318.346619ms","remote":"127.0.0.1:40880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:01:55.342529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.100521ms","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-26T21:01:55.342563Z","caller":"traceutil/trace.go:171","msg":"trace[1870449116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:918; }","duration":"561.156192ms","start":"2026-02-26T21:01:54.781397Z","end":"2026-02-26T21:01:55.342554Z","steps":["trace[1870449116] 'agreement among raft nodes before linearized reading' (duration: 560.922007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:55.342595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:54.781382Z","time spent":"561.205653ms","remote":"127.0.0.1:40990","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-26T21:01:55.344176Z","caller":"traceutil/trace.go:171","msg":"trace[1669571939] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"673.952055ms","start":"2026-02-26T21:01:54.668308Z","end":"2026-02-26T21:01:55.34226Z","steps":["trace[1669571939] 'process raft request' (duration: 481.499099ms)","trace[1669571939] 'compare' (duration: 191.992736ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:55.344311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:54.66829Z","time spent":"675.952782ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:01:55.505642Z","caller":"traceutil/trace.go:171","msg":"trace[1688408189] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:957; }","duration":"157.795631ms","start":"2026-02-26T21:01:55.347832Z","end":"2026-02-26T21:01:55.505627Z","steps":["trace[1688408189] 'read index received' (duration: 112.967025ms)","trace[1688408189] 'applied index is now lower than readState.Index' (duration: 44.828106ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:55.505679Z","caller":"traceutil/trace.go:171","msg":"trace[509870493] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"159.853261ms","start":"2026-02-26T21:01:55.34579Z","end":"2026-02-26T21:01:55.505643Z","steps":["trace[509870493] 'process raft request' (duration: 114.902872ms)","trace[509870493] 'compare' (duration: 44.740084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:55.505789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.935535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-26T21:01:55.505813Z","caller":"traceutil/trace.go:171","msg":"trace[926437728] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:920; }","duration":"157.976895ms","start":"2026-02-26T21:01:55.347827Z","end":"2026-02-26T21:01:55.505803Z","steps":["trace[926437728] 'agreement among raft nodes before linearized reading' (duration: 157.868623ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:55.505868Z","caller":"traceutil/trace.go:171","msg":"trace[1067463033] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"159.974723ms","start":"2026-02-26T21:01:55.345867Z","end":"2026-02-26T21:01:55.505842Z","steps":["trace[1067463033] 'process raft request' (duration: 159.734948ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:01:55.731078Z","caller":"traceutil/trace.go:171","msg":"trace[1158671924] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"152.868316ms","start":"2026-02-26T21:01:55.578179Z","end":"2026-02-26T21:01:55.731047Z","steps":["trace[1158671924] 'process raft request' (duration: 128.456925ms)","trace[1158671924] 'compare' (duration: 24.103092ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:55.95978Z","caller":"traceutil/trace.go:171","msg":"trace[384853904] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"224.631051ms","start":"2026-02-26T21:01:55.73512Z","end":"2026-02-26T21:01:55.959751Z","steps":["trace[384853904] 'process raft request' (duration: 141.033233ms)","trace[384853904] 'compare' (duration: 83.396403ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:57.481241Z","caller":"traceutil/trace.go:171","msg":"trace[1405910034] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"505.175751ms","start":"2026-02-26T21:01:56.976039Z","end":"2026-02-26T21:01:57.481215Z","steps":["trace[1405910034] 'process raft request' (duration: 505.011127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:57.481409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:56.976017Z","time spent":"505.309664ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:58.710408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090063188s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:01:58.710549Z","caller":"traceutil/trace.go:171","msg":"trace[1069991787] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:963; }","duration":"1.619860084s","start":"2026-02-26T21:01:57.090662Z","end":"2026-02-26T21:01:58.710522Z","steps":["trace[1069991787] 'read index received' (duration: 390.493034ms)","trace[1069991787] 'applied index is now lower than readState.Index' (duration: 1.22936516s)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:58.710641Z","caller":"traceutil/trace.go:171","msg":"trace[1291708899] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.633492919s","start":"2026-02-26T21:01:57.077111Z","end":"2026-02-26T21:01:58.710604Z","steps":["trace[1291708899] 'process raft request' (duration: 543.164815ms)","trace[1291708899] 'compare' (duration: 1.088676365s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:01:58.710711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.620041419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:01:58.710741Z","caller":"traceutil/trace.go:171","msg":"trace[199317231] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:926; }","duration":"1.62009773s","start":"2026-02-26T21:01:57.090634Z","end":"2026-02-26T21:01:58.710732Z","steps":["trace[199317231] 'agreement among raft nodes before linearized reading' (duration: 1.619966427s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:58.710762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.077098Z","time spent":"1.633605641s","remote":"127.0.0.1:40810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:01:58.710778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.090621Z","time spent":"1.620148481s","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:01:59.211631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871035142427692,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:01:59.541102Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.065269619s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:01:59.541571Z","caller":"traceutil/trace.go:171","msg":"trace[764023284] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.065839503s","start":"2026-02-26T21:01:58.47571Z","end":"2026-02-26T21:01:59.54155Z","steps":["trace[764023284] 'process raft request' (duration: 1.06572332s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.541697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:58.475684Z","time spent":"1.065938746s","remote":"127.0.0.1:40908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5958,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:01:59.640985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99982584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-26T21:01:59.64106Z","caller":"traceutil/trace.go:171","msg":"trace[1676809188] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999964583s","start":"2026-02-26T21:01:57.641074Z","end":"2026-02-26T21:01:59.641039Z","steps":["trace[1676809188] 'agreement among raft nodes before linearized reading' (duration: 1.999870181s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.641135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.641055Z","time spent":"2.000072085s","remote":"127.0.0.1:40730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/26 21:01:59 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-26T21:01:59.899157Z","caller":"traceutil/trace.go:171","msg":"trace[392093704] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:965; }","duration":"1.18855944s","start":"2026-02-26T21:01:58.710574Z","end":"2026-02-26T21:01:59.899133Z","steps":["trace[392093704] 'read index received' (duration: 830.811305ms)","trace[392093704] 'applied index is now lower than readState.Index' (duration: 357.747235ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:01:59.899356Z","caller":"traceutil/trace.go:171","msg":"trace[1607894941] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.184058533s","start":"2026-02-26T21:01:58.71528Z","end":"2026-02-26T21:01:59.899338Z","steps":["trace[1607894941] 'process raft request' (duration: 1.183588441s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.899391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.083381807s","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-26T21:01:59.899471Z","caller":"traceutil/trace.go:171","msg":"trace[486820172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:929; }","duration":"2.083493329s","start":"2026-02-26T21:01:57.815957Z","end":"2026-02-26T21:01:59.899451Z","steps":["trace[486820172] 'agreement among raft nodes before linearized reading' (duration: 2.083204641s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.89949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:58.715257Z","time spent":"1.184153766s","remote":"127.0.0.1:40810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:01:59.899523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.815942Z","time spent":"2.083571801s","remote":"127.0.0.1:40990","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-26T21:01:59.899789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.390414877s","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":"warn","ts":"2026-02-26T21:01:59.899841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.742463027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:01:59.899886Z","caller":"traceutil/trace.go:171","msg":"trace[1172468712] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:929; }","duration":"2.742534519s","start":"2026-02-26T21:01:57.15734Z","end":"2026-02-26T21:01:59.899875Z","steps":["trace[1172468712] 'agreement among raft nodes before linearized reading' (duration: 2.742470277s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.899916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.157325Z","time spent":"2.7425853s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-26T21:01:59.899939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.39054001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:01:59.899972Z","caller":"traceutil/trace.go:171","msg":"trace[1127223145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:929; }","duration":"2.390596961s","start":"2026-02-26T21:01:57.509366Z","end":"2026-02-26T21:01:59.899963Z","steps":["trace[1127223145] 'agreement among raft nodes before linearized reading' (duration: 2.390215852s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.900012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.509354Z","time spent":"2.390650593s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T21:01:59.899977Z","caller":"traceutil/trace.go:171","msg":"trace[1541583270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:929; }","duration":"2.390544441s","start":"2026-02-26T21:01:57.509322Z","end":"2026-02-26T21:01:59.899867Z","steps":["trace[1541583270] 'agreement among raft nodes before linearized reading' (duration: 2.390132241s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.900285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.509304Z","time spent":"2.39096615s","remote":"127.0.0.1:40990","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-26T21:01:59.899809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.740765877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:01:59.900459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166111203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:01:59.900488Z","caller":"traceutil/trace.go:171","msg":"trace[387152517] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:929; }","duration":"2.741521405s","start":"2026-02-26T21:01:57.158955Z","end":"2026-02-26T21:01:59.900476Z","steps":["trace[387152517] 'agreement among raft nodes before linearized reading' (duration: 2.740713926s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.900611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.158938Z","time spent":"2.741655328s","remote":"127.0.0.1:41278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":101,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:01:59.900551Z","caller":"traceutil/trace.go:171","msg":"trace[1395561694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:929; }","duration":"2.166214285s","start":"2026-02-26T21:01:57.734315Z","end":"2026-02-26T21:01:59.900529Z","steps":["trace[1395561694] 'agreement among raft nodes before linearized reading' (duration: 2.165003556s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.900716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.734304Z","time spent":"2.166397559s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T21:01:59.90125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937369834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-26T21:01:59.901358Z","caller":"traceutil/trace.go:171","msg":"trace[1413223572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:929; }","duration":"1.937613329s","start":"2026-02-26T21:01:57.963734Z","end":"2026-02-26T21:01:59.901348Z","steps":["trace[1413223572] 'agreement among raft nodes before linearized reading' (duration: 1.936615016s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.901476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:57.963691Z","time spent":"1.937772143s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T21:01:59.901153Z","caller":"traceutil/trace.go:171","msg":"trace[868810674] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"626.699201ms","start":"2026-02-26T21:01:59.274439Z","end":"2026-02-26T21:01:59.901138Z","steps":["trace[868810674] 'process raft request' (duration: 624.630362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:01:59.901736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:59.274405Z","time spent":"627.284345ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:00.01773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.189935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:00.017838Z","caller":"traceutil/trace.go:171","msg":"trace[1345207756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"376.315328ms","start":"2026-02-26T21:01:59.641466Z","end":"2026-02-26T21:02:00.017782Z","steps":["trace[1345207756] 'agreement among raft nodes before linearized reading' (duration: 376.205286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.017895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:59.641444Z","time spent":"376.43917ms","remote":"127.0.0.1:40722","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-26T21:02:00.018035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234704517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:00.018144Z","caller":"traceutil/trace.go:171","msg":"trace[1417750344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:929; }","duration":"1.234861401s","start":"2026-02-26T21:01:58.783262Z","end":"2026-02-26T21:02:00.018123Z","steps":["trace[1417750344] 'agreement among raft nodes before linearized reading' (duration: 1.234653466s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.018218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:01:58.783221Z","time spent":"1.234987664s","remote":"127.0.0.1:40730","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-26T21:02:00.017637Z","caller":"traceutil/trace.go:171","msg":"trace[79530982] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"118.321874ms","start":"2026-02-26T21:01:59.89929Z","end":"2026-02-26T21:02:00.017612Z","steps":["trace[79530982] 'read index received' (duration: 20.243782ms)","trace[79530982] 'applied index is now lower than readState.Index' (duration: 98.076572ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:00.096737Z","caller":"traceutil/trace.go:171","msg":"trace[1470873867] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"192.582139ms","start":"2026-02-26T21:01:59.904142Z","end":"2026-02-26T21:02:00.096724Z","steps":["trace[1470873867] 'process raft request' (duration: 176.63472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.096954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.471825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:00.096984Z","caller":"traceutil/trace.go:171","msg":"trace[155107406] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:935; }","duration":"189.506156ms","start":"2026-02-26T21:01:59.907467Z","end":"2026-02-26T21:02:00.096973Z","steps":["trace[155107406] 'agreement among raft nodes before linearized reading' (duration: 189.459165ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:00.097031Z","caller":"traceutil/trace.go:171","msg":"trace[1691008966] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"190.737735ms","start":"2026-02-26T21:01:59.906288Z","end":"2026-02-26T21:02:00.097025Z","steps":["trace[1691008966] 'process raft request' (duration: 190.416378ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:00.097123Z","caller":"traceutil/trace.go:171","msg":"trace[1062436333] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"190.894739ms","start":"2026-02-26T21:01:59.906221Z","end":"2026-02-26T21:02:00.097116Z","steps":["trace[1062436333] 'process raft request' (duration: 190.439789ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:00.097097Z","caller":"traceutil/trace.go:171","msg":"trace[1762789534] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"189.427194ms","start":"2026-02-26T21:01:59.907605Z","end":"2026-02-26T21:02:00.097032Z","steps":["trace[1762789534] 'process raft request' (duration: 189.151938ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:00.097402Z","caller":"traceutil/trace.go:171","msg":"trace[510481025] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"190.154812ms","start":"2026-02-26T21:01:59.90724Z","end":"2026-02-26T21:02:00.097395Z","steps":["trace[510481025] 'process raft request' (duration: 189.482176ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:00.097452Z","caller":"traceutil/trace.go:171","msg":"trace[458965154] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"190.128681ms","start":"2026-02-26T21:01:59.907318Z","end":"2026-02-26T21:02:00.097447Z","steps":["trace[458965154] 'process raft request' (duration: 189.422915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.74969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.447559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:00.749791Z","caller":"traceutil/trace.go:171","msg":"trace[1746892774] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:975; }","duration":"224.030437ms","start":"2026-02-26T21:02:00.525751Z","end":"2026-02-26T21:02:00.749782Z","steps":["trace[1746892774] 'read index received' (duration: 20.54µs)","trace[1746892774] 'applied index is now lower than readState.Index' (duration: 224.009387ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:00.749808Z","caller":"traceutil/trace.go:171","msg":"trace[2045340468] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"651.022239ms","start":"2026-02-26T21:02:00.098768Z","end":"2026-02-26T21:02:00.74979Z","steps":["trace[2045340468] 'process raft request' (duration: 68.437987ms)","trace[2045340468] 'compare' (duration: 582.333947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:00.74984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.087878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:02:00.749852Z","caller":"traceutil/trace.go:171","msg":"trace[1098207372] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:937; }","duration":"224.12694ms","start":"2026-02-26T21:02:00.525721Z","end":"2026-02-26T21:02:00.749848Z","steps":["trace[1098207372] 'agreement among raft nodes before linearized reading' (duration: 224.086229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.749895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:00.098756Z","time spent":"651.096411ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:02:00.749971Z","caller":"traceutil/trace.go:171","msg":"trace[1370984206] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"649.798321ms","start":"2026-02-26T21:02:00.100169Z","end":"2026-02-26T21:02:00.749967Z","steps":["trace[1370984206] 'process raft request' (duration: 649.583236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:00.749994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:00.100163Z","time spent":"649.819481ms","remote":"127.0.0.1:40810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T21:02:01.015983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.020199ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:01.016175Z","caller":"traceutil/trace.go:171","msg":"trace[1429939206] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:977; }","duration":"145.657054ms","start":"2026-02-26T21:02:00.870503Z","end":"2026-02-26T21:02:01.01616Z","steps":["trace[1429939206] 'read index received' (duration: 5.392999ms)","trace[1429939206] 'applied index is now lower than readState.Index' (duration: 140.263305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:01.016266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.756886ms","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-26T21:02:01.016267Z","caller":"traceutil/trace.go:171","msg":"trace[1127992863] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"258.829263ms","start":"2026-02-26T21:02:00.757411Z","end":"2026-02-26T21:02:01.01624Z","steps":["trace[1127992863] 'process raft request' (duration: 258.68468ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:01.016241Z","caller":"traceutil/trace.go:171","msg":"trace[623222384] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"261.121929ms","start":"2026-02-26T21:02:00.755082Z","end":"2026-02-26T21:02:01.016204Z","steps":["trace[623222384] 'process raft request' (duration: 120.803112ms)","trace[623222384] 'compare' (duration: 139.849546ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:01.016301Z","caller":"traceutil/trace.go:171","msg":"trace[1618503639] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:939; }","duration":"145.846168ms","start":"2026-02-26T21:02:00.870446Z","end":"2026-02-26T21:02:01.016293Z","steps":["trace[1618503639] 'agreement among raft nodes before linearized reading' (duration: 145.768386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:02.002761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.606039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T21:02:02.002832Z","caller":"traceutil/trace.go:171","msg":"trace[867887515] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:939; }","duration":"454.726462ms","start":"2026-02-26T21:02:01.54809Z","end":"2026-02-26T21:02:02.002816Z","steps":["trace[867887515] 'count revisions from in-memory index tree' (duration: 454.456395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:02.00287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:01.548068Z","time spent":"454.792523ms","remote":"127.0.0.1:40916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-02-26T21:02:02.002913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.969421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:02.002972Z","caller":"traceutil/trace.go:171","msg":"trace[518346366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"361.084954ms","start":"2026-02-26T21:02:01.641872Z","end":"2026-02-26T21:02:02.002957Z","steps":["trace[518346366] 'range keys from in-memory index tree' (duration: 360.866089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:02.003014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:01.641855Z","time spent":"361.136676ms","remote":"127.0.0.1:40730","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-26T21:02:02.894861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415871035142427719,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T21:02:03.106235Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.002550347s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T21:02:03.106647Z","caller":"traceutil/trace.go:171","msg":"trace[650938664] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"1.003014838s","start":"2026-02-26T21:02:02.103604Z","end":"2026-02-26T21:02:03.106619Z","steps":["trace[650938664] 'process raft request' (duration: 1.002806553s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:03.106791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.103592Z","time spent":"1.003124751s","remote":"127.0.0.1:40990","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-26T21:02:04.05105Z","caller":"traceutil/trace.go:171","msg":"trace[1821934396] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"1.946097032s","start":"2026-02-26T21:02:02.104932Z","end":"2026-02-26T21:02:04.051029Z","steps":["trace[1821934396] 'process raft request' (duration: 1.94603281s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:04.051188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.104919Z","time spent":"1.946192064s","remote":"127.0.0.1:40990","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-26T21:02:04.051224Z","caller":"traceutil/trace.go:171","msg":"trace[371764714] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"1.946544482s","start":"2026-02-26T21:02:02.104651Z","end":"2026-02-26T21:02:04.051195Z","steps":["trace[371764714] 'process raft request' (duration: 1.946153572s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:04.05135Z","caller":"traceutil/trace.go:171","msg":"trace[3991722] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:979; }","duration":"1.657137741s","start":"2026-02-26T21:02:02.394188Z","end":"2026-02-26T21:02:04.051326Z","steps":["trace[3991722] 'read index received' (duration: 712.191103ms)","trace[3991722] 'applied index is now lower than readState.Index' (duration: 944.945488ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:04.051403Z","caller":"traceutil/trace.go:171","msg":"trace[217334500] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"1.946668406s","start":"2026-02-26T21:02:02.104725Z","end":"2026-02-26T21:02:04.051393Z","steps":["trace[217334500] 'process raft request' (duration: 1.946203744s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:04.051462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.104716Z","time spent":"1.946713997s","remote":"127.0.0.1:40990","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-26T21:02:04.051475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.104639Z","time spent":"1.946677606s","remote":"127.0.0.1:40990","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-26T21:02:04.051569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657372517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:02:04.051599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.244002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:04.051602Z","caller":"traceutil/trace.go:171","msg":"trace[847735777] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:944; }","duration":"1.657436859s","start":"2026-02-26T21:02:02.394156Z","end":"2026-02-26T21:02:04.051593Z","steps":["trace[847735777] 'agreement among raft nodes before linearized reading' (duration: 1.657376787s)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:04.051634Z","caller":"traceutil/trace.go:171","msg":"trace[241182363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"412.329773ms","start":"2026-02-26T21:02:03.639294Z","end":"2026-02-26T21:02:04.051623Z","steps":["trace[241182363] 'agreement among raft nodes before linearized reading' (duration: 412.258342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:04.051643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.394138Z","time spent":"1.657497031s","remote":"127.0.0.1:41228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:02:04.05164Z","caller":"traceutil/trace.go:171","msg":"trace[718141436] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"1.9455504s","start":"2026-02-26T21:02:02.106077Z","end":"2026-02-26T21:02:04.051627Z","steps":["trace[718141436] 'process raft request' (duration: 1.944917755s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:04.051666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:03.639247Z","time spent":"412.412056ms","remote":"127.0.0.1:40730","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-26T21:02:04.051961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:02.106066Z","time spent":"1.945602691s","remote":"127.0.0.1:40990","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-26T21:02:04.969841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:04.497431Z","time spent":"472.406753ms","remote":"127.0.0.1:40772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-26T21:02:04.970208Z","caller":"traceutil/trace.go:171","msg":"trace[799898996] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:985; }","duration":"328.694556ms","start":"2026-02-26T21:02:04.641468Z","end":"2026-02-26T21:02:04.970162Z","steps":["trace[799898996] 'read index received' (duration: 328.688576ms)","trace[799898996] 'applied index is now lower than readState.Index' (duration: 4.48µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:04.970347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.86651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:04.970385Z","caller":"traceutil/trace.go:171","msg":"trace[1825730925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"328.956862ms","start":"2026-02-26T21:02:04.641419Z","end":"2026-02-26T21:02:04.970376Z","steps":["trace[1825730925] 'agreement among raft nodes before linearized reading' (duration: 328.829649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:04.970419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:04.641398Z","time spent":"329.014373ms","remote":"127.0.0.1:40730","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-26T21:02:06.32175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.827366ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:06.321827Z","caller":"traceutil/trace.go:171","msg":"trace[352824140] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:944; }","duration":"375.913998ms","start":"2026-02-26T21:02:05.945902Z","end":"2026-02-26T21:02:06.321816Z","steps":["trace[352824140] 'range keys from in-memory index tree' (duration: 375.815966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:06.322137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001906441s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:06.322171Z","caller":"traceutil/trace.go:171","msg":"trace[1487377905] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"1.210802359s","start":"2026-02-26T21:02:05.111363Z","end":"2026-02-26T21:02:06.322166Z","steps":["trace[1487377905] 'read index received' (duration: 208.770164ms)","trace[1487377905] 'applied index is now lower than readState.Index' (duration: 1.002031525s)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:06.322209Z","caller":"traceutil/trace.go:171","msg":"trace[656921829] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"1.35089574s","start":"2026-02-26T21:02:04.971307Z","end":"2026-02-26T21:02:06.322202Z","steps":["trace[656921829] 'process raft request' (duration: 348.885926ms)","trace[656921829] 'compare' (duration: 1.001731037s)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:06.322262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:04.971289Z","time spent":"1.350936211s","remote":"127.0.0.1:40772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:06.322432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.263784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:06.322961Z","caller":"traceutil/trace.go:171","msg":"trace[780077819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"680.85423ms","start":"2026-02-26T21:02:05.642084Z","end":"2026-02-26T21:02:06.322938Z","steps":["trace[780077819] 'agreement among raft nodes before linearized reading' (duration: 680.304726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:06.323041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:05.642056Z","time spent":"680.959422ms","remote":"127.0.0.1:40730","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-26T21:02:06.322511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211157737s","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-26T21:02:06.32315Z","caller":"traceutil/trace.go:171","msg":"trace[1160003360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:945; }","duration":"1.211773202s","start":"2026-02-26T21:02:05.111349Z","end":"2026-02-26T21:02:06.323122Z","steps":["trace[1160003360] 'agreement among raft nodes before linearized reading' (duration: 1.211093525s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:06.322545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.364244ms","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-26T21:02:06.323252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:05.111296Z","time spent":"1.211927567s","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T21:02:06.323263Z","caller":"traceutil/trace.go:171","msg":"trace[1579470160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:945; }","duration":"267.088851ms","start":"2026-02-26T21:02:06.056158Z","end":"2026-02-26T21:02:06.323247Z","steps":["trace[1579470160] 'agreement among raft nodes before linearized reading' (duration: 266.362563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:06.322586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.444205ms","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-26T21:02:06.323375Z","caller":"traceutil/trace.go:171","msg":"trace[653113552] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"267.246335ms","start":"2026-02-26T21:02:06.056115Z","end":"2026-02-26T21:02:06.323361Z","steps":["trace[653113552] 'agreement among raft nodes before linearized reading' (duration: 266.446235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:06.322633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.992158ms","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-26T21:02:06.323482Z","caller":"traceutil/trace.go:171","msg":"trace[1826642916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"267.867869ms","start":"2026-02-26T21:02:06.055591Z","end":"2026-02-26T21:02:06.323459Z","steps":["trace[1826642916] 'agreement among raft nodes before linearized reading' (duration: 267.001168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:06.322676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.283435ms","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-26T21:02:06.323648Z","caller":"traceutil/trace.go:171","msg":"trace[572705121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:945; }","duration":"268.278079ms","start":"2026-02-26T21:02:06.055358Z","end":"2026-02-26T21:02:06.323636Z","steps":["trace[572705121] 'agreement among raft nodes before linearized reading' (duration: 267.289235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:06.322712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.315838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-26T21:02:06.323749Z","caller":"traceutil/trace.go:171","msg":"trace[102888272] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:945; }","duration":"303.376753ms","start":"2026-02-26T21:02:06.020365Z","end":"2026-02-26T21:02:06.323742Z","steps":["trace[102888272] 'agreement among raft nodes before linearized reading' (duration: 302.318597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:06.323794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.020349Z","time spent":"303.423605ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-26T21:02:07.092692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.728292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:07.092818Z","caller":"traceutil/trace.go:171","msg":"trace[349463064] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"765.965862ms","start":"2026-02-26T21:02:06.326822Z","end":"2026-02-26T21:02:07.092788Z","steps":["trace[349463064] 'process raft request' (duration: 417.050256ms)","trace[349463064] 'compare' (duration: 348.565498ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:07.092788Z","caller":"traceutil/trace.go:171","msg":"trace[1694502009] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"765.504351ms","start":"2026-02-26T21:02:06.327267Z","end":"2026-02-26T21:02:07.092772Z","steps":["trace[1694502009] 'read index received' (duration: 416.647316ms)","trace[1694502009] 'applied index is now lower than readState.Index' (duration: 348.855995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:07.092966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.678436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-26T21:02:07.093037Z","caller":"traceutil/trace.go:171","msg":"trace[281645467] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:946; }","duration":"765.787778ms","start":"2026-02-26T21:02:06.327233Z","end":"2026-02-26T21:02:07.093021Z","steps":["trace[281645467] 'agreement among raft nodes before linearized reading' (duration: 765.664165ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.093042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.326802Z","time spent":"766.144566ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:07.093093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.327219Z","time spent":"765.85361ms","remote":"127.0.0.1:40904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-26T21:02:07.244087Z","caller":"traceutil/trace.go:171","msg":"trace[1023397146] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"916.796139ms","start":"2026-02-26T21:02:06.32726Z","end":"2026-02-26T21:02:07.244056Z","steps":["trace[1023397146] 'process raft request' (duration: 872.506767ms)","trace[1023397146] 'compare' (duration: 44.035096ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:07.244213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.327248Z","time spent":"916.901121ms","remote":"127.0.0.1:40990","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-26T21:02:07.244087Z","caller":"traceutil/trace.go:171","msg":"trace[469536846] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"915.715903ms","start":"2026-02-26T21:02:06.328354Z","end":"2026-02-26T21:02:07.24407Z","steps":["trace[469536846] 'process raft request' (duration: 915.644032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.244343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.9377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:07.244371Z","caller":"traceutil/trace.go:171","msg":"trace[523360491] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:951; }","duration":"464.017302ms","start":"2026-02-26T21:02:06.780345Z","end":"2026-02-26T21:02:07.244362Z","steps":["trace[523360491] 'agreement among raft nodes before linearized reading' (duration: 463.9469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.244648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.780325Z","time spent":"464.312011ms","remote":"127.0.0.1:50456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:02:07.244411Z","caller":"traceutil/trace.go:171","msg":"trace[565052804] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"915.61189ms","start":"2026-02-26T21:02:06.32879Z","end":"2026-02-26T21:02:07.244402Z","steps":["trace[565052804] 'process raft request' (duration: 915.247352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.244744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.328778Z","time spent":"915.933198ms","remote":"127.0.0.1:40990","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-26T21:02:07.244397Z","caller":"traceutil/trace.go:171","msg":"trace[1004828216] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"915.484567ms","start":"2026-02-26T21:02:06.328863Z","end":"2026-02-26T21:02:07.244348Z","steps":["trace[1004828216] 'process raft request' (duration: 915.350824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.244757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.328342Z","time spent":"915.966509ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:02:07.244448Z","caller":"traceutil/trace.go:171","msg":"trace[2074113646] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"915.447256ms","start":"2026-02-26T21:02:06.328996Z","end":"2026-02-26T21:02:07.244443Z","steps":["trace[2074113646] 'process raft request' (duration: 915.246731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:07.244903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.328857Z","time spent":"915.966049ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T21:02:07.244278Z","caller":"traceutil/trace.go:171","msg":"trace[1668041342] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:987; }","duration":"151.329028ms","start":"2026-02-26T21:02:07.092941Z","end":"2026-02-26T21:02:07.24427Z","steps":["trace[1668041342] 'read index received' (duration: 106.80711ms)","trace[1668041342] 'applied index is now lower than readState.Index' (duration: 44.521128ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:07.244968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:06.328988Z","time spent":"915.895617ms","remote":"127.0.0.1:40990","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-26T21:02:07.245075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.430677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T21:02:07.245102Z","caller":"traceutil/trace.go:171","msg":"trace[2109492568] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:951; }","duration":"150.459737ms","start":"2026-02-26T21:02:07.094636Z","end":"2026-02-26T21:02:07.245096Z","steps":["trace[2109492568] 'agreement among raft nodes before linearized reading' (duration: 150.351735ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:08.595134Z","caller":"traceutil/trace.go:171","msg":"trace[674137505] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"244.025612ms","start":"2026-02-26T21:02:08.351084Z","end":"2026-02-26T21:02:08.595109Z","steps":["trace[674137505] 'process raft request' (duration: 240.116329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:10.056581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.435445ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T21:02:10.056745Z","caller":"traceutil/trace.go:171","msg":"trace[1885691115] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"231.484803ms","start":"2026-02-26T21:02:09.825237Z","end":"2026-02-26T21:02:10.056722Z","steps":["trace[1885691115] 'read index received' (duration: 120.890944ms)","trace[1885691115] 'applied index is now lower than readState.Index' (duration: 110.592059ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:10.056835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.590646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:02:10.056872Z","caller":"traceutil/trace.go:171","msg":"trace[1122128039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"231.667417ms","start":"2026-02-26T21:02:09.825192Z","end":"2026-02-26T21:02:10.056859Z","steps":["trace[1122128039] 'agreement among raft nodes before linearized reading' (duration: 231.590486ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:11.471945Z","caller":"traceutil/trace.go:171","msg":"trace[2007868159] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"147.240912ms","start":"2026-02-26T21:02:11.32467Z","end":"2026-02-26T21:02:11.47191Z","steps":["trace[2007868159] 'process raft request' (duration: 143.466101ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:11.47205Z","caller":"traceutil/trace.go:171","msg":"trace[1862854711] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"147.120939ms","start":"2026-02-26T21:02:11.324883Z","end":"2026-02-26T21:02:11.472004Z","steps":["trace[1862854711] 'process raft request' (duration: 147.008996ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:11.471948Z","caller":"traceutil/trace.go:171","msg":"trace[492567557] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"147.106498ms","start":"2026-02-26T21:02:11.324818Z","end":"2026-02-26T21:02:11.471925Z","steps":["trace[492567557] 'process raft request' (duration: 146.992224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:12.296664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.832087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:12.296868Z","caller":"traceutil/trace.go:171","msg":"trace[315386913] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"505.57269ms","start":"2026-02-26T21:02:11.791263Z","end":"2026-02-26T21:02:12.296836Z","steps":["trace[315386913] 'process raft request' (duration: 195.514068ms)","trace[315386913] 'compare' (duration: 309.722725ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:12.296985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:11.791225Z","time spent":"505.708344ms","remote":"127.0.0.1:40810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":910,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T21:02:12.297016Z","caller":"traceutil/trace.go:171","msg":"trace[124207400] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"503.538623ms","start":"2026-02-26T21:02:11.793444Z","end":"2026-02-26T21:02:12.296983Z","steps":["trace[124207400] 'process raft request' (duration: 503.282077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:12.297192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:11.79343Z","time spent":"503.675756ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:12.616214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.043782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:12.61634Z","caller":"traceutil/trace.go:171","msg":"trace[500963186] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"316.170077ms","start":"2026-02-26T21:02:12.300143Z","end":"2026-02-26T21:02:12.616313Z","steps":["trace[500963186] 'process raft request' (duration: 124.960921ms)","trace[500963186] 'compare' (duration: 190.95992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:12.616387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:02:12.300128Z","time spent":"316.237649ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:02:12.834257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.416457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:02:12.834363Z","caller":"traceutil/trace.go:171","msg":"trace[1667029024] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"125.010003ms","start":"2026-02-26T21:02:12.709331Z","end":"2026-02-26T21:02:12.834341Z","steps":["trace[1667029024] 'process raft request' (duration: 19.466423ms)","trace[1667029024] 'compare' (duration: 105.325295ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:13.003081Z","caller":"traceutil/trace.go:171","msg":"trace[1374924435] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"123.982508ms","start":"2026-02-26T21:02:12.879067Z","end":"2026-02-26T21:02:13.003049Z","steps":["trace[1374924435] 'process raft request' (duration: 123.928786ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:13.003396Z","caller":"traceutil/trace.go:171","msg":"trace[1045116084] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"133.887564ms","start":"2026-02-26T21:02:12.86946Z","end":"2026-02-26T21:02:13.003347Z","steps":["trace[1045116084] 'process raft request' (duration: 103.176083ms)","trace[1045116084] 'compare' (duration: 30.225429ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:02:13.003556Z","caller":"traceutil/trace.go:171","msg":"trace[1646097122] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"124.877239ms","start":"2026-02-26T21:02:12.878656Z","end":"2026-02-26T21:02:13.003534Z","steps":["trace[1646097122] 'read index received' (duration: 93.991775ms)","trace[1646097122] 'applied index is now lower than readState.Index' (duration: 30.883684ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:02:13.003789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.110534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-26T21:02:13.003839Z","caller":"traceutil/trace.go:171","msg":"trace[975665505] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:990; }","duration":"125.205546ms","start":"2026-02-26T21:02:12.878617Z","end":"2026-02-26T21:02:13.003823Z","steps":["trace[975665505] 'agreement among raft nodes before linearized reading' (duration: 124.97727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:59.933943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.140882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T21:02:59.933943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.040435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-26T21:02:59.934016Z","caller":"traceutil/trace.go:171","msg":"trace[334331772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1189; }","duration":"116.244855ms","start":"2026-02-26T21:02:59.817754Z","end":"2026-02-26T21:02:59.933999Z","steps":["trace[334331772] 'range keys from in-memory index tree' (duration: 115.785704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:02:59.934054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.84015ms","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-26T21:02:59.934112Z","caller":"traceutil/trace.go:171","msg":"trace[1040699287] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1189; }","duration":"119.938532ms","start":"2026-02-26T21:02:59.814158Z","end":"2026-02-26T21:02:59.934097Z","steps":["trace[1040699287] 'range keys from in-memory index tree' (duration: 119.338148ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:02:59.934041Z","caller":"traceutil/trace.go:171","msg":"trace[30779299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1189; }","duration":"120.167917ms","start":"2026-02-26T21:02:59.813849Z","end":"2026-02-26T21:02:59.934017Z","steps":["trace[30779299] 'range keys from in-memory index tree' (duration: 119.671285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:00.817348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.607213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:00.817403Z","caller":"traceutil/trace.go:171","msg":"trace[1060270129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1192; }","duration":"177.672994ms","start":"2026-02-26T21:03:00.639718Z","end":"2026-02-26T21:03:00.817391Z","steps":["trace[1060270129] 'range keys from in-memory index tree' (duration: 177.537142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:00.817659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.856667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-26T21:03:00.817686Z","caller":"traceutil/trace.go:171","msg":"trace[861196114] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1192; }","duration":"383.919139ms","start":"2026-02-26T21:03:00.433757Z","end":"2026-02-26T21:03:00.817676Z","steps":["trace[861196114] 'range keys from in-memory index tree' (duration: 383.707524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:00.817701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:00.433735Z","time spent":"383.96126ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-26T21:03:00.954094Z","caller":"traceutil/trace.go:171","msg":"trace[612589893] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"132.692515ms","start":"2026-02-26T21:03:00.821375Z","end":"2026-02-26T21:03:00.954068Z","steps":["trace[612589893] 'process raft request' (duration: 132.565542ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:01.426144Z","caller":"traceutil/trace.go:171","msg":"trace[763794108] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"242.490987ms","start":"2026-02-26T21:03:01.18363Z","end":"2026-02-26T21:03:01.426121Z","steps":["trace[763794108] 'process raft request' (duration: 242.23375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:02.865381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.478927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:02.865456Z","caller":"traceutil/trace.go:171","msg":"trace[274282184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1200; }","duration":"224.58921ms","start":"2026-02-26T21:03:02.64085Z","end":"2026-02-26T21:03:02.865439Z","steps":["trace[274282184] 'range keys from in-memory index tree' (duration: 224.405596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:02.865552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.625586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-26T21:03:02.865618Z","caller":"traceutil/trace.go:171","msg":"trace[2133072994] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1200; }","duration":"482.710658ms","start":"2026-02-26T21:03:02.382885Z","end":"2026-02-26T21:03:02.865595Z","steps":["trace[2133072994] 'range keys from in-memory index tree' (duration: 482.437991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:02.865387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.307577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-26T21:03:02.865659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:02.382865Z","time spent":"482.78565ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-26T21:03:02.865666Z","caller":"traceutil/trace.go:171","msg":"trace[816351967] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1200; }","duration":"413.639975ms","start":"2026-02-26T21:03:02.452014Z","end":"2026-02-26T21:03:02.865654Z","steps":["trace[816351967] 'count revisions from in-memory index tree' (duration: 413.239895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:02.86571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:02.451999Z","time spent":"413.691156ms","remote":"127.0.0.1:40850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-02-26T21:03:02.98296Z","caller":"traceutil/trace.go:171","msg":"trace[727889094] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"112.772061ms","start":"2026-02-26T21:03:02.870169Z","end":"2026-02-26T21:03:02.982941Z","steps":["trace[727889094] 'process raft request' (duration: 110.819555ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:03.909741Z","caller":"traceutil/trace.go:171","msg":"trace[398720903] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"354.760856ms","start":"2026-02-26T21:03:03.554953Z","end":"2026-02-26T21:03:03.909714Z","steps":["trace[398720903] 'read index received' (duration: 354.581872ms)","trace[398720903] 'applied index is now lower than readState.Index' (duration: 177.844µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:03.909777Z","caller":"traceutil/trace.go:171","msg":"trace[1608438337] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"464.921405ms","start":"2026-02-26T21:03:03.444836Z","end":"2026-02-26T21:03:03.909758Z","steps":["trace[1608438337] 'process raft request' (duration: 464.727551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:03.909964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.957501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-26T21:03:03.909973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.151327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T21:03:03.91001Z","caller":"traceutil/trace.go:171","msg":"trace[311301672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1203; }","duration":"355.067703ms","start":"2026-02-26T21:03:03.554929Z","end":"2026-02-26T21:03:03.909997Z","steps":["trace[311301672] 'agreement among raft nodes before linearized reading' (duration: 354.906429ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:03.910037Z","caller":"traceutil/trace.go:171","msg":"trace[1505465383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1203; }","duration":"268.269999ms","start":"2026-02-26T21:03:03.64175Z","end":"2026-02-26T21:03:03.91002Z","steps":["trace[1505465383] 'agreement among raft nodes before linearized reading' (duration: 268.146166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:03.910056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:03.554918Z","time spent":"355.126575ms","remote":"127.0.0.1:40990","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-26T21:03:03.910171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T21:03:03.444818Z","time spent":"465.14954ms","remote":"127.0.0.1:40990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T21:03:03.910263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.116907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-26T21:03:03.910293Z","caller":"traceutil/trace.go:171","msg":"trace[835910256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1203; }","duration":"250.179709ms","start":"2026-02-26T21:03:03.660106Z","end":"2026-02-26T21:03:03.910286Z","steps":["trace[835910256] 'agreement among raft nodes before linearized reading' (duration: 250.072896ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:04.667929Z","caller":"traceutil/trace.go:171","msg":"trace[1743748640] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"152.502637ms","start":"2026-02-26T21:03:04.515402Z","end":"2026-02-26T21:03:04.667905Z","steps":["trace[1743748640] 'process raft request' (duration: 152.21932ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:26.366815Z","caller":"traceutil/trace.go:171","msg":"trace[289600796] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"142.453737ms","start":"2026-02-26T21:03:26.224329Z","end":"2026-02-26T21:03:26.366783Z","steps":["trace[289600796] 'read index received' (duration: 99.30009ms)","trace[289600796] 'applied index is now lower than readState.Index' (duration: 43.152417ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T21:03:26.367106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.750104ms","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-26T21:03:26.367181Z","caller":"traceutil/trace.go:171","msg":"trace[2060006537] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1297; }","duration":"142.864627ms","start":"2026-02-26T21:03:26.224299Z","end":"2026-02-26T21:03:26.367164Z","steps":["trace[2060006537] 'agreement among raft nodes before linearized reading' (duration: 142.641792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:26.367297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.759899ms","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-26T21:03:26.366894Z","caller":"traceutil/trace.go:171","msg":"trace[104112843] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"143.216036ms","start":"2026-02-26T21:03:26.223647Z","end":"2026-02-26T21:03:26.366863Z","steps":["trace[104112843] 'process raft request' (duration: 100.015078ms)","trace[104112843] 'compare' (duration: 42.978663ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:03:26.367364Z","caller":"traceutil/trace.go:171","msg":"trace[741037901] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1297; }","duration":"135.921742ms","start":"2026-02-26T21:03:26.231425Z","end":"2026-02-26T21:03:26.367347Z","steps":["trace[741037901] 'agreement among raft nodes before linearized reading' (duration: 135.546844ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:54.901346Z","caller":"traceutil/trace.go:171","msg":"trace[862165451] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"196.178505ms","start":"2026-02-26T21:03:54.705136Z","end":"2026-02-26T21:03:54.901314Z","steps":["trace[862165451] 'process raft request' (duration: 195.920809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T21:03:57.098406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.731038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T21:03:57.098639Z","caller":"traceutil/trace.go:171","msg":"trace[614847005] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"148.683978ms","start":"2026-02-26T21:03:56.949924Z","end":"2026-02-26T21:03:57.098608Z","steps":["trace[614847005] 'process raft request' (duration: 148.626607ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:03:57.098683Z","caller":"traceutil/trace.go:171","msg":"trace[92264200] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"149.348354ms","start":"2026-02-26T21:03:56.9493Z","end":"2026-02-26T21:03:57.098648Z","steps":["trace[92264200] 'process raft request' (duration: 32.274758ms)","trace[92264200] 'compare' (duration: 116.566624ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T21:05:21.633832Z","caller":"traceutil/trace.go:171","msg":"trace[1382736621] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"101.113918ms","start":"2026-02-26T21:05:21.532525Z","end":"2026-02-26T21:05:21.633639Z","steps":["trace[1382736621] 'process raft request' (duration: 101.012136ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:22.312332Z","caller":"traceutil/trace.go:171","msg":"trace[1191108680] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"265.637545ms","start":"2026-02-26T21:05:22.046668Z","end":"2026-02-26T21:05:22.312306Z","steps":["trace[1191108680] 'process raft request' (duration: 265.152794ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T21:05:34.824575Z","caller":"traceutil/trace.go:171","msg":"trace[686299526] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"100.023903ms","start":"2026-02-26T21:05:34.724529Z","end":"2026-02-26T21:05:34.824553Z","steps":["trace[686299526] 'process raft request' (duration: 99.830738ms)"],"step_count":1}