{"level":"warn","ts":"2026-02-17T16:11:08.392481Z","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-17T16:11:08.393301Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-17T16:11:08.393414Z","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-17T16:11:08.393429Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-17T16:11:08.393476Z","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-17T16:11:08.394533Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-17T16:11:08.394865Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-17T16:11:08.40088Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.608796ms"} {"level":"info","ts":"2026-02-17T16:11:08.409713Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-17T16:11:08.409789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T16:11:08.409805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-17T16:11:08.409826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T16:11:08.409832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-17T16:11:08.409901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-17T16:11:08.422059Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T16:11:08.431961Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T16:11:08.449656Z","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-17T16:11:08.453331Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T16:11:08.45429Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T16:11:08.454575Z","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-17T16:11:08.454734Z","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-17T16:11:08.458564Z","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-17T16:11:08.458809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-17T16:11:08.458882Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-17T16:11:08.459938Z","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-17T16:11:08.460281Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T16:11:08.46031Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T16:11:08.460392Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-17T16:11:08.460399Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-17T16:11:09.115908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T16:11:09.115961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T16:11:09.115982Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-17T16:11:09.115993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-17T16:11:09.115998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-17T16:11:09.116006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-17T16:11:09.116012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-17T16:11:09.123999Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T16:11:09.124249Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:11:09.127385Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:11:09.127691Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:11:09.127867Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T16:11:09.12788Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T16:11:09.127897Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:11:09.127957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:11:09.127968Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:11:09.129273Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T16:11:09.132529Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-17T16:11:12.278088Z","caller":"traceutil/trace.go:171","msg":"trace[1990932512] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"116.838701ms","start":"2026-02-17T16:11:12.161232Z","end":"2026-02-17T16:11:12.278071Z","steps":["trace[1990932512] 'process raft request' (duration: 44.232969ms)","trace[1990932512] 'compare' (duration: 72.362817ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:11:12.577331Z","caller":"traceutil/trace.go:171","msg":"trace[2077292827] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"226.132113ms","start":"2026-02-17T16:11:12.351171Z","end":"2026-02-17T16:11:12.577303Z","steps":["trace[2077292827] 'process raft request' (duration: 225.98711ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:13.03739Z","caller":"traceutil/trace.go:171","msg":"trace[1256386319] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"100.312482ms","start":"2026-02-17T16:11:12.937051Z","end":"2026-02-17T16:11:13.037363Z","steps":["trace[1256386319] 'process raft request' (duration: 87.625248ms)","trace[1256386319] 'compare' (duration: 12.593132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:11:14.075686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.156454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:11:14.075795Z","caller":"traceutil/trace.go:171","msg":"trace[776365544] linearizableReadLoop","detail":"{readStateIndex:208; appliedIndex:207; }","duration":"211.088778ms","start":"2026-02-17T16:11:13.86469Z","end":"2026-02-17T16:11:14.075779Z","steps":["trace[776365544] 'read index received' (duration: 99.297749ms)","trace[776365544] 'applied index is now lower than readState.Index' (duration: 111.790009ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:11:14.076232Z","caller":"traceutil/trace.go:171","msg":"trace[102878287] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"211.550178ms","start":"2026-02-17T16:11:13.864663Z","end":"2026-02-17T16:11:14.076213Z","steps":["trace[102878287] 'process raft request' (duration: 99.374751ms)","trace[102878287] 'compare' (duration: 111.039001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:11:14.076385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.678771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:11:14.076428Z","caller":"traceutil/trace.go:171","msg":"trace[1242836199] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:203; }","duration":"211.761873ms","start":"2026-02-17T16:11:13.864656Z","end":"2026-02-17T16:11:14.076418Z","steps":["trace[1242836199] 'agreement among raft nodes before linearized reading' (duration: 211.488557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:20.656745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.86389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:11:20.656821Z","caller":"traceutil/trace.go:171","msg":"trace[1463286962] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"172.828902ms","start":"2026-02-17T16:11:20.48398Z","end":"2026-02-17T16:11:20.656809Z","steps":["trace[1463286962] 'process raft request' (duration: 13.765027ms)","trace[1463286962] 'compare' (duration: 157.893278ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:11:20.662644Z","caller":"traceutil/trace.go:171","msg":"trace[230536206] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"165.329034ms","start":"2026-02-17T16:11:20.4973Z","end":"2026-02-17T16:11:20.662629Z","steps":["trace[230536206] 'process raft request' (duration: 165.075178ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:20.668724Z","caller":"traceutil/trace.go:171","msg":"trace[1692642223] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"171.300278ms","start":"2026-02-17T16:11:20.497407Z","end":"2026-02-17T16:11:20.668708Z","steps":["trace[1692642223] 'process raft request' (duration: 165.156531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:28.062593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.765433ms","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-17T16:11:28.062667Z","caller":"traceutil/trace.go:171","msg":"trace[1127817778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"173.887726ms","start":"2026-02-17T16:11:27.888762Z","end":"2026-02-17T16:11:28.06265Z","steps":["trace[1127817778] 'range keys from in-memory index tree' (duration: 173.668911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:28.063596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.932266ms","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-17T16:11:28.063662Z","caller":"traceutil/trace.go:171","msg":"trace[2094902192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"898.004129ms","start":"2026-02-17T16:11:27.165641Z","end":"2026-02-17T16:11:28.063645Z","steps":["trace[2094902192] 'range keys from in-memory index tree' (duration: 897.861486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:28.063697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:27.1656Z","time spent":"898.09019ms","remote":"127.0.0.1:52340","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-17T16:11:28.063915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.756441ms","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-17T16:11:28.06395Z","caller":"traceutil/trace.go:171","msg":"trace[285682918] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:375; }","duration":"790.822902ms","start":"2026-02-17T16:11:27.273116Z","end":"2026-02-17T16:11:28.063939Z","steps":["trace[285682918] 'range keys from in-memory index tree' (duration: 790.676229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:28.063984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:27.273098Z","time spent":"790.879344ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-17T16:11:28.064127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.129955ms","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-17T16:11:28.064155Z","caller":"traceutil/trace.go:171","msg":"trace[540581845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"627.158496ms","start":"2026-02-17T16:11:27.436987Z","end":"2026-02-17T16:11:28.064145Z","steps":["trace[540581845] 'range keys from in-memory index tree' (duration: 627.058463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:28.064181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:27.436928Z","time spent":"627.246058ms","remote":"127.0.0.1:52340","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-17T16:11:28.064191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.857051011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:11:28.064233Z","caller":"traceutil/trace.go:171","msg":"trace[940143138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"1.857123873s","start":"2026-02-17T16:11:26.207097Z","end":"2026-02-17T16:11:28.064221Z","steps":["trace[940143138] 'range keys from in-memory index tree' (duration: 1.857003979s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:28.064307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.338158ms","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-17T16:11:28.064262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:26.207083Z","time spent":"1.857172413s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:11:28.064336Z","caller":"traceutil/trace.go:171","msg":"trace[337136272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"618.41971ms","start":"2026-02-17T16:11:27.445907Z","end":"2026-02-17T16:11:28.064327Z","steps":["trace[337136272] 'range keys from in-memory index tree' (duration: 618.270747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:28.064364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:27.445886Z","time spent":"618.470342ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:11:28.064485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836537951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-17T16:11:28.067547Z","caller":"traceutil/trace.go:171","msg":"trace[1266387929] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:375; }","duration":"1.839624711s","start":"2026-02-17T16:11:26.227905Z","end":"2026-02-17T16:11:28.067529Z","steps":["trace[1266387929] 'range keys from in-memory index tree' (duration: 1.83647676s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:28.067607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:26.227832Z","time spent":"1.839759475s","remote":"127.0.0.1:52264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4085,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-17T16:11:43.218764Z","caller":"traceutil/trace.go:171","msg":"trace[727858859] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"775.090641ms","start":"2026-02-17T16:11:42.443659Z","end":"2026-02-17T16:11:43.21875Z","steps":["trace[727858859] 'process raft request' (duration: 774.694962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:43.218894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:42.443645Z","time spent":"775.196424ms","remote":"127.0.0.1:52264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:11:44.492363Z","caller":"traceutil/trace.go:171","msg":"trace[2017420212] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"314.714213ms","start":"2026-02-17T16:11:44.177633Z","end":"2026-02-17T16:11:44.492348Z","steps":["trace[2017420212] 'read index received' (duration: 314.630031ms)","trace[2017420212] 'applied index is now lower than readState.Index' (duration: 83.291µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:11:44.4924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:43.700375Z","time spent":"792.02004ms","remote":"127.0.0.1:52128","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T16:11:44.492489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.836645ms","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-17T16:11:44.492509Z","caller":"traceutil/trace.go:171","msg":"trace[1018523418] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"314.896666ms","start":"2026-02-17T16:11:44.177607Z","end":"2026-02-17T16:11:44.492504Z","steps":["trace[1018523418] 'agreement among raft nodes before linearized reading' (duration: 314.827244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:44.492529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:44.177594Z","time spent":"314.930727ms","remote":"127.0.0.1:52340","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-17T16:11:44.492696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.030009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-17T16:11:44.49271Z","caller":"traceutil/trace.go:171","msg":"trace[248992509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"315.050369ms","start":"2026-02-17T16:11:44.177655Z","end":"2026-02-17T16:11:44.492705Z","steps":["trace[248992509] 'agreement among raft nodes before linearized reading' (duration: 315.025629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:44.492722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:44.177652Z","time spent":"315.06708ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:11:44.492727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.278829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:11:44.492759Z","caller":"traceutil/trace.go:171","msg":"trace[3278600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"278.336411ms","start":"2026-02-17T16:11:44.214411Z","end":"2026-02-17T16:11:44.492747Z","steps":["trace[3278600] 'agreement among raft nodes before linearized reading' (duration: 278.27578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:44.49282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.400343ms","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-17T16:11:44.492849Z","caller":"traceutil/trace.go:171","msg":"trace[434208748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"308.441063ms","start":"2026-02-17T16:11:44.184402Z","end":"2026-02-17T16:11:44.492843Z","steps":["trace[434208748] 'agreement among raft nodes before linearized reading' (duration: 308.368541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:44.492862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:44.184397Z","time spent":"308.460484ms","remote":"127.0.0.1:52340","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-17T16:11:44.492901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.077762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T16:11:44.492912Z","caller":"traceutil/trace.go:171","msg":"trace[565926233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"315.104912ms","start":"2026-02-17T16:11:44.177803Z","end":"2026-02-17T16:11:44.492908Z","steps":["trace[565926233] 'agreement among raft nodes before linearized reading' (duration: 315.087451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:44.492922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:44.1778Z","time spent":"315.119912ms","remote":"127.0.0.1:52340","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-17T16:11:46.418984Z","caller":"traceutil/trace.go:171","msg":"trace[684206495] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"108.819482ms","start":"2026-02-17T16:11:46.310145Z","end":"2026-02-17T16:11:46.418965Z","steps":["trace[684206495] 'process raft request' (duration: 108.671988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:49.132041Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826742704078405,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T16:11:49.504314Z","caller":"traceutil/trace.go:171","msg":"trace[453231572] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"874.823518ms","start":"2026-02-17T16:11:48.629469Z","end":"2026-02-17T16:11:49.504293Z","steps":["trace[453231572] 'process raft request' (duration: 874.706036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:49.504443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:48.629455Z","time spent":"874.922201ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:11:49.507898Z","caller":"traceutil/trace.go:171","msg":"trace[1021914657] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"875.895743ms","start":"2026-02-17T16:11:48.631982Z","end":"2026-02-17T16:11:49.507878Z","steps":["trace[1021914657] 'read index received' (duration: 872.421705ms)","trace[1021914657] 'applied index is now lower than readState.Index' (duration: 3.472818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:11:49.508062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.059507ms","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-17T16:11:49.508102Z","caller":"traceutil/trace.go:171","msg":"trace[1025066001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"876.113868ms","start":"2026-02-17T16:11:48.631979Z","end":"2026-02-17T16:11:49.508093Z","steps":["trace[1025066001] 'agreement among raft nodes before linearized reading' (duration: 876.016216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:49.508141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:48.631943Z","time spent":"876.19049ms","remote":"127.0.0.1:52340","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-17T16:11:49.508345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.880112ms","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-17T16:11:49.508382Z","caller":"traceutil/trace.go:171","msg":"trace[817512245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:488; }","duration":"875.916613ms","start":"2026-02-17T16:11:48.632457Z","end":"2026-02-17T16:11:49.508374Z","steps":["trace[817512245] 'agreement among raft nodes before linearized reading' (duration: 875.856412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:49.508414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:48.632453Z","time spent":"875.954945ms","remote":"127.0.0.1:52340","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-17T16:11:49.50856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.470409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:11:49.508595Z","caller":"traceutil/trace.go:171","msg":"trace[1815069222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"302.52963ms","start":"2026-02-17T16:11:49.206058Z","end":"2026-02-17T16:11:49.508587Z","steps":["trace[1815069222] 'agreement among raft nodes before linearized reading' (duration: 302.480529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:49.508626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:49.206044Z","time spent":"302.5764ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:11:49.508758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.653304ms","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-17T16:11:49.50879Z","caller":"traceutil/trace.go:171","msg":"trace[597368671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"874.695396ms","start":"2026-02-17T16:11:48.634087Z","end":"2026-02-17T16:11:49.508783Z","steps":["trace[597368671] 'agreement among raft nodes before linearized reading' (duration: 874.643195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:49.508823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:48.634083Z","time spent":"874.734187ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:11:51.072767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.983293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qkbvc\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T16:11:51.072863Z","caller":"traceutil/trace.go:171","msg":"trace[163796225] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qkbvc; range_end:; response_count:1; response_revision:494; }","duration":"235.113835ms","start":"2026-02-17T16:11:50.837737Z","end":"2026-02-17T16:11:51.072851Z","steps":["trace[163796225] 'range keys from in-memory index tree' (duration: 234.723327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.427836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826742704078445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:11:53.928996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826742704078445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:11:53.929629Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042580299s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:11:53.929867Z","caller":"traceutil/trace.go:171","msg":"trace[463054812] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"1.002761799s","start":"2026-02-17T16:11:52.927088Z","end":"2026-02-17T16:11:53.92985Z","steps":["trace[463054812] 'read index received' (duration: 1.002629806s)","trace[463054812] 'applied index is now lower than readState.Index' (duration: 131.313µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:11:53.930096Z","caller":"traceutil/trace.go:171","msg":"trace[805104824] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.04309876s","start":"2026-02-17T16:11:52.886987Z","end":"2026-02-17T16:11:53.930086Z","steps":["trace[805104824] 'process raft request' (duration: 1.042774283s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.930259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:52.886978Z","time spent":"1.043228313s","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4807,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:11:53.930402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003311281s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-17T16:11:53.930455Z","caller":"traceutil/trace.go:171","msg":"trace[744610240] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:511; }","duration":"1.003357633s","start":"2026-02-17T16:11:52.927059Z","end":"2026-02-17T16:11:53.930417Z","steps":["trace[744610240] 'agreement among raft nodes before linearized reading' (duration: 1.003315292s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.93048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:52.927046Z","time spent":"1.003427404s","remote":"127.0.0.1:52220","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-17T16:11:53.930624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.734357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:11:53.930644Z","caller":"traceutil/trace.go:171","msg":"trace[121793058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"721.793038ms","start":"2026-02-17T16:11:53.208845Z","end":"2026-02-17T16:11:53.930638Z","steps":["trace[121793058] 'agreement among raft nodes before linearized reading' (duration: 721.738366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.930661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:53.208825Z","time spent":"721.83165ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:11:53.932406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.510842ms","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-17T16:11:53.932434Z","caller":"traceutil/trace.go:171","msg":"trace[1957143750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"393.547544ms","start":"2026-02-17T16:11:53.538872Z","end":"2026-02-17T16:11:53.93242Z","steps":["trace[1957143750] 'agreement among raft nodes before linearized reading' (duration: 393.506503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.932449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:53.538867Z","time spent":"393.578334ms","remote":"127.0.0.1:52340","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-17T16:11:53.932641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.549926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:11:53.932655Z","caller":"traceutil/trace.go:171","msg":"trace[2020435795] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:511; }","duration":"232.916615ms","start":"2026-02-17T16:11:53.699734Z","end":"2026-02-17T16:11:53.932651Z","steps":["trace[2020435795] 'agreement among raft nodes before linearized reading' (duration: 232.892064ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.93283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.801442ms","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-17T16:11:53.932845Z","caller":"traceutil/trace.go:171","msg":"trace[1175984454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"399.842704ms","start":"2026-02-17T16:11:53.532998Z","end":"2026-02-17T16:11:53.93284Z","steps":["trace[1175984454] 'agreement among raft nodes before linearized reading' (duration: 399.783763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.932864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:53.532993Z","time spent":"399.868424ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:11:53.932938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.254393ms","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-17T16:11:53.932949Z","caller":"traceutil/trace.go:171","msg":"trace[1469260915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:511; }","duration":"404.286413ms","start":"2026-02-17T16:11:53.528659Z","end":"2026-02-17T16:11:53.932946Z","steps":["trace[1469260915] 'agreement among raft nodes before linearized reading' (duration: 404.251392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.932963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:53.528646Z","time spent":"404.314843ms","remote":"127.0.0.1:52340","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-17T16:11:53.933025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.520854ms","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-17T16:11:53.933036Z","caller":"traceutil/trace.go:171","msg":"trace[2072406787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"415.565375ms","start":"2026-02-17T16:11:53.517467Z","end":"2026-02-17T16:11:53.933033Z","steps":["trace[2072406787] 'agreement among raft nodes before linearized reading' (duration: 415.545155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:53.933048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:53.517447Z","time spent":"415.597655ms","remote":"127.0.0.1:52340","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-17T16:11:54.186904Z","caller":"traceutil/trace.go:171","msg":"trace[2015731301] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"153.141862ms","start":"2026-02-17T16:11:54.033739Z","end":"2026-02-17T16:11:54.186881Z","steps":["trace[2015731301] 'read index received' (duration: 12.652053ms)","trace[2015731301] 'applied index is now lower than readState.Index' (duration: 140.488879ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:11:54.187015Z","caller":"traceutil/trace.go:171","msg":"trace[1455490053] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"158.546823ms","start":"2026-02-17T16:11:54.028458Z","end":"2026-02-17T16:11:54.187005Z","steps":["trace[1455490053] 'process raft request' (duration: 91.73194ms)","trace[1455490053] 'compare' (duration: 66.593618ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:11:54.187271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.51152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:11:54.187308Z","caller":"traceutil/trace.go:171","msg":"trace[1347053608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:518; }","duration":"153.574932ms","start":"2026-02-17T16:11:54.033722Z","end":"2026-02-17T16:11:54.187296Z","steps":["trace[1347053608] 'agreement among raft nodes before linearized reading' (duration: 153.47233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:54.187504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.256109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-17T16:11:54.187542Z","caller":"traceutil/trace.go:171","msg":"trace[354242247] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:518; }","duration":"138.31234ms","start":"2026-02-17T16:11:54.049218Z","end":"2026-02-17T16:11:54.18753Z","steps":["trace[354242247] 'agreement among raft nodes before linearized reading' (duration: 138.219118ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.087153Z","caller":"traceutil/trace.go:171","msg":"trace[113405583] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"439.727137ms","start":"2026-02-17T16:11:55.647405Z","end":"2026-02-17T16:11:56.087132Z","steps":["trace[113405583] 'process raft request' (duration: 439.41373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:56.087312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:55.647378Z","time spent":"439.872969ms","remote":"127.0.0.1:52144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:11:56.201527Z","caller":"traceutil/trace.go:171","msg":"trace[735964108] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:569; }","duration":"332.47142ms","start":"2026-02-17T16:11:55.869034Z","end":"2026-02-17T16:11:56.201506Z","steps":["trace[735964108] 'read index received' (duration: 217.856208ms)","trace[735964108] 'applied index is now lower than readState.Index' (duration: 114.614342ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:11:56.201961Z","caller":"traceutil/trace.go:171","msg":"trace[1651329947] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"334.163967ms","start":"2026-02-17T16:11:55.867772Z","end":"2026-02-17T16:11:56.201936Z","steps":["trace[1651329947] 'process raft request' (duration: 333.680887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:56.20222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.169816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qkbvc\" ","response":"range_response_count:1 size:17415"} {"level":"info","ts":"2026-02-17T16:11:56.20236Z","caller":"traceutil/trace.go:171","msg":"trace[959982539] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qkbvc; range_end:; response_count:1; response_revision:554; }","duration":"333.339189ms","start":"2026-02-17T16:11:55.869006Z","end":"2026-02-17T16:11:56.202345Z","steps":["trace[959982539] 'agreement among raft nodes before linearized reading' (duration: 332.945211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:56.20248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:55.868997Z","time spent":"333.468922ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17438,"request content":"key:\"/registry/pods/kube-system/cilium-qkbvc\" "} {"level":"info","ts":"2026-02-17T16:11:56.203026Z","caller":"traceutil/trace.go:171","msg":"trace[1113659857] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"360.288792ms","start":"2026-02-17T16:11:55.842719Z","end":"2026-02-17T16:11:56.203007Z","steps":["trace[1113659857] 'process raft request' (duration: 273.321709ms)","trace[1113659857] 'compare' (duration: 85.286615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:11:56.202605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:55.867754Z","time spent":"334.27676ms","remote":"127.0.0.1:52182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":749,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:11:56.203308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:11:55.842695Z","time spent":"360.465016ms","remote":"127.0.0.1:52144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:11:56.21686Z","caller":"traceutil/trace.go:171","msg":"trace[2132846396] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"117.338152ms","start":"2026-02-17T16:11:56.099509Z","end":"2026-02-17T16:11:56.216847Z","steps":["trace[2132846396] 'process raft request' (duration: 117.041075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:56.217061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.782792ms","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-17T16:11:56.217085Z","caller":"traceutil/trace.go:171","msg":"trace[1337152547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"168.816043ms","start":"2026-02-17T16:11:56.048263Z","end":"2026-02-17T16:11:56.217079Z","steps":["trace[1337152547] 'agreement among raft nodes before linearized reading' (duration: 168.742542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:56.21716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.599972ms","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-17T16:11:56.217293Z","caller":"traceutil/trace.go:171","msg":"trace[694151942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"187.849578ms","start":"2026-02-17T16:11:56.02943Z","end":"2026-02-17T16:11:56.21728Z","steps":["trace[694151942] 'agreement among raft nodes before linearized reading' (duration: 187.591882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:56.217523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.203658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T16:11:56.217601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.571036ms","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-17T16:11:56.217623Z","caller":"traceutil/trace.go:171","msg":"trace[196428565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"180.614147ms","start":"2026-02-17T16:11:56.037003Z","end":"2026-02-17T16:11:56.217617Z","steps":["trace[196428565] 'agreement among raft nodes before linearized reading' (duration: 180.584566ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.217611Z","caller":"traceutil/trace.go:171","msg":"trace[1592255379] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:555; }","duration":"176.29865ms","start":"2026-02-17T16:11:56.041303Z","end":"2026-02-17T16:11:56.217601Z","steps":["trace[1592255379] 'agreement among raft nodes before linearized reading' (duration: 176.197857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:56.217547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.040102ms","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-17T16:11:56.217899Z","caller":"traceutil/trace.go:171","msg":"trace[68680587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"188.42362ms","start":"2026-02-17T16:11:56.029466Z","end":"2026-02-17T16:11:56.21789Z","steps":["trace[68680587] 'agreement among raft nodes before linearized reading' (duration: 188.056832ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.395373Z","caller":"traceutil/trace.go:171","msg":"trace[754168984] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"132.600702ms","start":"2026-02-17T16:11:56.262749Z","end":"2026-02-17T16:11:56.39535Z","steps":["trace[754168984] 'process raft request' (duration: 132.565461ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.39553Z","caller":"traceutil/trace.go:171","msg":"trace[2140176126] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"136.973641ms","start":"2026-02-17T16:11:56.258548Z","end":"2026-02-17T16:11:56.395521Z","steps":["trace[2140176126] 'process raft request' (duration: 136.661544ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.395766Z","caller":"traceutil/trace.go:171","msg":"trace[1219613672] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"133.099763ms","start":"2026-02-17T16:11:56.262656Z","end":"2026-02-17T16:11:56.395756Z","steps":["trace[1219613672] 'process raft request' (duration: 132.600872ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.396011Z","caller":"traceutil/trace.go:171","msg":"trace[267575457] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"133.287919ms","start":"2026-02-17T16:11:56.262713Z","end":"2026-02-17T16:11:56.396001Z","steps":["trace[267575457] 'process raft request' (duration: 132.575262ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.395997Z","caller":"traceutil/trace.go:171","msg":"trace[1267606338] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"144.500279ms","start":"2026-02-17T16:11:56.25147Z","end":"2026-02-17T16:11:56.395971Z","steps":["trace[1267606338] 'process raft request' (duration: 143.628449ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.42921Z","caller":"traceutil/trace.go:171","msg":"trace[117493029] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:574; }","duration":"123.219193ms","start":"2026-02-17T16:11:56.305975Z","end":"2026-02-17T16:11:56.429194Z","steps":["trace[117493029] 'read index received' (duration: 89.075221ms)","trace[117493029] 'applied index is now lower than readState.Index' (duration: 34.143082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:11:56.429364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.372396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T16:11:56.429454Z","caller":"traceutil/trace.go:171","msg":"trace[1184222901] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:562; }","duration":"123.50168ms","start":"2026-02-17T16:11:56.305941Z","end":"2026-02-17T16:11:56.429443Z","steps":["trace[1184222901] 'agreement among raft nodes before linearized reading' (duration: 123.318236ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:56.429813Z","caller":"traceutil/trace.go:171","msg":"trace[242892783] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"157.177612ms","start":"2026-02-17T16:11:56.272622Z","end":"2026-02-17T16:11:56.4298Z","steps":["trace[242892783] 'process raft request' (duration: 156.480365ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:11:59.482404Z","caller":"traceutil/trace.go:171","msg":"trace[1421556277] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"131.079058ms","start":"2026-02-17T16:11:59.351304Z","end":"2026-02-17T16:11:59.482384Z","steps":["trace[1421556277] 'read index received' (duration: 130.810782ms)","trace[1421556277] 'applied index is now lower than readState.Index' (duration: 267.416µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:11:59.482701Z","caller":"traceutil/trace.go:171","msg":"trace[1475429351] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"179.788528ms","start":"2026-02-17T16:11:59.302902Z","end":"2026-02-17T16:11:59.48269Z","steps":["trace[1475429351] 'process raft request' (duration: 179.322597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:59.482883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.558479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:11:59.482914Z","caller":"traceutil/trace.go:171","msg":"trace[1697267805] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:600; }","duration":"131.631641ms","start":"2026-02-17T16:11:59.351273Z","end":"2026-02-17T16:11:59.482904Z","steps":["trace[1697267805] 'agreement among raft nodes before linearized reading' (duration: 131.567329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:59.48307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.711533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:11:59.483097Z","caller":"traceutil/trace.go:171","msg":"trace[1909023645] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:600; }","duration":"131.751584ms","start":"2026-02-17T16:11:59.351337Z","end":"2026-02-17T16:11:59.483089Z","steps":["trace[1909023645] 'agreement among raft nodes before linearized reading' (duration: 131.711033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:11:59.483558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.177713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:11:59.483599Z","caller":"traceutil/trace.go:171","msg":"trace[907088476] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:600; }","duration":"132.232194ms","start":"2026-02-17T16:11:59.351358Z","end":"2026-02-17T16:11:59.483591Z","steps":["trace[907088476] 'agreement among raft nodes before linearized reading' (duration: 132.174483ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:00.636668Z","caller":"traceutil/trace.go:171","msg":"trace[1268311462] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"205.185873ms","start":"2026-02-17T16:12:00.431339Z","end":"2026-02-17T16:12:00.636525Z","steps":["trace[1268311462] 'process raft request' (duration: 203.665628ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:00.638058Z","caller":"traceutil/trace.go:171","msg":"trace[956497673] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:620; }","duration":"136.621398ms","start":"2026-02-17T16:12:00.499999Z","end":"2026-02-17T16:12:00.636621Z","steps":["trace[956497673] 'read index received' (duration: 135.548493ms)","trace[956497673] 'applied index is now lower than readState.Index' (duration: 1.072155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:00.638503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.485282ms","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-17T16:12:00.638712Z","caller":"traceutil/trace.go:171","msg":"trace[833646666] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:604; }","duration":"138.736747ms","start":"2026-02-17T16:12:00.49997Z","end":"2026-02-17T16:12:00.638707Z","steps":["trace[833646666] 'agreement among raft nodes before linearized reading' (duration: 138.372059ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:00.638609Z","caller":"traceutil/trace.go:171","msg":"trace[1256935135] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"207.002536ms","start":"2026-02-17T16:12:00.431595Z","end":"2026-02-17T16:12:00.638597Z","steps":["trace[1256935135] 'process raft request' (duration: 204.811284ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:00.638628Z","caller":"traceutil/trace.go:171","msg":"trace[344344904] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"205.768736ms","start":"2026-02-17T16:12:00.432855Z","end":"2026-02-17T16:12:00.638624Z","steps":["trace[344344904] 'process raft request' (duration: 203.726798ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:00.784299Z","caller":"traceutil/trace.go:171","msg":"trace[738916363] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"138.40899ms","start":"2026-02-17T16:12:00.645873Z","end":"2026-02-17T16:12:00.784282Z","steps":["trace[738916363] 'process raft request' (duration: 138.374359ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:00.784507Z","caller":"traceutil/trace.go:171","msg":"trace[409320164] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"139.164608ms","start":"2026-02-17T16:12:00.645336Z","end":"2026-02-17T16:12:00.784501Z","steps":["trace[409320164] 'process raft request' (duration: 96.838667ms)","trace[409320164] 'compare' (duration: 41.985463ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:00.784718Z","caller":"traceutil/trace.go:171","msg":"trace[1140931200] transaction","detail":"{read_only:false; number_of_response:0; response_revision:606; }","duration":"138.722427ms","start":"2026-02-17T16:12:00.645985Z","end":"2026-02-17T16:12:00.784707Z","steps":["trace[1140931200] 'process raft request' (duration: 138.286717ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:03.111158Z","caller":"traceutil/trace.go:171","msg":"trace[1062649631] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"314.166594ms","start":"2026-02-17T16:12:02.796964Z","end":"2026-02-17T16:12:03.11113Z","steps":["trace[1062649631] 'read index received' (duration: 314.054461ms)","trace[1062649631] 'applied index is now lower than readState.Index' (duration: 111.443µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:03.111294Z","caller":"traceutil/trace.go:171","msg":"trace[1247439885] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"374.856884ms","start":"2026-02-17T16:12:02.73641Z","end":"2026-02-17T16:12:03.111267Z","steps":["trace[1247439885] 'process raft request' (duration: 374.623259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:03.111485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.507183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qkbvc\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-02-17T16:12:03.111518Z","caller":"traceutil/trace.go:171","msg":"trace[11253496] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qkbvc; range_end:; response_count:1; response_revision:618; }","duration":"314.575974ms","start":"2026-02-17T16:12:02.796936Z","end":"2026-02-17T16:12:03.111511Z","steps":["trace[11253496] 'agreement among raft nodes before linearized reading' (duration: 314.40032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:03.111563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:02.795826Z","time spent":"315.731481ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-qkbvc\" "} {"level":"warn","ts":"2026-02-17T16:12:03.113419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:02.736393Z","time spent":"375.002417ms","remote":"127.0.0.1:36736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:12:03.347768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.561595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:12:03.348116Z","caller":"traceutil/trace.go:171","msg":"trace[1930033534] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"236.491745ms","start":"2026-02-17T16:12:03.11134Z","end":"2026-02-17T16:12:03.347832Z","steps":["trace[1930033534] 'read index received' (duration: 29.822047ms)","trace[1930033534] 'applied index is now lower than readState.Index' (duration: 206.668688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:03.348326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.505595ms","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-17T16:12:03.348352Z","caller":"traceutil/trace.go:171","msg":"trace[343527684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:619; }","duration":"550.547815ms","start":"2026-02-17T16:12:02.797797Z","end":"2026-02-17T16:12:03.348345Z","steps":["trace[343527684] 'agreement among raft nodes before linearized reading' (duration: 550.349031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:03.348501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:02.797792Z","time spent":"550.651618ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-17T16:12:03.348915Z","caller":"traceutil/trace.go:171","msg":"trace[1284551079] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"515.192849ms","start":"2026-02-17T16:12:02.833713Z","end":"2026-02-17T16:12:03.348906Z","steps":["trace[1284551079] 'process raft request' (duration: 307.440816ms)","trace[1284551079] 'compare' (duration: 206.458953ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:03.350326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:02.833695Z","time spent":"515.24236ms","remote":"127.0.0.1:36736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2263,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T16:12:03.350812Z","caller":"traceutil/trace.go:171","msg":"trace[582276040] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"214.06515ms","start":"2026-02-17T16:12:03.136741Z","end":"2026-02-17T16:12:03.350806Z","steps":["trace[582276040] 'process raft request' (duration: 213.997469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:07.206596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826742704078727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:12:07.707611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826742704078727,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:12:08.092612Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.511797174s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:12:08.093181Z","caller":"traceutil/trace.go:171","msg":"trace[1340405550] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"1.512497821s","start":"2026-02-17T16:12:06.580654Z","end":"2026-02-17T16:12:08.093151Z","steps":["trace[1340405550] 'process raft request' (duration: 1.512319106s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:08.093375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:06.580623Z","time spent":"1.512616424s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:12:08.106115Z","caller":"traceutil/trace.go:171","msg":"trace[1884120491] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:690; }","duration":"1.400108152s","start":"2026-02-17T16:12:06.705987Z","end":"2026-02-17T16:12:08.106095Z","steps":["trace[1884120491] 'read index received' (duration: 1.387803872s)","trace[1884120491] 'applied index is now lower than readState.Index' (duration: 12.30347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:08.106304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400296836s","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-17T16:12:08.106324Z","caller":"traceutil/trace.go:171","msg":"trace[1044171415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:671; }","duration":"1.400389198s","start":"2026-02-17T16:12:06.705927Z","end":"2026-02-17T16:12:08.106316Z","steps":["trace[1044171415] 'agreement among raft nodes before linearized reading' (duration: 1.400249465s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:08.106347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:06.705883Z","time spent":"1.400459769s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T16:12:08.106551Z","caller":"traceutil/trace.go:171","msg":"trace[1010591741] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"1.32997276s","start":"2026-02-17T16:12:06.776568Z","end":"2026-02-17T16:12:08.106541Z","steps":["trace[1010591741] 'process raft request' (duration: 1.329387626s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:08.106611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:06.776554Z","time spent":"1.330009901s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:12:08.106677Z","caller":"traceutil/trace.go:171","msg":"trace[625790989] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"1.160598835s","start":"2026-02-17T16:12:06.946074Z","end":"2026-02-17T16:12:08.106673Z","steps":["trace[625790989] 'process raft request' (duration: 1.159987301s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:08.10671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:06.946046Z","time spent":"1.160640786s","remote":"127.0.0.1:52182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:12:08.106962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.58144ms","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-17T16:12:08.106977Z","caller":"traceutil/trace.go:171","msg":"trace[280872270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:671; }","duration":"683.59719ms","start":"2026-02-17T16:12:07.423375Z","end":"2026-02-17T16:12:08.106972Z","steps":["trace[280872270] 'agreement among raft nodes before linearized reading' (duration: 683.549949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:08.106992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:07.4233Z","time spent":"683.688252ms","remote":"127.0.0.1:52340","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-17T16:12:08.107112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158747372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-h6l6h\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-17T16:12:08.107125Z","caller":"traceutil/trace.go:171","msg":"trace[2004775278] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-h6l6h; range_end:; response_count:1; response_revision:671; }","duration":"1.158784773s","start":"2026-02-17T16:12:06.948336Z","end":"2026-02-17T16:12:08.107121Z","steps":["trace[2004775278] 'agreement among raft nodes before linearized reading' (duration: 1.158744722s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:08.107137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:06.948327Z","time spent":"1.158807374s","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4847,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-h6l6h\" "} {"level":"warn","ts":"2026-02-17T16:12:08.107295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355810134s","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-17T16:12:08.107308Z","caller":"traceutil/trace.go:171","msg":"trace[1497019232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:671; }","duration":"1.355836806s","start":"2026-02-17T16:12:06.751467Z","end":"2026-02-17T16:12:08.107304Z","steps":["trace[1497019232] 'agreement among raft nodes before linearized reading' (duration: 1.355798405s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:08.107319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:06.751447Z","time spent":"1.355869476s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:12:08.109901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361316993s","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-17T16:12:08.109991Z","caller":"traceutil/trace.go:171","msg":"trace[633335926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"1.364800845s","start":"2026-02-17T16:12:06.745165Z","end":"2026-02-17T16:12:08.109966Z","steps":["trace[633335926] 'agreement among raft nodes before linearized reading' (duration: 1.361236112s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:08.11003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:06.745147Z","time spent":"1.364872356s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:12:12.63554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826742704078754,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T16:12:13.034262Z","caller":"traceutil/trace.go:171","msg":"trace[302380523] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"925.183734ms","start":"2026-02-17T16:12:12.109058Z","end":"2026-02-17T16:12:13.034241Z","steps":["trace[302380523] 'process raft request' (duration: 925.057201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:13.034395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:12.109049Z","time spent":"925.280706ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:12:13.509046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.631323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:12:13.509243Z","caller":"traceutil/trace.go:171","msg":"trace[68312492] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"900.955978ms","start":"2026-02-17T16:12:12.608271Z","end":"2026-02-17T16:12:13.509227Z","steps":["trace[68312492] 'process raft request' (duration: 791.617518ms)","trace[68312492] 'compare' (duration: 108.122142ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:13.509314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:12.608251Z","time spent":"901.02773ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:12:13.509919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374580054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-17T16:12:13.509959Z","caller":"traceutil/trace.go:171","msg":"trace[1879942355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:683; }","duration":"1.374649325s","start":"2026-02-17T16:12:12.1353Z","end":"2026-02-17T16:12:13.509949Z","steps":["trace[1879942355] 'agreement among raft nodes before linearized reading' (duration: 1.374521702s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:13.509992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:12.135285Z","time spent":"1.374697947s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T16:12:13.509167Z","caller":"traceutil/trace.go:171","msg":"trace[1322337655] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"1.373819056s","start":"2026-02-17T16:12:12.135331Z","end":"2026-02-17T16:12:13.50915Z","steps":["trace[1322337655] 'read index received' (duration: 901.446429ms)","trace[1322337655] 'applied index is now lower than readState.Index' (duration: 472.371377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:13.510457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305931127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:13.510489Z","caller":"traceutil/trace.go:171","msg":"trace[452102493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"1.305982417s","start":"2026-02-17T16:12:12.204497Z","end":"2026-02-17T16:12:13.51048Z","steps":["trace[452102493] 'agreement among raft nodes before linearized reading' (duration: 1.305935366s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:13.510519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:12.204485Z","time spent":"1.306028359s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:12:13.510666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209036039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:13.510697Z","caller":"traceutil/trace.go:171","msg":"trace[234330985] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:683; }","duration":"1.2090898s","start":"2026-02-17T16:12:12.301597Z","end":"2026-02-17T16:12:13.510686Z","steps":["trace[234330985] 'agreement among raft nodes before linearized reading' (duration: 1.209046959s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:13.510725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:12.301584Z","time spent":"1.209134542s","remote":"127.0.0.1:52246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:12:13.511081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.363374042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T16:12:13.511111Z","caller":"traceutil/trace.go:171","msg":"trace[1396729375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.363405323s","start":"2026-02-17T16:12:12.147697Z","end":"2026-02-17T16:12:13.511102Z","steps":["trace[1396729375] 'agreement among raft nodes before linearized reading' (duration: 1.363318961s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:13.511139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:12.147681Z","time spent":"1.363451243s","remote":"127.0.0.1:52340","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-17T16:12:13.511472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36459009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T16:12:13.511507Z","caller":"traceutil/trace.go:171","msg":"trace[96520671] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:683; }","duration":"1.364651751s","start":"2026-02-17T16:12:12.146847Z","end":"2026-02-17T16:12:13.511499Z","steps":["trace[96520671] 'agreement among raft nodes before linearized reading' (duration: 1.364563019s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:13.511534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:12.146832Z","time spent":"1.364694941s","remote":"127.0.0.1:52340","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-17T16:12:13.513177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377767258s","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-17T16:12:13.513224Z","caller":"traceutil/trace.go:171","msg":"trace[616559849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:683; }","duration":"1.377813229s","start":"2026-02-17T16:12:12.135397Z","end":"2026-02-17T16:12:13.51321Z","steps":["trace[616559849] 'agreement among raft nodes before linearized reading' (duration: 1.375002233s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:13.513252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:12.135384Z","time spent":"1.37785968s","remote":"127.0.0.1:52340","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-17T16:12:14.129128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.945774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:12:14.129283Z","caller":"traceutil/trace.go:171","msg":"trace[1074761029] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:706; }","duration":"609.545757ms","start":"2026-02-17T16:12:13.519726Z","end":"2026-02-17T16:12:14.129272Z","steps":["trace[1074761029] 'read index received' (duration: 43.378405ms)","trace[1074761029] 'applied index is now lower than readState.Index' (duration: 566.166732ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:14.129442Z","caller":"traceutil/trace.go:171","msg":"trace[578708935] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"612.160529ms","start":"2026-02-17T16:12:13.517273Z","end":"2026-02-17T16:12:14.129434Z","steps":["trace[578708935] 'process raft request' (duration: 172.853426ms)","trace[578708935] 'compare' (duration: 438.874593ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:14.129492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:13.517267Z","time spent":"612.194179ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:12:14.129649Z","caller":"traceutil/trace.go:171","msg":"trace[29388379] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"609.972618ms","start":"2026-02-17T16:12:13.519669Z","end":"2026-02-17T16:12:14.129642Z","steps":["trace[29388379] 'process raft request' (duration: 609.529097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:14.129683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:13.519659Z","time spent":"610.006188ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:12:14.129764Z","caller":"traceutil/trace.go:171","msg":"trace[450343459] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"608.170515ms","start":"2026-02-17T16:12:13.521587Z","end":"2026-02-17T16:12:14.129758Z","steps":["trace[450343459] 'process raft request' (duration: 607.660464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:14.129796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:13.52158Z","time spent":"608.198336ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:12:14.129889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.160692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:14.129907Z","caller":"traceutil/trace.go:171","msg":"trace[1409463792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"610.196223ms","start":"2026-02-17T16:12:13.519705Z","end":"2026-02-17T16:12:14.129901Z","steps":["trace[1409463792] 'agreement among raft nodes before linearized reading' (duration: 610.163712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:14.129924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:13.519699Z","time spent":"610.221653ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:12:14.130059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.912929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:12:14.130076Z","caller":"traceutil/trace.go:171","msg":"trace[861531182] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:687; }","duration":"428.948911ms","start":"2026-02-17T16:12:13.701122Z","end":"2026-02-17T16:12:14.130071Z","steps":["trace[861531182] 'agreement among raft nodes before linearized reading' (duration: 428.878359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:14.130092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:13.701107Z","time spent":"428.982161ms","remote":"127.0.0.1:52128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"info","ts":"2026-02-17T16:12:21.210186Z","caller":"traceutil/trace.go:171","msg":"trace[349810033] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"147.904922ms","start":"2026-02-17T16:12:21.062261Z","end":"2026-02-17T16:12:21.210165Z","steps":["trace[349810033] 'process raft request' (duration: 147.530593ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:38.008112Z","caller":"traceutil/trace.go:171","msg":"trace[409210342] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"307.57777ms","start":"2026-02-17T16:12:37.700512Z","end":"2026-02-17T16:12:38.00809Z","steps":["trace[409210342] 'process raft request' (duration: 307.464857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:38.008279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:37.700498Z","time spent":"307.685452ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:12:38.388136Z","caller":"traceutil/trace.go:171","msg":"trace[1127179648] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"124.136975ms","start":"2026-02-17T16:12:38.263976Z","end":"2026-02-17T16:12:38.388113Z","steps":["trace[1127179648] 'process raft request' (duration: 124.051793ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:38.543687Z","caller":"traceutil/trace.go:171","msg":"trace[1308416218] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"276.936963ms","start":"2026-02-17T16:12:38.266731Z","end":"2026-02-17T16:12:38.543668Z","steps":["trace[1308416218] 'process raft request' (duration: 276.39155ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:38.545664Z","caller":"traceutil/trace.go:171","msg":"trace[12919743] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"273.094373ms","start":"2026-02-17T16:12:38.270549Z","end":"2026-02-17T16:12:38.543643Z","steps":["trace[12919743] 'process raft request' (duration: 272.706744ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:39.546326Z","caller":"traceutil/trace.go:171","msg":"trace[1743786884] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"342.072086ms","start":"2026-02-17T16:12:39.204227Z","end":"2026-02-17T16:12:39.546299Z","steps":["trace[1743786884] 'read index received' (duration: 268.904574ms)","trace[1743786884] 'applied index is now lower than readState.Index' (duration: 73.166182ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:39.546852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.604529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:39.547588Z","caller":"traceutil/trace.go:171","msg":"trace[669883083] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:762; }","duration":"343.394238ms","start":"2026-02-17T16:12:39.204179Z","end":"2026-02-17T16:12:39.547573Z","steps":["trace[669883083] 'agreement among raft nodes before linearized reading' (duration: 342.61274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:39.547699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:39.204159Z","time spent":"343.5206ms","remote":"127.0.0.1:52318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:12:39.548384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.324458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:39.5485Z","caller":"traceutil/trace.go:171","msg":"trace[83562233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"339.021486ms","start":"2026-02-17T16:12:39.209462Z","end":"2026-02-17T16:12:39.548484Z","steps":["trace[83562233] 'agreement among raft nodes before linearized reading' (duration: 338.314768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:39.549011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.803367ms","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-17T16:12:39.549375Z","caller":"traceutil/trace.go:171","msg":"trace[2012026030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"276.190835ms","start":"2026-02-17T16:12:39.273171Z","end":"2026-02-17T16:12:39.549362Z","steps":["trace[2012026030] 'agreement among raft nodes before linearized reading' (duration: 275.739045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:39.548654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:39.209455Z","time spent":"339.184409ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:12:40.364876Z","caller":"traceutil/trace.go:171","msg":"trace[430550530] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"347.581985ms","start":"2026-02-17T16:12:40.017272Z","end":"2026-02-17T16:12:40.364854Z","steps":["trace[430550530] 'process raft request' (duration: 347.435462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:40.365018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:40.017261Z","time spent":"347.685088ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:12:40.736234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.135411ms","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-17T16:12:40.736387Z","caller":"traceutil/trace.go:171","msg":"trace[1126753313] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:764; }","duration":"316.346205ms","start":"2026-02-17T16:12:40.42002Z","end":"2026-02-17T16:12:40.736366Z","steps":["trace[1126753313] 'count revisions from in-memory index tree' (duration: 316.057078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:40.736791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:40.420002Z","time spent":"316.768625ms","remote":"127.0.0.1:52290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:12:40.737506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.740424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T16:12:40.738115Z","caller":"traceutil/trace.go:171","msg":"trace[2106331070] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"158.367977ms","start":"2026-02-17T16:12:40.579731Z","end":"2026-02-17T16:12:40.738099Z","steps":["trace[2106331070] 'range keys from in-memory index tree' (duration: 157.621811ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:40.737814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.732186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T16:12:40.739689Z","caller":"traceutil/trace.go:171","msg":"trace[336710151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"298.625391ms","start":"2026-02-17T16:12:40.441049Z","end":"2026-02-17T16:12:40.739675Z","steps":["trace[336710151] 'range keys from in-memory index tree' (duration: 296.640494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:40.738001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.256192ms","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-17T16:12:40.740219Z","caller":"traceutil/trace.go:171","msg":"trace[650270484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"159.485444ms","start":"2026-02-17T16:12:40.580722Z","end":"2026-02-17T16:12:40.740207Z","steps":["trace[650270484] 'range keys from in-memory index tree' (duration: 157.14486ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:40.886493Z","caller":"traceutil/trace.go:171","msg":"trace[1659545653] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"141.87531ms","start":"2026-02-17T16:12:40.744593Z","end":"2026-02-17T16:12:40.886469Z","steps":["trace[1659545653] 'process raft request' (duration: 141.739767ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:41.017726Z","caller":"traceutil/trace.go:171","msg":"trace[1157729492] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"272.98785ms","start":"2026-02-17T16:12:40.744718Z","end":"2026-02-17T16:12:41.017706Z","steps":["trace[1157729492] 'process raft request' (duration: 241.771579ms)","trace[1157729492] 'compare' (duration: 30.824481ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:41.018094Z","caller":"traceutil/trace.go:171","msg":"trace[1927609484] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"270.973813ms","start":"2026-02-17T16:12:40.747113Z","end":"2026-02-17T16:12:41.018087Z","steps":["trace[1927609484] 'process raft request' (duration: 270.287777ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:12:53.950978Z","caller":"traceutil/trace.go:171","msg":"trace[1333434915] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:888; }","duration":"246.469489ms","start":"2026-02-17T16:12:53.704379Z","end":"2026-02-17T16:12:53.950848Z","steps":["trace[1333434915] 'read index received' (duration: 246.252204ms)","trace[1333434915] 'applied index is now lower than readState.Index' (duration: 216.135µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:53.951119Z","caller":"traceutil/trace.go:171","msg":"trace[633904812] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"310.732324ms","start":"2026-02-17T16:12:53.640366Z","end":"2026-02-17T16:12:53.951099Z","steps":["trace[633904812] 'process raft request' (duration: 310.271493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:53.951212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.808327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-17T16:12:53.951258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:53.640344Z","time spent":"310.837516ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:12:53.951261Z","caller":"traceutil/trace.go:171","msg":"trace[206772975] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:856; }","duration":"246.894669ms","start":"2026-02-17T16:12:53.704348Z","end":"2026-02-17T16:12:53.951243Z","steps":["trace[206772975] 'agreement among raft nodes before linearized reading' (duration: 246.730415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:56.927601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826742704079212,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T16:12:57.275955Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.310351691s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T16:12:57.276247Z","caller":"traceutil/trace.go:171","msg":"trace[625214457] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:896; }","duration":"849.188566ms","start":"2026-02-17T16:12:56.427043Z","end":"2026-02-17T16:12:57.276232Z","steps":["trace[625214457] 'read index received' (duration: 849.041553ms)","trace[625214457] 'applied index is now lower than readState.Index' (duration: 146.303µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:57.276327Z","caller":"traceutil/trace.go:171","msg":"trace[1176370524] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"1.310787741s","start":"2026-02-17T16:12:55.965534Z","end":"2026-02-17T16:12:57.276322Z","steps":["trace[1176370524] 'process raft request' (duration: 1.310552306s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:57.276391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:55.965524Z","time spent":"1.310818252s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:12:57.276547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.846973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:12:57.276594Z","caller":"traceutil/trace.go:171","msg":"trace[2095783673] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:863; }","duration":"823.929215ms","start":"2026-02-17T16:12:56.452648Z","end":"2026-02-17T16:12:57.276577Z","steps":["trace[2095783673] 'agreement among raft nodes before linearized reading' (duration: 823.842103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:57.276633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:56.452635Z","time spent":"823.988216ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:12:57.276768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.720578ms","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-17T16:12:57.276793Z","caller":"traceutil/trace.go:171","msg":"trace[985537162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:863; }","duration":"849.76776ms","start":"2026-02-17T16:12:56.427017Z","end":"2026-02-17T16:12:57.276785Z","steps":["trace[985537162] 'agreement among raft nodes before linearized reading' (duration: 849.713399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:57.276809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:56.427004Z","time spent":"849.8019ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:12:57.27695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.923213ms","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-17T16:12:57.276992Z","caller":"traceutil/trace.go:171","msg":"trace[1397182342] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:863; }","duration":"162.994384ms","start":"2026-02-17T16:12:57.113986Z","end":"2026-02-17T16:12:57.27698Z","steps":["trace[1397182342] 'agreement among raft nodes before linearized reading' (duration: 162.863961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:57.277059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.197108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:12:57.277076Z","caller":"traceutil/trace.go:171","msg":"trace[1877440982] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:863; }","duration":"635.24354ms","start":"2026-02-17T16:12:56.641828Z","end":"2026-02-17T16:12:57.277071Z","steps":["trace[1877440982] 'agreement among raft nodes before linearized reading' (duration: 635.209649ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:57.277091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:56.641814Z","time spent":"635.27268ms","remote":"127.0.0.1:52560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T16:12:57.277184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.390763ms","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-17T16:12:57.277196Z","caller":"traceutil/trace.go:171","msg":"trace[282218275] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:863; }","duration":"330.428194ms","start":"2026-02-17T16:12:56.946764Z","end":"2026-02-17T16:12:57.277192Z","steps":["trace[282218275] 'agreement among raft nodes before linearized reading' (duration: 330.390113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:57.277207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:56.946749Z","time spent":"330.455785ms","remote":"127.0.0.1:52340","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-17T16:12:58.304323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.170773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:12:58.304494Z","caller":"traceutil/trace.go:171","msg":"trace[443127097] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"1.017842363s","start":"2026-02-17T16:12:57.286639Z","end":"2026-02-17T16:12:58.304481Z","steps":["trace[443127097] 'process raft request' (duration: 571.448485ms)","trace[443127097] 'compare' (duration: 445.839365ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:12:58.304533Z","caller":"traceutil/trace.go:171","msg":"trace[388919147] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:898; }","duration":"701.855278ms","start":"2026-02-17T16:12:57.602665Z","end":"2026-02-17T16:12:58.304521Z","steps":["trace[388919147] 'read index received' (duration: 255.381058ms)","trace[388919147] 'applied index is now lower than readState.Index' (duration: 446.47346ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:12:58.304544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:57.28663Z","time spent":"1.017885954s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:12:58.304687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.021292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:12:58.304702Z","caller":"traceutil/trace.go:171","msg":"trace[1339554645] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:867; }","duration":"702.071133ms","start":"2026-02-17T16:12:57.602626Z","end":"2026-02-17T16:12:58.304697Z","steps":["trace[1339554645] 'agreement among raft nodes before linearized reading' (duration: 702.037972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:58.304716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:57.602607Z","time spent":"702.105743ms","remote":"127.0.0.1:52500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-17T16:12:58.3048Z","caller":"traceutil/trace.go:171","msg":"trace[1926783073] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"1.018105029s","start":"2026-02-17T16:12:57.286691Z","end":"2026-02-17T16:12:58.304796Z","steps":["trace[1926783073] 'process raft request' (duration: 1.01771549s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:58.304832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:57.286686Z","time spent":"1.0181245s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:12:58.304867Z","caller":"traceutil/trace.go:171","msg":"trace[750602715] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"1.011334261s","start":"2026-02-17T16:12:57.293529Z","end":"2026-02-17T16:12:58.304864Z","steps":["trace[750602715] 'process raft request' (duration: 1.010957022s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:12:58.304892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:12:57.293516Z","time spent":"1.011361871s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:03.648182Z","caller":"traceutil/trace.go:171","msg":"trace[1461490989] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"282.005662ms","start":"2026-02-17T16:13:03.366162Z","end":"2026-02-17T16:13:03.648168Z","steps":["trace[1461490989] 'read index received' (duration: 281.844518ms)","trace[1461490989] 'applied index is now lower than readState.Index' (duration: 160.634µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:03.648321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.143945ms","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-17T16:13:03.648336Z","caller":"traceutil/trace.go:171","msg":"trace[1010319941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:879; }","duration":"282.196685ms","start":"2026-02-17T16:13:03.366135Z","end":"2026-02-17T16:13:03.648331Z","steps":["trace[1010319941] 'agreement among raft nodes before linearized reading' (duration: 282.104763ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:03.648542Z","caller":"traceutil/trace.go:171","msg":"trace[266367062] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"347.919015ms","start":"2026-02-17T16:13:03.300618Z","end":"2026-02-17T16:13:03.648537Z","steps":["trace[266367062] 'process raft request' (duration: 347.426543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:03.648589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:03.300592Z","time spent":"347.963105ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:06.58964Z","caller":"traceutil/trace.go:171","msg":"trace[1425485338] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"143.523715ms","start":"2026-02-17T16:13:06.446102Z","end":"2026-02-17T16:13:06.589626Z","steps":["trace[1425485338] 'read index received' (duration: 143.413612ms)","trace[1425485338] 'applied index is now lower than readState.Index' (duration: 109.313µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:06.589796Z","caller":"traceutil/trace.go:171","msg":"trace[1527342864] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"160.992477ms","start":"2026-02-17T16:13:06.428798Z","end":"2026-02-17T16:13:06.58979Z","steps":["trace[1527342864] 'process raft request' (duration: 160.752761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:06.589937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.817113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:13:06.589961Z","caller":"traceutil/trace.go:171","msg":"trace[1737317611] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:888; }","duration":"143.858544ms","start":"2026-02-17T16:13:06.446097Z","end":"2026-02-17T16:13:06.589955Z","steps":["trace[1737317611] 'agreement among raft nodes before linearized reading' (duration: 143.798733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:06.590111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.776532ms","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-17T16:13:06.590125Z","caller":"traceutil/trace.go:171","msg":"trace[1983068668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:888; }","duration":"143.810003ms","start":"2026-02-17T16:13:06.446311Z","end":"2026-02-17T16:13:06.590121Z","steps":["trace[1983068668] 'agreement among raft nodes before linearized reading' (duration: 143.741172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:06.59025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.883195ms","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-17T16:13:06.590263Z","caller":"traceutil/trace.go:171","msg":"trace[802463076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:888; }","duration":"143.910515ms","start":"2026-02-17T16:13:06.446349Z","end":"2026-02-17T16:13:06.59026Z","steps":["trace[802463076] 'agreement among raft nodes before linearized reading' (duration: 143.871664ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:07.79227Z","caller":"traceutil/trace.go:171","msg":"trace[1214320119] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"126.573713ms","start":"2026-02-17T16:13:07.665682Z","end":"2026-02-17T16:13:07.792256Z","steps":["trace[1214320119] 'process raft request' (duration: 126.510421ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:08.847674Z","caller":"traceutil/trace.go:171","msg":"trace[2058442448] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:928; }","duration":"246.532402ms","start":"2026-02-17T16:13:08.601127Z","end":"2026-02-17T16:13:08.84766Z","steps":["trace[2058442448] 'read index received' (duration: 246.4191ms)","trace[2058442448] 'applied index is now lower than readState.Index' (duration: 112.762µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:08.848066Z","caller":"traceutil/trace.go:171","msg":"trace[1553710585] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"251.782365ms","start":"2026-02-17T16:13:08.596277Z","end":"2026-02-17T16:13:08.84806Z","steps":["trace[1553710585] 'process raft request' (duration: 251.307803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:08.848213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.071886ms","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-17T16:13:08.84823Z","caller":"traceutil/trace.go:171","msg":"trace[1308257787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"247.111717ms","start":"2026-02-17T16:13:08.601114Z","end":"2026-02-17T16:13:08.848225Z","steps":["trace[1308257787] 'agreement among raft nodes before linearized reading' (duration: 247.039786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:08.848309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.85246ms","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-17T16:13:08.84832Z","caller":"traceutil/trace.go:171","msg":"trace[1906603191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:893; }","duration":"246.87485ms","start":"2026-02-17T16:13:08.601442Z","end":"2026-02-17T16:13:08.848317Z","steps":["trace[1906603191] 'agreement among raft nodes before linearized reading' (duration: 246.84224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:09.600202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.925228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:13:09.600419Z","caller":"traceutil/trace.go:171","msg":"trace[1792865096] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"749.083323ms","start":"2026-02-17T16:13:08.851324Z","end":"2026-02-17T16:13:09.600407Z","steps":["trace[1792865096] 'process raft request' (duration: 310.898569ms)","trace[1792865096] 'compare' (duration: 437.782074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:09.600456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:08.851312Z","time spent":"749.127493ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:09.600633Z","caller":"traceutil/trace.go:171","msg":"trace[714722599] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"748.838067ms","start":"2026-02-17T16:13:08.851788Z","end":"2026-02-17T16:13:09.600626Z","steps":["trace[714722599] 'process raft request' (duration: 748.474579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:09.600664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:08.851783Z","time spent":"748.863098ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:09.60078Z","caller":"traceutil/trace.go:171","msg":"trace[1855234839] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"530.845332ms","start":"2026-02-17T16:13:09.069927Z","end":"2026-02-17T16:13:09.600773Z","steps":["trace[1855234839] 'process raft request' (duration: 530.364301ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:09.600825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:09.069911Z","time spent":"530.893993ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:09.941448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.126373ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T16:13:09.941518Z","caller":"traceutil/trace.go:171","msg":"trace[1158011669] linearizableReadLoop","detail":"{readStateIndex:933; appliedIndex:929; }","duration":"735.511784ms","start":"2026-02-17T16:13:09.205995Z","end":"2026-02-17T16:13:09.941507Z","steps":["trace[1158011669] 'read index received' (duration: 19.81µs)","trace[1158011669] 'applied index is now lower than readState.Index' (duration: 735.490954ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:09.941856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.853192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:09.941879Z","caller":"traceutil/trace.go:171","msg":"trace[289487092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"735.909504ms","start":"2026-02-17T16:13:09.205962Z","end":"2026-02-17T16:13:09.941872Z","steps":["trace[289487092] 'agreement among raft nodes before linearized reading' (duration: 735.868263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:09.941895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:09.205949Z","time spent":"735.942814ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T16:13:09.942034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.475809ms","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-17T16:13:09.94205Z","caller":"traceutil/trace.go:171","msg":"trace[254899943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:896; }","duration":"187.527831ms","start":"2026-02-17T16:13:09.754519Z","end":"2026-02-17T16:13:09.942047Z","steps":["trace[254899943] 'agreement among raft nodes before linearized reading' (duration: 187.472009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:09.942185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.630793ms","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-17T16:13:09.942202Z","caller":"traceutil/trace.go:171","msg":"trace[1313016700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"145.680354ms","start":"2026-02-17T16:13:09.796518Z","end":"2026-02-17T16:13:09.942198Z","steps":["trace[1313016700] 'agreement among raft nodes before linearized reading' (duration: 145.626993ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:10.275313Z","caller":"traceutil/trace.go:171","msg":"trace[607856784] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"327.577964ms","start":"2026-02-17T16:13:09.947721Z","end":"2026-02-17T16:13:10.275299Z","steps":["trace[607856784] 'process raft request' (duration: 327.477812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:10.275406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:09.947707Z","time spent":"327.652286ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:10.313084Z","caller":"traceutil/trace.go:171","msg":"trace[1076631346] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"363.622967ms","start":"2026-02-17T16:13:09.949446Z","end":"2026-02-17T16:13:10.313069Z","steps":["trace[1076631346] 'process raft request' (duration: 363.546975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:10.313186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:09.949436Z","time spent":"363.694048ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:11.749865Z","caller":"traceutil/trace.go:171","msg":"trace[1979042808] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"136.902331ms","start":"2026-02-17T16:13:11.612937Z","end":"2026-02-17T16:13:11.749839Z","steps":["trace[1979042808] 'process raft request' (duration: 92.065675ms)","trace[1979042808] 'compare' (duration: 44.089129ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:16.030833Z","caller":"traceutil/trace.go:171","msg":"trace[1688582585] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"104.551505ms","start":"2026-02-17T16:13:15.926265Z","end":"2026-02-17T16:13:16.030817Z","steps":["trace[1688582585] 'process raft request' (duration: 104.443612ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:18.452329Z","caller":"traceutil/trace.go:171","msg":"trace[167199925] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:983; }","duration":"106.819987ms","start":"2026-02-17T16:13:18.34549Z","end":"2026-02-17T16:13:18.45231Z","steps":["trace[167199925] 'read index received' (duration: 106.742185ms)","trace[167199925] 'applied index is now lower than readState.Index' (duration: 77.132µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:18.4525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.99083ms","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-17T16:13:18.452522Z","caller":"traceutil/trace.go:171","msg":"trace[2104784837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"107.056322ms","start":"2026-02-17T16:13:18.345459Z","end":"2026-02-17T16:13:18.452515Z","steps":["trace[2104784837] 'agreement among raft nodes before linearized reading' (duration: 106.937579ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:18.452594Z","caller":"traceutil/trace.go:171","msg":"trace[299669947] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"146.667346ms","start":"2026-02-17T16:13:18.305905Z","end":"2026-02-17T16:13:18.452573Z","steps":["trace[299669947] 'process raft request' (duration: 146.252287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:19.241323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.252691ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T16:13:19.241457Z","caller":"traceutil/trace.go:171","msg":"trace[224977970] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"173.535587ms","start":"2026-02-17T16:13:19.067907Z","end":"2026-02-17T16:13:19.241443Z","steps":["trace[224977970] 'read index received' (duration: 29.721µs)","trace[224977970] 'applied index is now lower than readState.Index' (duration: 173.504566ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:19.24154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.614689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:19.241566Z","caller":"traceutil/trace.go:171","msg":"trace[185148363] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:949; }","duration":"173.683851ms","start":"2026-02-17T16:13:19.067876Z","end":"2026-02-17T16:13:19.241559Z","steps":["trace[185148363] 'agreement among raft nodes before linearized reading' (duration: 173.604909ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:21.079454Z","caller":"traceutil/trace.go:171","msg":"trace[285598204] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"117.141895ms","start":"2026-02-17T16:13:20.962297Z","end":"2026-02-17T16:13:21.079439Z","steps":["trace[285598204] 'process raft request' (duration: 117.058094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:27.209395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.059708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:13:27.209464Z","caller":"traceutil/trace.go:171","msg":"trace[911417831] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"319.714963ms","start":"2026-02-17T16:13:26.889738Z","end":"2026-02-17T16:13:27.209453Z","steps":["trace[911417831] 'read index received' (duration: 43.321µs)","trace[911417831] 'applied index is now lower than readState.Index' (duration: 319.670542ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:27.209603Z","caller":"traceutil/trace.go:171","msg":"trace[327686321] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"637.87183ms","start":"2026-02-17T16:13:26.571722Z","end":"2026-02-17T16:13:27.209594Z","steps":["trace[327686321] 'process raft request' (duration: 292.555816ms)","trace[327686321] 'compare' (duration: 344.631109ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:27.209644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:26.571705Z","time spent":"637.917051ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:13:27.209815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.088271ms","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-17T16:13:27.209852Z","caller":"traceutil/trace.go:171","msg":"trace[510717290] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:987; }","duration":"320.163943ms","start":"2026-02-17T16:13:26.889679Z","end":"2026-02-17T16:13:27.209843Z","steps":["trace[510717290] 'agreement among raft nodes before linearized reading' (duration: 320.075891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:27.209871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:26.889602Z","time spent":"320.264105ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-17T16:13:27.209987Z","caller":"traceutil/trace.go:171","msg":"trace[1759247813] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:987; }","duration":"100.019039ms","start":"2026-02-17T16:13:27.109961Z","end":"2026-02-17T16:13:27.20998Z","steps":["trace[1759247813] 'agreement among raft nodes before linearized reading' (duration: 99.985058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:28.97985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080803708s","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-17T16:13:28.979904Z","caller":"traceutil/trace.go:171","msg":"trace[1850579106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"1.08091444s","start":"2026-02-17T16:13:27.898979Z","end":"2026-02-17T16:13:28.979893Z","steps":["trace[1850579106] 'range keys from in-memory index tree' (duration: 1.080701346s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:28.979926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:27.898965Z","time spent":"1.080955831s","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:13:28.980215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.871018ms","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-17T16:13:28.980236Z","caller":"traceutil/trace.go:171","msg":"trace[1777240475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:991; }","duration":"410.925189ms","start":"2026-02-17T16:13:28.569306Z","end":"2026-02-17T16:13:28.980231Z","steps":["trace[1777240475] 'range keys from in-memory index tree' (duration: 410.796517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:28.980248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:28.569287Z","time spent":"410.95745ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T16:13:29.168848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.28396ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-17T16:13:29.218525Z","caller":"traceutil/trace.go:171","msg":"trace[1666526493] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1032; }","duration":"192.794872ms","start":"2026-02-17T16:13:29.025717Z","end":"2026-02-17T16:13:29.218512Z","steps":["trace[1666526493] 'read index received' (duration: 192.631038ms)","trace[1666526493] 'applied index is now lower than readState.Index' (duration: 163.364µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:13:29.218745Z","caller":"traceutil/trace.go:171","msg":"trace[1521838164] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"230.567734ms","start":"2026-02-17T16:13:28.988171Z","end":"2026-02-17T16:13:29.218739Z","steps":["trace[1521838164] 'process raft request' (duration: 230.218196ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:29.218838Z","caller":"traceutil/trace.go:171","msg":"trace[1232978657] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"227.508433ms","start":"2026-02-17T16:13:28.991324Z","end":"2026-02-17T16:13:29.218832Z","steps":["trace[1232978657] 'process raft request' (duration: 227.159945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:29.218915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.18769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:29.218931Z","caller":"traceutil/trace.go:171","msg":"trace[43231922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"193.242532ms","start":"2026-02-17T16:13:29.025684Z","end":"2026-02-17T16:13:29.218926Z","steps":["trace[43231922] 'agreement among raft nodes before linearized reading' (duration: 193.203841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:30.70993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826742704079526,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T16:13:30.749386Z","caller":"traceutil/trace.go:171","msg":"trace[899942364] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"539.828795ms","start":"2026-02-17T16:13:30.209536Z","end":"2026-02-17T16:13:30.749365Z","steps":["trace[899942364] 'read index received' (duration: 539.653901ms)","trace[899942364] 'applied index is now lower than readState.Index' (duration: 174.064µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:30.749513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.958218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:30.749542Z","caller":"traceutil/trace.go:171","msg":"trace[1653433801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"540.03802ms","start":"2026-02-17T16:13:30.209495Z","end":"2026-02-17T16:13:30.749533Z","steps":["trace[1653433801] 'agreement among raft nodes before linearized reading' (duration: 539.948648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:30.749573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:30.209476Z","time spent":"540.090162ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:13:30.749939Z","caller":"traceutil/trace.go:171","msg":"trace[1091302885] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"837.370656ms","start":"2026-02-17T16:13:29.912557Z","end":"2026-02-17T16:13:30.749928Z","steps":["trace[1091302885] 'process raft request' (duration: 836.686221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:13:30.750014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:13:29.912537Z","time spent":"837.427378ms","remote":"127.0.0.1:52340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:13:31.113255Z","caller":"traceutil/trace.go:171","msg":"trace[1398344630] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"132.005989ms","start":"2026-02-17T16:13:30.981234Z","end":"2026-02-17T16:13:31.11324Z","steps":["trace[1398344630] 'read index received' (duration: 128.15249ms)","trace[1398344630] 'applied index is now lower than readState.Index' (duration: 3.852789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:13:31.113392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.152312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:13:31.113412Z","caller":"traceutil/trace.go:171","msg":"trace[958130341] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1000; }","duration":"132.208364ms","start":"2026-02-17T16:13:30.981197Z","end":"2026-02-17T16:13:31.113405Z","steps":["trace[958130341] 'agreement among raft nodes before linearized reading' (duration: 132.122012ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:13:31.114385Z","caller":"traceutil/trace.go:171","msg":"trace[251021661] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"207.407019ms","start":"2026-02-17T16:13:30.906194Z","end":"2026-02-17T16:13:31.113601Z","steps":["trace[251021661] 'process raft request' (duration: 203.163041ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:51.840004Z","caller":"traceutil/trace.go:171","msg":"trace[1797494680] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"190.695602ms","start":"2026-02-17T16:14:51.648996Z","end":"2026-02-17T16:14:51.839691Z","steps":["trace[1797494680] 'process raft request' (duration: 98.991313ms)","trace[1797494680] 'compare' (duration: 91.531345ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:52.072406Z","caller":"traceutil/trace.go:171","msg":"trace[890843613] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1401; }","duration":"200.973129ms","start":"2026-02-17T16:14:51.871413Z","end":"2026-02-17T16:14:52.072386Z","steps":["trace[890843613] 'read index received' (duration: 108.661298ms)","trace[890843613] 'applied index is now lower than readState.Index' (duration: 92.310981ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:52.07259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.164124ms","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-17T16:14:52.072623Z","caller":"traceutil/trace.go:171","msg":"trace[430897515] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1346; }","duration":"201.285276ms","start":"2026-02-17T16:14:51.871328Z","end":"2026-02-17T16:14:52.072613Z","steps":["trace[430897515] 'agreement among raft nodes before linearized reading' (duration: 201.136813ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:52.074096Z","caller":"traceutil/trace.go:171","msg":"trace[1553563872] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"231.272272ms","start":"2026-02-17T16:14:51.842804Z","end":"2026-02-17T16:14:52.074077Z","steps":["trace[1553563872] 'process raft request' (duration: 137.262792ms)","trace[1553563872] 'compare' (duration: 91.910752ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:52.074719Z","caller":"traceutil/trace.go:171","msg":"trace[261507820] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"231.22418ms","start":"2026-02-17T16:14:51.843447Z","end":"2026-02-17T16:14:52.074671Z","steps":["trace[261507820] 'process raft request' (duration: 228.871747ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:52.280733Z","caller":"traceutil/trace.go:171","msg":"trace[1330455064] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"150.094082ms","start":"2026-02-17T16:14:52.130619Z","end":"2026-02-17T16:14:52.280713Z","steps":["trace[1330455064] 'read index received' (duration: 109.118091ms)","trace[1330455064] 'applied index is now lower than readState.Index' (duration: 40.975211ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:52.281372Z","caller":"traceutil/trace.go:171","msg":"trace[1397181649] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"201.280571ms","start":"2026-02-17T16:14:52.080077Z","end":"2026-02-17T16:14:52.281357Z","steps":["trace[1397181649] 'process raft request' (duration: 159.713987ms)","trace[1397181649] 'compare' (duration: 40.826468ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:52.282304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.673526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-17T16:14:52.282496Z","caller":"traceutil/trace.go:171","msg":"trace[1435846664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"151.897872ms","start":"2026-02-17T16:14:52.130583Z","end":"2026-02-17T16:14:52.282481Z","steps":["trace[1435846664] 'agreement among raft nodes before linearized reading' (duration: 151.623336ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:52.578885Z","caller":"traceutil/trace.go:171","msg":"trace[89562674] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1405; }","duration":"120.331502ms","start":"2026-02-17T16:14:52.458519Z","end":"2026-02-17T16:14:52.57885Z","steps":["trace[89562674] 'read index received' (duration: 114.842728ms)","trace[89562674] 'applied index is now lower than readState.Index' (duration: 5.488104ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:52.579232Z","caller":"traceutil/trace.go:171","msg":"trace[1044512614] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"122.632352ms","start":"2026-02-17T16:14:52.456569Z","end":"2026-02-17T16:14:52.579201Z","steps":["trace[1044512614] 'process raft request' (duration: 116.814622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:52.582077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.181666ms","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-17T16:14:52.582265Z","caller":"traceutil/trace.go:171","msg":"trace[1145615648] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"111.600215ms","start":"2026-02-17T16:14:52.470652Z","end":"2026-02-17T16:14:52.582253Z","steps":["trace[1145615648] 'agreement among raft nodes before linearized reading' (duration: 111.127114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:14:52.579519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.953845ms","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-17T16:14:52.582567Z","caller":"traceutil/trace.go:171","msg":"trace[1847892521] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1349; }","duration":"124.075415ms","start":"2026-02-17T16:14:52.45848Z","end":"2026-02-17T16:14:52.582555Z","steps":["trace[1847892521] 'agreement among raft nodes before linearized reading' (duration: 120.857983ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:54.18598Z","caller":"traceutil/trace.go:171","msg":"trace[1490544320] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"103.844362ms","start":"2026-02-17T16:14:54.082109Z","end":"2026-02-17T16:14:54.185953Z","steps":["trace[1490544320] 'process raft request' (duration: 103.730569ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:56.33812Z","caller":"traceutil/trace.go:171","msg":"trace[106414045] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"141.680181ms","start":"2026-02-17T16:14:56.196417Z","end":"2026-02-17T16:14:56.338097Z","steps":["trace[106414045] 'process raft request' (duration: 60.282753ms)","trace[106414045] 'compare' (duration: 80.967178ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:14:56.610114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.092765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:14:56.610644Z","caller":"traceutil/trace.go:171","msg":"trace[1869712337] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"201.985586ms","start":"2026-02-17T16:14:56.408646Z","end":"2026-02-17T16:14:56.610631Z","steps":["trace[1869712337] 'process raft request' (duration: 201.70776ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:56.611204Z","caller":"traceutil/trace.go:171","msg":"trace[2092855956] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"204.34988ms","start":"2026-02-17T16:14:56.406826Z","end":"2026-02-17T16:14:56.611176Z","steps":["trace[2092855956] 'process raft request' (duration: 96.143459ms)","trace[2092855956] 'compare' (duration: 107.016283ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:14:57.751597Z","caller":"traceutil/trace.go:171","msg":"trace[1510814036] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"147.86729ms","start":"2026-02-17T16:14:57.603714Z","end":"2026-02-17T16:14:57.751582Z","steps":["trace[1510814036] 'process raft request' (duration: 147.649565ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:14:58.597393Z","caller":"traceutil/trace.go:171","msg":"trace[1153999929] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"163.206405ms","start":"2026-02-17T16:14:58.434162Z","end":"2026-02-17T16:14:58.597369Z","steps":["trace[1153999929] 'process raft request' (duration: 137.844145ms)","trace[1153999929] 'compare' (duration: 25.265778ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:17:01.042298Z","caller":"traceutil/trace.go:171","msg":"trace[1226026873] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"100.144714ms","start":"2026-02-17T16:17:00.942123Z","end":"2026-02-17T16:17:01.042268Z","steps":["trace[1226026873] 'process raft request' (duration: 99.987081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:17:01.348422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.912329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:17:01.348486Z","caller":"traceutil/trace.go:171","msg":"trace[1562075857] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1896; }","duration":"159.003851ms","start":"2026-02-17T16:17:01.189468Z","end":"2026-02-17T16:17:01.348472Z","steps":["trace[1562075857] 'count revisions from in-memory index tree' (duration: 158.841678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:17:01.348714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.957048ms","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-17T16:17:01.348781Z","caller":"traceutil/trace.go:171","msg":"trace[1531901632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1896; }","duration":"158.043741ms","start":"2026-02-17T16:17:01.19073Z","end":"2026-02-17T16:17:01.348774Z","steps":["trace[1531901632] 'range keys from in-memory index tree' (duration: 157.853966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:17:01.34895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.303345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:17:01.348971Z","caller":"traceutil/trace.go:171","msg":"trace[1898831281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"143.341596ms","start":"2026-02-17T16:17:01.205623Z","end":"2026-02-17T16:17:01.348965Z","steps":["trace[1898831281] 'range keys from in-memory index tree' (duration: 143.268714ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:17:03.528045Z","caller":"traceutil/trace.go:171","msg":"trace[1056418151] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1989; }","duration":"124.920151ms","start":"2026-02-17T16:17:03.403099Z","end":"2026-02-17T16:17:03.528019Z","steps":["trace[1056418151] 'read index received' (duration: 124.86027ms)","trace[1056418151] 'applied index is now lower than readState.Index' (duration: 58.581µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:17:03.528163Z","caller":"traceutil/trace.go:171","msg":"trace[1220031100] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"126.804442ms","start":"2026-02-17T16:17:03.401348Z","end":"2026-02-17T16:17:03.528153Z","steps":["trace[1220031100] 'process raft request' (duration: 126.548766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:17:03.528374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.258108ms","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-17T16:17:03.528408Z","caller":"traceutil/trace.go:171","msg":"trace[299063997] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1906; }","duration":"125.314649ms","start":"2026-02-17T16:17:03.40308Z","end":"2026-02-17T16:17:03.528395Z","steps":["trace[299063997] 'agreement among raft nodes before linearized reading' (duration: 125.189446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:17:03.529522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.131613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:17:03.529581Z","caller":"traceutil/trace.go:171","msg":"trace[1700167211] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1907; }","duration":"109.218095ms","start":"2026-02-17T16:17:03.420349Z","end":"2026-02-17T16:17:03.529567Z","steps":["trace[1700167211] 'agreement among raft nodes before linearized reading' (duration: 109.109922ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:17:03.529781Z","caller":"traceutil/trace.go:171","msg":"trace[1266200165] transaction","detail":"{read_only:false; response_revision:1907; number_of_response:1; }","duration":"125.282138ms","start":"2026-02-17T16:17:03.40449Z","end":"2026-02-17T16:17:03.529773Z","steps":["trace[1266200165] 'process raft request' (duration: 124.838398ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:17:09.204228Z","caller":"traceutil/trace.go:171","msg":"trace[319865997] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2019; }","duration":"179.432141ms","start":"2026-02-17T16:17:09.024761Z","end":"2026-02-17T16:17:09.204194Z","steps":["trace[319865997] 'read index received' (duration: 123.06375ms)","trace[319865997] 'applied index is now lower than readState.Index' (duration: 56.366471ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:17:09.204394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.617574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T16:17:09.204465Z","caller":"traceutil/trace.go:171","msg":"trace[1675926982] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1934; }","duration":"179.721227ms","start":"2026-02-17T16:17:09.024719Z","end":"2026-02-17T16:17:09.20444Z","steps":["trace[1675926982] 'agreement among raft nodes before linearized reading' (duration: 179.601114ms)"],"step_count":1}