{"level":"warn","ts":"2026-02-17T23:10:03.245309Z","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-17T23:10:03.245419Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-17T23:10:03.245493Z","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-17T23:10:03.245501Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-02-17T23:10:03.245518Z","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-17T23:10:03.246268Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-02-17T23:10:03.246405Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-17T23:10:03.25012Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.433379ms"} {"level":"info","ts":"2026-02-17T23:10:03.286634Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-02-17T23:10:03.286736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T23:10:03.286784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-02-17T23:10:03.2868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T23:10:03.28681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-02-17T23:10:03.286859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-02-17T23:10:03.303165Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T23:10:03.306625Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T23:10:03.309118Z","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-17T23:10:03.311247Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T23:10:03.312225Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T23:10:03.312398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-02-17T23:10:03.312481Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-02-17T23:10:03.312546Z","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-17T23:10:03.312568Z","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-17T23:10:03.312574Z","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-17T23:10:03.320962Z","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-17T23:10:03.323641Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T23:10:03.323668Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T23:10:03.323725Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-02-17T23:10:03.32373Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-02-17T23:10:03.587693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T23:10:03.587743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T23:10:03.587783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-02-17T23:10:03.587799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-02-17T23:10:03.587808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-02-17T23:10:03.587817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-02-17T23:10:03.587827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-02-17T23:10:03.594092Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T23:10:03.594288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T23:10:03.594669Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:10:03.594786Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T23:10:03.595916Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T23:10:03.595974Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T23:10:03.59766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T23:10:03.598978Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-02-17T23:10:03.599028Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:10:03.600156Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:10:03.600249Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T23:10:17.277491Z","caller":"traceutil/trace.go:171","msg":"trace[363631537] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"184.287992ms","start":"2026-02-17T23:10:17.093186Z","end":"2026-02-17T23:10:17.277474Z","steps":["trace[363631537] 'process raft request' (duration: 184.18289ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:23.19091Z","caller":"traceutil/trace.go:171","msg":"trace[538575089] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"477.259924ms","start":"2026-02-17T23:10:22.713618Z","end":"2026-02-17T23:10:23.190878Z","steps":["trace[538575089] 'read index received' (duration: 477.065739ms)","trace[538575089] 'applied index is now lower than readState.Index' (duration: 193.065µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:23.191159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.484038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:23.191209Z","caller":"traceutil/trace.go:171","msg":"trace[1197745916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"477.612221ms","start":"2026-02-17T23:10:22.713585Z","end":"2026-02-17T23:10:23.191198Z","steps":["trace[1197745916] 'agreement among raft nodes before linearized reading' (duration: 477.425427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:23.191245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:22.713578Z","time spent":"477.657883ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:10:23.191566Z","caller":"traceutil/trace.go:171","msg":"trace[605616655] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"493.252172ms","start":"2026-02-17T23:10:22.698302Z","end":"2026-02-17T23:10:23.191554Z","steps":["trace[605616655] 'process raft request' (duration: 492.416903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:23.192116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:22.698286Z","time spent":"493.307614ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:10:24.50696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.569708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:24.507036Z","caller":"traceutil/trace.go:171","msg":"trace[796258778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"792.687039ms","start":"2026-02-17T23:10:23.714329Z","end":"2026-02-17T23:10:24.507016Z","steps":["trace[796258778] 'range keys from in-memory index tree' (duration: 792.514036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:24.507078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:23.714315Z","time spent":"792.754202ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:10:24.507313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.817413ms","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-17T23:10:24.507342Z","caller":"traceutil/trace.go:171","msg":"trace[904951367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:416; }","duration":"792.853354ms","start":"2026-02-17T23:10:23.714479Z","end":"2026-02-17T23:10:24.507333Z","steps":["trace[904951367] 'range keys from in-memory index tree' (duration: 792.771572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:24.507369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:23.714476Z","time spent":"792.886184ms","remote":"127.0.0.1:60942","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-17T23:10:24.507495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.659406ms","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-17T23:10:24.50752Z","caller":"traceutil/trace.go:171","msg":"trace[478903842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"677.723638ms","start":"2026-02-17T23:10:23.829789Z","end":"2026-02-17T23:10:24.507512Z","steps":["trace[478903842] 'range keys from in-memory index tree' (duration: 677.588034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:24.507543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:23.829768Z","time spent":"677.770319ms","remote":"127.0.0.1:60942","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-17T23:10:24.507654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.673593ms","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-17T23:10:24.507679Z","caller":"traceutil/trace.go:171","msg":"trace[1186685165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"676.719105ms","start":"2026-02-17T23:10:23.830953Z","end":"2026-02-17T23:10:24.507672Z","steps":["trace[1186685165] 'range keys from in-memory index tree' (duration: 676.623412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:24.507706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:23.830942Z","time spent":"676.758295ms","remote":"127.0.0.1:60942","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-17T23:10:24.967474Z","caller":"traceutil/trace.go:171","msg":"trace[667134949] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"455.544041ms","start":"2026-02-17T23:10:24.511912Z","end":"2026-02-17T23:10:24.967456Z","steps":["trace[667134949] 'process raft request' (duration: 455.387837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:24.967581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:24.5119Z","time spent":"455.619973ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:10:24.969049Z","caller":"traceutil/trace.go:171","msg":"trace[1719562282] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"454.334574ms","start":"2026-02-17T23:10:24.514669Z","end":"2026-02-17T23:10:24.969004Z","steps":["trace[1719562282] 'process raft request' (duration: 454.314133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:24.9691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:24.514646Z","time spent":"454.431235ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:10:24.969259Z","caller":"traceutil/trace.go:171","msg":"trace[939620701] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"456.243117ms","start":"2026-02-17T23:10:24.51301Z","end":"2026-02-17T23:10:24.969253Z","steps":["trace[939620701] 'process raft request' (duration: 455.93417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:24.969292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:24.513003Z","time spent":"456.272108ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:10:35.208426Z","caller":"traceutil/trace.go:171","msg":"trace[613127225] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"190.002355ms","start":"2026-02-17T23:10:35.018408Z","end":"2026-02-17T23:10:35.20841Z","steps":["trace[613127225] 'process raft request' (duration: 189.919003ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:35.208793Z","caller":"traceutil/trace.go:171","msg":"trace[1916994223] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:495; }","duration":"188.802046ms","start":"2026-02-17T23:10:35.019982Z","end":"2026-02-17T23:10:35.208784Z","steps":["trace[1916994223] 'read index received' (duration: 188.799306ms)","trace[1916994223] 'applied index is now lower than readState.Index' (duration: 2.06µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:35.208852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.830437ms","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-17T23:10:35.208868Z","caller":"traceutil/trace.go:171","msg":"trace[1753316919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"188.866038ms","start":"2026-02-17T23:10:35.019997Z","end":"2026-02-17T23:10:35.208863Z","steps":["trace[1753316919] 'agreement among raft nodes before linearized reading' (duration: 188.820867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:35.208976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.992022ms","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-17T23:10:35.208989Z","caller":"traceutil/trace.go:171","msg":"trace[1850158949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"189.014682ms","start":"2026-02-17T23:10:35.01997Z","end":"2026-02-17T23:10:35.208985Z","steps":["trace[1850158949] 'agreement among raft nodes before linearized reading' (duration: 188.95332ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:36.559642Z","caller":"traceutil/trace.go:171","msg":"trace[68064799] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"213.861144ms","start":"2026-02-17T23:10:36.345764Z","end":"2026-02-17T23:10:36.559626Z","steps":["trace[68064799] 'process raft request' (duration: 213.760972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:37.281687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.265469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:37.281771Z","caller":"traceutil/trace.go:171","msg":"trace[1296776514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"567.382512ms","start":"2026-02-17T23:10:36.714372Z","end":"2026-02-17T23:10:37.281755Z","steps":["trace[1296776514] 'range keys from in-memory index tree' (duration: 567.181878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:37.281806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:36.714356Z","time spent":"567.441603ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:10:37.281951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.765954ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:37.282016Z","caller":"traceutil/trace.go:171","msg":"trace[1767607445] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:479; }","duration":"545.831315ms","start":"2026-02-17T23:10:36.736173Z","end":"2026-02-17T23:10:37.282005Z","steps":["trace[1767607445] 'range keys from in-memory index tree' (duration: 545.759353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:37.282431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.182621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T23:10:37.282464Z","caller":"traceutil/trace.go:171","msg":"trace[852401075] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:479; }","duration":"103.246792ms","start":"2026-02-17T23:10:37.179208Z","end":"2026-02-17T23:10:37.282454Z","steps":["trace[852401075] 'range keys from in-memory index tree' (duration: 103.112189ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:37.466517Z","caller":"traceutil/trace.go:171","msg":"trace[1820728510] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:500; }","duration":"178.037748ms","start":"2026-02-17T23:10:37.288448Z","end":"2026-02-17T23:10:37.466486Z","steps":["trace[1820728510] 'read index received' (duration: 178.027808ms)","trace[1820728510] 'applied index is now lower than readState.Index' (duration: 7.98µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:37.466674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.189131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:37.466709Z","caller":"traceutil/trace.go:171","msg":"trace[634109613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"178.481409ms","start":"2026-02-17T23:10:37.288216Z","end":"2026-02-17T23:10:37.466698Z","steps":["trace[634109613] 'agreement among raft nodes before linearized reading' (duration: 178.367966ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:37.468408Z","caller":"traceutil/trace.go:171","msg":"trace[1661079870] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"175.972511ms","start":"2026-02-17T23:10:37.292427Z","end":"2026-02-17T23:10:37.468399Z","steps":["trace[1661079870] 'process raft request' (duration: 175.950191ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:37.468594Z","caller":"traceutil/trace.go:171","msg":"trace[2095629939] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"179.961043ms","start":"2026-02-17T23:10:37.288627Z","end":"2026-02-17T23:10:37.468588Z","steps":["trace[2095629939] 'process raft request' (duration: 179.624145ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:37.468665Z","caller":"traceutil/trace.go:171","msg":"trace[1484729420] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"178.670013ms","start":"2026-02-17T23:10:37.28999Z","end":"2026-02-17T23:10:37.46866Z","steps":["trace[1484729420] 'process raft request' (duration: 178.348116ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:37.468742Z","caller":"traceutil/trace.go:171","msg":"trace[192863950] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"177.497356ms","start":"2026-02-17T23:10:37.29124Z","end":"2026-02-17T23:10:37.468737Z","steps":["trace[192863950] 'process raft request' (duration: 177.118157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:43.484063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.606278ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-17T23:10:43.484175Z","caller":"traceutil/trace.go:171","msg":"trace[90313434] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"768.196106ms","start":"2026-02-17T23:10:42.71596Z","end":"2026-02-17T23:10:43.484156Z","steps":["trace[90313434] 'read index received' (duration: 36.92µs)","trace[90313434] 'applied index is now lower than readState.Index' (duration: 768.157896ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:43.484246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.281459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:43.484271Z","caller":"traceutil/trace.go:171","msg":"trace[1415818911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"768.34071ms","start":"2026-02-17T23:10:42.715922Z","end":"2026-02-17T23:10:43.484262Z","steps":["trace[1415818911] 'agreement among raft nodes before linearized reading' (duration: 768.281769ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:43.484306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:42.715906Z","time spent":"768.392782ms","remote":"127.0.0.1:60744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:10:43.948006Z","caller":"traceutil/trace.go:171","msg":"trace[1117526293] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"443.248598ms","start":"2026-02-17T23:10:43.504733Z","end":"2026-02-17T23:10:43.947982Z","steps":["trace[1117526293] 'process raft request' (duration: 443.118355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:43.948163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:43.504718Z","time spent":"443.363401ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:10:44.16603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.966077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:10:44.166134Z","caller":"traceutil/trace.go:171","msg":"trace[1260032085] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"658.485444ms","start":"2026-02-17T23:10:43.507632Z","end":"2026-02-17T23:10:44.166117Z","steps":["trace[1260032085] 'read index received' (duration: 440.894554ms)","trace[1260032085] 'applied index is now lower than readState.Index' (duration: 217.58966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:44.166448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.810732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-17T23:10:44.166446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.73115ms","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-17T23:10:44.166516Z","caller":"traceutil/trace.go:171","msg":"trace[2043984610] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"660.811298ms","start":"2026-02-17T23:10:43.505694Z","end":"2026-02-17T23:10:44.166506Z","steps":["trace[2043984610] 'process raft request' (duration: 550.227047ms)","trace[2043984610] 'compare' (duration: 109.844694ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:10:44.166524Z","caller":"traceutil/trace.go:171","msg":"trace[1195366784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"658.839362ms","start":"2026-02-17T23:10:43.507665Z","end":"2026-02-17T23:10:44.166505Z","steps":["trace[1195366784] 'agreement among raft nodes before linearized reading' (duration: 658.671399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:44.166629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:43.507661Z","time spent":"658.957565ms","remote":"127.0.0.1:60942","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-17T23:10:44.166687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:43.505684Z","time spent":"660.955122ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:10:44.166479Z","caller":"traceutil/trace.go:171","msg":"trace[480206355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"658.861364ms","start":"2026-02-17T23:10:43.507608Z","end":"2026-02-17T23:10:44.166469Z","steps":["trace[480206355] 'agreement among raft nodes before linearized reading' (duration: 658.802342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:44.16683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:43.507598Z","time spent":"659.224952ms","remote":"127.0.0.1:60942","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-17T23:10:44.16719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.335201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:44.167226Z","caller":"traceutil/trace.go:171","msg":"trace[633801987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"450.408383ms","start":"2026-02-17T23:10:43.716807Z","end":"2026-02-17T23:10:44.167215Z","steps":["trace[633801987] 'agreement among raft nodes before linearized reading' (duration: 450.354892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:44.167253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:43.716786Z","time spent":"450.461015ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:10:46.375626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.522265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:10:46.375782Z","caller":"traceutil/trace.go:171","msg":"trace[2000744046] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"327.012306ms","start":"2026-02-17T23:10:46.048755Z","end":"2026-02-17T23:10:46.375767Z","steps":["trace[2000744046] 'process raft request' (duration: 326.960875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:46.375859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:46.048742Z","time spent":"327.071677ms","remote":"127.0.0.1:60802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T23:10:46.376089Z","caller":"traceutil/trace.go:171","msg":"trace[1857750023] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"360.626321ms","start":"2026-02-17T23:10:46.015449Z","end":"2026-02-17T23:10:46.376075Z","steps":["trace[1857750023] 'process raft request' (duration: 124.595314ms)","trace[1857750023] 'compare' (duration: 235.199368ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:46.376132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:46.01544Z","time spent":"360.673193ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T23:10:46.376236Z","caller":"traceutil/trace.go:171","msg":"trace[7553823] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"329.164855ms","start":"2026-02-17T23:10:46.047061Z","end":"2026-02-17T23:10:46.376225Z","steps":["trace[7553823] 'read index received' (duration: 93.030786ms)","trace[7553823] 'applied index is now lower than readState.Index' (duration: 236.133099ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:46.376479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.410131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gstbq\" ","response":"range_response_count:1 size:16916"} {"level":"info","ts":"2026-02-17T23:10:46.37651Z","caller":"traceutil/trace.go:171","msg":"trace[290355757] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gstbq; range_end:; response_count:1; response_revision:511; }","duration":"329.466883ms","start":"2026-02-17T23:10:46.047034Z","end":"2026-02-17T23:10:46.376501Z","steps":["trace[290355757] 'agreement among raft nodes before linearized reading' (duration: 329.36431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:46.376533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:46.047022Z","time spent":"329.506613ms","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16938,"request content":"key:\"/registry/pods/kube-system/cilium-gstbq\" "} {"level":"warn","ts":"2026-02-17T23:10:46.376641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.034112ms","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-17T23:10:46.376657Z","caller":"traceutil/trace.go:171","msg":"trace[702773170] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:511; }","duration":"202.051122ms","start":"2026-02-17T23:10:46.174601Z","end":"2026-02-17T23:10:46.376652Z","steps":["trace[702773170] 'agreement among raft nodes before linearized reading' (duration: 202.022732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:46.376813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.358129ms","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-17T23:10:46.37683Z","caller":"traceutil/trace.go:171","msg":"trace[1297484563] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"192.4012ms","start":"2026-02-17T23:10:46.184424Z","end":"2026-02-17T23:10:46.376825Z","steps":["trace[1297484563] 'agreement among raft nodes before linearized reading' (duration: 192.36891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:46.376906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.872524ms","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-17T23:10:46.376922Z","caller":"traceutil/trace.go:171","msg":"trace[904357522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:511; }","duration":"193.939755ms","start":"2026-02-17T23:10:46.182977Z","end":"2026-02-17T23:10:46.376916Z","steps":["trace[904357522] 'agreement among raft nodes before linearized reading' (duration: 193.914125ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:49.446664Z","caller":"traceutil/trace.go:171","msg":"trace[765399834] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"254.652666ms","start":"2026-02-17T23:10:49.191982Z","end":"2026-02-17T23:10:49.446634Z","steps":["trace[765399834] 'process raft request' (duration: 251.34962ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:10:56.816647Z","caller":"traceutil/trace.go:171","msg":"trace[960634055] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"387.561993ms","start":"2026-02-17T23:10:56.429072Z","end":"2026-02-17T23:10:56.816634Z","steps":["trace[960634055] 'read index received' (duration: 387.457021ms)","trace[960634055] 'applied index is now lower than readState.Index' (duration: 104.582µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:10:56.816713Z","caller":"traceutil/trace.go:171","msg":"trace[1748353663] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"775.798822ms","start":"2026-02-17T23:10:56.040908Z","end":"2026-02-17T23:10:56.816707Z","steps":["trace[1748353663] 'process raft request' (duration: 775.633118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:56.816782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:56.040887Z","time spent":"775.842272ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:10:56.816892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.820249ms","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-17T23:10:56.816907Z","caller":"traceutil/trace.go:171","msg":"trace[1598612341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"387.86927ms","start":"2026-02-17T23:10:56.429033Z","end":"2026-02-17T23:10:56.816902Z","steps":["trace[1598612341] 'agreement among raft nodes before linearized reading' (duration: 387.807419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:56.81692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:56.429021Z","time spent":"387.89689ms","remote":"127.0.0.1:60942","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-17T23:10:56.817369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.718611ms","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-17T23:10:56.817387Z","caller":"traceutil/trace.go:171","msg":"trace[1033740644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"359.770081ms","start":"2026-02-17T23:10:56.457612Z","end":"2026-02-17T23:10:56.817382Z","steps":["trace[1033740644] 'agreement among raft nodes before linearized reading' (duration: 359.72151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:56.817399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:56.4576Z","time spent":"359.796612ms","remote":"127.0.0.1:60942","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-17T23:10:56.817447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.191161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:56.817459Z","caller":"traceutil/trace.go:171","msg":"trace[836994442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"103.243952ms","start":"2026-02-17T23:10:56.714212Z","end":"2026-02-17T23:10:56.817456Z","steps":["trace[836994442] 'agreement among raft nodes before linearized reading' (duration: 103.216941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:56.817549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.57147ms","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-17T23:10:56.817561Z","caller":"traceutil/trace.go:171","msg":"trace[1966096611] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:628; }","duration":"282.602981ms","start":"2026-02-17T23:10:56.534955Z","end":"2026-02-17T23:10:56.817558Z","steps":["trace[1966096611] 'agreement among raft nodes before linearized reading' (duration: 282.56645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:56.817614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.781311ms","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-17T23:10:56.817624Z","caller":"traceutil/trace.go:171","msg":"trace[1346176681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"359.800813ms","start":"2026-02-17T23:10:56.45782Z","end":"2026-02-17T23:10:56.817621Z","steps":["trace[1346176681] 'agreement among raft nodes before linearized reading' (duration: 359.766882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:56.817635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:56.457815Z","time spent":"359.816683ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T23:10:58.378379Z","caller":"traceutil/trace.go:171","msg":"trace[952472564] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"246.362234ms","start":"2026-02-17T23:10:58.132001Z","end":"2026-02-17T23:10:58.378364Z","steps":["trace[952472564] 'process raft request' (duration: 246.251522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.796551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021345606s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:10:59.796714Z","caller":"traceutil/trace.go:171","msg":"trace[1574991652] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"1.412192355s","start":"2026-02-17T23:10:58.384504Z","end":"2026-02-17T23:10:59.796696Z","steps":["trace[1574991652] 'read index received' (duration: 390.640184ms)","trace[1574991652] 'applied index is now lower than readState.Index' (duration: 1.021551311s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:59.797083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412569504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-clgvs\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-17T23:10:59.797118Z","caller":"traceutil/trace.go:171","msg":"trace[130214700] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-clgvs; range_end:; response_count:1; response_revision:646; }","duration":"1.412619925s","start":"2026-02-17T23:10:58.384487Z","end":"2026-02-17T23:10:59.797107Z","steps":["trace[130214700] 'agreement among raft nodes before linearized reading' (duration: 1.412469811s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.797151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.384481Z","time spent":"1.412662225s","remote":"127.0.0.1:60874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4719,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-clgvs\" "} {"level":"info","ts":"2026-02-17T23:10:59.797321Z","caller":"traceutil/trace.go:171","msg":"trace[339172598] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"1.415168623s","start":"2026-02-17T23:10:58.38214Z","end":"2026-02-17T23:10:59.797308Z","steps":["trace[339172598] 'process raft request' (duration: 392.996228ms)","trace[339172598] 'compare' (duration: 1.020885496s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:10:59.797381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.382126Z","time spent":"1.415223975s","remote":"127.0.0.1:60958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1291,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:10:59.797528Z","caller":"traceutil/trace.go:171","msg":"trace[440334260] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"1.415133533s","start":"2026-02-17T23:10:58.382385Z","end":"2026-02-17T23:10:59.797519Z","steps":["trace[440334260] 'process raft request' (duration: 1.414258443s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.797573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.38238Z","time spent":"1.415168274s","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:10:59.797962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082522768s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:10:59.797995Z","caller":"traceutil/trace.go:171","msg":"trace[1258043489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"1.082554609s","start":"2026-02-17T23:10:58.715431Z","end":"2026-02-17T23:10:59.797985Z","steps":["trace[1258043489] 'agreement among raft nodes before linearized reading' (duration: 1.082504208s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.798021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.715393Z","time spent":"1.0826228s","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:10:59.798204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.984857ms","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-17T23:10:59.798231Z","caller":"traceutil/trace.go:171","msg":"trace[384588826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"962.023487ms","start":"2026-02-17T23:10:58.8362Z","end":"2026-02-17T23:10:59.798223Z","steps":["trace[384588826] 'agreement among raft nodes before linearized reading' (duration: 961.930795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.798255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.836194Z","time spent":"962.055868ms","remote":"127.0.0.1:60942","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-17T23:10:59.798414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.879478ms","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-17T23:10:59.798439Z","caller":"traceutil/trace.go:171","msg":"trace[324743504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"962.931198ms","start":"2026-02-17T23:10:58.8355Z","end":"2026-02-17T23:10:59.798431Z","steps":["trace[324743504] 'agreement among raft nodes before linearized reading' (duration: 962.862656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.798462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.835493Z","time spent":"962.96419ms","remote":"127.0.0.1:60942","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-17T23:10:59.798612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.016203ms","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-17T23:10:59.79864Z","caller":"traceutil/trace.go:171","msg":"trace[1791981481] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:646; }","duration":"964.059435ms","start":"2026-02-17T23:10:58.834574Z","end":"2026-02-17T23:10:59.798633Z","steps":["trace[1791981481] 'agreement among raft nodes before linearized reading' (duration: 963.987733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.798666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.834566Z","time spent":"964.093275ms","remote":"127.0.0.1:60942","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-17T23:10:59.798814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.229781ms","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-17T23:10:59.79884Z","caller":"traceutil/trace.go:171","msg":"trace[597443754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"968.269031ms","start":"2026-02-17T23:10:58.830562Z","end":"2026-02-17T23:10:59.798831Z","steps":["trace[597443754] 'agreement among raft nodes before linearized reading' (duration: 968.20217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.798863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.830554Z","time spent":"968.303472ms","remote":"127.0.0.1:60942","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-17T23:10:59.799008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.182347ms","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-17T23:10:59.799042Z","caller":"traceutil/trace.go:171","msg":"trace[1172065088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"977.229859ms","start":"2026-02-17T23:10:58.821795Z","end":"2026-02-17T23:10:59.799025Z","steps":["trace[1172065088] 'agreement among raft nodes before linearized reading' (duration: 977.161247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:10:59.799066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:10:58.821779Z","time spent":"977.28117ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-17T23:11:00.140943Z","caller":"traceutil/trace.go:171","msg":"trace[1605101138] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"280.770959ms","start":"2026-02-17T23:10:59.860158Z","end":"2026-02-17T23:11:00.140929Z","steps":["trace[1605101138] 'process raft request' (duration: 280.628836ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:00.15017Z","caller":"traceutil/trace.go:171","msg":"trace[16549343] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"264.105694ms","start":"2026-02-17T23:10:59.886053Z","end":"2026-02-17T23:11:00.150159Z","steps":["trace[16549343] 'process raft request' (duration: 263.802657ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:05.98201Z","caller":"traceutil/trace.go:171","msg":"trace[1684029044] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"128.097436ms","start":"2026-02-17T23:11:05.85386Z","end":"2026-02-17T23:11:05.981958Z","steps":["trace[1684029044] 'process raft request' (duration: 127.892361ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:05.982268Z","caller":"traceutil/trace.go:171","msg":"trace[1176399818] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:717; }","duration":"123.069009ms","start":"2026-02-17T23:11:05.85918Z","end":"2026-02-17T23:11:05.982249Z","steps":["trace[1176399818] 'read index received' (duration: 123.061129ms)","trace[1176399818] 'applied index is now lower than readState.Index' (duration: 6.48µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:05.982688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.483739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T23:11:05.982745Z","caller":"traceutil/trace.go:171","msg":"trace[992499657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:689; }","duration":"123.563461ms","start":"2026-02-17T23:11:05.859162Z","end":"2026-02-17T23:11:05.982726Z","steps":["trace[992499657] 'agreement among raft nodes before linearized reading' (duration: 123.431578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:07.09887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.213935ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:07.098955Z","caller":"traceutil/trace.go:171","msg":"trace[1956889253] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:689; }","duration":"361.305337ms","start":"2026-02-17T23:11:06.737635Z","end":"2026-02-17T23:11:07.098941Z","steps":["trace[1956889253] 'range keys from in-memory index tree' (duration: 361.201944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:07.099401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.979929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:11:07.099439Z","caller":"traceutil/trace.go:171","msg":"trace[2140470323] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.109986702s","start":"2026-02-17T23:11:05.989444Z","end":"2026-02-17T23:11:07.099431Z","steps":["trace[2140470323] 'process raft request' (duration: 748.928631ms)","trace[2140470323] 'compare' (duration: 360.755254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:07.099474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:05.989428Z","time spent":"1.110028432s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:07.361753Z","caller":"traceutil/trace.go:171","msg":"trace[880089476] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:717; }","duration":"648.77364ms","start":"2026-02-17T23:11:06.712963Z","end":"2026-02-17T23:11:07.361737Z","steps":["trace[880089476] 'read index received' (duration: 25.416117ms)","trace[880089476] 'applied index is now lower than readState.Index' (duration: 623.356773ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:07.361927Z","caller":"traceutil/trace.go:171","msg":"trace[877777850] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"1.372370416s","start":"2026-02-17T23:11:05.98955Z","end":"2026-02-17T23:11:07.36192Z","steps":["trace[877777850] 'process raft request' (duration: 1.371384313s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:07.361998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:05.989544Z","time spent":"1.372401216s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:07.362112Z","caller":"traceutil/trace.go:171","msg":"trace[1901777943] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"1.365974458s","start":"2026-02-17T23:11:05.996132Z","end":"2026-02-17T23:11:07.362107Z","steps":["trace[1901777943] 'process raft request' (duration: 1.365528338s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:07.362145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:05.996112Z","time spent":"1.366014019s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:07.362218Z","caller":"traceutil/trace.go:171","msg":"trace[1032953185] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"1.365066137s","start":"2026-02-17T23:11:05.997146Z","end":"2026-02-17T23:11:07.362212Z","steps":["trace[1032953185] 'process raft request' (duration: 1.364569236s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:07.362249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:05.997137Z","time spent":"1.365094048s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:07.362332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.367554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:07.36235Z","caller":"traceutil/trace.go:171","msg":"trace[209363710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:693; }","duration":"649.410184ms","start":"2026-02-17T23:11:06.712934Z","end":"2026-02-17T23:11:07.362345Z","steps":["trace[209363710] 'agreement among raft nodes before linearized reading' (duration: 649.380084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:07.362369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:06.712921Z","time spent":"649.444906ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:11:07.362466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.469383ms","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-17T23:11:07.362483Z","caller":"traceutil/trace.go:171","msg":"trace[345892299] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:693; }","duration":"466.527644ms","start":"2026-02-17T23:11:06.89595Z","end":"2026-02-17T23:11:07.362478Z","steps":["trace[345892299] 'agreement among raft nodes before linearized reading' (duration: 466.498084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:07.362499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:06.895919Z","time spent":"466.575806ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:11:07.362743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.348354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T23:11:07.362763Z","caller":"traceutil/trace.go:171","msg":"trace[531625161] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:693; }","duration":"181.367615ms","start":"2026-02-17T23:11:07.181388Z","end":"2026-02-17T23:11:07.362756Z","steps":["trace[531625161] 'agreement among raft nodes before linearized reading' (duration: 181.297833ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:20.246609Z","caller":"traceutil/trace.go:171","msg":"trace[974873782] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"212.374032ms","start":"2026-02-17T23:11:20.034211Z","end":"2026-02-17T23:11:20.246585Z","steps":["trace[974873782] 'process raft request' (duration: 212.239219ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:21.597631Z","caller":"traceutil/trace.go:171","msg":"trace[1719482246] linearizableReadLoop","detail":"{readStateIndex:769; appliedIndex:768; }","duration":"125.846497ms","start":"2026-02-17T23:11:21.471771Z","end":"2026-02-17T23:11:21.597617Z","steps":["trace[1719482246] 'read index received' (duration: 125.722354ms)","trace[1719482246] 'applied index is now lower than readState.Index' (duration: 123.393µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:21.597797Z","caller":"traceutil/trace.go:171","msg":"trace[1493196075] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"127.192288ms","start":"2026-02-17T23:11:21.470599Z","end":"2026-02-17T23:11:21.597791Z","steps":["trace[1493196075] 'process raft request' (duration: 126.936332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:21.597915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.130153ms","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-17T23:11:21.597936Z","caller":"traceutil/trace.go:171","msg":"trace[1902204544] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:738; }","duration":"126.183974ms","start":"2026-02-17T23:11:21.471747Z","end":"2026-02-17T23:11:21.597931Z","steps":["trace[1902204544] 'agreement among raft nodes before linearized reading' (duration: 126.138423ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:23.615249Z","caller":"traceutil/trace.go:171","msg":"trace[312384166] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"437.833657ms","start":"2026-02-17T23:11:23.177396Z","end":"2026-02-17T23:11:23.61523Z","steps":["trace[312384166] 'process raft request' (duration: 437.478248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:23.615364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.177383Z","time spent":"437.927509ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:24.819727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.51499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:11:24.819811Z","caller":"traceutil/trace.go:171","msg":"trace[681520639] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"1.201675548s","start":"2026-02-17T23:11:23.618122Z","end":"2026-02-17T23:11:24.819798Z","steps":["trace[681520639] 'process raft request' (duration: 502.028105ms)","trace[681520639] 'compare' (duration: 699.230523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:24.819849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.618109Z","time spent":"1.201721549s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:25.376936Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.256862326s","expected-duration":"1s"} {"level":"info","ts":"2026-02-17T23:11:25.377145Z","caller":"traceutil/trace.go:171","msg":"trace[1597756152] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:774; }","duration":"1.660969431s","start":"2026-02-17T23:11:23.71616Z","end":"2026-02-17T23:11:25.37713Z","steps":["trace[1597756152] 'read index received' (duration: 403.946901ms)","trace[1597756152] 'applied index is now lower than readState.Index' (duration: 1.25702195s)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:25.377291Z","caller":"traceutil/trace.go:171","msg":"trace[2103837779] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"1.757701883s","start":"2026-02-17T23:11:23.619577Z","end":"2026-02-17T23:11:25.377279Z","steps":["trace[2103837779] 'process raft request' (duration: 1.757460888s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:25.377357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.619557Z","time spent":"1.757754584s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:25.377458Z","caller":"traceutil/trace.go:171","msg":"trace[2125406297] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"1.756177527s","start":"2026-02-17T23:11:23.621275Z","end":"2026-02-17T23:11:25.377453Z","steps":["trace[2125406297] 'process raft request' (duration: 1.755833429s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:25.377505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.621267Z","time spent":"1.756224089s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:25.377578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.661416911s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:25.377595Z","caller":"traceutil/trace.go:171","msg":"trace[1449692261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"1.661460223s","start":"2026-02-17T23:11:23.71613Z","end":"2026-02-17T23:11:25.37759Z","steps":["trace[1449692261] 'agreement among raft nodes before linearized reading' (duration: 1.661432532s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:25.377613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.716116Z","time spent":"1.661494623s","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:11:25.377712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.545824263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:11:25.377729Z","caller":"traceutil/trace.go:171","msg":"trace[1892004187] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:745; }","duration":"1.545886925s","start":"2026-02-17T23:11:23.831838Z","end":"2026-02-17T23:11:25.377725Z","steps":["trace[1892004187] 'agreement among raft nodes before linearized reading' (duration: 1.545847794s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:25.377746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:23.831815Z","time spent":"1.545927145s","remote":"127.0.0.1:60826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:11:25.377923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.085244349s","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-17T23:11:25.377941Z","caller":"traceutil/trace.go:171","msg":"trace[781374807] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:745; }","duration":"1.08528898s","start":"2026-02-17T23:11:24.292648Z","end":"2026-02-17T23:11:25.377937Z","steps":["trace[781374807] 'agreement among raft nodes before linearized reading' (duration: 1.085230668s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:25.377954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:24.292634Z","time spent":"1.085316731s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-17T23:11:25.828311Z","caller":"traceutil/trace.go:171","msg":"trace[824421480] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:777; }","duration":"447.658815ms","start":"2026-02-17T23:11:25.380635Z","end":"2026-02-17T23:11:25.828294Z","steps":["trace[824421480] 'read index received' (duration: 352.229343ms)","trace[824421480] 'applied index is now lower than readState.Index' (duration: 95.428732ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:25.828399Z","caller":"traceutil/trace.go:171","msg":"trace[821299526] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"447.959722ms","start":"2026-02-17T23:11:25.380432Z","end":"2026-02-17T23:11:25.828392Z","steps":["trace[821299526] 'process raft request' (duration: 352.483459ms)","trace[821299526] 'compare' (duration: 95.304249ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:25.828469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:25.38042Z","time spent":"447.997523ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:25.828497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.499502ms","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-17T23:11:25.828541Z","caller":"traceutil/trace.go:171","msg":"trace[1403266131] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:746; }","duration":"208.571873ms","start":"2026-02-17T23:11:25.619957Z","end":"2026-02-17T23:11:25.828529Z","steps":["trace[1403266131] 'agreement among raft nodes before linearized reading' (duration: 208.45557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:25.828613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.977532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:25.828624Z","caller":"traceutil/trace.go:171","msg":"trace[1621341643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"447.999282ms","start":"2026-02-17T23:11:25.380621Z","end":"2026-02-17T23:11:25.828621Z","steps":["trace[1621341643] 'agreement among raft nodes before linearized reading' (duration: 447.982242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:25.828648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:25.380616Z","time spent":"448.028503ms","remote":"127.0.0.1:60744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:11:26.506749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.722147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:11:26.506819Z","caller":"traceutil/trace.go:171","msg":"trace[594144337] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"665.584015ms","start":"2026-02-17T23:11:25.841223Z","end":"2026-02-17T23:11:26.506807Z","steps":["trace[594144337] 'read index received' (duration: 201.734825ms)","trace[594144337] 'applied index is now lower than readState.Index' (duration: 463.84807ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:26.506971Z","caller":"traceutil/trace.go:171","msg":"trace[1570528372] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"674.471241ms","start":"2026-02-17T23:11:25.832492Z","end":"2026-02-17T23:11:26.506963Z","steps":["trace[1570528372] 'process raft request' (duration: 210.475888ms)","trace[1570528372] 'compare' (duration: 463.641765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:26.507012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:25.832476Z","time spent":"674.513922ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:26.50715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.924853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:26.50717Z","caller":"traceutil/trace.go:171","msg":"trace[969582554] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"665.965784ms","start":"2026-02-17T23:11:25.841197Z","end":"2026-02-17T23:11:26.507163Z","steps":["trace[969582554] 'agreement among raft nodes before linearized reading' (duration: 665.934003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:26.507187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:25.841184Z","time spent":"665.998755ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:11:26.507287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.504658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:26.507305Z","caller":"traceutil/trace.go:171","msg":"trace[1401620022] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:747; }","duration":"254.55471ms","start":"2026-02-17T23:11:26.252745Z","end":"2026-02-17T23:11:26.507299Z","steps":["trace[1401620022] 'agreement among raft nodes before linearized reading' (duration: 254.518259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:27.852975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:27.182395Z","time spent":"670.57609ms","remote":"127.0.0.1:60776","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-17T23:11:27.853494Z","caller":"traceutil/trace.go:171","msg":"trace[128195153] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:781; }","duration":"470.419632ms","start":"2026-02-17T23:11:27.383055Z","end":"2026-02-17T23:11:27.853474Z","steps":["trace[128195153] 'read index received' (duration: 470.414202ms)","trace[128195153] 'applied index is now lower than readState.Index' (duration: 4µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:27.853687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.626046ms","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-17T23:11:27.85372Z","caller":"traceutil/trace.go:171","msg":"trace[542308434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"470.706978ms","start":"2026-02-17T23:11:27.383004Z","end":"2026-02-17T23:11:27.853711Z","steps":["trace[542308434] 'agreement among raft nodes before linearized reading' (duration: 470.575745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:27.853762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:27.382988Z","time spent":"470.764669ms","remote":"127.0.0.1:60942","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-17T23:11:28.353825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867499602746739,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-17T23:11:29.256722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.663192ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:11:29.256812Z","caller":"traceutil/trace.go:171","msg":"trace[226952499] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.68894139s","start":"2026-02-17T23:11:27.567854Z","end":"2026-02-17T23:11:29.256795Z","steps":["trace[226952499] 'process raft request' (duration: 870.137644ms)","trace[226952499] 'compare' (duration: 818.146181ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:29.256888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:27.567829Z","time spent":"1.689012662s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:29.33795Z","caller":"traceutil/trace.go:171","msg":"trace[1067210491] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:781; }","duration":"1.48438833s","start":"2026-02-17T23:11:27.853541Z","end":"2026-02-17T23:11:29.337929Z","steps":["trace[1067210491] 'read index received' (duration: 584.464455ms)","trace[1067210491] 'applied index is now lower than readState.Index' (duration: 899.922965ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:29.337974Z","caller":"traceutil/trace.go:171","msg":"trace[828344256] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.483487488s","start":"2026-02-17T23:11:27.854457Z","end":"2026-02-17T23:11:29.337944Z","steps":["trace[828344256] 'process raft request' (duration: 1.483303974s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.33812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:27.85444Z","time spent":"1.483606622s","remote":"127.0.0.1:60776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:29.338325Z","caller":"traceutil/trace.go:171","msg":"trace[1319892633] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"1.481470582s","start":"2026-02-17T23:11:27.856841Z","end":"2026-02-17T23:11:29.338312Z","steps":["trace[1319892633] 'process raft request' (duration: 1.481045462s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.338424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:27.856829Z","time spent":"1.481531363s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:29.338498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945730391s","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-17T23:11:29.338525Z","caller":"traceutil/trace.go:171","msg":"trace[999275978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.945778872s","start":"2026-02-17T23:11:27.392738Z","end":"2026-02-17T23:11:29.338517Z","steps":["trace[999275978] 'agreement among raft nodes before linearized reading' (duration: 1.945665719s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.338544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:27.392726Z","time spent":"1.945814023s","remote":"127.0.0.1:60942","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-17T23:11:29.338682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504949576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-17T23:11:29.338679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.21849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T23:11:29.338699Z","caller":"traceutil/trace.go:171","msg":"trace[297907661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"1.505003368s","start":"2026-02-17T23:11:27.83369Z","end":"2026-02-17T23:11:29.338694Z","steps":["trace[297907661] 'agreement among raft nodes before linearized reading' (duration: 1.504951387s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.338716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:27.833673Z","time spent":"1.505039108s","remote":"127.0.0.1:60942","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-17T23:11:29.338757Z","caller":"traceutil/trace.go:171","msg":"trace[1137266082] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"827.331423ms","start":"2026-02-17T23:11:28.511415Z","end":"2026-02-17T23:11:29.338746Z","steps":["trace[1137266082] 'agreement among raft nodes before linearized reading' (duration: 827.174069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.338789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:28.511395Z","time spent":"827.386994ms","remote":"127.0.0.1:60942","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-17T23:11:29.338805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.622979541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:29.338823Z","caller":"traceutil/trace.go:171","msg":"trace[1821428169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"1.623034933s","start":"2026-02-17T23:11:27.715782Z","end":"2026-02-17T23:11:29.338817Z","steps":["trace[1821428169] 'agreement among raft nodes before linearized reading' (duration: 1.623008082s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.338838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:27.715763Z","time spent":"1.623072483s","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-17T23:11:29.338992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.290456ms","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-17T23:11:29.339027Z","caller":"traceutil/trace.go:171","msg":"trace[477565943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"504.360388ms","start":"2026-02-17T23:11:28.834658Z","end":"2026-02-17T23:11:29.339018Z","steps":["trace[477565943] 'agreement among raft nodes before linearized reading' (duration: 504.269606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.339054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:28.834634Z","time spent":"504.414029ms","remote":"127.0.0.1:60942","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-17T23:11:29.530563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.316322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:11:29.530631Z","caller":"traceutil/trace.go:171","msg":"trace[1535513447] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"184.738432ms","start":"2026-02-17T23:11:29.345881Z","end":"2026-02-17T23:11:29.530619Z","steps":["trace[1535513447] 'read index received' (duration: 282.517µs)","trace[1535513447] 'applied index is now lower than readState.Index' (duration: 184.455215ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:29.530756Z","caller":"traceutil/trace.go:171","msg":"trace[1329090940] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"184.897235ms","start":"2026-02-17T23:11:29.345851Z","end":"2026-02-17T23:11:29.530748Z","steps":["trace[1329090940] 'process raft request' (duration: 25.162903ms)","trace[1329090940] 'compare' (duration: 159.130808ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:29.530888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.009378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:29.531614Z","caller":"traceutil/trace.go:171","msg":"trace[1529716485] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"185.033239ms","start":"2026-02-17T23:11:29.345871Z","end":"2026-02-17T23:11:29.530904Z","steps":["trace[1529716485] 'agreement among raft nodes before linearized reading' (duration: 184.995898ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:29.707201Z","caller":"traceutil/trace.go:171","msg":"trace[1372966975] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:785; }","duration":"176.52324ms","start":"2026-02-17T23:11:29.530652Z","end":"2026-02-17T23:11:29.707175Z","steps":["trace[1372966975] 'read index received' (duration: 100.381646ms)","trace[1372966975] 'applied index is now lower than readState.Index' (duration: 76.139794ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:29.707567Z","caller":"traceutil/trace.go:171","msg":"trace[2139229899] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"353.709928ms","start":"2026-02-17T23:11:29.353838Z","end":"2026-02-17T23:11:29.707548Z","steps":["trace[2139229899] 'process raft request' (duration: 277.269426ms)","trace[2139229899] 'compare' (duration: 75.639133ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:29.707691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:29.353819Z","time spent":"353.791229ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:29.708006Z","caller":"traceutil/trace.go:171","msg":"trace[1374381859] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"351.979277ms","start":"2026-02-17T23:11:29.356011Z","end":"2026-02-17T23:11:29.70799Z","steps":["trace[1374381859] 'process raft request' (duration: 350.966814ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.708119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:29.356005Z","time spent":"352.053309ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:29.708424Z","caller":"traceutil/trace.go:171","msg":"trace[1407558879] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"352.362046ms","start":"2026-02-17T23:11:29.356046Z","end":"2026-02-17T23:11:29.708408Z","steps":["trace[1407558879] 'process raft request' (duration: 350.992504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.708555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:29.356043Z","time spent":"352.435487ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:29.708894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.070426ms","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-17T23:11:29.708943Z","caller":"traceutil/trace.go:171","msg":"trace[137151391] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:755; }","duration":"354.128517ms","start":"2026-02-17T23:11:29.354801Z","end":"2026-02-17T23:11:29.708929Z","steps":["trace[137151391] 'agreement among raft nodes before linearized reading' (duration: 353.980433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.708989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:29.354796Z","time spent":"354.181198ms","remote":"127.0.0.1:60776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-17T23:11:29.709112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.636024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:29.70915Z","caller":"traceutil/trace.go:171","msg":"trace[260884170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"151.692085ms","start":"2026-02-17T23:11:29.557447Z","end":"2026-02-17T23:11:29.709139Z","steps":["trace[260884170] 'agreement among raft nodes before linearized reading' (duration: 151.633993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:29.709765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.89017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:11:29.709812Z","caller":"traceutil/trace.go:171","msg":"trace[992460623] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:755; }","duration":"113.975751ms","start":"2026-02-17T23:11:29.595823Z","end":"2026-02-17T23:11:29.709799Z","steps":["trace[992460623] 'agreement among raft nodes before linearized reading' (duration: 113.894739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.139498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.868184ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-17T23:11:44.139566Z","caller":"traceutil/trace.go:171","msg":"trace[243118174] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"735.024164ms","start":"2026-02-17T23:11:43.40453Z","end":"2026-02-17T23:11:44.139554Z","steps":["trace[243118174] 'read index received' (duration: 147.044658ms)","trace[243118174] 'applied index is now lower than readState.Index' (duration: 587.978776ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:44.139857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.145685ms","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-17T23:11:44.139875Z","caller":"traceutil/trace.go:171","msg":"trace[1079460078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:841; }","duration":"530.193247ms","start":"2026-02-17T23:11:43.609674Z","end":"2026-02-17T23:11:44.139868Z","steps":["trace[1079460078] 'agreement among raft nodes before linearized reading' (duration: 530.102515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.139892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:43.609663Z","time spent":"530.224167ms","remote":"127.0.0.1:60942","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-17T23:11:44.139895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.345941ms","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-17T23:11:44.139954Z","caller":"traceutil/trace.go:171","msg":"trace[964606385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:841; }","duration":"735.433574ms","start":"2026-02-17T23:11:43.404505Z","end":"2026-02-17T23:11:44.139939Z","steps":["trace[964606385] 'agreement among raft nodes before linearized reading' (duration: 735.26309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.139982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:43.404491Z","time spent":"735.484184ms","remote":"127.0.0.1:60942","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-17T23:11:44.140042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.196782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:11:44.140059Z","caller":"traceutil/trace.go:171","msg":"trace[1968773759] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:841; }","duration":"229.238002ms","start":"2026-02-17T23:11:43.910816Z","end":"2026-02-17T23:11:44.140054Z","steps":["trace[1968773759] 'agreement among raft nodes before linearized reading' (duration: 229.190261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.140135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.432307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:44.140149Z","caller":"traceutil/trace.go:171","msg":"trace[449584772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:841; }","duration":"270.469148ms","start":"2026-02-17T23:11:43.869676Z","end":"2026-02-17T23:11:44.140145Z","steps":["trace[449584772] 'agreement among raft nodes before linearized reading' (duration: 270.447488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.140244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.170486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-17T23:11:44.140242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.961749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:44.140258Z","caller":"traceutil/trace.go:171","msg":"trace[1814665090] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:841; }","duration":"347.194336ms","start":"2026-02-17T23:11:43.793059Z","end":"2026-02-17T23:11:44.140254Z","steps":["trace[1814665090] 'agreement among raft nodes before linearized reading' (duration: 347.152435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.14027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:43.793055Z","time spent":"347.213286ms","remote":"127.0.0.1:60942","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":"info","ts":"2026-02-17T23:11:44.140269Z","caller":"traceutil/trace.go:171","msg":"trace[1937691930] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:841; }","duration":"498.009001ms","start":"2026-02-17T23:11:43.642251Z","end":"2026-02-17T23:11:44.14026Z","steps":["trace[1937691930] 'agreement among raft nodes before linearized reading' (duration: 497.96585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.140288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:43.642239Z","time spent":"498.044202ms","remote":"127.0.0.1:32836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:11:44.140434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.788696ms","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-17T23:11:44.140453Z","caller":"traceutil/trace.go:171","msg":"trace[225557912] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:841; }","duration":"349.814786ms","start":"2026-02-17T23:11:43.790632Z","end":"2026-02-17T23:11:44.140447Z","steps":["trace[225557912] 'agreement among raft nodes before linearized reading' (duration: 349.759375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.140469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:43.79063Z","time spent":"349.834877ms","remote":"127.0.0.1:60942","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-17T23:11:44.140642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.013972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T23:11:44.140662Z","caller":"traceutil/trace.go:171","msg":"trace[2058621645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:841; }","duration":"350.057542ms","start":"2026-02-17T23:11:43.790599Z","end":"2026-02-17T23:11:44.140656Z","steps":["trace[2058621645] 'agreement among raft nodes before linearized reading' (duration: 349.97777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.140684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:43.790586Z","time spent":"350.089423ms","remote":"127.0.0.1:60942","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-17T23:11:44.783469Z","caller":"traceutil/trace.go:171","msg":"trace[1587756549] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"631.697979ms","start":"2026-02-17T23:11:44.151754Z","end":"2026-02-17T23:11:44.783452Z","steps":["trace[1587756549] 'process raft request' (duration: 631.583186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:44.783561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:44.151741Z","time spent":"631.770111ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:45.502274Z","caller":"traceutil/trace.go:171","msg":"trace[252079987] linearizableReadLoop","detail":"{readStateIndex:885; appliedIndex:881; }","duration":"789.21035ms","start":"2026-02-17T23:11:44.71305Z","end":"2026-02-17T23:11:45.502261Z","steps":["trace[252079987] 'read index received' (duration: 70.805231ms)","trace[252079987] 'applied index is now lower than readState.Index' (duration: 718.404669ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:45.502347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.286362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:45.502363Z","caller":"traceutil/trace.go:171","msg":"trace[1804706078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:846; }","duration":"789.337653ms","start":"2026-02-17T23:11:44.713021Z","end":"2026-02-17T23:11:45.502358Z","steps":["trace[1804706078] 'agreement among raft nodes before linearized reading' (duration: 789.289982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:45.502377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:44.713009Z","time spent":"789.364844ms","remote":"127.0.0.1:60744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:11:45.502523Z","caller":"traceutil/trace.go:171","msg":"trace[1980359717] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"1.346634468s","start":"2026-02-17T23:11:44.155882Z","end":"2026-02-17T23:11:45.502517Z","steps":["trace[1980359717] 'process raft request' (duration: 1.313285125s)","trace[1980359717] 'compare' (duration: 32.960804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:45.502567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:44.155876Z","time spent":"1.346659229s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:45.502638Z","caller":"traceutil/trace.go:171","msg":"trace[340251266] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"1.346688359s","start":"2026-02-17T23:11:44.155946Z","end":"2026-02-17T23:11:45.502634Z","steps":["trace[340251266] 'process raft request' (duration: 1.346261409s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:45.50266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:44.155942Z","time spent":"1.346705909s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:45.502723Z","caller":"traceutil/trace.go:171","msg":"trace[364541466] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"1.346748241s","start":"2026-02-17T23:11:44.15597Z","end":"2026-02-17T23:11:45.502719Z","steps":["trace[364541466] 'process raft request' (duration: 1.34625515s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:45.502744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:44.155965Z","time spent":"1.346766551s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:45.5028Z","caller":"traceutil/trace.go:171","msg":"trace[1932690102] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"1.343831594s","start":"2026-02-17T23:11:44.158965Z","end":"2026-02-17T23:11:45.502796Z","steps":["trace[1932690102] 'process raft request' (duration: 1.343279001s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:45.502823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:44.158959Z","time spent":"1.343850954s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:11:47.327671Z","caller":"traceutil/trace.go:171","msg":"trace[1664142002] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"115.302012ms","start":"2026-02-17T23:11:47.212353Z","end":"2026-02-17T23:11:47.327655Z","steps":["trace[1664142002] 'read index received' (duration: 115.18866ms)","trace[1664142002] 'applied index is now lower than readState.Index' (duration: 112.432µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:11:47.327796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.428915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:11:47.327817Z","caller":"traceutil/trace.go:171","msg":"trace[776229371] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:847; }","duration":"115.491727ms","start":"2026-02-17T23:11:47.212318Z","end":"2026-02-17T23:11:47.32781Z","steps":["trace[776229371] 'agreement among raft nodes before linearized reading' (duration: 115.394085ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:47.585048Z","caller":"traceutil/trace.go:171","msg":"trace[1359458695] transaction","detail":"{read_only:false; response_revision:848; number_of_response:1; }","duration":"256.376982ms","start":"2026-02-17T23:11:47.328652Z","end":"2026-02-17T23:11:47.585029Z","steps":["trace[1359458695] 'process raft request' (duration: 256.254689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.094131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867499602746978,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T23:11:50.197114Z","caller":"traceutil/trace.go:171","msg":"trace[851288459] linearizableReadLoop","detail":"{readStateIndex:894; appliedIndex:893; }","duration":"603.724601ms","start":"2026-02-17T23:11:49.593372Z","end":"2026-02-17T23:11:50.197097Z","steps":["trace[851288459] 'read index received' (duration: 603.586238ms)","trace[851288459] 'applied index is now lower than readState.Index' (duration: 137.523µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:50.197203Z","caller":"traceutil/trace.go:171","msg":"trace[1545872050] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"719.213388ms","start":"2026-02-17T23:11:49.477983Z","end":"2026-02-17T23:11:50.197196Z","steps":["trace[1545872050] 'process raft request' (duration: 718.990642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.19728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:49.477959Z","time spent":"719.262119ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:50.197455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.493486ms","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-17T23:11:50.197503Z","caller":"traceutil/trace.go:171","msg":"trace[1119136151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:854; }","duration":"603.556507ms","start":"2026-02-17T23:11:49.593934Z","end":"2026-02-17T23:11:50.197491Z","steps":["trace[1119136151] 'agreement among raft nodes before linearized reading' (duration: 603.429534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.197528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:49.593929Z","time spent":"603.593358ms","remote":"127.0.0.1:60942","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-17T23:11:50.197604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.51577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:50.197629Z","caller":"traceutil/trace.go:171","msg":"trace[127989345] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:854; }","duration":"497.566001ms","start":"2026-02-17T23:11:49.700056Z","end":"2026-02-17T23:11:50.197622Z","steps":["trace[127989345] 'agreement among raft nodes before linearized reading' (duration: 497.52308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.197651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:49.700044Z","time spent":"497.600702ms","remote":"127.0.0.1:60978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:11:50.197673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.675781ms","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-17T23:11:50.197693Z","caller":"traceutil/trace.go:171","msg":"trace[1041823674] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:854; }","duration":"597.704332ms","start":"2026-02-17T23:11:49.599983Z","end":"2026-02-17T23:11:50.197688Z","steps":["trace[1041823674] 'agreement among raft nodes before linearized reading' (duration: 597.649991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.197711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:49.599979Z","time spent":"597.728502ms","remote":"127.0.0.1:60942","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-17T23:11:50.197798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.424407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T23:11:50.197817Z","caller":"traceutil/trace.go:171","msg":"trace[1585223986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:854; }","duration":"604.466249ms","start":"2026-02-17T23:11:49.593345Z","end":"2026-02-17T23:11:50.197811Z","steps":["trace[1585223986] 'agreement among raft nodes before linearized reading' (duration: 604.395757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.197827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.319506ms","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-17T23:11:50.197832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:49.593333Z","time spent":"604.495969ms","remote":"127.0.0.1:60942","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-17T23:11:50.197845Z","caller":"traceutil/trace.go:171","msg":"trace[223320950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:854; }","duration":"598.346417ms","start":"2026-02-17T23:11:49.599493Z","end":"2026-02-17T23:11:50.19784Z","steps":["trace[223320950] 'agreement among raft nodes before linearized reading' (duration: 598.293835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.197863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:49.599489Z","time spent":"598.369107ms","remote":"127.0.0.1:60942","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-17T23:11:50.197932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.422026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:50.19795Z","caller":"traceutil/trace.go:171","msg":"trace[816063127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:854; }","duration":"484.469358ms","start":"2026-02-17T23:11:49.713476Z","end":"2026-02-17T23:11:50.197945Z","steps":["trace[816063127] 'agreement among raft nodes before linearized reading' (duration: 484.427267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.197968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:49.713468Z","time spent":"484.496358ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:11:50.340111Z","caller":"traceutil/trace.go:171","msg":"trace[831349910] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"134.080857ms","start":"2026-02-17T23:11:50.206012Z","end":"2026-02-17T23:11:50.340093Z","steps":["trace[831349910] 'process raft request' (duration: 133.955064ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:50.340292Z","caller":"traceutil/trace.go:171","msg":"trace[998353798] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"134.21001ms","start":"2026-02-17T23:11:50.206067Z","end":"2026-02-17T23:11:50.340277Z","steps":["trace[998353798] 'process raft request' (duration: 134.001086ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:11:50.340462Z","caller":"traceutil/trace.go:171","msg":"trace[1978373674] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"134.356194ms","start":"2026-02-17T23:11:50.2061Z","end":"2026-02-17T23:11:50.340456Z","steps":["trace[1978373674] 'process raft request' (duration: 134.131658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:50.914524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.018259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:11:50.914589Z","caller":"traceutil/trace.go:171","msg":"trace[1428342993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:858; }","duration":"198.112701ms","start":"2026-02-17T23:11:50.71646Z","end":"2026-02-17T23:11:50.914573Z","steps":["trace[1428342993] 'range keys from in-memory index tree' (duration: 197.972108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:58.97507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867499602747043,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T23:11:59.046525Z","caller":"traceutil/trace.go:171","msg":"trace[1278267311] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"571.833603ms","start":"2026-02-17T23:11:58.474666Z","end":"2026-02-17T23:11:59.046499Z","steps":["trace[1278267311] 'read index received' (duration: 571.658729ms)","trace[1278267311] 'applied index is now lower than readState.Index' (duration: 174.004µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:11:59.046651Z","caller":"traceutil/trace.go:171","msg":"trace[1696303750] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"624.453802ms","start":"2026-02-17T23:11:58.422186Z","end":"2026-02-17T23:11:59.04664Z","steps":["trace[1696303750] 'process raft request' (duration: 624.195386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:59.046761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:58.422176Z","time spent":"624.502633ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:11:59.047061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.73471ms","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-17T23:11:59.047159Z","caller":"traceutil/trace.go:171","msg":"trace[2014586862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:877; }","duration":"571.846752ms","start":"2026-02-17T23:11:58.47529Z","end":"2026-02-17T23:11:59.047137Z","steps":["trace[2014586862] 'agreement among raft nodes before linearized reading' (duration: 571.606147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:59.047208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:58.475285Z","time spent":"571.910274ms","remote":"127.0.0.1:60942","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-17T23:11:59.047371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.706458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:11:59.047401Z","caller":"traceutil/trace.go:171","msg":"trace[680151736] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:877; }","duration":"284.756459ms","start":"2026-02-17T23:11:58.762632Z","end":"2026-02-17T23:11:59.047389Z","steps":["trace[680151736] 'agreement among raft nodes before linearized reading' (duration: 284.708018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:59.047455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.783375ms","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-17T23:11:59.047497Z","caller":"traceutil/trace.go:171","msg":"trace[1956192629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:877; }","duration":"572.850575ms","start":"2026-02-17T23:11:58.474634Z","end":"2026-02-17T23:11:59.047485Z","steps":["trace[1956192629] 'agreement among raft nodes before linearized reading' (duration: 572.750423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:59.047528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:58.47462Z","time spent":"572.901407ms","remote":"127.0.0.1:60942","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-17T23:11:59.047645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.495365ms","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-17T23:11:59.047676Z","caller":"traceutil/trace.go:171","msg":"trace[1158018562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:877; }","duration":"571.537556ms","start":"2026-02-17T23:11:58.47613Z","end":"2026-02-17T23:11:59.047668Z","steps":["trace[1158018562] 'agreement among raft nodes before linearized reading' (duration: 571.450374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:59.047702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:58.476125Z","time spent":"571.570726ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T23:11:59.8652Z","caller":"traceutil/trace.go:171","msg":"trace[1115451237] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"811.840835ms","start":"2026-02-17T23:11:59.053343Z","end":"2026-02-17T23:11:59.865183Z","steps":["trace[1115451237] 'process raft request' (duration: 811.746412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:11:59.865293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:59.053328Z","time spent":"811.915086ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:00.508812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.58201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:00.51184Z","caller":"traceutil/trace.go:171","msg":"trace[434523458] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:919; }","duration":"799.389294ms","start":"2026-02-17T23:11:59.712433Z","end":"2026-02-17T23:12:00.511823Z","steps":["trace[434523458] 'read index received' (duration: 152.617307ms)","trace[434523458] 'applied index is now lower than readState.Index' (duration: 646.770987ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:00.512055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.598759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:00.512117Z","caller":"traceutil/trace.go:171","msg":"trace[1469088798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"799.696592ms","start":"2026-02-17T23:11:59.712407Z","end":"2026-02-17T23:12:00.512103Z","steps":["trace[1469088798] 'agreement among raft nodes before linearized reading' (duration: 799.554859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:00.512148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:59.712393Z","time spent":"799.749153ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:12:00.512354Z","caller":"traceutil/trace.go:171","msg":"trace[317794352] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"1.454366154s","start":"2026-02-17T23:11:59.057978Z","end":"2026-02-17T23:12:00.512344Z","steps":["trace[317794352] 'process raft request' (duration: 1.45373669s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:00.512417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:59.057959Z","time spent":"1.454416355s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:00.512477Z","caller":"traceutil/trace.go:171","msg":"trace[420780477] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"1.454078757s","start":"2026-02-17T23:11:59.058392Z","end":"2026-02-17T23:12:00.512471Z","steps":["trace[420780477] 'process raft request' (duration: 1.453388851s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:00.512512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:59.058385Z","time spent":"1.454109008s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:00.51265Z","caller":"traceutil/trace.go:171","msg":"trace[1805077936] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"1.456392331s","start":"2026-02-17T23:11:59.05625Z","end":"2026-02-17T23:12:00.512642Z","steps":["trace[1805077936] 'process raft request' (duration: 970.896151ms)","trace[1805077936] 'compare' (duration: 481.18025ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:00.512691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:59.05624Z","time spent":"1.456430612s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:01.184066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.554132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:01.184166Z","caller":"traceutil/trace.go:171","msg":"trace[432965427] linearizableReadLoop","detail":"{readStateIndex:924; appliedIndex:923; }","duration":"672.176167ms","start":"2026-02-17T23:12:00.511975Z","end":"2026-02-17T23:12:01.184152Z","steps":["trace[432965427] 'read index received' (duration: 186.40816ms)","trace[432965427] 'applied index is now lower than readState.Index' (duration: 485.767077ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:01.184411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204875481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-17T23:12:01.18446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.939558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:01.18448Z","caller":"traceutil/trace.go:171","msg":"trace[1734709875] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:882; }","duration":"1.204975674s","start":"2026-02-17T23:11:59.979487Z","end":"2026-02-17T23:12:01.184462Z","steps":["trace[1734709875] 'agreement among raft nodes before linearized reading' (duration: 1.20482875s)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:01.184493Z","caller":"traceutil/trace.go:171","msg":"trace[1329866614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:882; }","duration":"671.00673ms","start":"2026-02-17T23:12:00.513477Z","end":"2026-02-17T23:12:01.184483Z","steps":["trace[1329866614] 'agreement among raft nodes before linearized reading' (duration: 670.955199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:01.184525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:59.97944Z","time spent":"1.205074526s","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:12:01.184547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:00.513461Z","time spent":"671.078082ms","remote":"127.0.0.1:60744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:12:01.184735Z","caller":"traceutil/trace.go:171","msg":"trace[777110748] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"973.370847ms","start":"2026-02-17T23:12:00.21135Z","end":"2026-02-17T23:12:01.184721Z","steps":["trace[777110748] 'process raft request' (duration: 487.081248ms)","trace[777110748] 'compare' (duration: 485.44831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:01.184844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:00.211326Z","time spent":"973.457829ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:01.184902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.712982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T23:12:01.184932Z","caller":"traceutil/trace.go:171","msg":"trace[2069633365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:882; }","duration":"131.780014ms","start":"2026-02-17T23:12:01.053143Z","end":"2026-02-17T23:12:01.184923Z","steps":["trace[2069633365] 'agreement among raft nodes before linearized reading' (duration: 131.668312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:01.185035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202026396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:12:01.18507Z","caller":"traceutil/trace.go:171","msg":"trace[1368295077] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:882; }","duration":"1.202079096s","start":"2026-02-17T23:11:59.982979Z","end":"2026-02-17T23:12:01.185058Z","steps":["trace[1368295077] 'agreement among raft nodes before linearized reading' (duration: 1.202003135s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:01.1851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:11:59.98297Z","time spent":"1.202122598s","remote":"127.0.0.1:32808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:12:01.185115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.303526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:12:01.185142Z","caller":"traceutil/trace.go:171","msg":"trace[400993918] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:882; }","duration":"299.368538ms","start":"2026-02-17T23:12:00.885766Z","end":"2026-02-17T23:12:01.185134Z","steps":["trace[400993918] 'agreement among raft nodes before linearized reading' (duration: 299.284826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:01.710582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.892149ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:01.710788Z","caller":"traceutil/trace.go:171","msg":"trace[1779131266] linearizableReadLoop","detail":"{readStateIndex:925; appliedIndex:924; }","duration":"512.648301ms","start":"2026-02-17T23:12:01.198127Z","end":"2026-02-17T23:12:01.710775Z","steps":["trace[1779131266] 'read index received' (duration: 146.491705ms)","trace[1779131266] 'applied index is now lower than readState.Index' (duration: 366.155516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:01.71096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.832125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:01.711013Z","caller":"traceutil/trace.go:171","msg":"trace[1579715470] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"512.918257ms","start":"2026-02-17T23:12:01.198088Z","end":"2026-02-17T23:12:01.711006Z","steps":["trace[1579715470] 'agreement among raft nodes before linearized reading' (duration: 512.787804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:01.711111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:01.19807Z","time spent":"513.014019ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:12:01.711328Z","caller":"traceutil/trace.go:171","msg":"trace[1184908449] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"519.275894ms","start":"2026-02-17T23:12:01.192045Z","end":"2026-02-17T23:12:01.711321Z","steps":["trace[1184908449] 'process raft request' (duration: 152.597437ms)","trace[1184908449] 'compare' (duration: 365.583522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:01.711474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:01.19203Z","time spent":"519.420267ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:02.344433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.42031ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:02.344505Z","caller":"traceutil/trace.go:171","msg":"trace[1199040424] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"631.673169ms","start":"2026-02-17T23:12:01.71282Z","end":"2026-02-17T23:12:02.344493Z","steps":["trace[1199040424] 'read index received' (duration: 146.070255ms)","trace[1199040424] 'applied index is now lower than readState.Index' (duration: 485.602144ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:02.344648Z","caller":"traceutil/trace.go:171","msg":"trace[798872854] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"788.363311ms","start":"2026-02-17T23:12:01.556275Z","end":"2026-02-17T23:12:02.344639Z","steps":["trace[798872854] 'process raft request' (duration: 302.673795ms)","trace[798872854] 'compare' (duration: 485.341357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:02.344688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:01.556251Z","time spent":"788.416311ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:02.344963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.913359ms","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-17T23:12:02.34508Z","caller":"traceutil/trace.go:171","msg":"trace[1777313697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"476.068843ms","start":"2026-02-17T23:12:01.868988Z","end":"2026-02-17T23:12:02.345056Z","steps":["trace[1777313697] 'agreement among raft nodes before linearized reading' (duration: 475.810857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:02.345134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:01.868969Z","time spent":"476.152465ms","remote":"127.0.0.1:60942","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-17T23:12:02.34705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.498758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:02.347161Z","caller":"traceutil/trace.go:171","msg":"trace[80204103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"634.33415ms","start":"2026-02-17T23:12:01.712806Z","end":"2026-02-17T23:12:02.34714Z","steps":["trace[80204103] 'agreement among raft nodes before linearized reading' (duration: 632.488218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:02.347214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:01.712797Z","time spent":"634.400172ms","remote":"127.0.0.1:60744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:12:02.73594Z","caller":"traceutil/trace.go:171","msg":"trace[1550451820] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"383.767424ms","start":"2026-02-17T23:12:02.352152Z","end":"2026-02-17T23:12:02.73592Z","steps":["trace[1550451820] 'process raft request' (duration: 355.374316ms)","trace[1550451820] 'compare' (duration: 27.926058ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:02.73604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:02.352121Z","time spent":"383.866496ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:02.736103Z","caller":"traceutil/trace.go:171","msg":"trace[1173985488] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"380.896498ms","start":"2026-02-17T23:12:02.355158Z","end":"2026-02-17T23:12:02.736055Z","steps":["trace[1173985488] 'read index received' (duration: 352.348546ms)","trace[1173985488] 'applied index is now lower than readState.Index' (duration: 28.544902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:02.896425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.602781ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-17T23:12:02.896516Z","caller":"traceutil/trace.go:171","msg":"trace[25363716] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:927; }","duration":"160.332826ms","start":"2026-02-17T23:12:02.736166Z","end":"2026-02-17T23:12:02.896498Z","steps":["trace[25363716] 'read index received' (duration: 65.072µs)","trace[25363716] 'applied index is now lower than readState.Index' (duration: 160.266254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:02.89669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.004245ms","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-17T23:12:02.896699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.51919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:02.896772Z","caller":"traceutil/trace.go:171","msg":"trace[1883855745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"541.600071ms","start":"2026-02-17T23:12:02.355152Z","end":"2026-02-17T23:12:02.896752Z","steps":["trace[1883855745] 'agreement among raft nodes before linearized reading' (duration: 381.054421ms)","trace[1883855745] 'range keys from in-memory index tree' (duration: 160.434927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:02.89681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:02.355063Z","time spent":"541.739905ms","remote":"127.0.0.1:60758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:12:02.896731Z","caller":"traceutil/trace.go:171","msg":"trace[1888387718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"373.090586ms","start":"2026-02-17T23:12:02.523631Z","end":"2026-02-17T23:12:02.896721Z","steps":["trace[1888387718] 'agreement among raft nodes before linearized reading' (duration: 372.915672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:02.897033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:02.523613Z","time spent":"373.409604ms","remote":"127.0.0.1:60942","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-17T23:12:02.897155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.77221ms","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-17T23:12:02.897229Z","caller":"traceutil/trace.go:171","msg":"trace[335729783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:885; }","duration":"347.879352ms","start":"2026-02-17T23:12:02.549334Z","end":"2026-02-17T23:12:02.897213Z","steps":["trace[335729783] 'agreement among raft nodes before linearized reading' (duration: 347.717188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:02.897268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:02.549313Z","time spent":"347.945193ms","remote":"127.0.0.1:60942","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-17T23:12:02.897507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.792553ms","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-17T23:12:02.897545Z","caller":"traceutil/trace.go:171","msg":"trace[520595717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:885; }","duration":"373.844334ms","start":"2026-02-17T23:12:02.52369Z","end":"2026-02-17T23:12:02.897534Z","steps":["trace[520595717] 'agreement among raft nodes before linearized reading' (duration: 373.70096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:02.897573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:02.523683Z","time spent":"373.882884ms","remote":"127.0.0.1:60942","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":"info","ts":"2026-02-17T23:12:03.287385Z","caller":"traceutil/trace.go:171","msg":"trace[2117458758] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"384.01886ms","start":"2026-02-17T23:12:02.903332Z","end":"2026-02-17T23:12:03.287351Z","steps":["trace[2117458758] 'process raft request' (duration: 383.850146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:03.287543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:02.90331Z","time spent":"384.151283ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:03.894893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.552481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:03.89541Z","caller":"traceutil/trace.go:171","msg":"trace[126216272] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:928; }","duration":"725.751038ms","start":"2026-02-17T23:12:03.16964Z","end":"2026-02-17T23:12:03.895391Z","steps":["trace[126216272] 'read index received' (duration: 117.490543ms)","trace[126216272] 'applied index is now lower than readState.Index' (duration: 608.259555ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:03.895746Z","caller":"traceutil/trace.go:171","msg":"trace[1566104163] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"986.357778ms","start":"2026-02-17T23:12:02.909372Z","end":"2026-02-17T23:12:03.89573Z","steps":["trace[1566104163] 'process raft request' (duration: 414.895536ms)","trace[1566104163] 'compare' (duration: 570.270794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:03.897175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:02.909355Z","time spent":"986.432079ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:03.89759Z","caller":"traceutil/trace.go:171","msg":"trace[724058992] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"985.713713ms","start":"2026-02-17T23:12:02.911868Z","end":"2026-02-17T23:12:03.897582Z","steps":["trace[724058992] 'process raft request' (duration: 983.107112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:03.89763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:02.911854Z","time spent":"985.757824ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:03.898456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.812929ms","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-17T23:12:03.89848Z","caller":"traceutil/trace.go:171","msg":"trace[654335227] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:888; }","duration":"728.874881ms","start":"2026-02-17T23:12:03.169599Z","end":"2026-02-17T23:12:03.898474Z","steps":["trace[654335227] 'agreement among raft nodes before linearized reading' (duration: 728.83296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:03.899016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:03.169582Z","time spent":"729.425404ms","remote":"127.0.0.1:32922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:12:03.8993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.098184ms","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-17T23:12:03.899316Z","caller":"traceutil/trace.go:171","msg":"trace[2102736573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"183.149194ms","start":"2026-02-17T23:12:03.716162Z","end":"2026-02-17T23:12:03.899311Z","steps":["trace[2102736573] 'agreement among raft nodes before linearized reading' (duration: 183.085583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:03.899452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.378406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:12:03.899505Z","caller":"traceutil/trace.go:171","msg":"trace[1817537140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:888; }","duration":"182.494169ms","start":"2026-02-17T23:12:03.716997Z","end":"2026-02-17T23:12:03.899491Z","steps":["trace[1817537140] 'agreement among raft nodes before linearized reading' (duration: 182.365146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.242401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.287279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:04.242601Z","caller":"traceutil/trace.go:171","msg":"trace[880134881] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"267.823848ms","start":"2026-02-17T23:12:03.974761Z","end":"2026-02-17T23:12:04.242585Z","steps":["trace[880134881] 'read index received' (duration: 90.083658ms)","trace[880134881] 'applied index is now lower than readState.Index' (duration: 177.738649ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:04.243627Z","caller":"traceutil/trace.go:171","msg":"trace[1132523284] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"341.243088ms","start":"2026-02-17T23:12:03.902368Z","end":"2026-02-17T23:12:04.243611Z","steps":["trace[1132523284] 'process raft request' (duration: 162.527096ms)","trace[1132523284] 'compare' (duration: 176.804778ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:04.243141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.3834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-17T23:12:04.243823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:03.902361Z","time spent":"341.364771ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:12:04.243934Z","caller":"traceutil/trace.go:171","msg":"trace[2047277805] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:889; }","duration":"269.197869ms","start":"2026-02-17T23:12:03.974704Z","end":"2026-02-17T23:12:04.243902Z","steps":["trace[2047277805] 'agreement among raft nodes before linearized reading' (duration: 268.226487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:04.946038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.24464ms","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-17T23:12:04.946107Z","caller":"traceutil/trace.go:171","msg":"trace[611091972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"206.356163ms","start":"2026-02-17T23:12:04.739734Z","end":"2026-02-17T23:12:04.94609Z","steps":["trace[611091972] 'range keys from in-memory index tree' (duration: 206.053906ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:05.309083Z","caller":"traceutil/trace.go:171","msg":"trace[1002307710] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"357.652499ms","start":"2026-02-17T23:12:04.951416Z","end":"2026-02-17T23:12:05.309068Z","steps":["trace[1002307710] 'process raft request' (duration: 357.26318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:05.309175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:04.951396Z","time spent":"357.72842ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:05.734182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.725205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:12:05.734235Z","caller":"traceutil/trace.go:171","msg":"trace[1721078985] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"305.822287ms","start":"2026-02-17T23:12:05.428405Z","end":"2026-02-17T23:12:05.734227Z","steps":["trace[1721078985] 'read index received' (duration: 29.99µs)","trace[1721078985] 'applied index is now lower than readState.Index' (duration: 305.791677ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:12:05.734332Z","caller":"traceutil/trace.go:171","msg":"trace[1715660271] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"404.74161ms","start":"2026-02-17T23:12:05.329584Z","end":"2026-02-17T23:12:05.734326Z","steps":["trace[1715660271] 'process raft request' (duration: 73.826611ms)","trace[1715660271] 'compare' (duration: 330.51966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:12:05.734358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:05.329566Z","time spent":"404.778152ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:12:05.734449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.042972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T23:12:05.734463Z","caller":"traceutil/trace.go:171","msg":"trace[215492359] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:891; }","duration":"306.082753ms","start":"2026-02-17T23:12:05.428376Z","end":"2026-02-17T23:12:05.734458Z","steps":["trace[215492359] 'agreement among raft nodes before linearized reading' (duration: 306.056323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:05.734476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:05.428362Z","time spent":"306.110254ms","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-17T23:12:08.060016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.74092ms","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-17T23:12:08.060132Z","caller":"traceutil/trace.go:171","msg":"trace[1890913505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"319.867563ms","start":"2026-02-17T23:12:07.740246Z","end":"2026-02-17T23:12:08.060113Z","steps":["trace[1890913505] 'range keys from in-memory index tree' (duration: 319.616777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:08.060185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:07.740203Z","time spent":"319.972836ms","remote":"127.0.0.1:60942","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-17T23:12:08.060311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.538248ms","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-17T23:12:08.060387Z","caller":"traceutil/trace.go:171","msg":"trace[625784050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"153.657371ms","start":"2026-02-17T23:12:07.906711Z","end":"2026-02-17T23:12:08.060368Z","steps":["trace[625784050] 'range keys from in-memory index tree' (duration: 153.379015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:08.060599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.267654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-17T23:12:08.060625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.058384ms","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-17T23:12:08.060666Z","caller":"traceutil/trace.go:171","msg":"trace[575081746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"152.115026ms","start":"2026-02-17T23:12:07.908541Z","end":"2026-02-17T23:12:08.060656Z","steps":["trace[575081746] 'range keys from in-memory index tree' (duration: 151.984512ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:08.060644Z","caller":"traceutil/trace.go:171","msg":"trace[2108950472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"346.333206ms","start":"2026-02-17T23:12:07.714302Z","end":"2026-02-17T23:12:08.060635Z","steps":["trace[2108950472] 'range keys from in-memory index tree' (duration: 346.219753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:12:08.060741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:12:07.714287Z","time spent":"346.446268ms","remote":"127.0.0.1:60744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T23:12:08.172808Z","caller":"traceutil/trace.go:171","msg":"trace[1747381425] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"107.273046ms","start":"2026-02-17T23:12:08.06549Z","end":"2026-02-17T23:12:08.172763Z","steps":["trace[1747381425] 'process raft request' (duration: 107.072171ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:08.289766Z","caller":"traceutil/trace.go:171","msg":"trace[427385407] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"219.925267ms","start":"2026-02-17T23:12:08.067148Z","end":"2026-02-17T23:12:08.287074Z","steps":["trace[427385407] 'process raft request' (duration: 211.292277ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:08.289899Z","caller":"traceutil/trace.go:171","msg":"trace[448099657] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"222.68542ms","start":"2026-02-17T23:12:08.067204Z","end":"2026-02-17T23:12:08.28989Z","steps":["trace[448099657] 'process raft request' (duration: 217.126481ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:17.435315Z","caller":"traceutil/trace.go:171","msg":"trace[1945089586] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"147.48522ms","start":"2026-02-17T23:12:17.287809Z","end":"2026-02-17T23:12:17.435294Z","steps":["trace[1945089586] 'process raft request' (duration: 145.412972ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:12:23.811955Z","caller":"traceutil/trace.go:171","msg":"trace[1651416318] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"260.563733ms","start":"2026-02-17T23:12:23.55137Z","end":"2026-02-17T23:12:23.811933Z","steps":["trace[1651416318] 'process raft request' (duration: 260.063432ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:15:30.416354Z","caller":"traceutil/trace.go:171","msg":"trace[171588878] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"183.504315ms","start":"2026-02-17T23:15:30.232827Z","end":"2026-02-17T23:15:30.416331Z","steps":["trace[171588878] 'process raft request' (duration: 183.381432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:36.409567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880867499602749389,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-17T23:15:36.522784Z","caller":"traceutil/trace.go:171","msg":"trace[955089822] linearizableReadLoop","detail":"{readStateIndex:1902; appliedIndex:1901; }","duration":"613.377964ms","start":"2026-02-17T23:15:35.909384Z","end":"2026-02-17T23:15:36.522762Z","steps":["trace[955089822] 'read index received' (duration: 613.125228ms)","trace[955089822] 'applied index is now lower than readState.Index' (duration: 251.876µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:15:36.523931Z","caller":"traceutil/trace.go:171","msg":"trace[311366902] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"720.20178ms","start":"2026-02-17T23:15:35.803716Z","end":"2026-02-17T23:15:36.523917Z","steps":["trace[311366902] 'process raft request' (duration: 718.852939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:36.524118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.715925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-17T23:15:36.52439Z","caller":"traceutil/trace.go:171","msg":"trace[560226996] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1816; }","duration":"615.017251ms","start":"2026-02-17T23:15:35.909361Z","end":"2026-02-17T23:15:36.524379Z","steps":["trace[560226996] 'agreement among raft nodes before linearized reading' (duration: 614.600571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:36.524482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:35.909352Z","time spent":"615.120193ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-17T23:15:36.524256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.827341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T23:15:36.524714Z","caller":"traceutil/trace.go:171","msg":"trace[920609735] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1816; }","duration":"174.314213ms","start":"2026-02-17T23:15:36.350392Z","end":"2026-02-17T23:15:36.524706Z","steps":["trace[920609735] 'agreement among raft nodes before linearized reading' (duration: 173.844022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:36.525271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:35.803696Z","time spent":"720.6047ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:15:36.79151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.83501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:15:36.791576Z","caller":"traceutil/trace.go:171","msg":"trace[680679482] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"162.5821ms","start":"2026-02-17T23:15:36.628983Z","end":"2026-02-17T23:15:36.791565Z","steps":["trace[680679482] 'read index received' (duration: 37.481µs)","trace[680679482] 'applied index is now lower than readState.Index' (duration: 162.543079ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:15:36.791709Z","caller":"traceutil/trace.go:171","msg":"trace[766142152] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"260.88495ms","start":"2026-02-17T23:15:36.530815Z","end":"2026-02-17T23:15:36.7917Z","steps":["trace[766142152] 'process raft request' (duration: 61.776523ms)","trace[766142152] 'compare' (duration: 198.737818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:15:36.791876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.892257ms","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-17T23:15:36.791895Z","caller":"traceutil/trace.go:171","msg":"trace[1311444575] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1817; }","duration":"162.943048ms","start":"2026-02-17T23:15:36.628946Z","end":"2026-02-17T23:15:36.791889Z","steps":["trace[1311444575] 'agreement among raft nodes before linearized reading' (duration: 162.863246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:36.792031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.79619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-17T23:15:36.792049Z","caller":"traceutil/trace.go:171","msg":"trace[1707964250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1817; }","duration":"101.840792ms","start":"2026-02-17T23:15:36.690203Z","end":"2026-02-17T23:15:36.792044Z","steps":["trace[1707964250] 'agreement among raft nodes before linearized reading' (duration: 101.787221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.760005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.563641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T23:15:37.760941Z","caller":"traceutil/trace.go:171","msg":"trace[1330787623] linearizableReadLoop","detail":"{readStateIndex:1906; appliedIndex:1903; }","duration":"559.054263ms","start":"2026-02-17T23:15:37.20187Z","end":"2026-02-17T23:15:37.760924Z","steps":["trace[1330787623] 'read index received' (duration: 445.429088ms)","trace[1330787623] 'applied index is now lower than readState.Index' (duration: 113.624315ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:15:37.761156Z","caller":"traceutil/trace.go:171","msg":"trace[219739908] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"956.093351ms","start":"2026-02-17T23:15:36.805046Z","end":"2026-02-17T23:15:37.761139Z","steps":["trace[219739908] 'process raft request' (duration: 842.310953ms)","trace[219739908] 'compare' (duration: 112.372995ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T23:15:37.763183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:36.805029Z","time spent":"958.078887ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:15:37.762363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.474197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-17T23:15:37.763422Z","caller":"traceutil/trace.go:171","msg":"trace[1470238584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1820; }","duration":"561.582082ms","start":"2026-02-17T23:15:37.201831Z","end":"2026-02-17T23:15:37.763414Z","steps":["trace[1470238584] 'agreement among raft nodes before linearized reading' (duration: 560.22095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.763465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:37.201811Z","time spent":"561.646154ms","remote":"127.0.0.1:60942","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-17T23:15:37.762452Z","caller":"traceutil/trace.go:171","msg":"trace[63860274] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"956.793977ms","start":"2026-02-17T23:15:36.805638Z","end":"2026-02-17T23:15:37.762432Z","steps":["trace[63860274] 'process raft request' (duration: 954.454713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.763793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:36.805632Z","time spent":"958.133408ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T23:15:37.76256Z","caller":"traceutil/trace.go:171","msg":"trace[1255252752] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"670.912888ms","start":"2026-02-17T23:15:37.091636Z","end":"2026-02-17T23:15:37.762549Z","steps":["trace[1255252752] 'process raft request' (duration: 669.241599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.764157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:37.091604Z","time spent":"672.527095ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T23:15:37.762664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.023344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T23:15:37.76437Z","caller":"traceutil/trace.go:171","msg":"trace[973218805] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:1820; }","duration":"554.742324ms","start":"2026-02-17T23:15:37.209619Z","end":"2026-02-17T23:15:37.764362Z","steps":["trace[973218805] 'agreement among raft nodes before linearized reading' (duration: 552.983023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.764495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:37.209613Z","time spent":"554.871167ms","remote":"127.0.0.1:60776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-02-17T23:15:37.762731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.494783ms","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-17T23:15:37.76487Z","caller":"traceutil/trace.go:171","msg":"trace[1481974078] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1820; }","duration":"109.679713ms","start":"2026-02-17T23:15:37.655182Z","end":"2026-02-17T23:15:37.764862Z","steps":["trace[1481974078] 'agreement among raft nodes before linearized reading' (duration: 107.500633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.762808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.900568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-17T23:15:37.762867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.050622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-17T23:15:37.762924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.756117ms","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-17T23:15:37.765219Z","caller":"traceutil/trace.go:171","msg":"trace[449518695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1820; }","duration":"511.072371ms","start":"2026-02-17T23:15:37.254137Z","end":"2026-02-17T23:15:37.765209Z","steps":["trace[449518695] 'agreement among raft nodes before linearized reading' (duration: 508.744287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.765267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:37.254126Z","time spent":"511.131863ms","remote":"127.0.0.1:60942","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":"info","ts":"2026-02-17T23:15:37.765411Z","caller":"traceutil/trace.go:171","msg":"trace[2073352485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1820; }","duration":"410.54283ms","start":"2026-02-17T23:15:37.354861Z","end":"2026-02-17T23:15:37.765404Z","steps":["trace[2073352485] 'agreement among raft nodes before linearized reading' (duration: 407.883738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.765472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:37.354841Z","time spent":"410.622981ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T23:15:37.765558Z","caller":"traceutil/trace.go:171","msg":"trace[581072748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1820; }","duration":"410.780675ms","start":"2026-02-17T23:15:37.35477Z","end":"2026-02-17T23:15:37.765551Z","steps":["trace[581072748] 'agreement among raft nodes before linearized reading' (duration: 408.054622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T23:15:37.765616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T23:15:37.354749Z","time spent":"410.847186ms","remote":"127.0.0.1:60942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T23:15:37.914428Z","caller":"traceutil/trace.go:171","msg":"trace[1078043400] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"139.678049ms","start":"2026-02-17T23:15:37.774729Z","end":"2026-02-17T23:15:37.914408Z","steps":["trace[1078043400] 'process raft request' (duration: 139.642078ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:15:37.915074Z","caller":"traceutil/trace.go:171","msg":"trace[840620279] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"141.578973ms","start":"2026-02-17T23:15:37.773479Z","end":"2026-02-17T23:15:37.915058Z","steps":["trace[840620279] 'process raft request' (duration: 128.976011ms)","trace[840620279] 'compare' (duration: 11.300762ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T23:15:37.915516Z","caller":"traceutil/trace.go:171","msg":"trace[406702454] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"141.975622ms","start":"2026-02-17T23:15:37.773528Z","end":"2026-02-17T23:15:37.915504Z","steps":["trace[406702454] 'process raft request' (duration: 140.736684ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:15:37.915769Z","caller":"traceutil/trace.go:171","msg":"trace[43348229] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"142.156216ms","start":"2026-02-17T23:15:37.773588Z","end":"2026-02-17T23:15:37.915744Z","steps":["trace[43348229] 'process raft request' (duration: 140.725953ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T23:15:37.916177Z","caller":"traceutil/trace.go:171","msg":"trace[526939724] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"142.372531ms","start":"2026-02-17T23:15:37.773606Z","end":"2026-02-17T23:15:37.915979Z","steps":["trace[526939724] 'process raft request' (duration: 140.736524ms)"],"step_count":1}