{"level":"warn","ts":"2026-02-19T02:03:11.545072Z","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-19T02:03:11.545149Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-19T02:03:11.545205Z","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-19T02:03:11.545213Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-19T02:03:11.545233Z","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-19T02:03:11.545811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-19T02:03:11.5459Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-19T02:03:11.55024Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.113505ms"} {"level":"info","ts":"2026-02-19T02:03:11.557387Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-19T02:03:11.557463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-19T02:03:11.557488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-19T02:03:11.557503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-19T02:03:11.557511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-19T02:03:11.557554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-19T02:03:11.5634Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-19T02:03:11.565118Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-19T02:03:11.568491Z","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-19T02:03:11.57025Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-19T02:03:11.575617Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-19T02:03:11.575653Z","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-19T02:03:11.575764Z","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-19T02:03:11.575786Z","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-19T02:03:11.576216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-19T02:03:11.577795Z","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-19T02:03:11.577991Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-19T02:03:11.578037Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-19T02:03:11.578143Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-19T02:03:11.578568Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-19T02:03:11.578652Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-19T02:03:12.458079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-19T02:03:12.458142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-19T02:03:12.45818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-19T02:03:12.4582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-19T02:03:12.458211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-19T02:03:12.458227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-19T02:03:12.458245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-19T02:03:12.459368Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-19T02:03:12.459373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:03:12.459485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-19T02:03:12.459409Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:03:12.459698Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-19T02:03:12.459732Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-19T02:03:12.461032Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:03:12.461096Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-19T02:03:12.461135Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:03:12.461169Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-19T02:03:12.462451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-19T02:03:26.169658Z","caller":"traceutil/trace.go:171","msg":"trace[289393662] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"261.467968ms","start":"2026-02-19T02:03:25.908164Z","end":"2026-02-19T02:03:26.169632Z","steps":["trace[289393662] 'read index received' (duration: 261.271694ms)","trace[289393662] 'applied index is now lower than readState.Index' (duration: 195.404µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:03:26.169797Z","caller":"traceutil/trace.go:171","msg":"trace[1783917263] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"267.17585ms","start":"2026-02-19T02:03:25.90261Z","end":"2026-02-19T02:03:26.169786Z","steps":["trace[1783917263] 'process raft request' (duration: 266.875254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:26.170238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.00856ms","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-19T02:03:26.170315Z","caller":"traceutil/trace.go:171","msg":"trace[1960562696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"262.151634ms","start":"2026-02-19T02:03:25.908148Z","end":"2026-02-19T02:03:26.1703Z","steps":["trace[1960562696] 'agreement among raft nodes before linearized reading' (duration: 261.99017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:26.451796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.554513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:03:26.451863Z","caller":"traceutil/trace.go:171","msg":"trace[753513797] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"227.429973ms","start":"2026-02-19T02:03:26.224424Z","end":"2026-02-19T02:03:26.451854Z","steps":["trace[753513797] 'read index received' (duration: 108.41659ms)","trace[753513797] 'applied index is now lower than readState.Index' (duration: 119.012653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:03:26.451972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.567746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-19T02:03:26.451991Z","caller":"traceutil/trace.go:171","msg":"trace[943782984] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:407; }","duration":"227.587536ms","start":"2026-02-19T02:03:26.224399Z","end":"2026-02-19T02:03:26.451986Z","steps":["trace[943782984] 'agreement among raft nodes before linearized reading' (duration: 227.518145ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:03:26.451976Z","caller":"traceutil/trace.go:171","msg":"trace[1252558407] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"277.000825ms","start":"2026-02-19T02:03:26.174926Z","end":"2026-02-19T02:03:26.451927Z","steps":["trace[1252558407] 'process raft request' (duration: 157.950431ms)","trace[1252558407] 'compare' (duration: 118.464131ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:03:26.452277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.967762ms","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-19T02:03:26.452391Z","caller":"traceutil/trace.go:171","msg":"trace[944651293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"171.120696ms","start":"2026-02-19T02:03:26.281245Z","end":"2026-02-19T02:03:26.452366Z","steps":["trace[944651293] 'agreement among raft nodes before linearized reading' (duration: 170.922291ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:03:26.590507Z","caller":"traceutil/trace.go:171","msg":"trace[1280278449] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"134.789277ms","start":"2026-02-19T02:03:26.455694Z","end":"2026-02-19T02:03:26.590483Z","steps":["trace[1280278449] 'process raft request' (duration: 134.648083ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:03:26.598137Z","caller":"traceutil/trace.go:171","msg":"trace[1228654477] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"140.12847ms","start":"2026-02-19T02:03:26.457987Z","end":"2026-02-19T02:03:26.598115Z","steps":["trace[1228654477] 'process raft request' (duration: 139.922065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:28.393207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.183412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:03:28.39344Z","caller":"traceutil/trace.go:171","msg":"trace[789274006] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:452; }","duration":"169.682101ms","start":"2026-02-19T02:03:28.223742Z","end":"2026-02-19T02:03:28.393424Z","steps":["trace[789274006] 'read index received' (duration: 68.263123ms)","trace[789274006] 'applied index is now lower than readState.Index' (duration: 101.418188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:03:28.393534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.783334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-19T02:03:28.393562Z","caller":"traceutil/trace.go:171","msg":"trace[1180209579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:436; }","duration":"169.844456ms","start":"2026-02-19T02:03:28.223708Z","end":"2026-02-19T02:03:28.393553Z","steps":["trace[1180209579] 'agreement among raft nodes before linearized reading' (duration: 169.771763ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:03:28.393652Z","caller":"traceutil/trace.go:171","msg":"trace[1895550289] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"200.511112ms","start":"2026-02-19T02:03:28.193116Z","end":"2026-02-19T02:03:28.393627Z","steps":["trace[1895550289] 'process raft request' (duration: 200.255006ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:03:28.393722Z","caller":"traceutil/trace.go:171","msg":"trace[2133619245] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"212.528959ms","start":"2026-02-19T02:03:28.181174Z","end":"2026-02-19T02:03:28.393703Z","steps":["trace[2133619245] 'process raft request' (duration: 110.784163ms)","trace[2133619245] 'compare' (duration: 100.971668ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:03:28.393749Z","caller":"traceutil/trace.go:171","msg":"trace[2014031389] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"212.492139ms","start":"2026-02-19T02:03:28.181234Z","end":"2026-02-19T02:03:28.393726Z","steps":["trace[2014031389] 'process raft request' (duration: 212.070448ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:03:38.668418Z","caller":"traceutil/trace.go:171","msg":"trace[1642651048] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"230.460182ms","start":"2026-02-19T02:03:38.43794Z","end":"2026-02-19T02:03:38.6684Z","steps":["trace[1642651048] 'read index received' (duration: 230.386951ms)","trace[1642651048] 'applied index is now lower than readState.Index' (duration: 72.451µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:03:38.668495Z","caller":"traceutil/trace.go:171","msg":"trace[580673607] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"231.515867ms","start":"2026-02-19T02:03:38.436933Z","end":"2026-02-19T02:03:38.668449Z","steps":["trace[580673607] 'process raft request' (duration: 231.367033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:38.668551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.60266ms","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-19T02:03:38.668583Z","caller":"traceutil/trace.go:171","msg":"trace[395763775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"166.680782ms","start":"2026-02-19T02:03:38.501892Z","end":"2026-02-19T02:03:38.668573Z","steps":["trace[395763775] 'agreement among raft nodes before linearized reading' (duration: 166.618661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:38.66875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.278865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12661"} {"level":"info","ts":"2026-02-19T02:03:38.668834Z","caller":"traceutil/trace.go:171","msg":"trace[508050104] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:467; }","duration":"137.397757ms","start":"2026-02-19T02:03:38.531415Z","end":"2026-02-19T02:03:38.668813Z","steps":["trace[508050104] 'agreement among raft nodes before linearized reading' (duration: 137.198202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:38.668703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.76226ms","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-19T02:03:38.669262Z","caller":"traceutil/trace.go:171","msg":"trace[428702063] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"231.355284ms","start":"2026-02-19T02:03:38.43789Z","end":"2026-02-19T02:03:38.669245Z","steps":["trace[428702063] 'agreement among raft nodes before linearized reading' (duration: 230.590715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:49.251438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088550988007824,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:03:49.28414Z","caller":"traceutil/trace.go:171","msg":"trace[2091180377] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"532.781292ms","start":"2026-02-19T02:03:48.751331Z","end":"2026-02-19T02:03:49.284112Z","steps":["trace[2091180377] 'read index received' (duration: 532.563438ms)","trace[2091180377] 'applied index is now lower than readState.Index' (duration: 216.554µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:03:49.284278Z","caller":"traceutil/trace.go:171","msg":"trace[1796058606] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"578.881436ms","start":"2026-02-19T02:03:48.705387Z","end":"2026-02-19T02:03:49.284268Z","steps":["trace[1796058606] 'process raft request' (duration: 578.534628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:49.284384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:03:48.705362Z","time spent":"578.943547ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:03:49.28443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.75431ms","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-19T02:03:49.284489Z","caller":"traceutil/trace.go:171","msg":"trace[1816011319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"492.854763ms","start":"2026-02-19T02:03:48.791615Z","end":"2026-02-19T02:03:49.28447Z","steps":["trace[1816011319] 'agreement among raft nodes before linearized reading' (duration: 492.73409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:49.284535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:03:48.791604Z","time spent":"492.922164ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:03:49.284657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.324195ms","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-19T02:03:49.284685Z","caller":"traceutil/trace.go:171","msg":"trace[76005653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"533.381146ms","start":"2026-02-19T02:03:48.751292Z","end":"2026-02-19T02:03:49.284673Z","steps":["trace[76005653] 'agreement among raft nodes before linearized reading' (duration: 533.339645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:49.284748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.00578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T02:03:49.284811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:03:48.751277Z","time spent":"533.426567ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T02:03:49.284828Z","caller":"traceutil/trace.go:171","msg":"trace[436349600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"488.150033ms","start":"2026-02-19T02:03:48.796655Z","end":"2026-02-19T02:03:49.284805Z","steps":["trace[436349600] 'agreement among raft nodes before linearized reading' (duration: 487.968559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:49.28488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:03:48.796645Z","time spent":"488.221246ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:03:49.284967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.958984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4qnnb\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-19T02:03:49.285386Z","caller":"traceutil/trace.go:171","msg":"trace[1847632693] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4qnnb; range_end:; response_count:1; response_revision:528; }","duration":"293.383274ms","start":"2026-02-19T02:03:48.991964Z","end":"2026-02-19T02:03:49.285348Z","steps":["trace[1847632693] 'agreement among raft nodes before linearized reading' (duration: 292.516735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:49.934167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088550988007827,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:03:50.284933Z","caller":"traceutil/trace.go:171","msg":"trace[102437581] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"995.419507ms","start":"2026-02-19T02:03:49.289482Z","end":"2026-02-19T02:03:50.284901Z","steps":["trace[102437581] 'process raft request' (duration: 995.226273ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:03:50.285199Z","caller":"traceutil/trace.go:171","msg":"trace[1284638111] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:552; }","duration":"851.106151ms","start":"2026-02-19T02:03:49.434076Z","end":"2026-02-19T02:03:50.285182Z","steps":["trace[1284638111] 'read index received' (duration: 850.568489ms)","trace[1284638111] 'applied index is now lower than readState.Index' (duration: 537.092µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:03:50.28528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.189003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:03:50.285736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:03:49.289466Z","time spent":"996.072482ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:03:50.285801Z","caller":"traceutil/trace.go:171","msg":"trace[1606030261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"851.775396ms","start":"2026-02-19T02:03:49.434003Z","end":"2026-02-19T02:03:50.285778Z","steps":["trace[1606030261] 'agreement among raft nodes before linearized reading' (duration: 851.217834ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:03:50.28531Z","caller":"traceutil/trace.go:171","msg":"trace[957699307] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"995.816057ms","start":"2026-02-19T02:03:49.289487Z","end":"2026-02-19T02:03:50.285303Z","steps":["trace[957699307] 'process raft request' (duration: 995.362496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:50.28587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:03:49.433982Z","time spent":"851.863568ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:03:50.285909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:03:49.289468Z","time spent":"996.41128ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:03:50.285308Z","caller":"traceutil/trace.go:171","msg":"trace[980835292] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"986.994013ms","start":"2026-02-19T02:03:49.298298Z","end":"2026-02-19T02:03:50.285292Z","steps":["trace[980835292] 'process raft request' (duration: 986.811109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:03:50.286198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:03:49.298289Z","time spent":"987.826302ms","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:03:52.364909Z","caller":"traceutil/trace.go:171","msg":"trace[1441246906] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"167.576523ms","start":"2026-02-19T02:03:52.197303Z","end":"2026-02-19T02:03:52.36488Z","steps":["trace[1441246906] 'process raft request' (duration: 167.444899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:03.59717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.518077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:04:03.597264Z","caller":"traceutil/trace.go:171","msg":"trace[393667309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"162.66074ms","start":"2026-02-19T02:04:03.434582Z","end":"2026-02-19T02:04:03.597243Z","steps":["trace[393667309] 'range keys from in-memory index tree' (duration: 162.445254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:03.59729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.179017ms","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-19T02:04:03.597378Z","caller":"traceutil/trace.go:171","msg":"trace[1647199266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:697; }","duration":"168.324501ms","start":"2026-02-19T02:04:03.42903Z","end":"2026-02-19T02:04:03.597354Z","steps":["trace[1647199266] 'range keys from in-memory index tree' (duration: 168.017584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:09.934559Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088550988008178,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-19T02:04:09.983137Z","caller":"traceutil/trace.go:171","msg":"trace[1101996653] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:744; }","duration":"548.770051ms","start":"2026-02-19T02:04:09.434339Z","end":"2026-02-19T02:04:09.983109Z","steps":["trace[1101996653] 'read index received' (duration: 548.587707ms)","trace[1101996653] 'applied index is now lower than readState.Index' (duration: 181.364µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:04:09.983241Z","caller":"traceutil/trace.go:171","msg":"trace[887715197] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"588.112628ms","start":"2026-02-19T02:04:09.395105Z","end":"2026-02-19T02:04:09.983217Z","steps":["trace[887715197] 'process raft request' (duration: 587.850512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:09.983375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:09.395078Z","time spent":"588.21004ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:04:09.98336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.974575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:04:09.983406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.931405ms","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-19T02:04:09.983459Z","caller":"traceutil/trace.go:171","msg":"trace[1679332235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:717; }","duration":"359.040836ms","start":"2026-02-19T02:04:09.624405Z","end":"2026-02-19T02:04:09.983446Z","steps":["trace[1679332235] 'agreement among raft nodes before linearized reading' (duration: 358.862472ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:04:09.983482Z","caller":"traceutil/trace.go:171","msg":"trace[99722931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:717; }","duration":"549.150699ms","start":"2026-02-19T02:04:09.434291Z","end":"2026-02-19T02:04:09.983442Z","steps":["trace[99722931] 'agreement among raft nodes before linearized reading' (duration: 548.945195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:09.983509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:09.62439Z","time spent":"359.106688ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T02:04:09.98354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:09.434269Z","time spent":"549.261502ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-19T02:04:10.394234Z","caller":"traceutil/trace.go:171","msg":"trace[162443331] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"318.364298ms","start":"2026-02-19T02:04:10.075842Z","end":"2026-02-19T02:04:10.394206Z","steps":["trace[162443331] 'read index received' (duration: 292.280237ms)","trace[162443331] 'applied index is now lower than readState.Index' (duration: 26.082891ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:04:10.394282Z","caller":"traceutil/trace.go:171","msg":"trace[460159910] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"406.785018ms","start":"2026-02-19T02:04:09.987472Z","end":"2026-02-19T02:04:10.394257Z","steps":["trace[460159910] 'process raft request' (duration: 380.755638ms)","trace[460159910] 'compare' (duration: 25.880666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:04:10.394439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:09.987448Z","time spent":"406.894ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:04:10.394492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.621774ms","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-19T02:04:10.394528Z","caller":"traceutil/trace.go:171","msg":"trace[1448164835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:718; }","duration":"318.718097ms","start":"2026-02-19T02:04:10.075799Z","end":"2026-02-19T02:04:10.394517Z","steps":["trace[1448164835] 'agreement among raft nodes before linearized reading' (duration: 318.517152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:10.394562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:10.075777Z","time spent":"318.776379ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-19T02:04:11.511184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.313861ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-19T02:04:32.411712Z","caller":"traceutil/trace.go:171","msg":"trace[1758999451] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"335.609882ms","start":"2026-02-19T02:04:32.076076Z","end":"2026-02-19T02:04:32.411686Z","steps":["trace[1758999451] 'process raft request' (duration: 335.460529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:32.412017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:32.07605Z","time spent":"335.744476ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:04:34.875205Z","caller":"traceutil/trace.go:171","msg":"trace[859790194] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"341.569574ms","start":"2026-02-19T02:04:34.53362Z","end":"2026-02-19T02:04:34.87519Z","steps":["trace[859790194] 'read index received' (duration: 341.431001ms)","trace[859790194] 'applied index is now lower than readState.Index' (duration: 137.643µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:04:34.875247Z","caller":"traceutil/trace.go:171","msg":"trace[146395792] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"370.383931ms","start":"2026-02-19T02:04:34.504845Z","end":"2026-02-19T02:04:34.875228Z","steps":["trace[146395792] 'process raft request' (duration: 370.244698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:34.875368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.065216ms","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-19T02:04:34.875389Z","caller":"traceutil/trace.go:171","msg":"trace[1140765910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:790; }","duration":"121.103807ms","start":"2026-02-19T02:04:34.75428Z","end":"2026-02-19T02:04:34.875384Z","steps":["trace[1140765910] 'agreement among raft nodes before linearized reading' (duration: 121.002175ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:34.875384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:34.50483Z","time spent":"370.479213ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:04:34.875459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.81045ms","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-19T02:04:34.875541Z","caller":"traceutil/trace.go:171","msg":"trace[803556018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:790; }","duration":"341.954133ms","start":"2026-02-19T02:04:34.533568Z","end":"2026-02-19T02:04:34.875522Z","steps":["trace[803556018] 'agreement among raft nodes before linearized reading' (duration: 341.695557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:34.87559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:34.533554Z","time spent":"342.026836ms","remote":"127.0.0.1:59150","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-19T02:04:34.875808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.427667ms","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-19T02:04:34.875901Z","caller":"traceutil/trace.go:171","msg":"trace[1278806767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"121.564349ms","start":"2026-02-19T02:04:34.754322Z","end":"2026-02-19T02:04:34.875887Z","steps":["trace[1278806767] 'agreement among raft nodes before linearized reading' (duration: 121.051416ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:04:35.001368Z","caller":"traceutil/trace.go:171","msg":"trace[2071023862] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"121.536918ms","start":"2026-02-19T02:04:34.879811Z","end":"2026-02-19T02:04:35.001348Z","steps":["trace[2071023862] 'process raft request' (duration: 121.442655ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:04:35.001431Z","caller":"traceutil/trace.go:171","msg":"trace[128355391] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"121.22301ms","start":"2026-02-19T02:04:34.880183Z","end":"2026-02-19T02:04:35.001406Z","steps":["trace[128355391] 'process raft request' (duration: 121.134798ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:04:35.001635Z","caller":"traceutil/trace.go:171","msg":"trace[948044944] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"122.600513ms","start":"2026-02-19T02:04:34.879Z","end":"2026-02-19T02:04:35.0016Z","steps":["trace[948044944] 'process raft request' (duration: 33.641262ms)","trace[948044944] 'compare' (duration: 88.381877ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:04:45.201866Z","caller":"traceutil/trace.go:171","msg":"trace[1966034436] linearizableReadLoop","detail":"{readStateIndex:906; appliedIndex:905; }","duration":"141.748541ms","start":"2026-02-19T02:04:45.060093Z","end":"2026-02-19T02:04:45.201841Z","steps":["trace[1966034436] 'read index received' (duration: 141.591207ms)","trace[1966034436] 'applied index is now lower than readState.Index' (duration: 156.354µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:04:45.202323Z","caller":"traceutil/trace.go:171","msg":"trace[707408255] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"287.009194ms","start":"2026-02-19T02:04:44.915301Z","end":"2026-02-19T02:04:45.20231Z","steps":["trace[707408255] 'process raft request' (duration: 286.391769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:45.202615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.497448ms","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-19T02:04:45.202663Z","caller":"traceutil/trace.go:171","msg":"trace[1718720032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"142.5877ms","start":"2026-02-19T02:04:45.060065Z","end":"2026-02-19T02:04:45.202652Z","steps":["trace[1718720032] 'agreement among raft nodes before linearized reading' (duration: 142.437206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:45.202877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.556779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-19T02:04:45.202908Z","caller":"traceutil/trace.go:171","msg":"trace[1487953832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:869; }","duration":"142.59391ms","start":"2026-02-19T02:04:45.060303Z","end":"2026-02-19T02:04:45.202897Z","steps":["trace[1487953832] 'agreement among raft nodes before linearized reading' (duration: 142.514119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:45.203042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.992318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-19T02:04:45.203076Z","caller":"traceutil/trace.go:171","msg":"trace[315618057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:869; }","duration":"140.05183ms","start":"2026-02-19T02:04:45.063017Z","end":"2026-02-19T02:04:45.203069Z","steps":["trace[315618057] 'agreement among raft nodes before linearized reading' (duration: 139.970738ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:04:46.705997Z","caller":"traceutil/trace.go:171","msg":"trace[493168010] linearizableReadLoop","detail":"{readStateIndex:912; appliedIndex:911; }","duration":"198.455523ms","start":"2026-02-19T02:04:46.507523Z","end":"2026-02-19T02:04:46.705978Z","steps":["trace[493168010] 'read index received' (duration: 198.319628ms)","trace[493168010] 'applied index is now lower than readState.Index' (duration: 133.545µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:04:46.706177Z","caller":"traceutil/trace.go:171","msg":"trace[1978464195] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"226.374218ms","start":"2026-02-19T02:04:46.479769Z","end":"2026-02-19T02:04:46.706143Z","steps":["trace[1978464195] 'process raft request' (duration: 226.087201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:46.70622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.684678ms","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-19T02:04:46.706252Z","caller":"traceutil/trace.go:171","msg":"trace[671488062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:874; }","duration":"198.76622ms","start":"2026-02-19T02:04:46.507478Z","end":"2026-02-19T02:04:46.706244Z","steps":["trace[671488062] 'agreement among raft nodes before linearized reading' (duration: 198.602766ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:04:47.240926Z","caller":"traceutil/trace.go:171","msg":"trace[1639820630] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"531.136523ms","start":"2026-02-19T02:04:46.709765Z","end":"2026-02-19T02:04:47.240901Z","steps":["trace[1639820630] 'process raft request' (duration: 530.970279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:47.241081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:04:46.709748Z","time spent":"531.248868ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:04:57.515337Z","caller":"traceutil/trace.go:171","msg":"trace[1445771582] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"211.617946ms","start":"2026-02-19T02:04:57.3037Z","end":"2026-02-19T02:04:57.515318Z","steps":["trace[1445771582] 'read index received' (duration: 160.284972ms)","trace[1445771582] 'applied index is now lower than readState.Index' (duration: 51.331894ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:04:57.515447Z","caller":"traceutil/trace.go:171","msg":"trace[1455386061] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"215.98369ms","start":"2026-02-19T02:04:57.299454Z","end":"2026-02-19T02:04:57.515437Z","steps":["trace[1455386061] 'process raft request' (duration: 164.606845ms)","trace[1455386061] 'compare' (duration: 51.16905ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:04:57.51569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.233417ms","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-19T02:04:57.515775Z","caller":"traceutil/trace.go:171","msg":"trace[1643118961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:904; }","duration":"211.342229ms","start":"2026-02-19T02:04:57.304413Z","end":"2026-02-19T02:04:57.515755Z","steps":["trace[1643118961] 'agreement among raft nodes before linearized reading' (duration: 211.111574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:04:57.51591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.356389ms","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-19T02:04:57.515912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.189059ms","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-19T02:04:57.515948Z","caller":"traceutil/trace.go:171","msg":"trace[1428750185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"211.440982ms","start":"2026-02-19T02:04:57.304499Z","end":"2026-02-19T02:04:57.51594Z","steps":["trace[1428750185] 'agreement among raft nodes before linearized reading' (duration: 211.305378ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:04:57.515963Z","caller":"traceutil/trace.go:171","msg":"trace[1585861000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:904; }","duration":"212.269761ms","start":"2026-02-19T02:04:57.303681Z","end":"2026-02-19T02:04:57.515951Z","steps":["trace[1585861000] 'agreement among raft nodes before linearized reading' (duration: 212.104167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:04.152002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.695604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T02:05:04.152015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.348139ms","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-19T02:05:04.152074Z","caller":"traceutil/trace.go:171","msg":"trace[1367885160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"577.816207ms","start":"2026-02-19T02:05:03.574241Z","end":"2026-02-19T02:05:04.152057Z","steps":["trace[1367885160] 'range keys from in-memory index tree' (duration: 577.549031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:04.152108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:03.574223Z","time spent":"577.878989ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-19T02:05:04.152129Z","caller":"traceutil/trace.go:171","msg":"trace[322848333] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:939; }","duration":"583.501693ms","start":"2026-02-19T02:05:03.568585Z","end":"2026-02-19T02:05:04.152087Z","steps":["trace[322848333] 'range keys from in-memory index tree' (duration: 583.157395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:04.152187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:03.568571Z","time spent":"583.605005ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:05:04.152223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.72989ms","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-19T02:05:04.152295Z","caller":"traceutil/trace.go:171","msg":"trace[473354719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"581.829952ms","start":"2026-02-19T02:05:03.570445Z","end":"2026-02-19T02:05:04.152275Z","steps":["trace[473354719] 'range keys from in-memory index tree' (duration: 581.561086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:04.152334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:03.570433Z","time spent":"581.892324ms","remote":"127.0.0.1:59150","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-19T02:05:04.160948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.328265ms","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-19T02:05:04.161006Z","caller":"traceutil/trace.go:171","msg":"trace[1587968060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"624.418419ms","start":"2026-02-19T02:05:03.536573Z","end":"2026-02-19T02:05:04.160991Z","steps":["trace[1587968060] 'range keys from in-memory index tree' (duration: 615.624138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:04.161049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:03.536553Z","time spent":"624.48319ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T02:05:04.580949Z","caller":"traceutil/trace.go:171","msg":"trace[1789619823] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"424.487841ms","start":"2026-02-19T02:05:04.156433Z","end":"2026-02-19T02:05:04.580921Z","steps":["trace[1789619823] 'process raft request' (duration: 424.265655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:04.581142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:04.156418Z","time spent":"424.632015ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:05:05.12644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.757629ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:05:05.126593Z","caller":"traceutil/trace.go:171","msg":"trace[1614604171] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"962.774977ms","start":"2026-02-19T02:05:04.163792Z","end":"2026-02-19T02:05:05.126567Z","steps":["trace[1614604171] 'process raft request' (duration: 962.748577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:05.126636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:04.163784Z","time spent":"962.832388ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:05:05.12683Z","caller":"traceutil/trace.go:171","msg":"trace[862783132] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"968.591976ms","start":"2026-02-19T02:05:04.158214Z","end":"2026-02-19T02:05:05.126806Z","steps":["trace[862783132] 'process raft request' (duration: 968.291049ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:05.126864Z","caller":"traceutil/trace.go:171","msg":"trace[184194127] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"969.021726ms","start":"2026-02-19T02:05:04.157822Z","end":"2026-02-19T02:05:05.126844Z","steps":["trace[184194127] 'process raft request' (duration: 678.800286ms)","trace[184194127] 'compare' (duration: 289.685047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:05:05.126945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:04.158204Z","time spent":"968.678768ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:05:05.126955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:04.1578Z","time spent":"969.108447ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:05:05.540456Z","caller":"traceutil/trace.go:171","msg":"trace[655085013] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"105.822074ms","start":"2026-02-19T02:05:05.434613Z","end":"2026-02-19T02:05:05.540436Z","steps":["trace[655085013] 'read index received' (duration: 105.67154ms)","trace[655085013] 'applied index is now lower than readState.Index' (duration: 149.304µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:05:05.540598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.968567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:05:05.540638Z","caller":"traceutil/trace.go:171","msg":"trace[319549529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"106.054549ms","start":"2026-02-19T02:05:05.434573Z","end":"2026-02-19T02:05:05.540627Z","steps":["trace[319549529] 'agreement among raft nodes before linearized reading' (duration: 105.947497ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:05.540835Z","caller":"traceutil/trace.go:171","msg":"trace[886654443] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"125.957704ms","start":"2026-02-19T02:05:05.414848Z","end":"2026-02-19T02:05:05.540806Z","steps":["trace[886654443] 'process raft request' (duration: 125.446862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:06.666615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088550988008756,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-19T02:05:07.40292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.779572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:05:07.403152Z","caller":"traceutil/trace.go:171","msg":"trace[1824018427] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.249702968s","start":"2026-02-19T02:05:06.153437Z","end":"2026-02-19T02:05:07.40314Z","steps":["trace[1824018427] 'process raft request' (duration: 696.651521ms)","trace[1824018427] 'compare' (duration: 552.621257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:05:07.403241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:06.153418Z","time spent":"1.249765339s","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:05:07.403318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.237327883s","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-19T02:05:07.403346Z","caller":"traceutil/trace.go:171","msg":"trace[460137995] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:948; }","duration":"1.237392065s","start":"2026-02-19T02:05:06.165945Z","end":"2026-02-19T02:05:07.403337Z","steps":["trace[460137995] 'agreement among raft nodes before linearized reading' (duration: 1.237314763s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:07.403366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:06.16593Z","time spent":"1.237430695s","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-19T02:05:07.402993Z","caller":"traceutil/trace.go:171","msg":"trace[1910607211] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"1.236992636s","start":"2026-02-19T02:05:06.165988Z","end":"2026-02-19T02:05:07.402981Z","steps":["trace[1910607211] 'read index received' (duration: 684.091161ms)","trace[1910607211] 'applied index is now lower than readState.Index' (duration: 552.900424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:05:07.739934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.605307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:05:07.740138Z","caller":"traceutil/trace.go:171","msg":"trace[905865375] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"332.604491ms","start":"2026-02-19T02:05:07.407518Z","end":"2026-02-19T02:05:07.740122Z","steps":["trace[905865375] 'process raft request' (duration: 332.56888ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.740149Z","caller":"traceutil/trace.go:171","msg":"trace[1587104019] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"545.540118ms","start":"2026-02-19T02:05:07.194595Z","end":"2026-02-19T02:05:07.740135Z","steps":["trace[1587104019] 'process raft request' (duration: 387.656384ms)","trace[1587104019] 'compare' (duration: 157.358342ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:05:07.740202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:07.40751Z","time spent":"332.667532ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:05:07.740216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:07.19457Z","time spent":"545.616099ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:05:07.74035Z","caller":"traceutil/trace.go:171","msg":"trace[1119984870] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"333.165224ms","start":"2026-02-19T02:05:07.407165Z","end":"2026-02-19T02:05:07.74033Z","steps":["trace[1119984870] 'process raft request' (duration: 332.875267ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.7404Z","caller":"traceutil/trace.go:171","msg":"trace[512860301] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"336.171925ms","start":"2026-02-19T02:05:07.404221Z","end":"2026-02-19T02:05:07.740392Z","steps":["trace[512860301] 'read index received' (duration: 178.045845ms)","trace[512860301] 'applied index is now lower than readState.Index' (duration: 158.12538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:05:07.740451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:07.40715Z","time spent":"333.255866ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:05:07.740516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.203202ms","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-19T02:05:07.740542Z","caller":"traceutil/trace.go:171","msg":"trace[735165493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"608.248453ms","start":"2026-02-19T02:05:07.132288Z","end":"2026-02-19T02:05:07.740536Z","steps":["trace[735165493] 'agreement among raft nodes before linearized reading' (duration: 608.15975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:07.740562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:07.132278Z","time spent":"608.279554ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:05:07.740575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155749297s","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-19T02:05:07.740608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.801599ms","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-19T02:05:07.740623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.923985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:05:07.740632Z","caller":"traceutil/trace.go:171","msg":"trace[873402569] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"1.155839839s","start":"2026-02-19T02:05:06.584778Z","end":"2026-02-19T02:05:07.740618Z","steps":["trace[873402569] 'agreement among raft nodes before linearized reading' (duration: 1.155683396s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.740646Z","caller":"traceutil/trace.go:171","msg":"trace[35993596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"308.967627ms","start":"2026-02-19T02:05:07.431671Z","end":"2026-02-19T02:05:07.740639Z","steps":["trace[35993596] 'agreement among raft nodes before linearized reading' (duration: 308.938736ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.74065Z","caller":"traceutil/trace.go:171","msg":"trace[1588309588] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"609.884722ms","start":"2026-02-19T02:05:07.130759Z","end":"2026-02-19T02:05:07.740644Z","steps":["trace[1588309588] 'agreement among raft nodes before linearized reading' (duration: 609.772429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:07.740662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:07.431659Z","time spent":"309.000497ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:05:07.740667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:07.130737Z","time spent":"609.926744ms","remote":"127.0.0.1:59150","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-19T02:05:07.740726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.965014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-19T02:05:07.740662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:06.584764Z","time spent":"1.15589202s","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T02:05:07.740757Z","caller":"traceutil/trace.go:171","msg":"trace[1035832136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"610.027175ms","start":"2026-02-19T02:05:07.130721Z","end":"2026-02-19T02:05:07.740749Z","steps":["trace[1035832136] 'agreement among raft nodes before linearized reading' (duration: 609.957594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:07.740781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:07.130703Z","time spent":"610.072997ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-19T02:05:07.740804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.536943ms","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-19T02:05:07.740829Z","caller":"traceutil/trace.go:171","msg":"trace[1016900631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:951; }","duration":"195.588533ms","start":"2026-02-19T02:05:07.545235Z","end":"2026-02-19T02:05:07.740823Z","steps":["trace[1016900631] 'agreement among raft nodes before linearized reading' (duration: 195.528022ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.851665Z","caller":"traceutil/trace.go:171","msg":"trace[691987901] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"105.738731ms","start":"2026-02-19T02:05:07.745888Z","end":"2026-02-19T02:05:07.851627Z","steps":["trace[691987901] 'process raft request' (duration: 95.499126ms)","trace[691987901] 'compare' (duration: 10.008299ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:05:07.851896Z","caller":"traceutil/trace.go:171","msg":"trace[1757830988] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"105.985937ms","start":"2026-02-19T02:05:07.745894Z","end":"2026-02-19T02:05:07.85188Z","steps":["trace[1757830988] 'process raft request' (duration: 105.673479ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.85237Z","caller":"traceutil/trace.go:171","msg":"trace[2122939861] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"103.387915ms","start":"2026-02-19T02:05:07.74897Z","end":"2026-02-19T02:05:07.852358Z","steps":["trace[2122939861] 'process raft request' (duration: 102.953925ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.852456Z","caller":"traceutil/trace.go:171","msg":"trace[1400948739] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"103.414396ms","start":"2026-02-19T02:05:07.74901Z","end":"2026-02-19T02:05:07.852424Z","steps":["trace[1400948739] 'process raft request' (duration: 102.949065ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.852542Z","caller":"traceutil/trace.go:171","msg":"trace[2130950516] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"106.328875ms","start":"2026-02-19T02:05:07.746202Z","end":"2026-02-19T02:05:07.852531Z","steps":["trace[2130950516] 'process raft request' (duration: 105.590818ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:07.852754Z","caller":"traceutil/trace.go:171","msg":"trace[728606893] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"106.025928ms","start":"2026-02-19T02:05:07.74672Z","end":"2026-02-19T02:05:07.852746Z","steps":["trace[728606893] 'process raft request' (duration: 105.176998ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:09.666608Z","caller":"traceutil/trace.go:171","msg":"trace[2091350180] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"233.12518ms","start":"2026-02-19T02:05:09.433466Z","end":"2026-02-19T02:05:09.666592Z","steps":["trace[2091350180] 'read index received' (duration: 232.984057ms)","trace[2091350180] 'applied index is now lower than readState.Index' (duration: 140.483µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:05:09.666712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.230392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:05:09.666729Z","caller":"traceutil/trace.go:171","msg":"trace[1774249041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:959; }","duration":"233.301374ms","start":"2026-02-19T02:05:09.433423Z","end":"2026-02-19T02:05:09.666724Z","steps":["trace[1774249041] 'agreement among raft nodes before linearized reading' (duration: 233.238903ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:09.666993Z","caller":"traceutil/trace.go:171","msg":"trace[187088676] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"489.223185ms","start":"2026-02-19T02:05:09.177765Z","end":"2026-02-19T02:05:09.666988Z","steps":["trace[187088676] 'process raft request' (duration: 488.727594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:09.667049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:09.177737Z","time spent":"489.274676ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:05:16.962952Z","caller":"traceutil/trace.go:171","msg":"trace[539293531] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"101.990081ms","start":"2026-02-19T02:05:16.860943Z","end":"2026-02-19T02:05:16.962933Z","steps":["trace[539293531] 'process raft request' (duration: 101.874249ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:18.172821Z","caller":"traceutil/trace.go:171","msg":"trace[469082685] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"306.520159ms","start":"2026-02-19T02:05:17.866277Z","end":"2026-02-19T02:05:18.172798Z","steps":["trace[469082685] 'read index received' (duration: 306.1622ms)","trace[469082685] 'applied index is now lower than readState.Index' (duration: 356.829µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:05:18.173166Z","caller":"traceutil/trace.go:171","msg":"trace[1133418837] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"364.929911ms","start":"2026-02-19T02:05:17.808201Z","end":"2026-02-19T02:05:18.173131Z","steps":["trace[1133418837] 'process raft request' (duration: 364.320907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:18.17324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.952338ms","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-19T02:05:18.173383Z","caller":"traceutil/trace.go:171","msg":"trace[1658236416] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:997; }","duration":"307.107932ms","start":"2026-02-19T02:05:17.866221Z","end":"2026-02-19T02:05:18.173329Z","steps":["trace[1658236416] 'agreement among raft nodes before linearized reading' (duration: 306.883047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:18.173434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:17.866192Z","time spent":"307.230965ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-19T02:05:18.173409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:05:17.808189Z","time spent":"365.072265ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:05:18.173991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.415598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:05:18.174083Z","caller":"traceutil/trace.go:171","msg":"trace[1970440019] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"110.568527ms","start":"2026-02-19T02:05:18.063499Z","end":"2026-02-19T02:05:18.174067Z","steps":["trace[1970440019] 'process raft request' (duration: 110.272529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:18.174085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.817152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-19T02:05:18.174146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.19997ms","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-19T02:05:18.174177Z","caller":"traceutil/trace.go:171","msg":"trace[621445774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:998; }","duration":"279.994717ms","start":"2026-02-19T02:05:17.894163Z","end":"2026-02-19T02:05:18.174157Z","steps":["trace[621445774] 'agreement among raft nodes before linearized reading' (duration: 279.72884ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:18.174231Z","caller":"traceutil/trace.go:171","msg":"trace[1102087348] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"277.262671ms","start":"2026-02-19T02:05:17.896919Z","end":"2026-02-19T02:05:18.174181Z","steps":["trace[1102087348] 'agreement among raft nodes before linearized reading' (duration: 277.042656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:18.174252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.024557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-19T02:05:18.174304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.059131ms","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-19T02:05:18.174325Z","caller":"traceutil/trace.go:171","msg":"trace[1681607938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"280.125679ms","start":"2026-02-19T02:05:17.894183Z","end":"2026-02-19T02:05:18.174309Z","steps":["trace[1681607938] 'agreement among raft nodes before linearized reading' (duration: 279.942515ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:18.174372Z","caller":"traceutil/trace.go:171","msg":"trace[1002938914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"278.156923ms","start":"2026-02-19T02:05:17.896201Z","end":"2026-02-19T02:05:18.174358Z","steps":["trace[1002938914] 'agreement among raft nodes before linearized reading' (duration: 277.742694ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:18.17453Z","caller":"traceutil/trace.go:171","msg":"trace[1438372155] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:998; }","duration":"231.571722ms","start":"2026-02-19T02:05:17.942538Z","end":"2026-02-19T02:05:18.17411Z","steps":["trace[1438372155] 'agreement among raft nodes before linearized reading' (duration: 231.392718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:05:18.485798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.987798ms","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-19T02:05:18.485991Z","caller":"traceutil/trace.go:171","msg":"trace[543158802] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1003; }","duration":"270.236934ms","start":"2026-02-19T02:05:18.215736Z","end":"2026-02-19T02:05:18.485973Z","steps":["trace[543158802] 'range keys from in-memory index tree' (duration: 269.969758ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:05:18.486506Z","caller":"traceutil/trace.go:171","msg":"trace[1044048297] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"131.488655ms","start":"2026-02-19T02:05:18.355003Z","end":"2026-02-19T02:05:18.486492Z","steps":["trace[1044048297] 'process raft request' (duration: 128.551415ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:07:25.35925Z","caller":"traceutil/trace.go:171","msg":"trace[384513735] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"106.910599ms","start":"2026-02-19T02:07:25.252306Z","end":"2026-02-19T02:07:25.359216Z","steps":["trace[384513735] 'process raft request' (duration: 106.652842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:08.733211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.265123ms","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-19T02:08:08.733384Z","caller":"traceutil/trace.go:171","msg":"trace[160630534] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1710; }","duration":"517.456657ms","start":"2026-02-19T02:08:08.2159Z","end":"2026-02-19T02:08:08.733357Z","steps":["trace[160630534] 'range keys from in-memory index tree' (duration: 517.241942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:08.733473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30071338s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:08.733535Z","caller":"traceutil/trace.go:171","msg":"trace[1736064979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1710; }","duration":"1.300814972s","start":"2026-02-19T02:08:07.432702Z","end":"2026-02-19T02:08:08.733517Z","steps":["trace[1736064979] 'range keys from in-memory index tree' (duration: 1.300621348s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:08.733526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453543103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-02-19T02:08:08.733582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:07.432684Z","time spent":"1.300883044s","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:08:08.733591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.33831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:08:08.733615Z","caller":"traceutil/trace.go:171","msg":"trace[1100462465] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1710; }","duration":"1.453668657s","start":"2026-02-19T02:08:07.27992Z","end":"2026-02-19T02:08:08.733589Z","steps":["trace[1100462465] 'range keys from in-memory index tree' (duration: 1.453357089s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:08.733654Z","caller":"traceutil/trace.go:171","msg":"trace[1316197395] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1710; }","duration":"735.455413ms","start":"2026-02-19T02:08:07.998181Z","end":"2026-02-19T02:08:08.733636Z","steps":["trace[1316197395] 'count revisions from in-memory index tree' (duration: 735.248048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:08.733662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:07.279909Z","time spent":"1.453743108s","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-19T02:08:08.733683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:07.998162Z","time spent":"735.513764ms","remote":"127.0.0.1:58998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:08:08.733931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505366258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:08.733961Z","caller":"traceutil/trace.go:171","msg":"trace[500515142] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:1710; }","duration":"1.50543775s","start":"2026-02-19T02:08:07.228513Z","end":"2026-02-19T02:08:08.733951Z","steps":["trace[500515142] 'range keys from in-memory index tree' (duration: 1.505289556s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:08.733988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:07.228493Z","time spent":"1.505488822s","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-02-19T02:08:08.734116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.507896ms","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-19T02:08:08.734142Z","caller":"traceutil/trace.go:171","msg":"trace[1663475506] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1710; }","duration":"193.574497ms","start":"2026-02-19T02:08:08.54056Z","end":"2026-02-19T02:08:08.734134Z","steps":["trace[1663475506] 'range keys from in-memory index tree' (duration: 193.309401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:08.73413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183901497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-19T02:08:08.734224Z","caller":"traceutil/trace.go:171","msg":"trace[665637992] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1710; }","duration":"1.184045941s","start":"2026-02-19T02:08:07.550153Z","end":"2026-02-19T02:08:08.734198Z","steps":["trace[665637992] 'range keys from in-memory index tree' (duration: 1.183617871s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:08.7343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:07.550132Z","time spent":"1.184131463s","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-19T02:08:08.97794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.392771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-19T02:08:08.978011Z","caller":"traceutil/trace.go:171","msg":"trace[974407861] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:1; response_revision:1714; }","duration":"143.497913ms","start":"2026-02-19T02:08:08.834496Z","end":"2026-02-19T02:08:08.977994Z","steps":["trace[974407861] 'range keys from in-memory index tree' (duration: 143.296758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:09.217338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.353655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:08:09.217492Z","caller":"traceutil/trace.go:171","msg":"trace[1328024304] linearizableReadLoop","detail":"{readStateIndex:1795; appliedIndex:1793; }","duration":"207.745924ms","start":"2026-02-19T02:08:09.009731Z","end":"2026-02-19T02:08:09.217477Z","steps":["trace[1328024304] 'read index received' (duration: 38.134033ms)","trace[1328024304] 'applied index is now lower than readState.Index' (duration: 169.610821ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:09.217584Z","caller":"traceutil/trace.go:171","msg":"trace[1166896322] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"233.372969ms","start":"2026-02-19T02:08:08.9842Z","end":"2026-02-19T02:08:09.217573Z","steps":["trace[1166896322] 'process raft request' (duration: 104.695246ms)","trace[1166896322] 'compare' (duration: 128.246662ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:09.217755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.00481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-19T02:08:09.217815Z","caller":"traceutil/trace.go:171","msg":"trace[784794996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1717; }","duration":"208.105112ms","start":"2026-02-19T02:08:09.009695Z","end":"2026-02-19T02:08:09.2178Z","steps":["trace[784794996] 'agreement among raft nodes before linearized reading' (duration: 207.934108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:09.217869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.438792ms","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-19T02:08:09.217905Z","caller":"traceutil/trace.go:171","msg":"trace[1033005446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1717; }","duration":"206.488034ms","start":"2026-02-19T02:08:09.011407Z","end":"2026-02-19T02:08:09.217895Z","steps":["trace[1033005446] 'agreement among raft nodes before linearized reading' (duration: 206.362601ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:09.217917Z","caller":"traceutil/trace.go:171","msg":"trace[199968357] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"233.460551ms","start":"2026-02-19T02:08:08.984447Z","end":"2026-02-19T02:08:09.217908Z","steps":["trace[199968357] 'process raft request' (duration: 232.97326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:09.218078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.727521ms","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-19T02:08:09.218107Z","caller":"traceutil/trace.go:171","msg":"trace[151067126] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1717; }","duration":"193.782813ms","start":"2026-02-19T02:08:09.024316Z","end":"2026-02-19T02:08:09.218099Z","steps":["trace[151067126] 'agreement among raft nodes before linearized reading' (duration: 193.65809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:09.218148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.668187ms","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-19T02:08:09.2182Z","caller":"traceutil/trace.go:171","msg":"trace[422368800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1717; }","duration":"206.74057ms","start":"2026-02-19T02:08:09.011446Z","end":"2026-02-19T02:08:09.218187Z","steps":["trace[422368800] 'agreement among raft nodes before linearized reading' (duration: 206.596756ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:09.410882Z","caller":"traceutil/trace.go:171","msg":"trace[18559749] transaction","detail":"{read_only:false; response_revision:1721; number_of_response:1; }","duration":"186.991182ms","start":"2026-02-19T02:08:09.223867Z","end":"2026-02-19T02:08:09.410858Z","steps":["trace[18559749] 'process raft request' (duration: 186.941321ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:09.411142Z","caller":"traceutil/trace.go:171","msg":"trace[1666740576] transaction","detail":"{read_only:false; response_revision:1720; number_of_response:1; }","duration":"189.128153ms","start":"2026-02-19T02:08:09.222004Z","end":"2026-02-19T02:08:09.411132Z","steps":["trace[1666740576] 'process raft request' (duration: 188.668313ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:09.410938Z","caller":"traceutil/trace.go:171","msg":"trace[566444325] transaction","detail":"{read_only:false; response_revision:1719; number_of_response:1; }","duration":"188.985289ms","start":"2026-02-19T02:08:09.221934Z","end":"2026-02-19T02:08:09.410919Z","steps":["trace[566444325] 'process raft request' (duration: 188.512147ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:09.67633Z","caller":"traceutil/trace.go:171","msg":"trace[1414802339] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"160.491885ms","start":"2026-02-19T02:08:09.515809Z","end":"2026-02-19T02:08:09.676301Z","steps":["trace[1414802339] 'process raft request' (duration: 145.44358ms)","trace[1414802339] 'compare' (duration: 14.900622ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:14.874606Z","caller":"traceutil/trace.go:171","msg":"trace[158594363] linearizableReadLoop","detail":"{readStateIndex:1838; appliedIndex:1837; }","duration":"126.844409ms","start":"2026-02-19T02:08:14.74774Z","end":"2026-02-19T02:08:14.874585Z","steps":["trace[158594363] 'read index received' (duration: 126.663535ms)","trace[158594363] 'applied index is now lower than readState.Index' (duration: 179.814µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:14.874909Z","caller":"traceutil/trace.go:171","msg":"trace[166058269] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"149.152607ms","start":"2026-02-19T02:08:14.725725Z","end":"2026-02-19T02:08:14.874878Z","steps":["trace[166058269] 'process raft request' (duration: 148.737797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:14.874956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.196477ms","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:536"} {"level":"info","ts":"2026-02-19T02:08:14.875709Z","caller":"traceutil/trace.go:171","msg":"trace[685230608] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1758; }","duration":"127.988526ms","start":"2026-02-19T02:08:14.747698Z","end":"2026-02-19T02:08:14.875687Z","steps":["trace[685230608] 'agreement among raft nodes before linearized reading' (duration: 127.099035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.058746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.621950621s","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-19T02:08:19.058786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.989494464s","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-19T02:08:19.058839Z","caller":"traceutil/trace.go:171","msg":"trace[456740683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1760; }","duration":"3.622082924s","start":"2026-02-19T02:08:15.436734Z","end":"2026-02-19T02:08:19.058817Z","steps":["trace[456740683] 'range keys from in-memory index tree' (duration: 3.621760146s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:19.058867Z","caller":"traceutil/trace.go:171","msg":"trace[687751334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1760; }","duration":"3.989611596s","start":"2026-02-19T02:08:15.069229Z","end":"2026-02-19T02:08:19.05884Z","steps":["trace[687751334] 'range keys from in-memory index tree' (duration: 3.989315469s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:15.436721Z","time spent":"3.62234102s","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-19T02:08:19.059064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.171727947s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-19T02:08:19.059102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:15.069209Z","time spent":"3.989883143s","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-19T02:08:19.059121Z","caller":"traceutil/trace.go:171","msg":"trace[1544760482] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1760; }","duration":"2.172009264s","start":"2026-02-19T02:08:16.887096Z","end":"2026-02-19T02:08:19.059105Z","steps":["trace[1544760482] 'range keys from in-memory index tree' (duration: 2.171629764s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.05912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.622107684s","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-19T02:08:19.059174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.372807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-19T02:08:19.0592Z","caller":"traceutil/trace.go:171","msg":"trace[141191850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1760; }","duration":"3.622220437s","start":"2026-02-19T02:08:15.436959Z","end":"2026-02-19T02:08:19.059179Z","steps":["trace[141191850] 'range keys from in-memory index tree' (duration: 3.621751717s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.765643829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-19T02:08:19.059258Z","caller":"traceutil/trace.go:171","msg":"trace[429504327] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1760; }","duration":"3.765712551s","start":"2026-02-19T02:08:15.293537Z","end":"2026-02-19T02:08:19.059249Z","steps":["trace[429504327] 'range keys from in-memory index tree' (duration: 3.765523955s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.464117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-02-19T02:08:19.059284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:15.293516Z","time spent":"3.765762802s","remote":"127.0.0.1:59150","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-19T02:08:19.059308Z","caller":"traceutil/trace.go:171","msg":"trace[525757321] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1760; }","duration":"251.578139ms","start":"2026-02-19T02:08:18.807713Z","end":"2026-02-19T02:08:19.059291Z","steps":["trace[525757321] 'range keys from in-memory index tree' (duration: 251.276302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.621425188s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-19T02:08:19.059373Z","caller":"traceutil/trace.go:171","msg":"trace[1211314384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1760; }","duration":"3.62147489s","start":"2026-02-19T02:08:15.437889Z","end":"2026-02-19T02:08:19.059363Z","steps":["trace[1211314384] 'range keys from in-memory index tree' (duration: 3.621333276s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:15.437876Z","time spent":"3.621516281s","remote":"127.0.0.1:59150","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-19T02:08:19.059408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.721731581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-19T02:08:19.059406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.477847ms","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-19T02:08:19.059477Z","caller":"traceutil/trace.go:171","msg":"trace[1699674177] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1760; }","duration":"843.550908ms","start":"2026-02-19T02:08:18.215917Z","end":"2026-02-19T02:08:19.059468Z","steps":["trace[1699674177] 'range keys from in-memory index tree' (duration: 843.469256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624305551s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:19.05952Z","caller":"traceutil/trace.go:171","msg":"trace[1603515213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1760; }","duration":"1.624323591s","start":"2026-02-19T02:08:17.435191Z","end":"2026-02-19T02:08:19.059515Z","steps":["trace[1603515213] 'range keys from in-memory index tree' (duration: 1.62423991s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:17.435133Z","time spent":"1.624396903s","remote":"127.0.0.1:58896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:08:19.059588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.624694506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:19.059618Z","caller":"traceutil/trace.go:171","msg":"trace[188383395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1760; }","duration":"3.624750548s","start":"2026-02-19T02:08:15.434859Z","end":"2026-02-19T02:08:19.05961Z","steps":["trace[188383395] 'range keys from in-memory index tree' (duration: 3.624637605s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.363503959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-19T02:08:19.059644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:15.434845Z","time spent":"3.624792908s","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:08:19.059649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.836365ms","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-19T02:08:19.05966Z","caller":"traceutil/trace.go:171","msg":"trace[644760149] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1760; }","duration":"3.363593031s","start":"2026-02-19T02:08:15.696054Z","end":"2026-02-19T02:08:19.059647Z","steps":["trace[644760149] 'count revisions from in-memory index tree' (duration: 3.363386466s)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:19.05968Z","caller":"traceutil/trace.go:171","msg":"trace[510089549] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1760; }","duration":"270.904877ms","start":"2026-02-19T02:08:18.788766Z","end":"2026-02-19T02:08:19.059671Z","steps":["trace[510089549] 'range keys from in-memory index tree' (duration: 270.716312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:15.696033Z","time spent":"3.363650702s","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-19T02:08:19.059224Z","caller":"traceutil/trace.go:171","msg":"trace[1505854767] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1760; }","duration":"153.464509ms","start":"2026-02-19T02:08:18.905746Z","end":"2026-02-19T02:08:19.059211Z","steps":["trace[1505854767] 'count revisions from in-memory index tree' (duration: 153.292705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.059252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:15.436953Z","time spent":"3.622290349s","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T02:08:19.059443Z","caller":"traceutil/trace.go:171","msg":"trace[391095928] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:1760; }","duration":"3.721794152s","start":"2026-02-19T02:08:15.33764Z","end":"2026-02-19T02:08:19.059434Z","steps":["trace[391095928] 'range keys from in-memory index tree' (duration: 3.721612958s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.05915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:16.887079Z","time spent":"2.172065755s","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-19T02:08:19.060107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:15.337606Z","time spent":"3.722483309s","remote":"127.0.0.1:58938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-19T02:08:19.059475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.72110409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:19.060169Z","caller":"traceutil/trace.go:171","msg":"trace[770686327] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1760; }","duration":"1.721840797s","start":"2026-02-19T02:08:17.338315Z","end":"2026-02-19T02:08:19.060156Z","steps":["trace[770686327] 'count revisions from in-memory index tree' (duration: 1.720984967s)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.060206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:17.338264Z","time spent":"1.721929199s","remote":"127.0.0.1:59376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-02-19T02:08:19.406453Z","caller":"traceutil/trace.go:171","msg":"trace[1530231829] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"341.575468ms","start":"2026-02-19T02:08:19.064852Z","end":"2026-02-19T02:08:19.406428Z","steps":["trace[1530231829] 'process raft request' (duration: 341.344192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.406602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.064836Z","time spent":"341.687581ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:19.406823Z","caller":"traceutil/trace.go:171","msg":"trace[1282092427] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"341.193759ms","start":"2026-02-19T02:08:19.06559Z","end":"2026-02-19T02:08:19.406783Z","steps":["trace[1282092427] 'process raft request' (duration: 340.779629ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:19.406907Z","caller":"traceutil/trace.go:171","msg":"trace[1834221848] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"341.115357ms","start":"2026-02-19T02:08:19.06578Z","end":"2026-02-19T02:08:19.406895Z","steps":["trace[1834221848] 'process raft request' (duration: 340.872072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.406969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.065773Z","time spent":"341.162338ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:19.407542Z","caller":"traceutil/trace.go:171","msg":"trace[479195083] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"341.202879ms","start":"2026-02-19T02:08:19.066326Z","end":"2026-02-19T02:08:19.407529Z","steps":["trace[479195083] 'process raft request' (duration: 341.021045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.407609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.065573Z","time spent":"341.378453ms","remote":"127.0.0.1:58982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-19T02:08:19.407679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.066318Z","time spent":"341.283851ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:19.407857Z","caller":"traceutil/trace.go:171","msg":"trace[1820576889] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"341.337683ms","start":"2026-02-19T02:08:19.066463Z","end":"2026-02-19T02:08:19.4078Z","steps":["trace[1820576889] 'process raft request' (duration: 341.034256ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:19.408021Z","caller":"traceutil/trace.go:171","msg":"trace[1855280253] linearizableReadLoop","detail":"{readStateIndex:1850; appliedIndex:1840; }","duration":"338.981156ms","start":"2026-02-19T02:08:19.069025Z","end":"2026-02-19T02:08:19.408006Z","steps":["trace[1855280253] 'read index received' (duration: 161.589532ms)","trace[1855280253] 'applied index is now lower than readState.Index' (duration: 177.390704ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:19.4081Z","caller":"traceutil/trace.go:171","msg":"trace[1616288059] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"340.767048ms","start":"2026-02-19T02:08:19.067324Z","end":"2026-02-19T02:08:19.408091Z","steps":["trace[1616288059] 'process raft request' (duration: 340.636315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.408131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.066457Z","time spent":"341.491036ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:19.408167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.067317Z","time spent":"340.810579ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:19.408178Z","caller":"traceutil/trace.go:171","msg":"trace[507519969] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"341.317461ms","start":"2026-02-19T02:08:19.066773Z","end":"2026-02-19T02:08:19.408091Z","steps":["trace[507519969] 'process raft request' (duration: 341.130927ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.408255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.066766Z","time spent":"341.448285ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:19.4082Z","caller":"traceutil/trace.go:171","msg":"trace[1236059868] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"341.509596ms","start":"2026-02-19T02:08:19.066662Z","end":"2026-02-19T02:08:19.408171Z","steps":["trace[1236059868] 'process raft request' (duration: 340.984954ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:19.408281Z","caller":"traceutil/trace.go:171","msg":"trace[1876019804] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"341.613408ms","start":"2026-02-19T02:08:19.065834Z","end":"2026-02-19T02:08:19.407448Z","steps":["trace[1876019804] 'process raft request' (duration: 341.280951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.408364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.065829Z","time spent":"342.494819ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:19.408312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.266643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:19.408517Z","caller":"traceutil/trace.go:171","msg":"trace[605241151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1769; }","duration":"339.470118ms","start":"2026-02-19T02:08:19.068997Z","end":"2026-02-19T02:08:19.408467Z","steps":["trace[605241151] 'agreement among raft nodes before linearized reading' (duration: 339.10624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.408565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.068987Z","time spent":"339.5664ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:08:19.408734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:19.066652Z","time spent":"341.654359ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:19.450715Z","caller":"traceutil/trace.go:171","msg":"trace[633209476] transaction","detail":"{read_only:false; response_revision:1770; number_of_response:1; }","duration":"218.689512ms","start":"2026-02-19T02:08:19.232005Z","end":"2026-02-19T02:08:19.450695Z","steps":["trace[633209476] 'process raft request' (duration: 218.496267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.450834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.440383ms","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-19T02:08:19.450891Z","caller":"traceutil/trace.go:171","msg":"trace[276005607] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1770; }","duration":"280.538165ms","start":"2026-02-19T02:08:19.17034Z","end":"2026-02-19T02:08:19.450878Z","steps":["trace[276005607] 'agreement among raft nodes before linearized reading' (duration: 280.439532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.451015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.839711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:19.451208Z","caller":"traceutil/trace.go:171","msg":"trace[1435056519] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1770; }","duration":"245.21777ms","start":"2026-02-19T02:08:19.205964Z","end":"2026-02-19T02:08:19.451181Z","steps":["trace[1435056519] 'agreement among raft nodes before linearized reading' (duration: 244.716798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.451509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.340731ms","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-19T02:08:19.451631Z","caller":"traceutil/trace.go:171","msg":"trace[591132171] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1770; }","duration":"217.505014ms","start":"2026-02-19T02:08:19.234091Z","end":"2026-02-19T02:08:19.451596Z","steps":["trace[591132171] 'agreement among raft nodes before linearized reading' (duration: 216.574511ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:19.737938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.498647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:08:19.738064Z","caller":"traceutil/trace.go:171","msg":"trace[923408120] linearizableReadLoop","detail":"{readStateIndex:1852; appliedIndex:1851; }","duration":"281.312232ms","start":"2026-02-19T02:08:19.456736Z","end":"2026-02-19T02:08:19.738048Z","steps":["trace[923408120] 'read index received' (duration: 92.510958ms)","trace[923408120] 'applied index is now lower than readState.Index' (duration: 188.797994ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:19.73821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.463386ms","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-19T02:08:19.738197Z","caller":"traceutil/trace.go:171","msg":"trace[1816617094] transaction","detail":"{read_only:false; response_revision:1771; number_of_response:1; }","duration":"281.586078ms","start":"2026-02-19T02:08:19.456583Z","end":"2026-02-19T02:08:19.738169Z","steps":["trace[1816617094] 'process raft request' (duration: 92.704462ms)","trace[1816617094] 'compare' (duration: 188.379205ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:19.738271Z","caller":"traceutil/trace.go:171","msg":"trace[479508202] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1771; }","duration":"281.550917ms","start":"2026-02-19T02:08:19.456704Z","end":"2026-02-19T02:08:19.738255Z","steps":["trace[479508202] 'agreement among raft nodes before linearized reading' (duration: 281.436645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:20.002125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.263921ms","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:536"} {"level":"info","ts":"2026-02-19T02:08:20.002217Z","caller":"traceutil/trace.go:171","msg":"trace[2123268768] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1771; }","duration":"114.427936ms","start":"2026-02-19T02:08:19.887766Z","end":"2026-02-19T02:08:20.002194Z","steps":["trace[2123268768] 'range keys from in-memory index tree' (duration: 114.008766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:20.8262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.91996ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-19T02:08:21.765508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.637449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:08:21.765693Z","caller":"traceutil/trace.go:171","msg":"trace[1830707559] transaction","detail":"{read_only:false; response_revision:1774; number_of_response:1; }","duration":"349.783652ms","start":"2026-02-19T02:08:21.415888Z","end":"2026-02-19T02:08:21.765671Z","steps":["trace[1830707559] 'process raft request' (duration: 155.897317ms)","trace[1830707559] 'compare' (duration: 193.496986ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:21.765719Z","caller":"traceutil/trace.go:171","msg":"trace[2057565872] transaction","detail":"{read_only:false; response_revision:1775; number_of_response:1; }","duration":"349.048504ms","start":"2026-02-19T02:08:21.416655Z","end":"2026-02-19T02:08:21.765704Z","steps":["trace[2057565872] 'process raft request' (duration: 348.955552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:21.765762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:21.415862Z","time spent":"349.870954ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:21.765792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:21.416637Z","time spent":"349.116837ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:21.765935Z","caller":"traceutil/trace.go:171","msg":"trace[273629922] transaction","detail":"{read_only:false; response_revision:1776; number_of_response:1; }","duration":"349.105776ms","start":"2026-02-19T02:08:21.416817Z","end":"2026-02-19T02:08:21.765922Z","steps":["trace[273629922] 'process raft request' (duration: 348.84688ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:21.765955Z","caller":"traceutil/trace.go:171","msg":"trace[1007840433] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"349.036345ms","start":"2026-02-19T02:08:21.416893Z","end":"2026-02-19T02:08:21.76593Z","steps":["trace[1007840433] 'process raft request' (duration: 348.929422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:21.76599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:21.416809Z","time spent":"349.151907ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:21.766054Z","caller":"traceutil/trace.go:171","msg":"trace[182749889] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"185.922736ms","start":"2026-02-19T02:08:21.580117Z","end":"2026-02-19T02:08:21.76604Z","steps":["trace[182749889] 'process raft request' (duration: 185.858955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:21.766079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:21.416887Z","time spent":"349.116606ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:21.766104Z","caller":"traceutil/trace.go:171","msg":"trace[2031139163] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"348.239135ms","start":"2026-02-19T02:08:21.417846Z","end":"2026-02-19T02:08:21.766086Z","steps":["trace[2031139163] 'process raft request' (duration: 348.055171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:21.766182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:21.417837Z","time spent":"348.304277ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:24.056087Z","caller":"traceutil/trace.go:171","msg":"trace[1283490148] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"286.157277ms","start":"2026-02-19T02:08:23.769906Z","end":"2026-02-19T02:08:24.056064Z","steps":["trace[1283490148] 'read index received' (duration: 285.900621ms)","trace[1283490148] 'applied index is now lower than readState.Index' (duration: 255.796µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:24.056135Z","caller":"traceutil/trace.go:171","msg":"trace[964366123] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"288.655026ms","start":"2026-02-19T02:08:23.767445Z","end":"2026-02-19T02:08:24.0561Z","steps":["trace[964366123] 'process raft request' (duration: 288.442491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:24.056344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.411194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-19T02:08:24.056423Z","caller":"traceutil/trace.go:171","msg":"trace[188055982] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1782; }","duration":"286.528516ms","start":"2026-02-19T02:08:23.769877Z","end":"2026-02-19T02:08:24.056406Z","steps":["trace[188055982] 'agreement among raft nodes before linearized reading' (duration: 286.281469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:24.056499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.29834ms","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-19T02:08:24.05655Z","caller":"traceutil/trace.go:171","msg":"trace[644600343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1782; }","duration":"286.409144ms","start":"2026-02-19T02:08:23.77013Z","end":"2026-02-19T02:08:24.056539Z","steps":["trace[644600343] 'agreement among raft nodes before linearized reading' (duration: 286.23224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:24.056634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.335612ms","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-19T02:08:24.056668Z","caller":"traceutil/trace.go:171","msg":"trace[4223890] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1782; }","duration":"286.389843ms","start":"2026-02-19T02:08:23.770271Z","end":"2026-02-19T02:08:24.05666Z","steps":["trace[4223890] 'agreement among raft nodes before linearized reading' (duration: 286.24848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:24.056753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.553577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-19T02:08:24.056839Z","caller":"traceutil/trace.go:171","msg":"trace[1848419418] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1782; }","duration":"286.67606ms","start":"2026-02-19T02:08:23.770149Z","end":"2026-02-19T02:08:24.056825Z","steps":["trace[1848419418] 'agreement among raft nodes before linearized reading' (duration: 286.381103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:24.05727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.352386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-19T02:08:24.057312Z","caller":"traceutil/trace.go:171","msg":"trace[1360916200] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1782; }","duration":"287.451708ms","start":"2026-02-19T02:08:23.76985Z","end":"2026-02-19T02:08:24.057302Z","steps":["trace[1360916200] 'agreement among raft nodes before linearized reading' (duration: 286.492075ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:24.505798Z","caller":"traceutil/trace.go:171","msg":"trace[637330317] transaction","detail":"{read_only:false; response_revision:1784; number_of_response:1; }","duration":"443.223441ms","start":"2026-02-19T02:08:24.062542Z","end":"2026-02-19T02:08:24.505766Z","steps":["trace[637330317] 'process raft request' (duration: 355.664731ms)","trace[637330317] 'compare' (duration: 87.1424ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:24.505939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:24.062523Z","time spent":"443.343963ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:24.5061Z","caller":"traceutil/trace.go:171","msg":"trace[1978124576] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"443.268852ms","start":"2026-02-19T02:08:24.062815Z","end":"2026-02-19T02:08:24.506084Z","steps":["trace[1978124576] 'process raft request' (duration: 442.963825ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:24.505853Z","caller":"traceutil/trace.go:171","msg":"trace[465107340] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"443.106128ms","start":"2026-02-19T02:08:24.06272Z","end":"2026-02-19T02:08:24.505826Z","steps":["trace[465107340] 'process raft request' (duration: 443.009376ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:24.506077Z","caller":"traceutil/trace.go:171","msg":"trace[660516373] linearizableReadLoop","detail":"{readStateIndex:1869; appliedIndex:1864; }","duration":"395.419611ms","start":"2026-02-19T02:08:24.110566Z","end":"2026-02-19T02:08:24.505985Z","steps":["trace[660516373] 'read index received' (duration: 2.790546ms)","trace[660516373] 'applied index is now lower than readState.Index' (duration: 392.626765ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:24.506226Z","caller":"traceutil/trace.go:171","msg":"trace[1287649411] transaction","detail":"{read_only:false; response_revision:1785; number_of_response:1; }","duration":"443.20646ms","start":"2026-02-19T02:08:24.062618Z","end":"2026-02-19T02:08:24.505825Z","steps":["trace[1287649411] 'process raft request' (duration: 442.995645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:24.506305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.980413ms","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-19T02:08:24.506348Z","caller":"traceutil/trace.go:171","msg":"trace[78845242] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1787; }","duration":"160.057415ms","start":"2026-02-19T02:08:24.346274Z","end":"2026-02-19T02:08:24.506332Z","steps":["trace[78845242] 'agreement among raft nodes before linearized reading' (duration: 160.010584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:24.506434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:24.062611Z","time spent":"443.703372ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:24.506238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.673527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-19T02:08:24.50663Z","caller":"traceutil/trace.go:171","msg":"trace[1478910017] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1787; }","duration":"396.066626ms","start":"2026-02-19T02:08:24.110554Z","end":"2026-02-19T02:08:24.506621Z","steps":["trace[1478910017] 'agreement among raft nodes before linearized reading' (duration: 395.571804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:24.506664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:24.110538Z","time spent":"396.116737ms","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1265,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-02-19T02:08:24.506498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:24.062691Z","time spent":"443.565699ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:24.506234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:24.062809Z","time spent":"443.351714ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:25.534515Z","caller":"traceutil/trace.go:171","msg":"trace[1170657147] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"152.134208ms","start":"2026-02-19T02:08:25.382334Z","end":"2026-02-19T02:08:25.534468Z","steps":["trace[1170657147] 'process raft request' (duration: 91.719849ms)","trace[1170657147] 'compare' (duration: 60.002589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:26.817135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.264836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:08:26.817463Z","caller":"traceutil/trace.go:171","msg":"trace[715588944] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"300.629699ms","start":"2026-02-19T02:08:26.516801Z","end":"2026-02-19T02:08:26.817431Z","steps":["trace[715588944] 'process raft request' (duration: 184.947043ms)","trace[715588944] 'compare' (duration: 115.083452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:26.817786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:26.516778Z","time spent":"300.811583ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:26.818056Z","caller":"traceutil/trace.go:171","msg":"trace[781799214] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"300.209969ms","start":"2026-02-19T02:08:26.517792Z","end":"2026-02-19T02:08:26.818002Z","steps":["trace[781799214] 'process raft request' (duration: 299.785439ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:26.818387Z","caller":"traceutil/trace.go:171","msg":"trace[1511691622] transaction","detail":"{read_only:false; response_revision:1797; number_of_response:1; }","duration":"301.317045ms","start":"2026-02-19T02:08:26.517049Z","end":"2026-02-19T02:08:26.818367Z","steps":["trace[1511691622] 'process raft request' (duration: 300.217119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:26.818466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:26.517039Z","time spent":"301.390148ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:26.818746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:26.51778Z","time spent":"300.355722ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:37.337388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.557245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:08:37.337851Z","caller":"traceutil/trace.go:171","msg":"trace[1161208178] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"454.406117ms","start":"2026-02-19T02:08:36.883418Z","end":"2026-02-19T02:08:37.337825Z","steps":["trace[1161208178] 'process raft request' (duration: 96.125334ms)","trace[1161208178] 'compare' (duration: 357.231708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:37.337948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:36.883396Z","time spent":"454.505199ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:37.7917Z","caller":"traceutil/trace.go:171","msg":"trace[1553171892] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"138.062886ms","start":"2026-02-19T02:08:37.653614Z","end":"2026-02-19T02:08:37.791677Z","steps":["trace[1553171892] 'process raft request' (duration: 137.879951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:49.079147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.901809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-19T02:08:49.079243Z","caller":"traceutil/trace.go:171","msg":"trace[2133580777] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:1899; }","duration":"248.049093ms","start":"2026-02-19T02:08:48.831171Z","end":"2026-02-19T02:08:49.07922Z","steps":["trace[2133580777] 'range keys from in-memory index tree' (duration: 247.789836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:49.079579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.805235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:1 size:725"} {"level":"warn","ts":"2026-02-19T02:08:49.079575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.854033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-19T02:08:49.079635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.376342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-19T02:08:49.079673Z","caller":"traceutil/trace.go:171","msg":"trace[1473787985] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1899; }","duration":"112.018017ms","start":"2026-02-19T02:08:48.96763Z","end":"2026-02-19T02:08:49.079648Z","steps":["trace[1473787985] 'range keys from in-memory index tree' (duration: 111.650818ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:49.079724Z","caller":"traceutil/trace.go:171","msg":"trace[316125405] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1899; }","duration":"157.495785ms","start":"2026-02-19T02:08:48.922206Z","end":"2026-02-19T02:08:49.079702Z","steps":["trace[316125405] 'range keys from in-memory index tree' (duration: 157.137966ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:49.079932Z","caller":"traceutil/trace.go:171","msg":"trace[1673024186] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:1; response_revision:1899; }","duration":"252.907607ms","start":"2026-02-19T02:08:48.82672Z","end":"2026-02-19T02:08:49.079628Z","steps":["trace[1673024186] 'range keys from in-memory index tree' (duration: 252.710962ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:49.534185Z","caller":"traceutil/trace.go:171","msg":"trace[339712327] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"449.311205ms","start":"2026-02-19T02:08:49.084844Z","end":"2026-02-19T02:08:49.534156Z","steps":["trace[339712327] 'process raft request' (duration: 449.10715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:49.534354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:49.084829Z","time spent":"449.435488ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:49.75773Z","caller":"traceutil/trace.go:171","msg":"trace[1849978304] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"672.438037ms","start":"2026-02-19T02:08:49.085262Z","end":"2026-02-19T02:08:49.7577Z","steps":["trace[1849978304] 'process raft request' (duration: 642.013835ms)","trace[1849978304] 'compare' (duration: 30.118704ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:49.757878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:49.085255Z","time spent":"672.544279ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-19T02:08:49.757902Z","caller":"traceutil/trace.go:171","msg":"trace[1617296631] linearizableReadLoop","detail":"{readStateIndex:1988; appliedIndex:1986; }","duration":"606.111394ms","start":"2026-02-19T02:08:49.151764Z","end":"2026-02-19T02:08:49.757876Z","steps":["trace[1617296631] 'read index received' (duration: 382.164033ms)","trace[1617296631] 'applied index is now lower than readState.Index' (duration: 223.945211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:49.758084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.301268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:volume-scheduler\" ","response":"range_response_count:1 size:725"} {"level":"info","ts":"2026-02-19T02:08:49.758135Z","caller":"traceutil/trace.go:171","msg":"trace[464690846] range","detail":"{range_begin:/registry/clusterroles/system:volume-scheduler; range_end:; response_count:1; response_revision:1901; }","duration":"606.38593ms","start":"2026-02-19T02:08:49.151738Z","end":"2026-02-19T02:08:49.758124Z","steps":["trace[464690846] 'agreement among raft nodes before linearized reading' (duration: 606.266858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:49.758167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:49.151725Z","time spent":"606.433591ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":747,"request content":"key:\"/registry/clusterroles/system:volume-scheduler\" "} {"level":"warn","ts":"2026-02-19T02:08:49.758272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.756728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-02-19T02:08:49.758319Z","caller":"traceutil/trace.go:171","msg":"trace[265559657] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:job-controller; range_end:; response_count:1; response_revision:1901; }","duration":"603.812279ms","start":"2026-02-19T02:08:49.154495Z","end":"2026-02-19T02:08:49.758307Z","steps":["trace[265559657] 'agreement among raft nodes before linearized reading' (duration: 603.718517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:49.75835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:49.154491Z","time spent":"603.85138ms","remote":"127.0.0.1:59242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":753,"request content":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" "} {"level":"warn","ts":"2026-02-19T02:08:49.758373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.608625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-19T02:08:49.758459Z","caller":"traceutil/trace.go:171","msg":"trace[1956513160] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1901; }","duration":"178.744247ms","start":"2026-02-19T02:08:49.579673Z","end":"2026-02-19T02:08:49.758417Z","steps":["trace[1956513160] 'agreement among raft nodes before linearized reading' (duration: 178.550783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:49.758637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.034099ms","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-19T02:08:49.758673Z","caller":"traceutil/trace.go:171","msg":"trace[1326323455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1901; }","duration":"374.101921ms","start":"2026-02-19T02:08:49.384562Z","end":"2026-02-19T02:08:49.758664Z","steps":["trace[1326323455] 'agreement among raft nodes before linearized reading' (duration: 374.016759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:49.758705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:49.384546Z","time spent":"374.151963ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-19T02:08:49.758555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.838279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:49.758836Z","caller":"traceutil/trace.go:171","msg":"trace[1716807730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1901; }","duration":"324.146846ms","start":"2026-02-19T02:08:49.43467Z","end":"2026-02-19T02:08:49.758817Z","steps":["trace[1716807730] 'agreement among raft nodes before linearized reading' (duration: 323.855159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:49.758892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:49.43465Z","time spent":"324.227319ms","remote":"127.0.0.1:58890","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-19T02:08:49.758999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.710743ms","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-19T02:08:49.759202Z","caller":"traceutil/trace.go:171","msg":"trace[1299191251] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1901; }","duration":"147.880977ms","start":"2026-02-19T02:08:49.611185Z","end":"2026-02-19T02:08:49.759066Z","steps":["trace[1299191251] 'agreement among raft nodes before linearized reading' (duration: 147.61071ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:49.937137Z","caller":"traceutil/trace.go:171","msg":"trace[1935425746] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"172.997403ms","start":"2026-02-19T02:08:49.764079Z","end":"2026-02-19T02:08:49.937076Z","steps":["trace[1935425746] 'process raft request' (duration: 117.155228ms)","trace[1935425746] 'compare' (duration: 55.489856ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:49.937386Z","caller":"traceutil/trace.go:171","msg":"trace[1967360058] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"172.575663ms","start":"2026-02-19T02:08:49.764796Z","end":"2026-02-19T02:08:49.937371Z","steps":["trace[1967360058] 'process raft request' (duration: 172.393588ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:49.93732Z","caller":"traceutil/trace.go:171","msg":"trace[2061073694] linearizableReadLoop","detail":"{readStateIndex:1991; appliedIndex:1989; }","duration":"102.150552ms","start":"2026-02-19T02:08:49.83511Z","end":"2026-02-19T02:08:49.937261Z","steps":["trace[2061073694] 'read index received' (duration: 46.185975ms)","trace[2061073694] 'applied index is now lower than readState.Index' (duration: 55.963447ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:49.937639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.49826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:job-controller\" ","response":"range_response_count:1 size:731"} {"level":"info","ts":"2026-02-19T02:08:49.93784Z","caller":"traceutil/trace.go:171","msg":"trace[2036732589] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:job-controller; range_end:; response_count:1; response_revision:1904; }","duration":"102.731126ms","start":"2026-02-19T02:08:49.83508Z","end":"2026-02-19T02:08:49.937811Z","steps":["trace[2036732589] 'agreement among raft nodes before linearized reading' (duration: 102.426299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:50.277077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.538307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-19T02:08:50.277238Z","caller":"traceutil/trace.go:171","msg":"trace[2118277469] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1991; }","duration":"270.523486ms","start":"2026-02-19T02:08:50.006698Z","end":"2026-02-19T02:08:50.277222Z","steps":["trace[2118277469] 'read index received' (duration: 55.777873ms)","trace[2118277469] 'applied index is now lower than readState.Index' (duration: 214.744693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:50.277346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.642348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-02-19T02:08:50.277341Z","caller":"traceutil/trace.go:171","msg":"trace[1503196789] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"333.019437ms","start":"2026-02-19T02:08:49.944275Z","end":"2026-02-19T02:08:50.277294Z","steps":["trace[1503196789] 'process raft request' (duration: 332.898535ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:50.277382Z","caller":"traceutil/trace.go:171","msg":"trace[109686354] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:1906; }","duration":"270.70543ms","start":"2026-02-19T02:08:50.006667Z","end":"2026-02-19T02:08:50.277372Z","steps":["trace[109686354] 'agreement among raft nodes before linearized reading' (duration: 270.639369ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:50.277361Z","caller":"traceutil/trace.go:171","msg":"trace[155090071] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"333.291273ms","start":"2026-02-19T02:08:49.944048Z","end":"2026-02-19T02:08:50.277339Z","steps":["trace[155090071] 'process raft request' (duration: 118.415687ms)","trace[155090071] 'compare' (duration: 214.418755ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:50.27749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:49.944266Z","time spent":"333.14148ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:50.277507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:49.944036Z","time spent":"333.397917ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:50.277661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.419102ms","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-19T02:08:50.277692Z","caller":"traceutil/trace.go:171","msg":"trace[1795767208] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1906; }","duration":"264.453572ms","start":"2026-02-19T02:08:50.013231Z","end":"2026-02-19T02:08:50.277684Z","steps":["trace[1795767208] 'agreement among raft nodes before linearized reading' (duration: 264.401821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:50.277876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.261512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:namespace-controller\" ","response":"range_response_count:1 size:755"} {"level":"warn","ts":"2026-02-19T02:08:50.277878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.115901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-19T02:08:50.277914Z","caller":"traceutil/trace.go:171","msg":"trace[1867877972] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:namespace-controller; range_end:; response_count:1; response_revision:1906; }","duration":"260.335014ms","start":"2026-02-19T02:08:50.017569Z","end":"2026-02-19T02:08:50.277904Z","steps":["trace[1867877972] 'agreement among raft nodes before linearized reading' (duration: 260.270672ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:50.27792Z","caller":"traceutil/trace.go:171","msg":"trace[950940123] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1906; }","duration":"126.186953ms","start":"2026-02-19T02:08:50.151721Z","end":"2026-02-19T02:08:50.277908Z","steps":["trace[950940123] 'agreement among raft nodes before linearized reading' (duration: 126.06117ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:51.673209Z","caller":"traceutil/trace.go:171","msg":"trace[75496828] linearizableReadLoop","detail":"{readStateIndex:1998; appliedIndex:1997; }","duration":"128.446086ms","start":"2026-02-19T02:08:51.544742Z","end":"2026-02-19T02:08:51.673188Z","steps":["trace[75496828] 'read index received' (duration: 128.276382ms)","trace[75496828] 'applied index is now lower than readState.Index' (duration: 168.624µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:51.67329Z","caller":"traceutil/trace.go:171","msg":"trace[1989773834] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"131.99686ms","start":"2026-02-19T02:08:51.541267Z","end":"2026-02-19T02:08:51.673264Z","steps":["trace[1989773834] 'process raft request' (duration: 131.755434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:51.673341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.586089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" ","response":"range_response_count:1 size:779"} {"level":"info","ts":"2026-02-19T02:08:51.673418Z","caller":"traceutil/trace.go:171","msg":"trace[934402329] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-account-controller; range_end:; response_count:1; response_revision:1910; }","duration":"128.680392ms","start":"2026-02-19T02:08:51.544718Z","end":"2026-02-19T02:08:51.673399Z","steps":["trace[934402329] 'agreement among raft nodes before linearized reading' (duration: 128.553849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:51.869187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.814031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-02-19T02:08:51.869287Z","caller":"traceutil/trace.go:171","msg":"trace[887282579] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1910; }","duration":"103.937685ms","start":"2026-02-19T02:08:51.765326Z","end":"2026-02-19T02:08:51.869263Z","steps":["trace[887282579] 'range keys from in-memory index tree' (duration: 103.708209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:51.8695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.174858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-19T02:08:51.869579Z","caller":"traceutil/trace.go:171","msg":"trace[70487528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1910; }","duration":"106.290121ms","start":"2026-02-19T02:08:51.763271Z","end":"2026-02-19T02:08:51.869561Z","steps":["trace[70487528] 'range keys from in-memory index tree' (duration: 106.030285ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:52.071084Z","caller":"traceutil/trace.go:171","msg":"trace[1501903988] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1999; }","duration":"108.32837ms","start":"2026-02-19T02:08:51.962728Z","end":"2026-02-19T02:08:52.071057Z","steps":["trace[1501903988] 'read index received' (duration: 20.693722ms)","trace[1501903988] 'applied index is now lower than readState.Index' (duration: 87.633728ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:52.071164Z","caller":"traceutil/trace.go:171","msg":"trace[1670181864] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"126.656434ms","start":"2026-02-19T02:08:51.944473Z","end":"2026-02-19T02:08:52.071129Z","steps":["trace[1670181864] 'process raft request' (duration: 38.997916ms)","trace[1670181864] 'compare' (duration: 87.458594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:52.071237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.487933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" ","response":"range_response_count:1 size:763"} {"level":"info","ts":"2026-02-19T02:08:52.071276Z","caller":"traceutil/trace.go:171","msg":"trace[556681683] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:statefulset-controller; range_end:; response_count:1; response_revision:1912; }","duration":"108.551924ms","start":"2026-02-19T02:08:51.962709Z","end":"2026-02-19T02:08:52.071261Z","steps":["trace[556681683] 'agreement among raft nodes before linearized reading' (duration: 108.472392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:52.071261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.940183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:1 size:791"} {"level":"info","ts":"2026-02-19T02:08:52.07133Z","caller":"traceutil/trace.go:171","msg":"trace[432703647] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:1; response_revision:1912; }","duration":"106.030855ms","start":"2026-02-19T02:08:51.96528Z","end":"2026-02-19T02:08:52.071311Z","steps":["trace[432703647] 'agreement among raft nodes before linearized reading' (duration: 105.923372ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:52.60513Z","caller":"traceutil/trace.go:171","msg":"trace[1383361998] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"100.037013ms","start":"2026-02-19T02:08:52.505069Z","end":"2026-02-19T02:08:52.605106Z","steps":["trace[1383361998] 'process raft request' (duration: 99.806297ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:54.222559Z","caller":"traceutil/trace.go:171","msg":"trace[1147437375] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"143.869892ms","start":"2026-02-19T02:08:54.078664Z","end":"2026-02-19T02:08:54.222534Z","steps":["trace[1147437375] 'process raft request' (duration: 143.737469ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:55.146165Z","caller":"traceutil/trace.go:171","msg":"trace[1440735634] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"354.337674ms","start":"2026-02-19T02:08:54.791794Z","end":"2026-02-19T02:08:55.146132Z","steps":["trace[1440735634] 'process raft request' (duration: 290.797827ms)","trace[1440735634] 'compare' (duration: 63.174098ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:55.145973Z","caller":"traceutil/trace.go:171","msg":"trace[592825373] linearizableReadLoop","detail":"{readStateIndex:2012; appliedIndex:2011; }","duration":"346.837446ms","start":"2026-02-19T02:08:54.7991Z","end":"2026-02-19T02:08:55.145937Z","steps":["trace[592825373] 'read index received' (duration: 283.491033ms)","trace[592825373] 'applied index is now lower than readState.Index' (duration: 63.344813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:55.146299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:54.791774Z","time spent":"354.452636ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:55.146361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.235235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:4 size:1383"} {"level":"info","ts":"2026-02-19T02:08:55.146442Z","caller":"traceutil/trace.go:171","msg":"trace[1532738951] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:4; response_revision:1924; }","duration":"347.366658ms","start":"2026-02-19T02:08:54.799058Z","end":"2026-02-19T02:08:55.146425Z","steps":["trace[1532738951] 'agreement among raft nodes before linearized reading' (duration: 347.182134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.146493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:54.799038Z","time spent":"347.44406ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":4,"response size":1405,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"warn","ts":"2026-02-19T02:08:55.146604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.441068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-19T02:08:55.146688Z","caller":"traceutil/trace.go:171","msg":"trace[591358918] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1924; }","duration":"204.579342ms","start":"2026-02-19T02:08:54.942086Z","end":"2026-02-19T02:08:55.146666Z","steps":["trace[591358918] 'agreement among raft nodes before linearized reading' (duration: 204.347096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.147105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.507497ms","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-19T02:08:55.147182Z","caller":"traceutil/trace.go:171","msg":"trace[2110863581] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1924; }","duration":"311.590929ms","start":"2026-02-19T02:08:54.835559Z","end":"2026-02-19T02:08:55.14715Z","steps":["trace[2110863581] 'agreement among raft nodes before linearized reading' (duration: 310.7938ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.147244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:54.835491Z","time spent":"311.744503ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-19T02:08:55.377058Z","caller":"traceutil/trace.go:171","msg":"trace[1378099106] linearizableReadLoop","detail":"{readStateIndex:2014; appliedIndex:2013; }","duration":"145.815098ms","start":"2026-02-19T02:08:55.231219Z","end":"2026-02-19T02:08:55.377034Z","steps":["trace[1378099106] 'read index received' (duration: 74.043325ms)","trace[1378099106] 'applied index is now lower than readState.Index' (duration: 71.770343ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:55.377186Z","caller":"traceutil/trace.go:171","msg":"trace[313726183] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"224.493214ms","start":"2026-02-19T02:08:55.152646Z","end":"2026-02-19T02:08:55.377139Z","steps":["trace[313726183] 'process raft request' (duration: 152.686431ms)","trace[313726183] 'compare' (duration: 71.411614ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:55.377281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.044524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/default/\" range_end:\"/registry/configmaps/default0\" limit:500 ","response":"range_response_count:1 size:1738"} {"level":"info","ts":"2026-02-19T02:08:55.377315Z","caller":"traceutil/trace.go:171","msg":"trace[383476786] range","detail":"{range_begin:/registry/configmaps/default/; range_end:/registry/configmaps/default0; response_count:1; response_revision:1926; }","duration":"146.149826ms","start":"2026-02-19T02:08:55.231155Z","end":"2026-02-19T02:08:55.377305Z","steps":["trace[383476786] 'agreement among raft nodes before linearized reading' (duration: 146.014013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.377342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.974442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/\" range_end:\"/registry/configmaps/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-19T02:08:55.377408Z","caller":"traceutil/trace.go:171","msg":"trace[591769960] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/; range_end:/registry/configmaps/kube-node-lease0; response_count:1; response_revision:1926; }","duration":"130.071975ms","start":"2026-02-19T02:08:55.24732Z","end":"2026-02-19T02:08:55.377392Z","steps":["trace[591769960] 'agreement among raft nodes before linearized reading' (duration: 129.925762ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:55.576985Z","caller":"traceutil/trace.go:171","msg":"trace[1896195257] linearizableReadLoop","detail":"{readStateIndex:2017; appliedIndex:2014; }","duration":"113.164463ms","start":"2026-02-19T02:08:55.4638Z","end":"2026-02-19T02:08:55.576964Z","steps":["trace[1896195257] 'read index received' (duration: 20.066666ms)","trace[1896195257] 'applied index is now lower than readState.Index' (duration: 93.096487ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:55.57708Z","caller":"traceutil/trace.go:171","msg":"trace[154061322] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"194.264697ms","start":"2026-02-19T02:08:55.382792Z","end":"2026-02-19T02:08:55.577056Z","steps":["trace[154061322] 'process raft request' (duration: 194.027591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.577395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.650222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-node-lease/kube-root-ca.crt\" ","response":"range_response_count:1 size:1754"} {"level":"info","ts":"2026-02-19T02:08:55.577433Z","caller":"traceutil/trace.go:171","msg":"trace[1263924386] range","detail":"{range_begin:/registry/configmaps/kube-node-lease/kube-root-ca.crt; range_end:; response_count:1; response_revision:1928; }","duration":"104.712644ms","start":"2026-02-19T02:08:55.47271Z","end":"2026-02-19T02:08:55.577423Z","steps":["trace[1263924386] 'agreement among raft nodes before linearized reading' (duration: 104.635112ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:55.577186Z","caller":"traceutil/trace.go:171","msg":"trace[1444833820] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"189.292448ms","start":"2026-02-19T02:08:55.387859Z","end":"2026-02-19T02:08:55.577152Z","steps":["trace[1444833820] 'process raft request' (duration: 189.062102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.577524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.3735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/default/\" range_end:\"/registry/cronjobs/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:08:55.5772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.382998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-node-lease/\" range_end:\"/registry/cronjobs/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:55.577571Z","caller":"traceutil/trace.go:171","msg":"trace[569950763] range","detail":"{range_begin:/registry/cronjobs/default/; range_end:/registry/cronjobs/default0; response_count:0; response_revision:1928; }","duration":"100.417561ms","start":"2026-02-19T02:08:55.477137Z","end":"2026-02-19T02:08:55.577554Z","steps":["trace[569950763] 'agreement among raft nodes before linearized reading' (duration: 100.358ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:55.577601Z","caller":"traceutil/trace.go:171","msg":"trace[1216442627] range","detail":"{range_begin:/registry/cronjobs/kube-node-lease/; range_end:/registry/cronjobs/kube-node-lease0; response_count:0; response_revision:1928; }","duration":"113.820299ms","start":"2026-02-19T02:08:55.463761Z","end":"2026-02-19T02:08:55.577582Z","steps":["trace[1216442627] 'agreement among raft nodes before linearized reading' (duration: 113.379138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.87273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.70057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/default/\" range_end:\"/registry/deployments/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:55.872816Z","caller":"traceutil/trace.go:171","msg":"trace[1188172057] range","detail":"{range_begin:/registry/deployments/default/; range_end:/registry/deployments/default0; response_count:0; response_revision:1929; }","duration":"114.868644ms","start":"2026-02-19T02:08:55.757928Z","end":"2026-02-19T02:08:55.872796Z","steps":["trace[1188172057] 'range keys from in-memory index tree' (duration: 114.581178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.872834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.682303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-node-lease/\" range_end:\"/registry/deployments/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:55.872913Z","caller":"traceutil/trace.go:171","msg":"trace[1809665788] range","detail":"{range_begin:/registry/deployments/kube-node-lease/; range_end:/registry/deployments/kube-node-lease0; response_count:0; response_revision:1929; }","duration":"115.829657ms","start":"2026-02-19T02:08:55.757059Z","end":"2026-02-19T02:08:55.872889Z","steps":["trace[1809665788] 'range keys from in-memory index tree' (duration: 115.585881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:55.872927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.91926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-19T02:08:55.873045Z","caller":"traceutil/trace.go:171","msg":"trace[1960595237] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1929; }","duration":"181.098475ms","start":"2026-02-19T02:08:55.691921Z","end":"2026-02-19T02:08:55.87302Z","steps":["trace[1960595237] 'range keys from in-memory index tree' (duration: 180.588043ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:57.051059Z","caller":"traceutil/trace.go:171","msg":"trace[948369438] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"412.476253ms","start":"2026-02-19T02:08:56.63855Z","end":"2026-02-19T02:08:57.051026Z","steps":["trace[948369438] 'process raft request' (duration: 380.601826ms)","trace[948369438] 'compare' (duration: 31.61144ms)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:57.051065Z","caller":"traceutil/trace.go:171","msg":"trace[1444985067] linearizableReadLoop","detail":"{readStateIndex:2023; appliedIndex:2022; }","duration":"409.779659ms","start":"2026-02-19T02:08:56.641242Z","end":"2026-02-19T02:08:57.051021Z","steps":["trace[1444985067] 'read index received' (duration: 377.956313ms)","trace[1444985067] 'applied index is now lower than readState.Index' (duration: 31.820866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:57.051156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.902172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:57.051195Z","caller":"traceutil/trace.go:171","msg":"trace[1211884945] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1934; }","duration":"409.971684ms","start":"2026-02-19T02:08:56.641214Z","end":"2026-02-19T02:08:57.051186Z","steps":["trace[1211884945] 'agreement among raft nodes before linearized reading' (duration: 409.883772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:57.051206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:56.638529Z","time spent":"412.593745ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:57.05122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:56.641206Z","time spent":"410.007504ms","remote":"127.0.0.1:59118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-19T02:08:57.051439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.576738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-19T02:08:57.051479Z","caller":"traceutil/trace.go:171","msg":"trace[517303182] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1934; }","duration":"386.647569ms","start":"2026-02-19T02:08:56.66482Z","end":"2026-02-19T02:08:57.051468Z","steps":["trace[517303182] 'agreement among raft nodes before linearized reading' (duration: 386.414304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:57.051536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.937081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:08:57.051541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:56.664802Z","time spent":"386.70727ms","remote":"127.0.0.1:59056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1265,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"info","ts":"2026-02-19T02:08:57.05157Z","caller":"traceutil/trace.go:171","msg":"trace[819645513] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:1934; }","duration":"318.007312ms","start":"2026-02-19T02:08:56.733553Z","end":"2026-02-19T02:08:57.05156Z","steps":["trace[819645513] 'agreement among raft nodes before linearized reading' (duration: 317.949851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:57.051602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:56.73354Z","time spent":"318.054203ms","remote":"127.0.0.1:59208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":27,"request content":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 "} {"level":"warn","ts":"2026-02-19T02:08:57.051628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.918644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:57.051699Z","caller":"traceutil/trace.go:171","msg":"trace[1742197529] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:1934; }","duration":"384.044077ms","start":"2026-02-19T02:08:56.66764Z","end":"2026-02-19T02:08:57.051684Z","steps":["trace[1742197529] 'agreement among raft nodes before linearized reading' (duration: 383.893514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:57.051738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:56.667623Z","time spent":"384.107879ms","remote":"127.0.0.1:59084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 "} {"level":"info","ts":"2026-02-19T02:08:57.529804Z","caller":"traceutil/trace.go:171","msg":"trace[1435295128] linearizableReadLoop","detail":"{readStateIndex:2024; appliedIndex:2023; }","duration":"114.746311ms","start":"2026-02-19T02:08:57.415032Z","end":"2026-02-19T02:08:57.529778Z","steps":["trace[1435295128] 'read index received' (duration: 114.645648ms)","trace[1435295128] 'applied index is now lower than readState.Index' (duration: 99.593µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:57.529986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.939357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-02-19T02:08:57.52984Z","caller":"traceutil/trace.go:171","msg":"trace[1730504430] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"145.042949ms","start":"2026-02-19T02:08:57.384777Z","end":"2026-02-19T02:08:57.52982Z","steps":["trace[1730504430] 'process raft request' (duration: 144.856474ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:57.530065Z","caller":"traceutil/trace.go:171","msg":"trace[1440553728] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1935; }","duration":"115.0728ms","start":"2026-02-19T02:08:57.414982Z","end":"2026-02-19T02:08:57.530055Z","steps":["trace[1440553728] 'agreement among raft nodes before linearized reading' (duration: 114.940917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:57.530052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.647284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-02-19T02:08:57.530316Z","caller":"traceutil/trace.go:171","msg":"trace[959929368] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:1935; }","duration":"110.957002ms","start":"2026-02-19T02:08:57.41933Z","end":"2026-02-19T02:08:57.530287Z","steps":["trace[959929368] 'agreement among raft nodes before linearized reading' (duration: 110.641094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:58.241199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.078115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-02-19T02:08:58.24126Z","caller":"traceutil/trace.go:171","msg":"trace[395271315] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1937; }","duration":"130.189477ms","start":"2026-02-19T02:08:58.111057Z","end":"2026-02-19T02:08:58.241246Z","steps":["trace[395271315] 'range keys from in-memory index tree' (duration: 129.978403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:58.241351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.588126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-public/\" range_end:\"/registry/ingress/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:58.241441Z","caller":"traceutil/trace.go:171","msg":"trace[93970474] range","detail":"{range_begin:/registry/ingress/kube-public/; range_end:/registry/ingress/kube-public0; response_count:0; response_revision:1937; }","duration":"138.704789ms","start":"2026-02-19T02:08:58.10272Z","end":"2026-02-19T02:08:58.241425Z","steps":["trace[93970474] 'range keys from in-memory index tree' (duration: 138.547935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:58.241456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.285571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/\" range_end:\"/registry/configmaps/kube-system0\" limit:500 ","response":"range_response_count:11 size:19144"} {"level":"info","ts":"2026-02-19T02:08:58.241522Z","caller":"traceutil/trace.go:171","msg":"trace[1959264711] range","detail":"{range_begin:/registry/configmaps/kube-system/; range_end:/registry/configmaps/kube-system0; response_count:11; response_revision:1937; }","duration":"141.409044ms","start":"2026-02-19T02:08:58.100099Z","end":"2026-02-19T02:08:58.241508Z","steps":["trace[1959264711] 'range keys from in-memory index tree' (duration: 141.074235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:58.916308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.815803ms","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":"warn","ts":"2026-02-19T02:08:58.916222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.982134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-19T02:08:58.916514Z","caller":"traceutil/trace.go:171","msg":"trace[320140346] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1939; }","duration":"104.038957ms","start":"2026-02-19T02:08:58.812414Z","end":"2026-02-19T02:08:58.916453Z","steps":["trace[320140346] 'count revisions from in-memory index tree' (duration: 103.677389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:58.916346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.707535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-system/\" range_end:\"/registry/ingress/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:58.916664Z","caller":"traceutil/trace.go:171","msg":"trace[947599286] range","detail":"{range_begin:/registry/ingress/kube-system/; range_end:/registry/ingress/kube-system0; response_count:0; response_revision:1939; }","duration":"170.077784ms","start":"2026-02-19T02:08:58.74657Z","end":"2026-02-19T02:08:58.916648Z","steps":["trace[947599286] 'range keys from in-memory index tree' (duration: 169.634752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:58.916395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.781402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-02-19T02:08:58.916868Z","caller":"traceutil/trace.go:171","msg":"trace[1058042408] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-encryption-kms-config; range_end:; response_count:1; response_revision:1939; }","duration":"239.257664ms","start":"2026-02-19T02:08:58.677588Z","end":"2026-02-19T02:08:58.916845Z","steps":["trace[1058042408] 'range keys from in-memory index tree' (duration: 238.636309ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:58.916503Z","caller":"traceutil/trace.go:171","msg":"trace[77099456] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1939; }","duration":"203.321152ms","start":"2026-02-19T02:08:58.713167Z","end":"2026-02-19T02:08:58.916488Z","steps":["trace[77099456] 'range keys from in-memory index tree' (duration: 202.85656ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:59.256036Z","caller":"traceutil/trace.go:171","msg":"trace[1618447965] linearizableReadLoop","detail":"{readStateIndex:2029; appliedIndex:2028; }","duration":"180.269775ms","start":"2026-02-19T02:08:59.075747Z","end":"2026-02-19T02:08:59.256017Z","steps":["trace[1618447965] 'read index received' (duration: 180.097621ms)","trace[1618447965] 'applied index is now lower than readState.Index' (duration: 171.104µs)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:08:59.256193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.417909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/kube-system/\" range_end:\"/registry/jobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:08:59.256231Z","caller":"traceutil/trace.go:171","msg":"trace[807901042] range","detail":"{range_begin:/registry/jobs/kube-system/; range_end:/registry/jobs/kube-system0; response_count:0; response_revision:1940; }","duration":"180.48933ms","start":"2026-02-19T02:08:59.075731Z","end":"2026-02-19T02:08:59.256221Z","steps":["trace[807901042] 'agreement among raft nodes before linearized reading' (duration: 180.411288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:59.256286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.439758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/\" range_end:\"/registry/rolebindings/kube-public0\" limit:500 ","response":"range_response_count:2 size:1230"} {"level":"info","ts":"2026-02-19T02:08:59.256355Z","caller":"traceutil/trace.go:171","msg":"trace[1567840564] range","detail":"{range_begin:/registry/rolebindings/kube-public/; range_end:/registry/rolebindings/kube-public0; response_count:2; response_revision:1940; }","duration":"158.568911ms","start":"2026-02-19T02:08:59.09777Z","end":"2026-02-19T02:08:59.256338Z","steps":["trace[1567840564] 'agreement among raft nodes before linearized reading' (duration: 158.402177ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:59.256614Z","caller":"traceutil/trace.go:171","msg":"trace[995285688] transaction","detail":"{read_only:false; response_revision:1940; number_of_response:1; }","duration":"197.863053ms","start":"2026-02-19T02:08:59.05822Z","end":"2026-02-19T02:08:59.256083Z","steps":["trace[995285688] 'process raft request' (duration: 197.662518ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:59.846327Z","caller":"traceutil/trace.go:171","msg":"trace[1400551490] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2029; }","duration":"283.007071ms","start":"2026-02-19T02:08:59.5633Z","end":"2026-02-19T02:08:59.846307Z","steps":["trace[1400551490] 'read index received' (duration: 282.969161ms)","trace[1400551490] 'applied index is now lower than readState.Index' (duration: 37.14µs)"],"step_count":2} {"level":"info","ts":"2026-02-19T02:08:59.846417Z","caller":"traceutil/trace.go:171","msg":"trace[2085123289] transaction","detail":"{read_only:false; response_revision:1941; number_of_response:1; }","duration":"309.54946ms","start":"2026-02-19T02:08:59.536832Z","end":"2026-02-19T02:08:59.846382Z","steps":["trace[2085123289] 'process raft request' (duration: 309.365156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:59.846487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.181175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1000"} {"level":"info","ts":"2026-02-19T02:08:59.846512Z","caller":"traceutil/trace.go:171","msg":"trace[769093918] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1941; }","duration":"283.273538ms","start":"2026-02-19T02:08:59.563231Z","end":"2026-02-19T02:08:59.846504Z","steps":["trace[769093918] 'agreement among raft nodes before linearized reading' (duration: 283.201376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:08:59.846624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-19T02:08:59.536817Z","time spent":"309.666134ms","remote":"127.0.0.1:59150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-19T02:08:59.846683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.438348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:08:59.846678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.33265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-19T02:08:59.846703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.151204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"info","ts":"2026-02-19T02:08:59.846746Z","caller":"traceutil/trace.go:171","msg":"trace[669179802] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:1941; }","duration":"104.543979ms","start":"2026-02-19T02:08:59.742183Z","end":"2026-02-19T02:08:59.846727Z","steps":["trace[669179802] 'agreement among raft nodes before linearized reading' (duration: 104.429746ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:59.846762Z","caller":"traceutil/trace.go:171","msg":"trace[273914588] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:1941; }","duration":"259.461973ms","start":"2026-02-19T02:08:59.587276Z","end":"2026-02-19T02:08:59.846738Z","steps":["trace[273914588] 'agreement among raft nodes before linearized reading' (duration: 259.277138ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:08:59.84677Z","caller":"traceutil/trace.go:171","msg":"trace[294094958] range","detail":"{range_begin:/registry/configmaps/kube-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1941; }","duration":"237.245036ms","start":"2026-02-19T02:08:59.609509Z","end":"2026-02-19T02:08:59.846754Z","steps":["trace[294094958] 'agreement among raft nodes before linearized reading' (duration: 237.04616ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:09:00.353716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.189471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-public/\" range_end:\"/registry/statefulsets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-19T02:09:00.353797Z","caller":"traceutil/trace.go:171","msg":"trace[1874585909] range","detail":"{range_begin:/registry/statefulsets/kube-public/; range_end:/registry/statefulsets/kube-public0; response_count:0; response_revision:1945; }","duration":"120.316413ms","start":"2026-02-19T02:09:00.233462Z","end":"2026-02-19T02:09:00.353778Z","steps":["trace[1874585909] 'range keys from in-memory index tree' (duration: 120.120238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:09:00.353983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.417295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9324"} {"level":"info","ts":"2026-02-19T02:09:00.35409Z","caller":"traceutil/trace.go:171","msg":"trace[990122632] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:1945; }","duration":"133.548818ms","start":"2026-02-19T02:09:00.220518Z","end":"2026-02-19T02:09:00.354067Z","steps":["trace[990122632] 'range keys from in-memory index tree' (duration: 133.291882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:09:00.354088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.332389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14326"} {"level":"info","ts":"2026-02-19T02:09:00.35416Z","caller":"traceutil/trace.go:171","msg":"trace[643498816] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:1945; }","duration":"116.418992ms","start":"2026-02-19T02:09:00.237728Z","end":"2026-02-19T02:09:00.354147Z","steps":["trace[643498816] 'range keys from in-memory index tree' (duration: 116.105874ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:09:00.688506Z","caller":"traceutil/trace.go:171","msg":"trace[1895168455] linearizableReadLoop","detail":"{readStateIndex:2036; appliedIndex:2035; }","duration":"105.783688ms","start":"2026-02-19T02:09:00.582699Z","end":"2026-02-19T02:09:00.688482Z","steps":["trace[1895168455] 'read index received' (duration: 31.816944ms)","trace[1895168455] 'applied index is now lower than readState.Index' (duration: 73.965494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-19T02:09:00.688735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.016564ms","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:536"} {"level":"info","ts":"2026-02-19T02:09:00.688781Z","caller":"traceutil/trace.go:171","msg":"trace[244796762] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1946; }","duration":"106.097246ms","start":"2026-02-19T02:09:00.582669Z","end":"2026-02-19T02:09:00.688766Z","steps":["trace[244796762] 'agreement among raft nodes before linearized reading' (duration: 105.936972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:09:00.693131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.753123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2896"} {"level":"info","ts":"2026-02-19T02:09:00.693211Z","caller":"traceutil/trace.go:171","msg":"trace[1503448812] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1947; }","duration":"109.868936ms","start":"2026-02-19T02:09:00.583325Z","end":"2026-02-19T02:09:00.693193Z","steps":["trace[1503448812] 'agreement among raft nodes before linearized reading' (duration: 109.709582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:09:00.693528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.850834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:11093"} {"level":"info","ts":"2026-02-19T02:09:00.693567Z","caller":"traceutil/trace.go:171","msg":"trace[950042068] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1947; }","duration":"106.910125ms","start":"2026-02-19T02:09:00.586646Z","end":"2026-02-19T02:09:00.693556Z","steps":["trace[950042068] 'agreement among raft nodes before linearized reading' (duration: 106.762711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-19T02:09:00.693536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.490916ms","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-19T02:09:00.69363Z","caller":"traceutil/trace.go:171","msg":"trace[1143173450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1947; }","duration":"101.61761ms","start":"2026-02-19T02:09:00.591989Z","end":"2026-02-19T02:09:00.693606Z","steps":["trace[1143173450] 'agreement among raft nodes before linearized reading' (duration: 101.339163ms)"],"step_count":1} {"level":"info","ts":"2026-02-19T02:09:00.693845Z","caller":"traceutil/trace.go:171","msg":"trace[580758433] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"108.477373ms","start":"2026-02-19T02:09:00.58535Z","end":"2026-02-19T02:09:00.693827Z","steps":["trace[580758433] 'process raft request' (duration: 107.50053ms)"],"step_count":1}